Run Test: slices-bi-singlenode-login 2026-04-13 00:03:16.635105 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-13 00:03:19.755602 CEST - Run: slices --version 2026-04-13 00:03:19.969930 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 00:03:19.970179 CEST - Run: slices pubkey list --format text 2026-04-13 00:03:20.585333 CEST - Pubkey already registered 2026-04-13 00:03:20.585549 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-13 00:03:21.120820 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-13 00:03:21.120932 CEST - Check List Flavors 2026-04-13 00:03:21.121100 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-13 00:03:22.137186 CEST - Check List DiskImages 2026-04-13 00:03:22.137402 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-13 00:03:22.902798 CEST - Requesting resources 2026-04-13 00:03:22.903345 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-13 00:03:22.903466 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-068c7095 --user-data /tmp/tmpd08bx5a7 2026-04-13 00:03:27.878699 CEST - Resource ID: r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq 2026-04-13 00:03:27.878820 CEST - Waiting until resource ready 2026-04-13 00:03:29.879224 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-068c7095 tst 2026-04-13 00:03:30.695038 CEST - Status: IMAGING 2026-04-13 00:03:33.510939 CEST - Status: IMAGING 2026-04-13 00:03:36.327065 CEST - Status: IMAGING 2026-04-13 00:03:39.143171 CEST - Status: IMAGING 2026-04-13 00:03:42.009311 CEST - Status: IMAGING 2026-04-13 00:03:44.825299 CEST - Status: IMAGING 2026-04-13 00:03:47.641483 CEST - Status: IMAGING 2026-04-13 00:03:50.507589 CEST - Status: IMAGING 2026-04-13 00:03:53.373809 CEST - Status: IMAGING 2026-04-13 00:03:56.189971 CEST - Status: IMAGING 2026-04-13 00:03:59.005925 CEST - Status: IMAGING 2026-04-13 00:04:01.822083 CEST - Status: IMAGING 2026-04-13 00:04:04.638169 CEST - Status: IMAGING 2026-04-13 00:04:07.554470 CEST - Status: IMAGING 2026-04-13 00:04:10.420493 CEST - Status: IMAGING 2026-04-13 00:04:13.336906 CEST - Status: IMAGING 2026-04-13 00:04:16.202792 CEST - Status: IMAGING 2026-04-13 00:04:19.118946 CEST - Status: IMAGING 2026-04-13 00:04:21.935091 CEST - Status: IMAGING 2026-04-13 00:04:24.801152 CEST - Status: IMAGING 2026-04-13 00:04:27.667439 CEST - Status: IMAGING 2026-04-13 00:04:30.483523 CEST - Status: IMAGING 2026-04-13 00:04:33.299316 CEST - Status: IMAGING 2026-04-13 00:04:36.115456 CEST - Status: IMAGING 2026-04-13 00:04:38.931784 CEST - Status: IMAGING 2026-04-13 00:04:41.747990 CEST - Status: IMAGING 2026-04-13 00:04:44.614121 CEST - Status: IMAGING 2026-04-13 00:04:47.480319 CEST - Status: IMAGING 2026-04-13 00:04:50.296266 CEST - Status: IMAGING 2026-04-13 00:04:53.162356 CEST - Status: IMAGING 2026-04-13 00:04:56.028383 CEST - Status: IMAGING 2026-04-13 00:04:58.844461 CEST - Status: IMAGING 2026-04-13 00:05:01.662427 CEST - Status: IMAGING 2026-04-13 00:05:04.478149 CEST - Status: IMAGING 2026-04-13 00:05:07.294090 CEST - Status: IMAGING 2026-04-13 00:05:10.110073 CEST - Status: IMAGING 2026-04-13 00:05:12.926119 CEST - Status: IMAGING 2026-04-13 00:05:15.742075 CEST - Status: IMAGING 2026-04-13 00:05:18.557943 CEST - Status: IMAGING 2026-04-13 00:05:21.373875 CEST - Status: IMAGING 2026-04-13 00:05:24.189875 CEST - Status: IMAGING 2026-04-13 00:05:27.006025 CEST - Status: IMAGING 2026-04-13 00:05:29.822002 CEST - Status: IMAGING 2026-04-13 00:05:32.637818 CEST - Status: IMAGING 2026-04-13 00:05:35.453780 CEST - Status: IMAGING 2026-04-13 00:05:38.269739 CEST - Status: IMAGING 2026-04-13 00:05:41.085625 CEST - Status: IMAGING 2026-04-13 00:05:43.901526 CEST - Status: IMAGING 2026-04-13 00:05:46.717513 CEST - Status: IMAGING 2026-04-13 00:05:49.533433 CEST - Status: IMAGING 2026-04-13 00:05:52.349595 CEST - Status: IMAGING 2026-04-13 00:05:55.165508 CEST - Status: BOOTING 2026-04-13 00:05:57.981409 CEST - Status: BOOTING 2026-04-13 00:06:00.847534 CEST - Status: BOOTING 2026-04-13 00:06:03.663403 CEST - Status: BOOTING 2026-04-13 00:06:06.479307 CEST - Status: BOOTING 2026-04-13 00:06:09.345428 CEST - Status: BOOTING 2026-04-13 00:06:12.161476 CEST - Status: BOOTING 2026-04-13 00:06:15.027672 CEST - Status: BOOTING 2026-04-13 00:06:17.843621 CEST - Status: BOOTING 2026-04-13 00:06:20.659569 CEST - Status: BOOTING 2026-04-13 00:06:23.475433 CEST - Status: BOOTING 2026-04-13 00:06:26.291473 CEST - Status: BOOTING 2026-04-13 00:06:29.107485 CEST - Status: BOOTING 2026-04-13 00:06:29.108735 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/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-068c7095 tst 2026-04-13 00:06:29.108801 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-12T22:03:27.637595Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01kp1vh3qtfpcat9kbtgje1pee", "expires_at": "2026-04-13T00:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq", "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 00:06:29.108819 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-13 00:06:29.108845 CEST - Destroying tst-068c7095 tst 2026-04-13 00:06:29.108975 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-068c7095 tst 2026-04-13 00:06:32.430522 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-068c7095 tst 2026-04-13 00:06:32.430599 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/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='fcaf1880ea504e9986a85b0de3d83559' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq Failure: INTERNAL_ERROR task_id='fcaf1880ea504e9986a85b0de3d83559' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d83b8-9e19-7411-b3be-81a734a651d7'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T22:06:32+00:00.txt 2026-04-13 00:06:32.430639 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-13 00:06:32.430664 CEST - Wait 2s before retry 2026-04-13 00:06:35.748228 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-068c7095 tst 2026-04-13 00:06:35.748346 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/destroy_retry1.txt': Deleting tst: task starting # task_id='3b3f4c4d68344748ae1d0567a759dd7e' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq Failure: INTERNAL_ERROR task_id='3b3f4c4d68344748ae1d0567a759dd7e' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d83b8-9e19-7411-b3be-81a734a651d7'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T22:06:35+00:00.txt 2026-04-13 00:06:35.748371 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-13 00:06:35.748397 CEST - Wait 2s before retry 2026-04-13 00:06:40.819910 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-068c7095 tst 2026-04-13 00:06:40.819994 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/destroy_retry2.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='44aef157ff0f45ecbe590722ac407ae6' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq Failure: INTERNAL_ERROR task_id='44aef157ff0f45ecbe590722ac407ae6' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d83b8-9e19-7411-b3be-81a734a651d7'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T22:06:40+00:00.txt 2026-04-13 00:06:40.820015 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-13 00:06:40.820038 CEST - Wait 2s before retry 2026-04-13 00:06:44.188048 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-068c7095 tst 2026-04-13 00:06:44.188129 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/bc840674-f959-42e3-a5b5-1938a7d81310/destroy_retry3.txt': Deleting tst: task starting # task_id='772d7c67ba5443acaab6ca2541bf559e' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp1vh7gseg8v7fm1mwtacmeq Failure: INTERNAL_ERROR task_id='772d7c67ba5443acaab6ca2541bf559e' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d83b8-9e19-7411-b3be-81a734a651d7'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T22:06:44+00:00.txt 2026-04-13 00:06:44.188149 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)