Run Test: slices-bi-singlenode-login 2026-04-12 17:03:17.001076 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-12 17:03:20.172003 CEST - Run: slices --version 2026-04-12 17:03:20.386451 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 17:03:20.386713 CEST - Run: slices pubkey list --format text 2026-04-12 17:03:20.951632 CEST - Pubkey already registered 2026-04-12 17:03:20.951871 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-12 17:03:21.463289 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-12 17:03:21.463429 CEST - Check List Flavors 2026-04-12 17:03:21.463597 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-12 17:03:22.479491 CEST - Check List DiskImages 2026-04-12 17:03:22.479667 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-12 17:03:23.245187 CEST - Requesting resources 2026-04-12 17:03:23.245619 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-12 17:03:23.245709 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-67aab8b0 --user-data /tmp/tmpjz4n91lu 2026-04-12 17:03:29.474206 CEST - Resource ID: r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq 2026-04-12 17:03:29.474316 CEST - Waiting until resource ready 2026-04-12 17:03:31.474722 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-67aab8b0 tst 2026-04-12 17:03:32.340532 CEST - Status: IMAGING 2026-04-12 17:03:35.207434 CEST - Status: IMAGING 2026-04-12 17:03:38.073492 CEST - Status: IMAGING 2026-04-12 17:03:40.889541 CEST - Status: IMAGING 2026-04-12 17:03:43.705878 CEST - Status: IMAGING 2026-04-12 17:03:46.522269 CEST - Status: IMAGING 2026-04-12 17:03:49.338161 CEST - Status: IMAGING 2026-04-12 17:03:52.204413 CEST - Status: IMAGING 2026-04-12 17:03:55.070700 CEST - Status: IMAGING 2026-04-12 17:03:57.886632 CEST - Status: IMAGING 2026-04-12 17:04:00.702497 CEST - Status: IMAGING 2026-04-12 17:04:03.518407 CEST - Status: IMAGING 2026-04-12 17:04:06.334401 CEST - Status: IMAGING 2026-04-12 17:04:09.150295 CEST - Status: IMAGING 2026-04-12 17:04:12.016165 CEST - Status: IMAGING 2026-04-12 17:04:14.882291 CEST - Status: IMAGING 2026-04-12 17:04:17.698182 CEST - Status: IMAGING 2026-04-12 17:04:20.514148 CEST - Status: IMAGING 2026-04-12 17:04:23.330118 CEST - Status: IMAGING 2026-04-12 17:04:26.196322 CEST - Status: IMAGING 2026-04-12 17:04:29.012556 CEST - Status: IMAGING 2026-04-12 17:04:31.828480 CEST - Status: IMAGING 2026-04-12 17:04:34.645033 CEST - Status: IMAGING 2026-04-12 17:04:37.461148 CEST - Status: IMAGING 2026-04-12 17:04:40.327329 CEST - Status: IMAGING 2026-04-12 17:04:43.143316 CEST - Status: IMAGING 2026-04-12 17:04:45.959323 CEST - Status: IMAGING 2026-04-12 17:04:48.775155 CEST - Status: IMAGING 2026-04-12 17:04:51.591501 CEST - Status: IMAGING 2026-04-12 17:04:54.457627 CEST - Status: IMAGING 2026-04-12 17:04:57.273600 CEST - Status: IMAGING 2026-04-12 17:05:00.089601 CEST - Status: IMAGING 2026-04-12 17:05:02.905803 CEST - Status: IMAGING 2026-04-12 17:05:05.721724 CEST - Status: IMAGING 2026-04-12 17:05:08.587877 CEST - Status: IMAGING 2026-04-12 17:05:11.504410 CEST - Status: IMAGING 2026-04-12 17:05:14.420865 CEST - Status: IMAGING 2026-04-12 17:05:17.237004 CEST - Status: IMAGING 2026-04-12 17:05:20.053347 CEST - Status: IMAGING 2026-04-12 17:05:22.869702 CEST - Status: IMAGING 2026-04-12 17:05:25.736483 CEST - Status: IMAGING 2026-04-12 17:05:28.552294 CEST - Status: IMAGING 2026-04-12 17:05:31.368496 CEST - Status: IMAGING 2026-04-12 17:05:34.184364 CEST - Status: IMAGING 2026-04-12 17:05:37.000394 CEST - Status: IMAGING 2026-04-12 17:05:39.816286 CEST - Status: IMAGING 2026-04-12 17:05:42.632267 CEST - Status: IMAGING 2026-04-12 17:05:45.448732 CEST - Status: IMAGING 2026-04-12 17:05:48.464981 CEST - Status: IMAGING 2026-04-12 17:05:51.280816 CEST - Status: IMAGING 2026-04-12 17:05:54.096947 CEST - Status: IMAGING 2026-04-12 17:05:56.963231 CEST - Status: BOOTING 2026-04-12 17:05:59.779152 CEST - Status: BOOTING 2026-04-12 17:06:02.645243 CEST - Status: BOOTING 2026-04-12 17:06:05.462374 CEST - Status: BOOTING 2026-04-12 17:06:08.479942 CEST - Status: BOOTING 2026-04-12 17:06:11.447110 CEST - Status: BOOTING 2026-04-12 17:06:14.363503 CEST - Status: BOOTING 2026-04-12 17:06:17.229705 CEST - Status: BOOTING 2026-04-12 17:06:20.095808 CEST - Status: BOOTING 2026-04-12 17:06:22.962048 CEST - Status: BOOTING 2026-04-12 17:06:25.828072 CEST - Status: BOOTING 2026-04-12 17:06:28.694405 CEST - Status: BOOTING 2026-04-12 17:06:31.560845 CEST - Status: BOOTING 2026-04-12 17:06:31.562483 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/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-67aab8b0 tst 2026-04-12 17:06:31.562568 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-12T15:03:29.223142Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01kp13g2qyf68sdb8cmdj5aght", "expires_at": "2026-04-12T17:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq", "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 17:06:31.562592 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-12 17:06:31.562626 CEST - Destroying tst-67aab8b0 tst 2026-04-12 17:06:31.562798 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-67aab8b0 tst 2026-04-12 17:06:33.732436 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-67aab8b0 tst 2026-04-12 17:06:33.732513 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/destroy.txt': 0.0% / waiting for task start Deleting tst: task starting # task_id='8e0b6756d2ea4030a756353713fccfce' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq Failure: INTERNAL_ERROR task_id='8e0b6756d2ea4030a756353713fccfce' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8238-1eca-76a2-a76f-e8a64c9a2d57'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T15:06:33+00:00.txt 2026-04-12 17:06:33.732553 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-12 17:06:33.732578 CEST - Wait 2s before retry 2026-04-12 17:06:38.202675 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-67aab8b0 tst 2026-04-12 17:06:38.202758 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/destroy_retry1.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='581ab12a872e45fca473557f255f0794' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq Failure: INTERNAL_ERROR task_id='581ab12a872e45fca473557f255f0794' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8238-1eca-76a2-a76f-e8a64c9a2d57'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T15:06:38+00:00.txt 2026-04-12 17:06:38.202778 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-12 17:06:38.202801 CEST - Wait 2s before retry 2026-04-12 17:06:41.570758 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-67aab8b0 tst 2026-04-12 17:06:41.570835 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/destroy_retry2.txt': Deleting tst: task starting # task_id='0721631ea9ec4fa3a13dfc089cb5bd82' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq Failure: INTERNAL_ERROR task_id='0721631ea9ec4fa3a13dfc089cb5bd82' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8238-1eca-76a2-a76f-e8a64c9a2d57'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T15:06:41+00:00.txt 2026-04-12 17:06:41.570854 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-12 17:06:41.570873 CEST - Wait 2s before retry 2026-04-12 17:06:46.091511 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-67aab8b0 tst 2026-04-12 17:06:46.091587 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0c46bdc2-9760-4799-b09b-47e454422424/destroy_retry3.txt': 0.0% / waiting for task start 0.0% - waiting for task start Deleting tst: task starting # task_id='56d5f0b11757428da06ac81cfb330bd7' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01kp13g7paethaevz8ms69mbaq Failure: INTERNAL_ERROR task_id='56d5f0b11757428da06ac81cfb330bd7' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d8238-1eca-76a2-a76f-e8a64c9a2d57'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-12T15:06:45+00:00.txt 2026-04-12 17:06:46.091605 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)