Run Test: slices-bi-singlenode-login 2026-04-12 08:03:18.511519 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-12 08:03:21.732398 CEST - Run: slices --version 2026-04-12 08:03:21.946846 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-12 08:03:21.947112 CEST - Run: slices pubkey list --format text 2026-04-12 08:03:22.512047 CEST - Pubkey already registered 2026-04-12 08:03:22.512290 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-12 08:03:23.018407 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-12 08:03:23.018556 CEST - Check List Flavors 2026-04-12 08:03:23.018754 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-12 08:03:23.984640 CEST - Check List DiskImages 2026-04-12 08:03:23.984835 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-12 08:03:24.750325 CEST - Requesting resources 2026-04-12 08:03:24.750748 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-12 08:03:24.750834 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-54195140 --user-data /tmp/tmpq6hw8pbi 2026-04-12 08:03:28.523549 CEST - Resource ID: r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6 2026-04-12 08:03:28.523656 CEST - Waiting until resource ready 2026-04-12 08:03:30.524049 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-54195140 tst 2026-04-12 08:03:31.339569 CEST - Status: IMAGING 2026-04-12 08:03:34.205639 CEST - Status: IMAGING 2026-04-12 08:03:37.021564 CEST - Status: IMAGING 2026-04-12 08:03:39.887682 CEST - Status: IMAGING 2026-04-12 08:03:42.703563 CEST - Status: IMAGING 2026-04-12 08:03:45.519444 CEST - Status: IMAGING 2026-04-12 08:03:48.335389 CEST - Status: IMAGING 2026-04-12 08:03:51.151207 CEST - Status: IMAGING 2026-04-12 08:03:54.017693 CEST - Status: IMAGING 2026-04-12 08:03:56.884026 CEST - Status: IMAGING 2026-04-12 08:03:59.750481 CEST - Status: IMAGING 2026-04-12 08:04:02.566609 CEST - Status: IMAGING 2026-04-12 08:04:05.383790 CEST - Status: IMAGING 2026-04-12 08:04:08.199492 CEST - Status: IMAGING 2026-04-12 08:04:11.015329 CEST - Status: IMAGING 2026-04-12 08:04:13.831148 CEST - Status: IMAGING 2026-04-12 08:04:16.647045 CEST - Status: IMAGING 2026-04-12 08:04:19.462911 CEST - Status: IMAGING 2026-04-12 08:04:22.278762 CEST - Status: IMAGING 2026-04-12 08:04:25.094769 CEST - Status: IMAGING 2026-04-12 08:04:27.910562 CEST - Status: IMAGING 2026-04-12 08:04:30.726393 CEST - Status: IMAGING 2026-04-12 08:04:33.542275 CEST - Status: IMAGING 2026-04-12 08:04:36.408281 CEST - Status: IMAGING 2026-04-12 08:04:39.224124 CEST - Status: IMAGING 2026-04-12 08:04:42.039986 CEST - Status: IMAGING 2026-04-12 08:04:44.855882 CEST - Status: IMAGING 2026-04-12 08:04:47.671733 CEST - Status: IMAGING 2026-04-12 08:04:50.537577 CEST - Status: IMAGING 2026-04-12 08:04:53.353403 CEST - Status: IMAGING 2026-04-12 08:04:56.169350 CEST - Status: IMAGING 2026-04-12 08:04:58.985440 CEST - Status: IMAGING 2026-04-12 08:05:01.801284 CEST - Status: IMAGING 2026-04-12 08:05:04.617142 CEST - Status: IMAGING 2026-04-12 08:05:07.433071 CEST - Status: IMAGING 2026-04-12 08:05:10.248799 CEST - Status: IMAGING 2026-04-12 08:05:13.064482 CEST - Status: IMAGING 2026-04-12 08:05:15.880323 CEST - Status: IMAGING 2026-04-12 08:05:18.696169 CEST - Status: IMAGING 2026-04-12 08:05:21.512057 CEST - Status: IMAGING 2026-04-12 08:05:24.327995 CEST - Status: IMAGING 2026-04-12 08:05:27.143822 CEST - Status: IMAGING 2026-04-12 08:05:29.959741 CEST - Status: IMAGING 2026-04-12 08:05:32.775629 CEST - Status: IMAGING 2026-04-12 08:05:35.642341 CEST - Status: IMAGING 2026-04-12 08:05:38.458635 CEST - Status: IMAGING 2026-04-12 08:05:41.274511 CEST - Status: IMAGING 2026-04-12 08:05:44.090350 CEST - Status: IMAGING 2026-04-12 08:05:46.906504 CEST - Status: IMAGING 2026-04-12 08:05:49.722791 CEST - Status: IMAGING 2026-04-12 08:05:52.538811 CEST - Status: IMAGING 2026-04-12 08:05:55.404769 CEST - Status: BOOTING 2026-04-12 08:05:58.220605 CEST - Status: BOOTING 2026-04-12 08:06:01.036302 CEST - Status: BOOTING 2026-04-12 08:06:03.852232 CEST - Status: BOOTING 2026-04-12 08:06:06.668128 CEST - Status: BOOTING 2026-04-12 08:06:09.484906 CEST - Status: BOOTING 2026-04-12 08:06:12.300903 CEST - Status: BOOTING 2026-04-12 08:06:15.116774 CEST - Status: BOOTING 2026-04-12 08:06:17.932575 CEST - Status: BOOTING 2026-04-12 08:06:20.748416 CEST - Status: BOOTING 2026-04-12 08:06:23.614304 CEST - Status: BOOTING 2026-04-12 08:06:26.430049 CEST - Status: BOOTING 2026-04-12 08:06:29.245841 CEST - Status: BOOTING 2026-04-12 08:06:29.247027 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/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-54195140 tst 2026-04-12 08:06:29.247092 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-12T06:03:28.285824Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01kp04kbk4etmvq4wfxcf50hqc", "expires_at": "2026-04-12T08:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6", "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-12 08:06:29.247119 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-12 08:06:29.247146 CEST - Destroying tst-54195140 tst 2026-04-12 08:06:29.247288 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-54195140 tst 2026-04-12 08:06:32.669353 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-54195140 tst 2026-04-12 08:06:32.669464 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/destroy.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='c81f8629ed5b4fe2b47bb60659b7edf1' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6 Failure: INTERNAL_ERROR task_id='c81f8629ed5b4fe2b47bb60659b7edf1' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8049-b8a1-7832-b122-1c97e2af65c6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T06:06:32+00:00.txt 2026-04-12 08:06:32.669517 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-12 08:06:32.669551 CEST - Wait 2s before retry 2026-04-12 08:06:36.087746 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-54195140 tst 2026-04-12 08:06:36.087831 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/destroy_retry1.txt': Deleting tst: task starting # task_id='817388ef1d324fef965aface92795727' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6 Failure: INTERNAL_ERROR task_id='817388ef1d324fef965aface92795727' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8049-b8a1-7832-b122-1c97e2af65c6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T06:06:35+00:00.txt 2026-04-12 08:06:36.087851 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-12 08:06:36.087873 CEST - Wait 2s before retry 2026-04-12 08:06:40.557716 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-54195140 tst 2026-04-12 08:06:40.557792 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/destroy_retry2.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='b2c2cd31071240468109b302587b20ad' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6 Failure: INTERNAL_ERROR task_id='b2c2cd31071240468109b302587b20ad' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8049-b8a1-7832-b122-1c97e2af65c6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T06:06:40+00:00.txt 2026-04-12 08:06:40.557811 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-12 08:06:40.557832 CEST - Wait 2s before retry 2026-04-12 08:06:43.875312 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-54195140 tst 2026-04-12 08:06:43.875388 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/862dacde-c88e-4d40-800a-01499ed1bbb8/destroy_retry3.txt': Deleting tst: task starting # task_id='696a7dbe331648b3b126554bc3b6cb33' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp04ke51f0sb28gwjzhayse6 Failure: INTERNAL_ERROR task_id='696a7dbe331648b3b126554bc3b6cb33' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8049-b8a1-7832-b122-1c97e2af65c6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T06:06:43+00:00.txt 2026-04-12 08:06:43.875408 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)