Run Test: slices-bi-singlenode-login 2026-04-12 00:03:21.755762 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-12 00:03:25.026488 CEST - Run: slices --version 2026-04-12 00:03:25.240793 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 00:03:25.241061 CEST - Run: slices pubkey list --format text 2026-04-12 00:03:25.805975 CEST - Pubkey already registered 2026-04-12 00:03:25.806199 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-12 00:03:26.310023 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-12 00:03:26.310156 CEST - Check List Flavors 2026-04-12 00:03:26.310327 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-12 00:03:27.376593 CEST - Check List DiskImages 2026-04-12 00:03:27.376794 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-12 00:03:28.142330 CEST - Requesting resources 2026-04-12 00:03:28.142768 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-12 00:03:28.142856 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-f51b301b --user-data /tmp/tmp13ow1eg5 2026-04-12 00:03:30.812099 CEST - Resource ID: r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na 2026-04-12 00:03:30.812207 CEST - Waiting until resource ready 2026-04-12 00:03:32.812619 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-f51b301b tst 2026-04-12 00:03:33.628292 CEST - Status: IMAGING 2026-04-12 00:03:36.444251 CEST - Status: IMAGING 2026-04-12 00:03:39.260141 CEST - Status: IMAGING 2026-04-12 00:03:42.126376 CEST - Status: IMAGING 2026-04-12 00:03:44.942702 CEST - Status: IMAGING 2026-04-12 00:03:47.758724 CEST - Status: IMAGING 2026-04-12 00:03:50.574589 CEST - Status: IMAGING 2026-04-12 00:03:53.390469 CEST - Status: IMAGING 2026-04-12 00:03:56.256501 CEST - Status: IMAGING 2026-04-12 00:03:59.072708 CEST - Status: IMAGING 2026-04-12 00:04:01.938686 CEST - Status: IMAGING 2026-04-12 00:04:04.804809 CEST - Status: IMAGING 2026-04-12 00:04:07.670780 CEST - Status: IMAGING 2026-04-12 00:04:10.536781 CEST - Status: IMAGING 2026-04-12 00:04:13.402677 CEST - Status: IMAGING 2026-04-12 00:04:16.218568 CEST - Status: IMAGING 2026-04-12 00:04:19.034662 CEST - Status: IMAGING 2026-04-12 00:04:21.850584 CEST - Status: IMAGING 2026-04-12 00:04:24.767007 CEST - Status: IMAGING 2026-04-12 00:04:27.633158 CEST - Status: IMAGING 2026-04-12 00:04:30.449010 CEST - Status: IMAGING 2026-04-12 00:04:33.265093 CEST - Status: IMAGING 2026-04-12 00:04:36.081056 CEST - Status: IMAGING 2026-04-12 00:04:38.897022 CEST - Status: IMAGING 2026-04-12 00:04:41.712768 CEST - Status: IMAGING 2026-04-12 00:04:44.528904 CEST - Status: IMAGING 2026-04-12 00:04:47.344793 CEST - Status: IMAGING 2026-04-12 00:04:50.210920 CEST - Status: IMAGING 2026-04-12 00:04:53.026925 CEST - Status: IMAGING 2026-04-12 00:04:55.842900 CEST - Status: IMAGING 2026-04-12 00:04:58.658902 CEST - Status: IMAGING 2026-04-12 00:05:01.475085 CEST - Status: IMAGING 2026-04-12 00:05:04.291023 CEST - Status: IMAGING 2026-04-12 00:05:07.106911 CEST - Status: IMAGING 2026-04-12 00:05:09.922906 CEST - Status: IMAGING 2026-04-12 00:05:12.738927 CEST - Status: IMAGING 2026-04-12 00:05:15.554890 CEST - Status: IMAGING 2026-04-12 00:05:18.370735 CEST - Status: IMAGING 2026-04-12 00:05:21.186754 CEST - Status: IMAGING 2026-04-12 00:05:24.002661 CEST - Status: IMAGING 2026-04-12 00:05:26.818725 CEST - Status: IMAGING 2026-04-12 00:05:29.634581 CEST - Status: IMAGING 2026-04-12 00:05:32.450482 CEST - Status: IMAGING 2026-04-12 00:05:35.266400 CEST - Status: IMAGING 2026-04-12 00:05:38.082414 CEST - Status: IMAGING 2026-04-12 00:05:40.898242 CEST - Status: IMAGING 2026-04-12 00:05:43.764405 CEST - Status: IMAGING 2026-04-12 00:05:46.580456 CEST - Status: IMAGING 2026-04-12 00:05:49.396515 CEST - Status: IMAGING 2026-04-12 00:05:52.262659 CEST - Status: IMAGING 2026-04-12 00:05:55.078531 CEST - Status: BOOTING 2026-04-12 00:05:57.944588 CEST - Status: BOOTING 2026-04-12 00:06:00.861271 CEST - Status: BOOTING 2026-04-12 00:06:03.677459 CEST - Status: BOOTING 2026-04-12 00:06:06.543437 CEST - Status: BOOTING 2026-04-12 00:06:09.409682 CEST - Status: BOOTING 2026-04-12 00:06:12.225565 CEST - Status: BOOTING 2026-04-12 00:06:15.041409 CEST - Status: BOOTING 2026-04-12 00:06:17.857460 CEST - Status: BOOTING 2026-04-12 00:06:20.723744 CEST - Status: BOOTING 2026-04-12 00:06:23.539592 CEST - Status: BOOTING 2026-04-12 00:06:26.355418 CEST - Status: BOOTING 2026-04-12 00:06:29.171403 CEST - Status: BOOTING 2026-04-12 00:06:31.987241 CEST - Status: BOOTING 2026-04-12 00:06:31.988442 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/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-f51b301b tst 2026-04-12 00:06:31.988504 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-11T22:03:30.577767Z", "disk_image_id": "image_gr-volos-bi-vm1_01khr9qbapec8v4evhvyyzr9ax", "experiment_id": "exp_expauth.ilabt.imec.be_01knz94hxjegdanada2jytgjjs", "expires_at": "2026-04-12T00:03:00Z", "flavor_id": "flavor_gr-volos-bi-vm1_01khr9qbayfkgvs2thxhage6fv", "friendly_name": "tst", "id": "r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na", "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 00:06:31.988521 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-12 00:06:31.988546 CEST - Destroying tst-f51b301b tst 2026-04-12 00:06:31.988678 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f51b301b tst 2026-04-12 00:06:35.410608 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f51b301b tst 2026-04-12 00:06:35.410687 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/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='97281d7152c94019ac4832317b836ebb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na Failure: INTERNAL_ERROR task_id='97281d7152c94019ac4832317b836ebb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7e92-4d95-7752-b732-b128103702aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T22:06:35+00:00.txt 2026-04-12 00:06:35.410723 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-12 00:06:35.410745 CEST - Wait 2s before retry 2026-04-12 00:06:38.778400 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f51b301b tst 2026-04-12 00:06:38.778476 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/destroy_retry1.txt': Deleting tst: task starting # task_id='29210fde695d49bdb47477f2d6460a7b' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na Failure: INTERNAL_ERROR task_id='29210fde695d49bdb47477f2d6460a7b' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7e92-4d95-7752-b732-b128103702aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T22:06:38+00:00.txt 2026-04-12 00:06:38.778494 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-12 00:06:38.778514 CEST - Wait 2s before retry 2026-04-12 00:06:43.849844 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f51b301b tst 2026-04-12 00:06:43.849942 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/destroy_retry2.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='d327ba3a7d9549d482a85de840185eef' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na Failure: INTERNAL_ERROR task_id='d327ba3a7d9549d482a85de840185eef' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7e92-4d95-7752-b732-b128103702aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T22:06:43+00:00.txt 2026-04-12 00:06:43.849961 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-12 00:06:43.849980 CEST - Wait 2s before retry 2026-04-12 00:06:47.267995 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-f51b301b tst 2026-04-12 00:06:47.268085 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/48b7c079-d077-45e5-9939-712a4e34286b/destroy_retry3.txt': Deleting tst: task starting # task_id='2c8ca985787446a68f9ff5a7c887c596' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_gr-volos-bi-vm1_01knz94kcnex9becnh5083e0na Failure: INTERNAL_ERROR task_id='2c8ca985787446a68f9ff5a7c887c596' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d7e92-4d95-7752-b732-b128103702aa'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-11T22:06:47+00:00.txt 2026-04-12 00:06:47.268105 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1)