Run Test: slices-bi-singlenode-login 2026-04-10 08:07:52.484677 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-10 08:07:55.633051 CEST - Run: slices --version 2026-04-10 08:07:55.832054 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.0 Slices clientlib bi v5.1.2 2026-04-10 08:07:55.832395 CEST - Run: slices pubkey list --format text 2026-04-10 08:07:56.400252 CEST - Pubkey already registered 2026-04-10 08:07:56.400549 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-10 08:07:56.929380 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-10 08:07:56.929512 CEST - Check List Flavors 2026-04-10 08:07:56.929753 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-10 08:07:57.910824 CEST - Check List DiskImages 2026-04-10 08:07:57.911057 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-10 08:07:58.642034 CEST - Requesting resources 2026-04-10 08:07:58.642514 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-10 08:07:58.642606 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-65716231 --user-data /tmp/tmpekbx81kq 2026-04-10 08:08:05.005270 CEST - Resource ID: r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p 2026-04-10 08:08:05.005426 CEST - Waiting until resource ready 2026-04-10 08:08:07.005865 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-65716231 tst 2026-04-10 08:08:07.825993 CEST - Status: IMAGING 2026-04-10 08:08:10.630357 CEST - Status: IMAGING 2026-04-10 08:08:13.459919 CEST - Status: IMAGING 2026-04-10 08:08:16.289727 CEST - Status: IMAGING 2026-04-10 08:08:19.194357 CEST - Status: IMAGING 2026-04-10 08:08:22.086786 CEST - Status: IMAGING 2026-04-10 08:08:24.914812 CEST - Status: IMAGING 2026-04-10 08:08:27.723928 CEST - Status: IMAGING 2026-04-10 08:08:30.539690 CEST - Status: IMAGING 2026-04-10 08:08:33.351423 CEST - Status: IMAGING 2026-04-10 08:08:36.172838 CEST - Status: IMAGING 2026-04-10 08:08:38.988789 CEST - Status: IMAGING 2026-04-10 08:08:41.797540 CEST - Status: IMAGING 2026-04-10 08:08:44.640380 CEST - Status: IMAGING 2026-04-10 08:08:47.447642 CEST - Status: IMAGING 2026-04-10 08:08:50.285842 CEST - Status: IMAGING 2026-04-10 08:08:53.088321 CEST - Status: IMAGING 2026-04-10 08:08:55.896688 CEST - Status: IMAGING 2026-04-10 08:08:58.711500 CEST - Status: IMAGING 2026-04-10 08:09:01.545624 CEST - Status: IMAGING 2026-04-10 08:09:04.382695 CEST - Status: IMAGING 2026-04-10 08:09:07.216941 CEST - Status: IMAGING 2026-04-10 08:09:10.028335 CEST - Status: IMAGING 2026-04-10 08:09:12.846291 CEST - Status: IMAGING 2026-04-10 08:09:15.657013 CEST - Status: IMAGING 2026-04-10 08:09:18.473805 CEST - Status: IMAGING 2026-04-10 08:09:21.290203 CEST - Status: IMAGING 2026-04-10 08:09:24.114075 CEST - Status: IMAGING 2026-04-10 08:09:26.940056 CEST - Status: IMAGING 2026-04-10 08:09:29.743350 CEST - Status: IMAGING 2026-04-10 08:09:32.569036 CEST - Status: IMAGING 2026-04-10 08:09:35.379125 CEST - Status: IMAGING 2026-04-10 08:09:38.182097 CEST - Status: IMAGING 2026-04-10 08:09:40.986949 CEST - Status: IMAGING 2026-04-10 08:09:43.800906 CEST - Status: IMAGING 2026-04-10 08:09:46.627302 CEST - Status: IMAGING 2026-04-10 08:09:49.443258 CEST - Status: IMAGING 2026-04-10 08:09:52.273695 CEST - Status: IMAGING 2026-04-10 08:09:55.078443 CEST - Status: IMAGING 2026-04-10 08:09:57.905444 CEST - Status: IMAGING 2026-04-10 08:10:00.735689 CEST - Status: IMAGING 2026-04-10 08:10:03.541734 CEST - Status: IMAGING 2026-04-10 08:10:06.351702 CEST - Status: IMAGING 2026-04-10 08:10:09.180132 CEST - Status: IMAGING 2026-04-10 08:10:11.983473 CEST - Status: IMAGING 2026-04-10 08:10:14.796148 CEST - Status: IMAGING 2026-04-10 08:10:17.600809 CEST - Status: IMAGING 2026-04-10 08:10:20.420118 CEST - Status: IMAGING 2026-04-10 08:10:23.240399 CEST - Status: IMAGING 2026-04-10 08:10:26.046355 CEST - Status: IMAGING 2026-04-10 08:10:28.870245 CEST - Status: BOOTING 2026-04-10 08:10:31.674950 CEST - Status: BOOTING 2026-04-10 08:10:34.578129 CEST - Status: BOOTING 2026-04-10 08:10:37.409733 CEST - Status: BOOTING 2026-04-10 08:10:40.246994 CEST - Status: BOOTING 2026-04-10 08:10:43.078967 CEST - Status: BOOTING 2026-04-10 08:10:45.886123 CEST - Status: BOOTING 2026-04-10 08:10:48.704552 CEST - Status: BOOTING 2026-04-10 08:10:51.510550 CEST - Status: BOOTING 2026-04-10 08:10:54.326374 CEST - Status: BOOTING 2026-04-10 08:10:57.144313 CEST - Status: BOOTING 2026-04-10 08:10:59.961961 CEST - Status: BOOTING 2026-04-10 08:11:02.767095 CEST - Status: BOOTING 2026-04-10 08:11:05.573788 CEST - Status: BOOTING 2026-04-10 08:11:05.575053 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/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-65716231 tst 2026-04-10 08:11:05.575119 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-10T06:08:04.803376Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knv0291tfh2v3xjz8nhdbvw0", "expires_at": "2026-04-10T08:07:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p", "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-04-10 08:11:05.575137 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-10 08:11:05.575165 CEST - Destroying tst-65716231 tst 2026-04-10 08:11:05.575325 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-65716231 tst 2026-04-10 08:11:07.088833 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-65716231 tst 2026-04-10 08:11:07.088914 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/destroy.txt': Deleting tst: task starting # task_id='e58ecf108c9f44eba8d5adc37ac92ea1' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p Failure: INTERNAL_ERROR task_id='e58ecf108c9f44eba8d5adc37ac92ea1' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7601-38c6-7363-9f18-30f45d99dc56'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T06:11:06+00:00.txt 2026-04-10 08:11:07.088958 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-10 08:11:07.088992 CEST - Wait 2s before retry 2026-04-10 08:11:10.405100 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-65716231 tst 2026-04-10 08:11:10.405179 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/destroy_retry1.txt': Deleting tst: task starting # task_id='920ef7070c254cbbbfd7f8bd7b2bb326' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p Failure: INTERNAL_ERROR task_id='920ef7070c254cbbbfd7f8bd7b2bb326' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7601-38c6-7363-9f18-30f45d99dc56'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T06:11:10+00:00.txt 2026-04-10 08:11:10.405199 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-10 08:11:10.405222 CEST - Wait 2s before retry 2026-04-10 08:11:14.857364 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-65716231 tst 2026-04-10 08:11:14.857442 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='5bc1516a09e04430b2ab15d41ea056b4' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p Failure: INTERNAL_ERROR task_id='5bc1516a09e04430b2ab15d41ea056b4' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7601-38c6-7363-9f18-30f45d99dc56'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T06:11:14+00:00.txt 2026-04-10 08:11:14.857462 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-10 08:11:14.857481 CEST - Wait 2s before retry 2026-04-10 08:11:18.170199 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-65716231 tst 2026-04-10 08:11:18.170275 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2039517b-8cb8-46b9-a467-8652c0937203/destroy_retry3.txt': Deleting tst: task starting # task_id='507d97f51cd54ee18f24493024a876a8' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knv02e66edhsy61gyheskq2p Failure: INTERNAL_ERROR task_id='507d97f51cd54ee18f24493024a876a8' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7601-38c6-7363-9f18-30f45d99dc56'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T06:11:18+00:00.txt 2026-04-10 08:11:18.170294 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)