Run Test: slices-bi-singlenode-login 2026-04-11 00:03:48.283711 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-11 00:03:51.804972 CEST - Run: slices --version 2026-04-11 00:03:52.019302 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-11 00:03:52.019547 CEST - Run: slices pubkey list --format text 2026-04-11 00:03:52.634552 CEST - Pubkey already registered 2026-04-11 00:03:52.634792 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-11 00:03:53.160077 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-11 00:03:53.160172 CEST - Check List Flavors 2026-04-11 00:03:53.160339 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-11 00:03:54.226505 CEST - Check List DiskImages 2026-04-11 00:03:54.226700 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-11 00:03:54.992213 CEST - Requesting resources 2026-04-11 00:03:54.992697 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-11 00:03:54.992818 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3b5ee07f --user-data /tmp/tmpttaaflf_ 2026-04-11 00:03:58.815093 CEST - Resource ID: r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a 2026-04-11 00:03:58.815217 CEST - Waiting until resource ready 2026-04-11 00:04:00.815642 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-3b5ee07f tst 2026-04-11 00:04:01.681509 CEST - Status: IMAGING 2026-04-11 00:04:04.547755 CEST - Status: IMAGING 2026-04-11 00:04:07.363628 CEST - Status: IMAGING 2026-04-11 00:04:10.229781 CEST - Status: IMAGING 2026-04-11 00:04:13.145969 CEST - Status: IMAGING 2026-04-11 00:04:16.012238 CEST - Status: IMAGING 2026-04-11 00:04:18.878419 CEST - Status: IMAGING 2026-04-11 00:04:21.744452 CEST - Status: IMAGING 2026-04-11 00:04:24.610411 CEST - Status: IMAGING 2026-04-11 00:04:27.476454 CEST - Status: IMAGING 2026-04-11 00:04:30.292400 CEST - Status: IMAGING 2026-04-11 00:04:33.158427 CEST - Status: IMAGING 2026-04-11 00:04:35.974351 CEST - Status: IMAGING 2026-04-11 00:04:38.790429 CEST - Status: IMAGING 2026-04-11 00:04:41.656532 CEST - Status: IMAGING 2026-04-11 00:04:44.522407 CEST - Status: IMAGING 2026-04-11 00:04:47.388557 CEST - Status: IMAGING 2026-04-11 00:04:50.254652 CEST - Status: IMAGING 2026-04-11 00:04:53.120920 CEST - Status: IMAGING 2026-04-11 00:04:55.986701 CEST - Status: IMAGING 2026-04-11 00:04:58.902734 CEST - Status: IMAGING 2026-04-11 00:05:01.818958 CEST - Status: IMAGING 2026-04-11 00:05:04.684937 CEST - Status: IMAGING 2026-04-11 00:05:07.550940 CEST - Status: IMAGING 2026-04-11 00:05:10.416929 CEST - Status: IMAGING 2026-04-11 00:05:13.333120 CEST - Status: IMAGING 2026-04-11 00:05:16.249000 CEST - Status: IMAGING 2026-04-11 00:05:19.115302 CEST - Status: IMAGING 2026-04-11 00:05:21.981616 CEST - Status: IMAGING 2026-04-11 00:05:24.897875 CEST - Status: IMAGING 2026-04-11 00:05:27.713761 CEST - Status: IMAGING 2026-04-11 00:05:30.529641 CEST - Status: IMAGING 2026-04-11 00:05:33.396027 CEST - Status: IMAGING 2026-04-11 00:05:36.212068 CEST - Status: IMAGING 2026-04-11 00:05:39.028434 CEST - Status: IMAGING 2026-04-11 00:05:41.844840 CEST - Status: IMAGING 2026-04-11 00:05:44.761117 CEST - Status: IMAGING 2026-04-11 00:05:47.627225 CEST - Status: IMAGING 2026-04-11 00:05:50.493394 CEST - Status: IMAGING 2026-04-11 00:05:53.359562 CEST - Status: IMAGING 2026-04-11 00:05:56.175429 CEST - Status: IMAGING 2026-04-11 00:05:58.991304 CEST - Status: IMAGING 2026-04-11 00:06:01.857467 CEST - Status: IMAGING 2026-04-11 00:06:04.723676 CEST - Status: IMAGING 2026-04-11 00:06:07.539604 CEST - Status: IMAGING 2026-04-11 00:06:10.355503 CEST - Status: IMAGING 2026-04-11 00:06:13.222361 CEST - Status: IMAGING 2026-04-11 00:06:16.139172 CEST - Status: IMAGING 2026-04-11 00:06:19.005713 CEST - Status: IMAGING 2026-04-11 00:06:21.871816 CEST - Status: IMAGING 2026-04-11 00:06:24.737965 CEST - Status: IMAGING 2026-04-11 00:06:27.553830 CEST - Status: BOOTING 2026-04-11 00:06:30.419838 CEST - Status: BOOTING 2026-04-11 00:06:33.285852 CEST - Status: BOOTING 2026-04-11 00:06:36.101782 CEST - Status: BOOTING 2026-04-11 00:06:38.967877 CEST - Status: BOOTING 2026-04-11 00:06:41.834173 CEST - Status: BOOTING 2026-04-11 00:06:44.650088 CEST - Status: BOOTING 2026-04-11 00:06:47.465921 CEST - Status: BOOTING 2026-04-11 00:06:50.281745 CEST - Status: BOOTING 2026-04-11 00:06:53.097545 CEST - Status: BOOTING 2026-04-11 00:06:55.913452 CEST - Status: BOOTING 2026-04-11 00:06:58.829627 CEST - Status: BOOTING 2026-04-11 00:06:58.830794 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/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-3b5ee07f tst 2026-04-11 00:06:58.830861 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-10T22:03:58.548611Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knwprn4df77820sny0k5s8m1", "expires_at": "2026-04-11T00:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a", "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-11 00:06:58.830879 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-11 00:06:58.830905 CEST - Destroying tst-3b5ee07f tst 2026-04-11 00:06:58.831031 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3b5ee07f tst 2026-04-11 00:07:07.262992 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3b5ee07f tst 2026-04-11 00:07:07.263082 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/destroy.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 0.0% / waiting for task start 10.0% - task starting 10.0% \ task starting 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='5d4a6f4ac96d4946a9e7e6351b99d5eb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a Failure: INTERNAL_ERROR task_id='5d4a6f4ac96d4946a9e7e6351b99d5eb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d796c-5ed7-7a72-8f2d-4dd6ad65e0aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T22:07:07+00:00.txt 2026-04-11 00:07:07.263122 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-11 00:07:07.263146 CEST - Wait 2s before retry 2026-04-11 00:07:10.680985 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3b5ee07f tst 2026-04-11 00:07:10.681063 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/destroy_retry1.txt': Deleting tst: task starting # task_id='319dfe42eec348a1b74595c8f9d16b1e' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a Failure: INTERNAL_ERROR task_id='319dfe42eec348a1b74595c8f9d16b1e' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d796c-5ed7-7a72-8f2d-4dd6ad65e0aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T22:07:10+00:00.txt 2026-04-11 00:07:10.681081 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-11 00:07:10.681104 CEST - Wait 2s before retry 2026-04-11 00:07:15.351592 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3b5ee07f tst 2026-04-11 00:07:15.351670 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='070d9c0603b8486b8aef507998abfc96' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a Failure: INTERNAL_ERROR task_id='070d9c0603b8486b8aef507998abfc96' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d796c-5ed7-7a72-8f2d-4dd6ad65e0aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T22:07:15+00:00.txt 2026-04-11 00:07:15.351689 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-11 00:07:15.351709 CEST - Wait 2s before retry 2026-04-11 00:07:18.719513 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3b5ee07f tst 2026-04-11 00:07:18.719591 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/3f360631-285d-4380-8f66-d05150794c7c/destroy_retry3.txt': Deleting tst: task starting # task_id='c03d49c85de04a218e0616bb590c794e' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knwprqpqf9s8ybadttppbr5a Failure: INTERNAL_ERROR task_id='c03d49c85de04a218e0616bb590c794e' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d796c-5ed7-7a72-8f2d-4dd6ad65e0aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-10T22:07:18+00:00.txt 2026-04-11 00:07:18.719612 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)