Run Test: slices-bi-singlenode-login 2026-04-10 00:04:06.872772 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-10 00:04:10.029055 CEST - Run: slices --version 2026-04-10 00:04:10.237579 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-10 00:04:10.237874 CEST - Run: slices pubkey list --format text 2026-04-10 00:04:10.866153 CEST - Pubkey already registered 2026-04-10 00:04:10.866439 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-10 00:04:11.460501 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-10 00:04:11.460656 CEST - Check List Flavors 2026-04-10 00:04:11.460951 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-10 00:04:12.485873 CEST - Check List DiskImages 2026-04-10 00:04:12.486123 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-10 00:04:13.258439 CEST - Requesting resources 2026-04-10 00:04:13.258982 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-10 00:04:13.259087 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3032a35a --user-data /tmp/tmp5svr45qj 2026-04-10 00:04:16.543108 CEST - Resource ID: r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48 2026-04-10 00:04:16.543272 CEST - Waiting until resource ready 2026-04-10 00:04:18.543833 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-3032a35a tst 2026-04-10 00:04:19.430525 CEST - Status: IMAGING 2026-04-10 00:04:22.319533 CEST - Status: IMAGING 2026-04-10 00:04:25.187843 CEST - Status: IMAGING 2026-04-10 00:04:28.040422 CEST - Status: IMAGING 2026-04-10 00:04:30.902543 CEST - Status: IMAGING 2026-04-10 00:04:33.743628 CEST - Status: IMAGING 2026-04-10 00:04:36.551634 CEST - Status: IMAGING 2026-04-10 00:04:39.382426 CEST - Status: IMAGING 2026-04-10 00:04:42.239873 CEST - Status: IMAGING 2026-04-10 00:04:45.077397 CEST - Status: IMAGING 2026-04-10 00:04:47.901943 CEST - Status: IMAGING 2026-04-10 00:04:50.704308 CEST - Status: IMAGING 2026-04-10 00:04:53.521370 CEST - Status: IMAGING 2026-04-10 00:04:56.337568 CEST - Status: IMAGING 2026-04-10 00:04:59.149382 CEST - Status: IMAGING 2026-04-10 00:05:01.959222 CEST - Status: IMAGING 2026-04-10 00:05:04.775235 CEST - Status: IMAGING 2026-04-10 00:05:07.654161 CEST - Status: IMAGING 2026-04-10 00:05:10.480166 CEST - Status: IMAGING 2026-04-10 00:05:13.297190 CEST - Status: IMAGING 2026-04-10 00:05:16.129058 CEST - Status: IMAGING 2026-04-10 00:05:18.941304 CEST - Status: IMAGING 2026-04-10 00:05:21.758519 CEST - Status: IMAGING 2026-04-10 00:05:24.580346 CEST - Status: IMAGING 2026-04-10 00:05:27.475589 CEST - Status: IMAGING 2026-04-10 00:05:30.349616 CEST - Status: IMAGING 2026-04-10 00:05:33.182654 CEST - Status: IMAGING 2026-04-10 00:05:35.992877 CEST - Status: IMAGING 2026-04-10 00:05:38.822293 CEST - Status: IMAGING 2026-04-10 00:05:41.644733 CEST - Status: IMAGING 2026-04-10 00:05:44.487709 CEST - Status: IMAGING 2026-04-10 00:05:47.325089 CEST - Status: IMAGING 2026-04-10 00:05:50.146641 CEST - Status: IMAGING 2026-04-10 00:05:53.027195 CEST - Status: IMAGING 2026-04-10 00:05:55.851824 CEST - Status: IMAGING 2026-04-10 00:05:58.771810 CEST - Status: IMAGING 2026-04-10 00:06:01.592831 CEST - Status: IMAGING 2026-04-10 00:06:04.405504 CEST - Status: IMAGING 2026-04-10 00:06:07.217251 CEST - Status: IMAGING 2026-04-10 00:06:10.029088 CEST - Status: IMAGING 2026-04-10 00:06:12.914456 CEST - Status: IMAGING 2026-04-10 00:06:15.750270 CEST - Status: IMAGING 2026-04-10 00:06:18.601330 CEST - Status: IMAGING 2026-04-10 00:06:21.419676 CEST - Status: IMAGING 2026-04-10 00:06:24.231801 CEST - Status: IMAGING 2026-04-10 00:06:27.055395 CEST - Status: IMAGING 2026-04-10 00:06:29.885169 CEST - Status: IMAGING 2026-04-10 00:06:32.690647 CEST - Status: IMAGING 2026-04-10 00:06:35.502043 CEST - Status: IMAGING 2026-04-10 00:06:38.319701 CEST - Status: IMAGING 2026-04-10 00:06:41.133606 CEST - Status: BOOTING 2026-04-10 00:06:43.969692 CEST - Status: BOOTING 2026-04-10 00:06:46.787861 CEST - Status: BOOTING 2026-04-10 00:06:49.610369 CEST - Status: BOOTING 2026-04-10 00:06:52.420902 CEST - Status: BOOTING 2026-04-10 00:06:55.244377 CEST - Status: BOOTING 2026-04-10 00:06:58.071278 CEST - Status: BOOTING 2026-04-10 00:07:00.904491 CEST - Status: BOOTING 2026-04-10 00:07:03.715972 CEST - Status: BOOTING 2026-04-10 00:07:06.536156 CEST - Status: BOOTING 2026-04-10 00:07:09.541668 CEST - Status: BOOTING 2026-04-10 00:07:12.357587 CEST - Status: BOOTING 2026-04-10 00:07:15.176945 CEST - Status: BOOTING 2026-04-10 00:07:17.989566 CEST - Status: BOOTING 2026-04-10 00:07:17.990892 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/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-3032a35a tst 2026-04-10 00:07:17.990959 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-09T22:04:16.306059Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knt4cfzregm90rnfxney1nbt", "expires_at": "2026-04-10T00:04:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48", "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-10 00:07:17.990979 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-10 00:07:17.991005 CEST - Destroying tst-3032a35a tst 2026-04-10 00:07:17.991152 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3032a35a tst 2026-04-10 00:07:19.724286 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3032a35a tst 2026-04-10 00:07:19.724361 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/destroy.txt': Deleting tst: task starting # task_id='467b16ba09db428aafd62bdb7f927161' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48 Failure: INTERNAL_ERROR task_id='467b16ba09db428aafd62bdb7f927161' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7446-4835-7510-80f9-a7e95339bc88'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T22:07:19+00:00.txt 2026-04-10 00:07:19.724405 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-10 00:07:19.724429 CEST - Wait 2s before retry 2026-04-10 00:07:23.034524 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3032a35a tst 2026-04-10 00:07:23.034602 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/destroy_retry1.txt': Deleting tst: task starting # task_id='5b1454050328409eb158f8fa338ec901' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48 Failure: INTERNAL_ERROR task_id='5b1454050328409eb158f8fa338ec901' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7446-4835-7510-80f9-a7e95339bc88'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T22:07:22+00:00.txt 2026-04-10 00:07:23.034622 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-10 00:07:23.034646 CEST - Wait 2s before retry 2026-04-10 00:07:26.935200 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3032a35a tst 2026-04-10 00:07:26.935276 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/destroy_retry2.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='1b905aea8579478f9f52ece7af613cd8' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48 Failure: INTERNAL_ERROR task_id='1b905aea8579478f9f52ece7af613cd8' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7446-4835-7510-80f9-a7e95339bc88'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T22:07:26+00:00.txt 2026-04-10 00:07:26.935296 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-10 00:07:26.935341 CEST - Wait 2s before retry 2026-04-10 00:07:30.260058 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-3032a35a tst 2026-04-10 00:07:30.260140 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/321b410b-fb9b-4ea2-9a58-0b8dbf3a3fb2/destroy_retry3.txt': Deleting tst: task starting # task_id='e40648e6c72443f99ad0d4120a2c36fb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knt4cj1nem881yd7x59kkf48 Failure: INTERNAL_ERROR task_id='e40648e6c72443f99ad0d4120a2c36fb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7446-4835-7510-80f9-a7e95339bc88'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-09T22:07:30+00:00.txt 2026-04-10 00:07:30.260161 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)