Run Test: slices-bi-singlenode-login 2026-04-10 17:03:18.395791 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-10 17:03:21.566663 CEST - Run: slices --version 2026-04-10 17:03:21.781015 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 17:03:21.781247 CEST - Run: slices pubkey list --format text 2026-04-10 17:03:22.396271 CEST - Pubkey already registered 2026-04-10 17:03:22.396482 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-10 17:03:22.945955 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-10 17:03:22.946090 CEST - Check List Flavors 2026-04-10 17:03:22.946262 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-10 17:03:25.616269 CEST - Check List DiskImages 2026-04-10 17:03:25.616447 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-10 17:03:26.381916 CEST - Requesting resources 2026-04-10 17:03:26.382353 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-10 17:03:26.382442 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-69a0fff1 --user-data /tmp/tmpb7_m58mh 2026-04-10 17:03:28.701092 CEST - Resource ID: r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk 2026-04-10 17:03:28.701210 CEST - Waiting until resource ready 2026-04-10 17:03:30.701609 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-69a0fff1 tst 2026-04-10 17:03:31.567440 CEST - Status: IMAGING 2026-04-10 17:03:34.434155 CEST - Status: IMAGING 2026-04-10 17:03:37.350520 CEST - Status: IMAGING 2026-04-10 17:03:40.267283 CEST - Status: IMAGING 2026-04-10 17:03:43.184250 CEST - Status: IMAGING 2026-04-10 17:03:46.101311 CEST - Status: IMAGING 2026-04-10 17:03:49.018040 CEST - Status: IMAGING 2026-04-10 17:03:51.934695 CEST - Status: IMAGING 2026-04-10 17:03:54.801143 CEST - Status: IMAGING 2026-04-10 17:03:57.717439 CEST - Status: IMAGING 2026-04-10 17:04:00.683728 CEST - Status: IMAGING 2026-04-10 17:04:03.549788 CEST - Status: IMAGING 2026-04-10 17:04:06.515992 CEST - Status: IMAGING 2026-04-10 17:04:09.432151 CEST - Status: IMAGING 2026-04-10 17:04:12.298255 CEST - Status: IMAGING 2026-04-10 17:04:15.164464 CEST - Status: IMAGING 2026-04-10 17:04:18.030476 CEST - Status: IMAGING 2026-04-10 17:04:20.946601 CEST - Status: IMAGING 2026-04-10 17:04:23.812517 CEST - Status: IMAGING 2026-04-10 17:04:26.678600 CEST - Status: IMAGING 2026-04-10 17:04:29.594641 CEST - Status: IMAGING 2026-04-10 17:04:32.510662 CEST - Status: IMAGING 2026-04-10 17:04:35.476838 CEST - Status: IMAGING 2026-04-10 17:04:38.343156 CEST - Status: IMAGING 2026-04-10 17:04:41.259292 CEST - Status: IMAGING 2026-04-10 17:04:44.175416 CEST - Status: IMAGING 2026-04-10 17:04:47.141874 CEST - Status: IMAGING 2026-04-10 17:04:50.158193 CEST - Status: IMAGING 2026-04-10 17:04:53.124461 CEST - Status: IMAGING 2026-04-10 17:04:56.141092 CEST - Status: IMAGING 2026-04-10 17:04:59.007140 CEST - Status: IMAGING 2026-04-10 17:05:01.873148 CEST - Status: IMAGING 2026-04-10 17:05:04.739178 CEST - Status: IMAGING 2026-04-10 17:05:07.605244 CEST - Status: IMAGING 2026-04-10 17:05:10.522025 CEST - Status: IMAGING 2026-04-10 17:05:13.388528 CEST - Status: IMAGING 2026-04-10 17:05:16.254865 CEST - Status: IMAGING 2026-04-10 17:05:19.120894 CEST - Status: IMAGING 2026-04-10 17:05:21.986944 CEST - Status: IMAGING 2026-04-10 17:05:24.903407 CEST - Status: IMAGING 2026-04-10 17:05:27.819780 CEST - Status: IMAGING 2026-04-10 17:05:30.686431 CEST - Status: IMAGING 2026-04-10 17:05:33.552381 CEST - Status: IMAGING 2026-04-10 17:05:36.418465 CEST - Status: IMAGING 2026-04-10 17:05:39.234207 CEST - Status: IMAGING 2026-04-10 17:05:42.100148 CEST - Status: IMAGING 2026-04-10 17:05:44.966472 CEST - Status: IMAGING 2026-04-10 17:05:47.832437 CEST - Status: IMAGING 2026-04-10 17:05:50.698475 CEST - Status: IMAGING 2026-04-10 17:05:53.564620 CEST - Status: BOOTING 2026-04-10 17:05:56.430804 CEST - Status: BOOTING 2026-04-10 17:05:59.296829 CEST - Status: BOOTING 2026-04-10 17:06:02.162986 CEST - Status: BOOTING 2026-04-10 17:06:05.029098 CEST - Status: BOOTING 2026-04-10 17:06:07.895194 CEST - Status: BOOTING 2026-04-10 17:06:10.761282 CEST - Status: BOOTING 2026-04-10 17:06:13.627958 CEST - Status: BOOTING 2026-04-10 17:06:16.494315 CEST - Status: BOOTING 2026-04-10 17:06:19.360392 CEST - Status: BOOTING 2026-04-10 17:06:22.226486 CEST - Status: BOOTING 2026-04-10 17:06:25.142632 CEST - Status: BOOTING 2026-04-10 17:06:27.958520 CEST - Status: BOOTING 2026-04-10 17:06:30.974709 CEST - Status: BOOTING 2026-04-10 17:06:30.975961 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/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-69a0fff1 tst 2026-04-10 17:06:30.976026 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-10T15:03:28.475215Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knvypqvvffesw3h027nd2qme", "expires_at": "2026-04-10T17:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk", "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 17:06:30.976045 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-10 17:06:30.976070 CEST - Destroying tst-69a0fff1 tst 2026-04-10 17:06:30.976204 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-69a0fff1 tst 2026-04-10 17:06:34.648940 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-69a0fff1 tst 2026-04-10 17:06:34.649025 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/destroy.txt': 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start Deleting tst: task starting # task_id='22492d639055439b98b547c39b675c95' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk Failure: INTERNAL_ERROR task_id='22492d639055439b98b547c39b675c95' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d77eb-63de-7c23-b1e6-92d68f800573'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T15:06:34+00:00.txt 2026-04-10 17:06:34.649064 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-10 17:06:34.649090 CEST - Wait 2s before retry 2026-04-10 17:06:37.966549 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-69a0fff1 tst 2026-04-10 17:06:37.966630 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/destroy_retry1.txt': Deleting tst: task starting # task_id='8f7580b2674947b58c511b7784d78b3d' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk Failure: INTERNAL_ERROR task_id='8f7580b2674947b58c511b7784d78b3d' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d77eb-63de-7c23-b1e6-92d68f800573'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T15:06:37+00:00.txt 2026-04-10 17:06:37.966650 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-10 17:06:37.966673 CEST - Wait 2s before retry 2026-04-10 17:06:42.537223 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-69a0fff1 tst 2026-04-10 17:06:42.537307 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='46e6a39149c84fb3ab55ed557ce406f7' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk Failure: INTERNAL_ERROR task_id='46e6a39149c84fb3ab55ed557ce406f7' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d77eb-63de-7c23-b1e6-92d68f800573'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T15:06:42+00:00.txt 2026-04-10 17:06:42.537327 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-10 17:06:42.537350 CEST - Wait 2s before retry 2026-04-10 17:06:46.105379 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-69a0fff1 tst 2026-04-10 17:06:46.105457 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/17e933cc-d1f2-452f-9f63-debc76c56a92/destroy_retry3.txt': Deleting tst: task starting # task_id='9387ef0de2614634a13922fefd987229' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knvypryyfghv3smjtt7r01bk Failure: INTERNAL_ERROR task_id='9387ef0de2614634a13922fefd987229' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d77eb-63de-7c23-b1e6-92d68f800573'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T15:06:45+00:00.txt 2026-04-10 17:06:46.105475 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)