Run Test: slices-bi-singlenode-login 2026-04-09 00:03:19.230569 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-09 00:03:22.522900 CEST - Run: slices --version 2026-04-09 00:03:22.809703 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-09 00:03:22.810010 CEST - Run: slices pubkey list --format text 2026-04-09 00:03:23.602603 CEST - Pubkey already registered 2026-04-09 00:03:23.602899 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-09 00:03:24.330174 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-09 00:03:24.330319 CEST - Check List Flavors 2026-04-09 00:03:24.330545 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-09 00:03:25.580517 CEST - Check List DiskImages 2026-04-09 00:03:25.580830 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-09 00:03:26.546440 CEST - Requesting resources 2026-04-09 00:03:26.547057 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-09 00:03:26.547160 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3e5cd638 --user-data /tmp/tmpicxbkxr3 2026-04-09 00:03:30.540642 CEST - Resource ID: r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr 2026-04-09 00:03:30.540823 CEST - Waiting until resource ready 2026-04-09 00:03:32.541329 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-3e5cd638 tst 2026-04-09 00:03:33.432565 CEST - Status: IMAGING 2026-04-09 00:03:36.697225 CEST - Status: IMAGING 2026-04-09 00:03:39.615709 CEST - Status: IMAGING 2026-04-09 00:03:42.508241 CEST - Status: IMAGING 2026-04-09 00:03:45.423147 CEST - Status: IMAGING 2026-04-09 00:03:48.355511 CEST - Status: IMAGING 2026-04-09 00:03:51.283497 CEST - Status: IMAGING 2026-04-09 00:03:54.175504 CEST - Status: IMAGING 2026-04-09 00:03:57.061436 CEST - Status: IMAGING 2026-04-09 00:03:59.951891 CEST - Status: IMAGING 2026-04-09 00:04:02.863669 CEST - Status: IMAGING 2026-04-09 00:04:05.775058 CEST - Status: IMAGING 2026-04-09 00:04:08.652758 CEST - Status: IMAGING 2026-04-09 00:04:11.570119 CEST - Status: IMAGING 2026-04-09 00:04:14.455706 CEST - Status: IMAGING 2026-04-09 00:04:17.521213 CEST - Status: IMAGING 2026-04-09 00:04:20.428744 CEST - Status: IMAGING 2026-04-09 00:04:23.365302 CEST - Status: IMAGING 2026-04-09 00:04:26.253329 CEST - Status: IMAGING 2026-04-09 00:04:29.162206 CEST - Status: IMAGING 2026-04-09 00:04:32.247431 CEST - Status: IMAGING 2026-04-09 00:04:35.260368 CEST - Status: IMAGING 2026-04-09 00:04:38.171048 CEST - Status: IMAGING 2026-04-09 00:04:41.063169 CEST - Status: IMAGING 2026-04-09 00:04:43.966523 CEST - Status: IMAGING 2026-04-09 00:04:46.875796 CEST - Status: IMAGING 2026-04-09 00:04:49.769306 CEST - Status: IMAGING 2026-04-09 00:04:52.692899 CEST - Status: IMAGING 2026-04-09 00:04:55.582840 CEST - Status: IMAGING 2026-04-09 00:04:58.473375 CEST - Status: IMAGING 2026-04-09 00:05:01.402923 CEST - Status: IMAGING 2026-04-09 00:05:04.305287 CEST - Status: IMAGING 2026-04-09 00:05:07.193548 CEST - Status: IMAGING 2026-04-09 00:05:10.199420 CEST - Status: IMAGING 2026-04-09 00:05:13.149627 CEST - Status: IMAGING 2026-04-09 00:05:16.063555 CEST - Status: IMAGING 2026-04-09 00:05:19.028711 CEST - Status: IMAGING 2026-04-09 00:05:22.061498 CEST - Status: IMAGING 2026-04-09 00:05:24.939553 CEST - Status: IMAGING 2026-04-09 00:05:27.939538 CEST - Status: IMAGING 2026-04-09 00:05:30.893132 CEST - Status: IMAGING 2026-04-09 00:05:33.881324 CEST - Status: IMAGING 2026-04-09 00:05:36.941042 CEST - Status: IMAGING 2026-04-09 00:05:39.958410 CEST - Status: IMAGING 2026-04-09 00:05:42.919937 CEST - Status: IMAGING 2026-04-09 00:05:45.901212 CEST - Status: IMAGING 2026-04-09 00:05:48.850388 CEST - Status: IMAGING 2026-04-09 00:05:51.774268 CEST - Status: IMAGING 2026-04-09 00:05:54.661254 CEST - Status: IMAGING 2026-04-09 00:05:57.536916 CEST - Status: BOOTING 2026-04-09 00:06:00.626574 CEST - Status: BOOTING 2026-04-09 00:06:03.706468 CEST - Status: BOOTING 2026-04-09 00:06:06.675354 CEST - Status: BOOTING 2026-04-09 00:06:09.581396 CEST - Status: BOOTING 2026-04-09 00:06:12.483252 CEST - Status: BOOTING 2026-04-09 00:06:15.388561 CEST - Status: BOOTING 2026-04-09 00:06:18.291716 CEST - Status: BOOTING 2026-04-09 00:06:21.329126 CEST - Status: BOOTING 2026-04-09 00:06:24.255315 CEST - Status: BOOTING 2026-04-09 00:06:27.235573 CEST - Status: BOOTING 2026-04-09 00:06:30.146847 CEST - Status: BOOTING 2026-04-09 00:06:33.077026 CEST - Status: BOOTING 2026-04-09 00:06:33.078545 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/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-3e5cd638 tst 2026-04-09 00:06:33.078663 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-08T22:03:30.290696Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knqhybghf1gbhn3tx9tzzx06", "expires_at": "2026-04-09T00:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr", "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-09 00:06:33.078701 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-09 00:06:33.078741 CEST - Destroying tst-3e5cd638 tst 2026-04-09 00:06:33.078940 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3e5cd638 tst 2026-04-09 00:06:37.546163 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3e5cd638 tst 2026-04-09 00:06:37.546246 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/destroy.txt': 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting Deleting tst: task starting # task_id='708ff7a249634049bfa45b58fe488ea4' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr Failure: INTERNAL_ERROR task_id='708ff7a249634049bfa45b58fe488ea4' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d6f1f-3876-7cf0-905c-21e7ce9bfb58'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-08T22:06:37+00:00.txt 2026-04-09 00:06:37.546288 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-09 00:06:37.546312 CEST - Wait 2s before retry 2026-04-09 00:06:40.967432 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3e5cd638 tst 2026-04-09 00:06:40.967532 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/destroy_retry1.txt': Deleting tst: task starting # task_id='4649fb6a28c44965bfa32cbba2d6ef39' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr Failure: INTERNAL_ERROR task_id='4649fb6a28c44965bfa32cbba2d6ef39' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d6f1f-3876-7cf0-905c-21e7ce9bfb58'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-08T22:06:40+00:00.txt 2026-04-09 00:06:40.967553 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-09 00:06:40.967596 CEST - Wait 2s before retry 2026-04-09 00:06:45.629227 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3e5cd638 tst 2026-04-09 00:06:45.629311 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='a1e5719744d24462962d2edd8f7496e0' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr Failure: INTERNAL_ERROR task_id='a1e5719744d24462962d2edd8f7496e0' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d6f1f-3876-7cf0-905c-21e7ce9bfb58'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-08T22:06:45+00:00.txt 2026-04-09 00:06:45.629335 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-09 00:06:45.629356 CEST - Wait 2s before retry 2026-04-09 00:06:49.009565 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3e5cd638 tst 2026-04-09 00:06:49.009646 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/41714aea-43b9-4174-9ddd-d470955c8b45/destroy_retry3.txt': Deleting tst: task starting # task_id='a811266bb3654f3cbc3277d797d6b6ea' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knqhye3pfkr90q11wz79qytr Failure: INTERNAL_ERROR task_id='a811266bb3654f3cbc3277d797d6b6ea' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d6f1f-3876-7cf0-905c-21e7ce9bfb58'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-08T22:06:48+00:00.txt 2026-04-09 00:06:49.009666 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)