Run Test: slices-bi-singlenode-login 2026-04-11 08:03:17.310328 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-11 08:03:23.286752 CEST - Run: slices --version 2026-04-11 08:03:23.501071 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 08:03:23.501323 CEST - Run: slices pubkey list --format text 2026-04-11 08:03:24.066121 CEST - Pubkey already registered 2026-04-11 08:03:24.066338 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-11 08:03:24.573752 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-11 08:03:24.573889 CEST - Check List Flavors 2026-04-11 08:03:24.574072 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-11 08:03:25.540148 CEST - Check List DiskImages 2026-04-11 08:03:25.540334 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-11 08:03:26.255613 CEST - Requesting resources 2026-04-11 08:03:26.256076 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-11 08:03:26.256172 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-f93c41c7 --user-data /tmp/tmp9k0rls6p 2026-04-11 08:03:32.634481 CEST - Resource ID: r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0 2026-04-11 08:03:32.634592 CEST - Waiting until resource ready 2026-04-11 08:03:34.635004 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-f93c41c7 tst 2026-04-11 08:03:35.500881 CEST - Status: IMAGING 2026-04-11 08:03:38.367016 CEST - Status: IMAGING 2026-04-11 08:03:41.283275 CEST - Status: IMAGING 2026-04-11 08:03:44.199774 CEST - Status: IMAGING 2026-04-11 08:03:47.066355 CEST - Status: IMAGING 2026-04-11 08:03:49.882232 CEST - Status: IMAGING 2026-04-11 08:03:52.748260 CEST - Status: IMAGING 2026-04-11 08:03:55.564250 CEST - Status: IMAGING 2026-04-11 08:03:58.380221 CEST - Status: IMAGING 2026-04-11 08:04:01.196245 CEST - Status: IMAGING 2026-04-11 08:04:04.011873 CEST - Status: IMAGING 2026-04-11 08:04:06.827819 CEST - Status: IMAGING 2026-04-11 08:04:09.643876 CEST - Status: IMAGING 2026-04-11 08:04:12.459675 CEST - Status: IMAGING 2026-04-11 08:04:15.275590 CEST - Status: IMAGING 2026-04-11 08:04:18.093081 CEST - Status: IMAGING 2026-04-11 08:04:20.959294 CEST - Status: IMAGING 2026-04-11 08:04:23.775329 CEST - Status: IMAGING 2026-04-11 08:04:26.591308 CEST - Status: IMAGING 2026-04-11 08:04:29.407427 CEST - Status: IMAGING 2026-04-11 08:04:32.223389 CEST - Status: IMAGING 2026-04-11 08:04:35.089928 CEST - Status: IMAGING 2026-04-11 08:04:37.905712 CEST - Status: IMAGING 2026-04-11 08:04:40.721548 CEST - Status: IMAGING 2026-04-11 08:04:43.537407 CEST - Status: IMAGING 2026-04-11 08:04:46.403438 CEST - Status: IMAGING 2026-04-11 08:04:49.219391 CEST - Status: IMAGING 2026-04-11 08:04:52.035241 CEST - Status: IMAGING 2026-04-11 08:04:54.851069 CEST - Status: IMAGING 2026-04-11 08:04:57.666929 CEST - Status: IMAGING 2026-04-11 08:05:00.482806 CEST - Status: IMAGING 2026-04-11 08:05:03.298481 CEST - Status: IMAGING 2026-04-11 08:05:06.114442 CEST - Status: IMAGING 2026-04-11 08:05:08.930304 CEST - Status: IMAGING 2026-04-11 08:05:11.746288 CEST - Status: IMAGING 2026-04-11 08:05:14.562090 CEST - Status: IMAGING 2026-04-11 08:05:17.377872 CEST - Status: IMAGING 2026-04-11 08:05:20.193901 CEST - Status: IMAGING 2026-04-11 08:05:23.009855 CEST - Status: IMAGING 2026-04-11 08:05:25.875850 CEST - Status: IMAGING 2026-04-11 08:05:28.691912 CEST - Status: IMAGING 2026-04-11 08:05:31.507812 CEST - Status: IMAGING 2026-04-11 08:05:34.324119 CEST - Status: IMAGING 2026-04-11 08:05:37.140216 CEST - Status: IMAGING 2026-04-11 08:05:39.956078 CEST - Status: IMAGING 2026-04-11 08:05:42.772003 CEST - Status: IMAGING 2026-04-11 08:05:45.587988 CEST - Status: IMAGING 2026-04-11 08:05:48.403870 CEST - Status: IMAGING 2026-04-11 08:05:51.219836 CEST - Status: IMAGING 2026-04-11 08:05:54.035784 CEST - Status: IMAGING 2026-04-11 08:05:56.851669 CEST - Status: BOOTING 2026-04-11 08:05:59.667629 CEST - Status: BOOTING 2026-04-11 08:06:02.483807 CEST - Status: BOOTING 2026-04-11 08:06:05.349919 CEST - Status: BOOTING 2026-04-11 08:06:08.216109 CEST - Status: BOOTING 2026-04-11 08:06:11.031953 CEST - Status: BOOTING 2026-04-11 08:06:13.847956 CEST - Status: BOOTING 2026-04-11 08:06:16.764292 CEST - Status: BOOTING 2026-04-11 08:06:19.580444 CEST - Status: BOOTING 2026-04-11 08:06:22.396429 CEST - Status: BOOTING 2026-04-11 08:06:25.212168 CEST - Status: BOOTING 2026-04-11 08:06:28.078326 CEST - Status: BOOTING 2026-04-11 08:06:30.944430 CEST - Status: BOOTING 2026-04-11 08:06:33.810685 CEST - Status: BOOTING 2026-04-11 08:06:33.811854 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/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-f93c41c7 tst 2026-04-11 08:06:33.811916 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-11T06:03:32.362773Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knxj6p20exrvgtg2ftp0w8t8", "expires_at": "2026-04-11T08:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0", "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 08:06:33.811934 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-11 08:06:33.811958 CEST - Destroying tst-f93c41c7 tst 2026-04-11 08:06:33.812085 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f93c41c7 tst 2026-04-11 08:06:37.634894 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f93c41c7 tst 2026-04-11 08:06:37.634969 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/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 Deleting tst: task starting # task_id='86d71faea71548f3b95133aa287d11df' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0 Failure: INTERNAL_ERROR task_id='86d71faea71548f3b95133aa287d11df' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7b23-6c8f-7d03-a798-6fe1d4c97340'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T06:06:37+00:00.txt 2026-04-11 08:06:37.635009 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-11 08:06:37.635032 CEST - Wait 2s before retry 2026-04-11 08:06:41.002985 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f93c41c7 tst 2026-04-11 08:06:41.003082 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/destroy_retry1.txt': Deleting tst: task starting # task_id='4d2eb979cc364cd28a3d29af1ed42147' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0 Failure: INTERNAL_ERROR task_id='4d2eb979cc364cd28a3d29af1ed42147' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7b23-6c8f-7d03-a798-6fe1d4c97340'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T06:06:40+00:00.txt 2026-04-11 08:06:41.003105 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-11 08:06:41.003131 CEST - Wait 2s before retry 2026-04-11 08:06:45.473505 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f93c41c7 tst 2026-04-11 08:06:45.473583 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='fc6ad71a707b4804bbd7831d96738562' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0 Failure: INTERNAL_ERROR task_id='fc6ad71a707b4804bbd7831d96738562' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7b23-6c8f-7d03-a798-6fe1d4c97340'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T06:06:45+00:00.txt 2026-04-11 08:06:45.473602 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-11 08:06:45.473623 CEST - Wait 2s before retry 2026-04-11 08:06:48.841320 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f93c41c7 tst 2026-04-11 08:06:48.841401 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6432394f-7154-466c-a1af-2abc68d531cb/destroy_retry3.txt': Deleting tst: task starting # task_id='1ad51a830c0c474e8adfc05590d21bce' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knxj6v4ffm1tf63fw7acjwt0 Failure: INTERNAL_ERROR task_id='1ad51a830c0c474e8adfc05590d21bce' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7b23-6c8f-7d03-a798-6fe1d4c97340'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T06:06:48+00:00.txt 2026-04-11 08:06:48.841422 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)