Run Test: slices-bi-singlenode-login 2026-04-13 08:03:26.642818 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-13 08:03:29.863429 CEST - Run: slices --version 2026-04-13 08:03:30.077723 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-13 08:03:30.077993 CEST - Run: slices pubkey list --format text 2026-04-13 08:03:30.693352 CEST - Pubkey already registered 2026-04-13 08:03:30.693646 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-13 08:03:31.206175 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-13 08:03:31.206314 CEST - Check List Flavors 2026-04-13 08:03:31.206530 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-13 08:03:32.222890 CEST - Check List DiskImages 2026-04-13 08:03:32.223100 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-13 08:03:32.988618 CEST - Requesting resources 2026-04-13 08:03:32.989106 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-13 08:03:32.989200 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-965526a9 --user-data /tmp/tmpp290r2re 2026-04-13 08:03:38.064308 CEST - Resource ID: r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz 2026-04-13 08:03:38.064416 CEST - Waiting until resource ready 2026-04-13 08:03:40.064797 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-965526a9 tst 2026-04-13 08:03:41.030808 CEST - Status: IMAGING 2026-04-13 08:03:43.846870 CEST - Status: IMAGING 2026-04-13 08:03:46.662630 CEST - Status: IMAGING 2026-04-13 08:03:49.478563 CEST - Status: IMAGING 2026-04-13 08:03:52.294617 CEST - Status: IMAGING 2026-04-13 08:03:55.110613 CEST - Status: IMAGING 2026-04-13 08:03:57.926402 CEST - Status: IMAGING 2026-04-13 08:04:00.742218 CEST - Status: IMAGING 2026-04-13 08:04:03.558153 CEST - Status: IMAGING 2026-04-13 08:04:06.373997 CEST - Status: IMAGING 2026-04-13 08:04:09.190051 CEST - Status: IMAGING 2026-04-13 08:04:12.006633 CEST - Status: IMAGING 2026-04-13 08:04:14.922888 CEST - Status: IMAGING 2026-04-13 08:04:17.738936 CEST - Status: IMAGING 2026-04-13 08:04:20.554753 CEST - Status: IMAGING 2026-04-13 08:04:23.370579 CEST - Status: IMAGING 2026-04-13 08:04:26.186464 CEST - Status: IMAGING 2026-04-13 08:04:29.002151 CEST - Status: IMAGING 2026-04-13 08:04:31.818020 CEST - Status: IMAGING 2026-04-13 08:04:34.684163 CEST - Status: IMAGING 2026-04-13 08:04:37.499987 CEST - Status: IMAGING 2026-04-13 08:04:40.315871 CEST - Status: IMAGING 2026-04-13 08:04:43.131846 CEST - Status: IMAGING 2026-04-13 08:04:45.947692 CEST - Status: IMAGING 2026-04-13 08:04:48.763415 CEST - Status: IMAGING 2026-04-13 08:04:51.579633 CEST - Status: IMAGING 2026-04-13 08:04:54.596651 CEST - Status: IMAGING 2026-04-13 08:04:57.463220 CEST - Status: IMAGING 2026-04-13 08:05:00.279730 CEST - Status: IMAGING 2026-04-13 08:05:03.095695 CEST - Status: IMAGING 2026-04-13 08:05:05.911565 CEST - Status: IMAGING 2026-04-13 08:05:08.727601 CEST - Status: IMAGING 2026-04-13 08:05:11.543507 CEST - Status: IMAGING 2026-04-13 08:05:14.359280 CEST - Status: IMAGING 2026-04-13 08:05:17.175031 CEST - Status: IMAGING 2026-04-13 08:05:19.990988 CEST - Status: IMAGING 2026-04-13 08:05:22.807003 CEST - Status: IMAGING 2026-04-13 08:05:25.623080 CEST - Status: IMAGING 2026-04-13 08:05:28.439075 CEST - Status: IMAGING 2026-04-13 08:05:31.254846 CEST - Status: IMAGING 2026-04-13 08:05:34.070627 CEST - Status: IMAGING 2026-04-13 08:05:36.886590 CEST - Status: IMAGING 2026-04-13 08:05:39.702755 CEST - Status: IMAGING 2026-04-13 08:05:42.518738 CEST - Status: IMAGING 2026-04-13 08:05:45.334635 CEST - Status: IMAGING 2026-04-13 08:05:48.150572 CEST - Status: IMAGING 2026-04-13 08:05:50.966447 CEST - Status: IMAGING 2026-04-13 08:05:53.782731 CEST - Status: IMAGING 2026-04-13 08:05:56.598512 CEST - Status: IMAGING 2026-04-13 08:05:59.414229 CEST - Status: IMAGING 2026-04-13 08:06:02.230134 CEST - Status: BOOTING 2026-04-13 08:06:05.046305 CEST - Status: BOOTING 2026-04-13 08:06:07.862110 CEST - Status: BOOTING 2026-04-13 08:06:10.677924 CEST - Status: BOOTING 2026-04-13 08:06:13.493702 CEST - Status: BOOTING 2026-04-13 08:06:16.309638 CEST - Status: BOOTING 2026-04-13 08:06:19.125700 CEST - Status: BOOTING 2026-04-13 08:06:21.941646 CEST - Status: BOOTING 2026-04-13 08:06:24.757682 CEST - Status: BOOTING 2026-04-13 08:06:27.573490 CEST - Status: BOOTING 2026-04-13 08:06:30.389353 CEST - Status: BOOTING 2026-04-13 08:06:33.205265 CEST - Status: BOOTING 2026-04-13 08:06:36.021082 CEST - Status: BOOTING 2026-04-13 08:06:38.836943 CEST - Status: BOOTING 2026-04-13 08:06:38.838126 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/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-965526a9 tst 2026-04-13 08:06:38.838189 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-13T06:03:37.830947Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01kp2q0an0e2a8r0ks8dptaq74", "expires_at": "2026-04-13T08:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz", "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-13 08:06:38.838207 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-13 08:06:38.838232 CEST - Destroying tst-965526a9 tst 2026-04-13 08:06:38.838358 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-965526a9 tst 2026-04-13 08:06:40.906972 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-965526a9 tst 2026-04-13 08:06:40.907047 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/destroy.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='6bc81349710347e1b1ee4b5ce7ed9242' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz Failure: INTERNAL_ERROR task_id='6bc81349710347e1b1ee4b5ce7ed9242' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8570-39ea-78e3-88b4-3e9ca6cceebf'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-13T06:06:40+00:00.txt 2026-04-13 08:06:40.907089 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-13 08:06:40.907115 CEST - Wait 2s before retry 2026-04-13 08:06:44.224617 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-965526a9 tst 2026-04-13 08:06:44.224692 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/destroy_retry1.txt': Deleting tst: task starting # task_id='87a456c4160344b68a200e72f0db81f1' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz Failure: INTERNAL_ERROR task_id='87a456c4160344b68a200e72f0db81f1' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8570-39ea-78e3-88b4-3e9ca6cceebf'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-13T06:06:44+00:00.txt 2026-04-13 08:06:44.224711 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-13 08:06:44.224760 CEST - Wait 2s before retry 2026-04-13 08:06:48.695141 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-965526a9 tst 2026-04-13 08:06:48.695218 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='a99a6f1668fc43a89d6818874a86c105' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz Failure: INTERNAL_ERROR task_id='a99a6f1668fc43a89d6818874a86c105' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8570-39ea-78e3-88b4-3e9ca6cceebf'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-13T06:06:48+00:00.txt 2026-04-13 08:06:48.695238 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-13 08:06:48.695258 CEST - Wait 2s before retry 2026-04-13 08:06:52.062779 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-965526a9 tst 2026-04-13 08:06:52.062855 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/5a8b907e-493b-44f7-9655-b7dab5b7c30f/destroy_retry3.txt': Deleting tst: task starting # task_id='6749af541a584935a23ef4474f723456' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp2q0efaf3hrhd1ykjkcsvnz Failure: INTERNAL_ERROR task_id='6749af541a584935a23ef4474f723456' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8570-39ea-78e3-88b4-3e9ca6cceebf'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-13T06:06:51+00:00.txt 2026-04-13 08:06:52.062874 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)