Run Test: slices-bi-singlenode-login 2026-04-11 17:03:14.795102 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-11 17:03:18.166736 CEST - Run: slices --version 2026-04-11 17:03:18.381139 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 17:03:18.381368 CEST - Run: slices pubkey list --format text 2026-04-11 17:03:18.946207 CEST - Pubkey already registered 2026-04-11 17:03:18.946424 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-11 17:03:19.457454 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-11 17:03:19.457547 CEST - Check List Flavors 2026-04-11 17:03:19.457723 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-11 17:03:20.473636 CEST - Check List DiskImages 2026-04-11 17:03:20.473834 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-11 17:03:21.189116 CEST - Requesting resources 2026-04-11 17:03:21.189555 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-11 17:03:21.189654 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-61f0babf --user-data /tmp/tmp4kblqane 2026-04-11 17:03:23.458027 CEST - Resource ID: r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp 2026-04-11 17:03:23.458133 CEST - Waiting until resource ready 2026-04-11 17:03:25.458615 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-61f0babf tst 2026-04-11 17:03:26.274548 CEST - Status: IMAGING 2026-04-11 17:03:29.090481 CEST - Status: IMAGING 2026-04-11 17:03:31.906158 CEST - Status: IMAGING 2026-04-11 17:03:34.722074 CEST - Status: IMAGING 2026-04-11 17:03:37.538002 CEST - Status: IMAGING 2026-04-11 17:03:40.354035 CEST - Status: IMAGING 2026-04-11 17:03:43.169993 CEST - Status: IMAGING 2026-04-11 17:03:46.086640 CEST - Status: IMAGING 2026-04-11 17:03:48.902781 CEST - Status: IMAGING 2026-04-11 17:03:51.718790 CEST - Status: IMAGING 2026-04-11 17:03:54.534909 CEST - Status: IMAGING 2026-04-11 17:03:57.350847 CEST - Status: IMAGING 2026-04-11 17:04:00.166600 CEST - Status: IMAGING 2026-04-11 17:04:02.982465 CEST - Status: IMAGING 2026-04-11 17:04:05.798372 CEST - Status: IMAGING 2026-04-11 17:04:08.614187 CEST - Status: IMAGING 2026-04-11 17:04:11.430032 CEST - Status: IMAGING 2026-04-11 17:04:14.245847 CEST - Status: IMAGING 2026-04-11 17:04:17.061752 CEST - Status: IMAGING 2026-04-11 17:04:19.877740 CEST - Status: IMAGING 2026-04-11 17:04:22.693694 CEST - Status: IMAGING 2026-04-11 17:04:25.509579 CEST - Status: IMAGING 2026-04-11 17:04:28.375699 CEST - Status: IMAGING 2026-04-11 17:04:31.191474 CEST - Status: IMAGING 2026-04-11 17:04:34.007220 CEST - Status: IMAGING 2026-04-11 17:04:36.823151 CEST - Status: IMAGING 2026-04-11 17:04:39.638949 CEST - Status: IMAGING 2026-04-11 17:04:42.454735 CEST - Status: IMAGING 2026-04-11 17:04:45.320807 CEST - Status: IMAGING 2026-04-11 17:04:48.136569 CEST - Status: IMAGING 2026-04-11 17:04:50.952318 CEST - Status: IMAGING 2026-04-11 17:04:53.768090 CEST - Status: IMAGING 2026-04-11 17:04:56.584198 CEST - Status: IMAGING 2026-04-11 17:04:59.399987 CEST - Status: IMAGING 2026-04-11 17:05:02.216110 CEST - Status: IMAGING 2026-04-11 17:05:05.032013 CEST - Status: IMAGING 2026-04-11 17:05:07.847787 CEST - Status: IMAGING 2026-04-11 17:05:10.713854 CEST - Status: IMAGING 2026-04-11 17:05:14.632365 CEST - Status: IMAGING 2026-04-11 17:05:17.448234 CEST - Status: IMAGING 2026-04-11 17:05:20.264210 CEST - Status: IMAGING 2026-04-11 17:05:23.080435 CEST - Status: IMAGING 2026-04-11 17:05:25.897670 CEST - Status: IMAGING 2026-04-11 17:05:28.763718 CEST - Status: IMAGING 2026-04-11 17:05:31.579962 CEST - Status: IMAGING 2026-04-11 17:05:34.395928 CEST - Status: IMAGING 2026-04-11 17:05:37.211746 CEST - Status: IMAGING 2026-04-11 17:05:40.027778 CEST - Status: IMAGING 2026-04-11 17:05:42.843484 CEST - Status: IMAGING 2026-04-11 17:05:45.759787 CEST - Status: IMAGING 2026-04-11 17:05:48.625995 CEST - Status: IMAGING 2026-04-11 17:05:51.492434 CEST - Status: BOOTING 2026-04-11 17:05:54.358987 CEST - Status: BOOTING 2026-04-11 17:05:57.225355 CEST - Status: BOOTING 2026-04-11 17:06:00.141718 CEST - Status: BOOTING 2026-04-11 17:06:03.008038 CEST - Status: BOOTING 2026-04-11 17:06:05.824710 CEST - Status: BOOTING 2026-04-11 17:06:08.691006 CEST - Status: BOOTING 2026-04-11 17:06:11.506869 CEST - Status: BOOTING 2026-04-11 17:06:14.322819 CEST - Status: BOOTING 2026-04-11 17:06:17.138592 CEST - Status: BOOTING 2026-04-11 17:06:19.954505 CEST - Status: BOOTING 2026-04-11 17:06:22.770958 CEST - Status: BOOTING 2026-04-11 17:06:25.587034 CEST - Status: BOOTING 2026-04-11 17:06:25.589083 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/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-61f0babf tst 2026-04-11 17:06:25.589161 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-11T15:03:23.255607Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knyh39sxe6esnfz1myxdnmtx", "expires_at": "2026-04-11T17:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp", "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 17:06:25.589179 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-11 17:06:25.589206 CEST - Destroying tst-61f0babf tst 2026-04-11 17:06:25.589374 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-61f0babf tst 2026-04-11 17:06:29.562318 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-61f0babf tst 2026-04-11 17:06:29.562415 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/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='c089ab9a99494754bca9c2d6b3611686' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp Failure: INTERNAL_ERROR task_id='c089ab9a99494754bca9c2d6b3611686' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7d11-ab7a-7620-b8f1-5606a8550e36'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T15:06:29+00:00.txt 2026-04-11 17:06:29.562462 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-11 17:06:29.562490 CEST - Wait 2s before retry 2026-04-11 17:06:33.531618 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-61f0babf tst 2026-04-11 17:06:33.531718 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/destroy_retry1.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='cdb57f7d901c4215ac722ba7f7d95b41' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp Failure: INTERNAL_ERROR task_id='cdb57f7d901c4215ac722ba7f7d95b41' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7d11-ab7a-7620-b8f1-5606a8550e36'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T15:06:33+00:00.txt 2026-04-11 17:06:33.531743 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-11 17:06:33.531772 CEST - Wait 2s before retry 2026-04-11 17:06:37.450255 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-61f0babf tst 2026-04-11 17:06:37.450331 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/destroy_retry2.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='99f5a497d6874ec28a129c9f837ccca9' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp Failure: INTERNAL_ERROR task_id='99f5a497d6874ec28a129c9f837ccca9' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7d11-ab7a-7620-b8f1-5606a8550e36'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T15:06:37+00:00.txt 2026-04-11 17:06:37.450351 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-11 17:06:37.450371 CEST - Wait 2s before retry 2026-04-11 17:06:41.920165 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-61f0babf tst 2026-04-11 17:06:41.920248 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/857d2a99-ef9e-45f9-a058-9d4918abfe49/destroy_retry3.txt': 0.0% / waiting for task start 10.0% - task starting Deleting tst: task starting # task_id='a2952335f32c433d976c84555c0c60b2' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knyh3avtergbhwap0tm5a3hp Failure: INTERNAL_ERROR task_id='a2952335f32c433d976c84555c0c60b2' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7d11-ab7a-7620-b8f1-5606a8550e36'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T15:06:41+00:00.txt 2026-04-11 17:06:41.920269 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)