Run Test: slices-bi-singlenode-login 2026-04-09 17:03:15.333336 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-09 17:03:18.441058 CEST - Run: slices --version 2026-04-09 17:03:18.637473 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-09 17:03:18.637739 CEST - Run: slices pubkey list --format text 2026-04-09 17:03:19.204922 CEST - Pubkey already registered 2026-04-09 17:03:19.205133 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-09 17:03:19.725756 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-09 17:03:19.725883 CEST - Check List Flavors 2026-04-09 17:03:19.726048 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-09 17:03:20.707335 CEST - Check List DiskImages 2026-04-09 17:03:20.707521 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-09 17:03:21.445734 CEST - Requesting resources 2026-04-09 17:03:21.446157 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-09 17:03:21.446245 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-13142821 --user-data /tmp/tmpc2a0137s 2026-04-09 17:03:24.089440 CEST - Resource ID: r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v 2026-04-09 17:03:24.089609 CEST - Waiting until resource ready 2026-04-09 17:03:26.089993 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-13142821 tst 2026-04-09 17:03:26.890974 CEST - Status: IMAGING 2026-04-09 17:03:29.698771 CEST - Status: IMAGING 2026-04-09 17:03:32.518120 CEST - Status: IMAGING 2026-04-09 17:03:35.337006 CEST - Status: IMAGING 2026-04-09 17:03:38.148036 CEST - Status: IMAGING 2026-04-09 17:03:40.963532 CEST - Status: IMAGING 2026-04-09 17:03:43.778469 CEST - Status: IMAGING 2026-04-09 17:03:46.593113 CEST - Status: IMAGING 2026-04-09 17:03:49.395868 CEST - Status: IMAGING 2026-04-09 17:03:52.251308 CEST - Status: IMAGING 2026-04-09 17:03:55.071540 CEST - Status: IMAGING 2026-04-09 17:03:57.902621 CEST - Status: IMAGING 2026-04-09 17:04:00.715430 CEST - Status: IMAGING 2026-04-09 17:04:03.521171 CEST - Status: IMAGING 2026-04-09 17:04:06.347450 CEST - Status: IMAGING 2026-04-09 17:04:09.191907 CEST - Status: IMAGING 2026-04-09 17:04:12.025764 CEST - Status: IMAGING 2026-04-09 17:04:14.843907 CEST - Status: IMAGING 2026-04-09 17:04:17.645787 CEST - Status: IMAGING 2026-04-09 17:04:20.451461 CEST - Status: IMAGING 2026-04-09 17:04:23.281523 CEST - Status: IMAGING 2026-04-09 17:04:26.106278 CEST - Status: IMAGING 2026-04-09 17:04:28.954340 CEST - Status: IMAGING 2026-04-09 17:04:31.779286 CEST - Status: IMAGING 2026-04-09 17:04:34.583976 CEST - Status: IMAGING 2026-04-09 17:04:37.396954 CEST - Status: IMAGING 2026-04-09 17:04:40.208406 CEST - Status: IMAGING 2026-04-09 17:04:43.015888 CEST - Status: IMAGING 2026-04-09 17:04:45.843399 CEST - Status: IMAGING 2026-04-09 17:04:48.669657 CEST - Status: IMAGING 2026-04-09 17:04:51.499374 CEST - Status: IMAGING 2026-04-09 17:04:54.303209 CEST - Status: IMAGING 2026-04-09 17:04:57.127947 CEST - Status: IMAGING 2026-04-09 17:04:59.938653 CEST - Status: IMAGING 2026-04-09 17:05:02.811809 CEST - Status: IMAGING 2026-04-09 17:05:05.694042 CEST - Status: IMAGING 2026-04-09 17:05:08.522086 CEST - Status: IMAGING 2026-04-09 17:05:11.365290 CEST - Status: IMAGING 2026-04-09 17:05:14.178247 CEST - Status: IMAGING 2026-04-09 17:05:16.982564 CEST - Status: IMAGING 2026-04-09 17:05:19.789929 CEST - Status: IMAGING 2026-04-09 17:05:22.610511 CEST - Status: IMAGING 2026-04-09 17:05:25.420103 CEST - Status: IMAGING 2026-04-09 17:05:28.226873 CEST - Status: IMAGING 2026-04-09 17:05:31.045905 CEST - Status: IMAGING 2026-04-09 17:05:33.867512 CEST - Status: IMAGING 2026-04-09 17:05:36.683114 CEST - Status: IMAGING 2026-04-09 17:05:39.487189 CEST - Status: IMAGING 2026-04-09 17:05:42.335062 CEST - Status: IMAGING 2026-04-09 17:05:45.170919 CEST - Status: IMAGING 2026-04-09 17:05:47.994079 CEST - Status: IMAGING 2026-04-09 17:05:50.821222 CEST - Status: BOOTING 2026-04-09 17:05:53.631631 CEST - Status: BOOTING 2026-04-09 17:05:56.460854 CEST - Status: BOOTING 2026-04-09 17:05:59.272823 CEST - Status: BOOTING 2026-04-09 17:06:02.117355 CEST - Status: BOOTING 2026-04-09 17:06:04.931738 CEST - Status: BOOTING 2026-04-09 17:06:07.749277 CEST - Status: BOOTING 2026-04-09 17:06:10.560127 CEST - Status: BOOTING 2026-04-09 17:06:13.383380 CEST - Status: BOOTING 2026-04-09 17:06:16.211432 CEST - Status: BOOTING 2026-04-09 17:06:19.036013 CEST - Status: BOOTING 2026-04-09 17:06:21.849852 CEST - Status: BOOTING 2026-04-09 17:06:24.684980 CEST - Status: BOOTING 2026-04-09 17:06:24.686142 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/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-13142821 tst 2026-04-09 17:06:24.686254 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-09T15:03:23.891701Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knsc9w1af3pb3s1tb628kh8w", "expires_at": "2026-04-09T17:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v", "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-09 17:06:24.686275 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-09 17:06:24.686300 CEST - Destroying tst-13142821 tst 2026-04-09 17:06:24.686432 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-13142821 tst 2026-04-09 17:06:27.711247 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-13142821 tst 2026-04-09 17:06:27.711322 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/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='05cd0862eec04a669f6b2457768c93ca' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v Failure: INTERNAL_ERROR task_id='05cd0862eec04a669f6b2457768c93ca' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d72c4-f5f8-7953-9fb9-9d0931fb7cbb'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T15:06:27+00:00.txt 2026-04-09 17:06:27.711362 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-09 17:06:27.711387 CEST - Wait 2s before retry 2026-04-09 17:06:31.593275 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-13142821 tst 2026-04-09 17:06:31.593354 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/destroy_retry1.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='c99861adc4694fe58adc78a3a0911f53' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v Failure: INTERNAL_ERROR task_id='c99861adc4694fe58adc78a3a0911f53' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d72c4-f5f8-7953-9fb9-9d0931fb7cbb'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T15:06:31+00:00.txt 2026-04-09 17:06:31.593374 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-09 17:06:31.593398 CEST - Wait 2s before retry 2026-04-09 17:06:36.575544 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-13142821 tst 2026-04-09 17:06:36.575637 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/destroy_retry2.txt': 10.0% / task starting Deleting tst: task starting # task_id='43a0f558bcf542228b31995d6500e2ef' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v Failure: INTERNAL_ERROR task_id='43a0f558bcf542228b31995d6500e2ef' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d72c4-f5f8-7953-9fb9-9d0931fb7cbb'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T15:06:36+00:00.txt 2026-04-09 17:06:36.575656 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-09 17:06:36.575677 CEST - Wait 2s before retry 2026-04-09 17:06:39.912398 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-13142821 tst 2026-04-09 17:06:39.912477 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/96816b19-3ffb-479a-95b3-15e5ccb45a20/destroy_retry3.txt': Deleting tst: task starting # task_id='7184bfe81ff441cb8997fe2362f73ce3' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knsc9xfrf59szecx14rzpz5v Failure: INTERNAL_ERROR task_id='7184bfe81ff441cb8997fe2362f73ce3' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d72c4-f5f8-7953-9fb9-9d0931fb7cbb'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T15:06:39+00:00.txt 2026-04-09 17:06:39.912496 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)