Run Test: slices-bi-singlenode-login 2026-05-04 17:04:35.625585 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-04 17:04:38.847831 CEST - Run: slices --version 2026-05-04 17:04:39.062311 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.1 Slices clientlib bi v5.1.2 2026-05-04 17:04:39.062593 CEST - Run: slices pubkey list --format text 2026-05-04 17:04:39.627944 CEST - Pubkey already registered 2026-05-04 17:04:39.628197 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-04 17:04:40.193562 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-04 17:04:40.193705 CEST - Check List Flavors 2026-05-04 17:04:40.193943 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-05-04 17:04:41.360828 CEST - Check List DiskImages 2026-05-04 17:04:41.361033 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-05-04 17:04:42.176820 CEST - Requesting resources 2026-05-04 17:04:42.177279 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-05-04 17:04:42.177370 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-f0f99941 --user-data /tmp/tmp7g50adzo 2026-05-04 17:04:44.596747 CEST - Resource ID: r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b 2026-05-04 17:04:44.596893 CEST - Waiting until resource ready 2026-05-04 17:04:46.597321 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-f0f99941 tst 2026-05-04 17:04:47.513329 CEST - Status: IMAGING 2026-05-04 17:04:50.479965 CEST - Status: IMAGING 2026-05-04 17:04:53.446639 CEST - Status: IMAGING 2026-05-04 17:04:56.313172 CEST - Status: BOOTING 2026-05-04 17:04:59.230097 CEST - Status: BOOTING 2026-05-04 17:05:02.196816 CEST - Status: BOOTING 2026-05-04 17:05:05.113485 CEST - Status: BOOTING 2026-05-04 17:05:08.030061 CEST - Status: BOOTING 2026-05-04 17:05:10.946710 CEST - Status: BOOTING 2026-05-04 17:05:13.913390 CEST - Status: BOOTING 2026-05-04 17:05:16.830040 CEST - Status: BOOTING 2026-05-04 17:05:19.746499 CEST - Status: BOOTING 2026-05-04 17:05:22.663029 CEST - Status: BOOTING 2026-05-04 17:05:25.579615 CEST - Status: BOOTING 2026-05-04 17:05:28.546901 CEST - Status: BOOTING 2026-05-04 17:05:31.463508 CEST - Status: BOOTING 2026-05-04 17:05:34.380106 CEST - Status: BOOTING 2026-05-04 17:05:37.296606 CEST - Status: BOOTING 2026-05-04 17:05:40.263640 CEST - Status: BOOTING 2026-05-04 17:05:43.130170 CEST - Status: BOOTING 2026-05-04 17:05:46.096971 CEST - Status: BOOTING 2026-05-04 17:05:49.063880 CEST - Status: BOOTING 2026-05-04 17:05:52.080865 CEST - Status: BOOTING 2026-05-04 17:05:54.997334 CEST - Status: BOOTING 2026-05-04 17:05:57.913890 CEST - Status: BOOTING 2026-05-04 17:06:00.780214 CEST - Status: BOOTING 2026-05-04 17:06:03.696633 CEST - Status: BOOTING 2026-05-04 17:06:06.563330 CEST - Status: BOOTING 2026-05-04 17:06:09.480080 CEST - Status: BOOTING 2026-05-04 17:06:12.396819 CEST - Status: BOOTING 2026-05-04 17:06:15.313798 CEST - Status: BOOTING 2026-05-04 17:06:18.180317 CEST - Status: BOOTING 2026-05-04 17:06:21.047330 CEST - Status: BOOTING 2026-05-04 17:06:23.913790 CEST - Status: BOOTING 2026-05-04 17:06:26.780224 CEST - Status: BOOTING 2026-05-04 17:06:29.697086 CEST - Status: BOOTING 2026-05-04 17:06:32.563296 CEST - Status: BOOTING 2026-05-04 17:06:35.429589 CEST - Status: BOOTING 2026-05-04 17:06:38.346201 CEST - Status: BOOTING 2026-05-04 17:06:41.212487 CEST - Status: BOOTING 2026-05-04 17:06:44.079052 CEST - Status: BOOTING 2026-05-04 17:06:46.995824 CEST - Status: BOOTING 2026-05-04 17:06:49.962760 CEST - Status: BOOTING 2026-05-04 17:06:52.879579 CEST - Status: BOOTING 2026-05-04 17:06:55.746016 CEST - Status: BOOTING 2026-05-04 17:06:58.666119 CEST - Status: BOOTING 2026-05-04 17:06:58.667339 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/step_WaitforUP_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-f0f99941 tst 2026-05-04 17:06:58.667413 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-05-04T15:04:44.348454Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01kqsra9w1frtsqmsn8r2qnd1r", "expires_at": "2026-05-04T17:04:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b", "network_interfaces": [], "private_ipv4": null, "private_ipv6": null, "public_ipv4": null, "public_ipv6": null, "ssh_authorized_keys": [ "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINNiZPtQkfCEsc1x2QSwNWekfhWwWZbOvpE8hE0dH253 Wim (ftester) Van de Meerssche" ], "ssh_logins": [], "status": "booting", "terminated_at": null, "userdata": "#!/bin/sh\necho \"Hello World. The time is now $(date -R)!\" | tee '/user_data_output.txt'", "console_url": null, "machine_id": null, "failure_reason": null, "infra_id": "gr-volos-bi-vm1", "machine_name": null } ] 2026-05-04 17:06:58.667437 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-05-04 17:06:58.667480 CEST - Destroying tst-f0f99941 tst 2026-05-04 17:06:58.667653 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f0f99941 tst 2026-05-04 17:07:00.887508 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f0f99941 tst 2026-05-04 17:07:00.887600 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/destroy.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='611a4c758b774097b55152f4a97e747e' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b Failure: INTERNAL_ERROR task_id='611a4c758b774097b55152f4a97e747e' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019df385-2c40-7f31-9a92-1826068b5c2b'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-05-04T15:07:00+00:00.txt 2026-05-04 17:07:00.887627 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-05-04 17:07:00.887653 CEST - Wait 2s before retry 2026-05-04 17:07:07.312930 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f0f99941 tst 2026-05-04 17:07:07.313022 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/destroy_retry1.txt': 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 10.0% / task starting Deleting tst: task starting # task_id='ca2e39f6de264363aa56332d6d506628' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b Failure: INTERNAL_ERROR task_id='ca2e39f6de264363aa56332d6d506628' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019df385-2c40-7f31-9a92-1826068b5c2b'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-05-04T15:07:07+00:00.txt 2026-05-04 17:07:07.313050 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-05-04 17:07:07.313077 CEST - Wait 2s before retry 2026-05-04 17:07:13.191685 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f0f99941 tst 2026-05-04 17:07:13.191776 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start Deleting tst: task starting # task_id='7a484f162277407e811d6f5f4b5693eb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b Failure: INTERNAL_ERROR task_id='7a484f162277407e811d6f5f4b5693eb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019df385-2c40-7f31-9a92-1826068b5c2b'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-05-04T15:07:13+00:00.txt 2026-05-04 17:07:13.191801 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-05-04 17:07:13.191826 CEST - Wait 2s before retry 2026-05-04 17:07:19.066323 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f0f99941 tst 2026-05-04 17:07:19.066413 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5520314c-2888-427a-96cb-338924e12c0f/destroy_retry3.txt': 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start Deleting tst: task starting # task_id='9175257e5e0d402ab2343db80cb63a8b' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kqsrab20fwrsn4gr4r38pq1b Failure: INTERNAL_ERROR task_id='9175257e5e0d402ab2343db80cb63a8b' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019df385-2c40-7f31-9a92-1826068b5c2b'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-05-04T15:07:18+00:00.txt 2026-05-04 17:07:19.066437 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)