Run Test: slices-bi-singlenode-login 2026-04-16 08:44:36.888029 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-16 08:44:40.110488 CEST - Run: slices --version 2026-04-16 08:44:40.375644 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-16 08:44:40.375999 CEST - Run: slices pubkey list --format text 2026-04-16 08:44:41.091914 CEST - Pubkey already registered 2026-04-16 08:44:41.092151 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-16 08:44:41.707509 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-16 08:44:41.707623 CEST - Check List Flavors 2026-04-16 08:44:41.707803 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-04-16 08:44:42.473992 CEST - Check List DiskImages 2026-04-16 08:44:42.474267 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-04-16 08:44:43.240830 CEST - Requesting resources 2026-04-16 08:44:43.241432 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-16 08:44:43.241574 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e9bf7b0c --user-data /tmp/tmpzil_lmsf 2026-04-16 08:44:45.362205 CEST - Resource ID: r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6 2026-04-16 08:44:45.362381 CEST - Waiting until resource ready 2026-04-16 08:44:47.362972 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-e9bf7b0c tst 2026-04-16 08:44:48.179254 CEST - Status: IMAGING 2026-04-16 08:44:50.995895 CEST - Status: IMAGING 2026-04-16 08:44:53.812248 CEST - Status: IMAGING 2026-04-16 08:44:56.628494 CEST - Status: BOOTING 2026-04-16 08:44:59.444920 CEST - Status: BOOTING 2026-04-16 08:45:02.311477 CEST - Status: BOOTING 2026-04-16 08:45:05.128264 CEST - Status: BOOTING 2026-04-16 08:45:07.944825 CEST - Status: BOOTING 2026-04-16 08:45:10.811678 CEST - Status: BOOTING 2026-04-16 08:45:13.628653 CEST - Status: BOOTING 2026-04-16 08:45:16.445093 CEST - Status: BOOTING 2026-04-16 08:45:19.261849 CEST - Status: BOOTING 2026-04-16 08:45:22.128264 CEST - Status: BOOTING 2026-04-16 08:45:24.944802 CEST - Status: BOOTING 2026-04-16 08:45:27.811272 CEST - Status: BOOTING 2026-04-16 08:45:30.627609 CEST - Status: BOOTING 2026-04-16 08:45:33.393529 CEST - Status: BOOTING 2026-04-16 08:45:36.159323 CEST - Status: BOOTING 2026-04-16 08:45:38.925939 CEST - Status: BOOTING 2026-04-16 08:45:41.692685 CEST - Status: BOOTING 2026-04-16 08:45:44.459141 CEST - Status: BOOTING 2026-04-16 08:45:47.225386 CEST - Status: BOOTING 2026-04-16 08:45:49.991715 CEST - Status: BOOTING 2026-04-16 08:45:52.757685 CEST - Status: BOOTING 2026-04-16 08:45:55.523436 CEST - Status: BOOTING 2026-04-16 08:45:58.289267 CEST - Status: BOOTING 2026-04-16 08:46:01.005136 CEST - Status: BOOTING 2026-04-16 08:46:03.771595 CEST - Status: BOOTING 2026-04-16 08:46:06.537617 CEST - Status: BOOTING 2026-04-16 08:46:09.303868 CEST - Status: BOOTING 2026-04-16 08:46:12.069985 CEST - Status: BOOTING 2026-04-16 08:46:14.835777 CEST - Status: BOOTING 2026-04-16 08:46:17.601514 CEST - Status: BOOTING 2026-04-16 08:46:20.367378 CEST - Status: BOOTING 2026-04-16 08:46:23.133408 CEST - Status: BOOTING 2026-04-16 08:46:25.898987 CEST - Status: BOOTING 2026-04-16 08:46:28.614704 CEST - Status: BOOTING 2026-04-16 08:46:31.330362 CEST - Status: BOOTING 2026-04-16 08:46:34.096390 CEST - Status: BOOTING 2026-04-16 08:46:36.912978 CEST - Status: BOOTING 2026-04-16 08:46:39.729427 CEST - Status: BOOTING 2026-04-16 08:46:42.545766 CEST - Status: BOOTING 2026-04-16 08:46:45.362104 CEST - Status: BOOTING 2026-04-16 08:46:48.128046 CEST - Status: BOOTING 2026-04-16 08:46:50.843806 CEST - Status: BOOTING 2026-04-16 08:46:53.609572 CEST - Status: BOOTING 2026-04-16 08:46:56.375636 CEST - Status: BOOTING 2026-04-16 08:46:59.141615 CEST - Status: BOOTING 2026-04-16 08:46:59.142828 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/step_WaitforUP_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-e9bf7b0c tst 2026-04-16 08:46:59.142898 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/list-resources_json.txt': [ { "cluster_id": "default", "created_at": "2026-04-16T06:44:45.047755Z", "disk_image_id": "image_fr-sophia2-bi-vm1_01khk1n25kesh84c19p5sj6ps1", "experiment_id": "exp_expauth.ilabt.imec.be_01kpaghw2tfn0syvt3d88hmgwy", "expires_at": "2026-04-16T08:44:00Z", "flavor_id": "flavor_fr-sophia2-bi-vm1_01khk1n260ef1sc5vm89c7wjeh", "friendly_name": "tst", "id": "r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6", "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": "fr-sophia2-bi-vm1", "machine_name": null } ] 2026-04-16 08:46:59.142921 CEST - Error in test step 'Wait for UP': experiment did not become ready before timeout 2026-04-16 08:46:59.142950 CEST - Destroying tst-e9bf7b0c tst 2026-04-16 08:46:59.143078 CEST - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-e9bf7b0c tst 2026-04-16 08:47:00.259667 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-e9bf7b0c tst 2026-04-16 08:47:00.259793 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/destroy.txt': Deleting tst: task starting # task_id='2509b9180478453ba49b91b404dbaf2a' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6 Failure: INTERNAL_ERROR task_id='2509b9180478453ba49b91b404dbaf2a' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d9508-f37f-7320-b150-f61564fd39e6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-16T06:47:00+00:00.txt 2026-04-16 08:47:00.259822 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is 1) 2026-04-16 08:47:00.259848 CEST - Wait 2s before retry 2026-04-16 08:47:43.117045 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-e9bf7b0c tst 2026-04-16 08:47:43.117153 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/destroy_retry1.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 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ task starting 0.0% | task starting 0.0% / task starting 0.0% - task starting 0.0% \ task starting 0.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting Deleting tst: task starting # task_id='6269c6c7379642cca42f6cd5fc4f2294' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6 Failure: INTERNAL_ERROR task_id='6269c6c7379642cca42f6cd5fc4f2294' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d9508-f37f-7320-b150-f61564fd39e6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-16T06:47:43+00:00.txt 2026-04-16 08:47:43.117187 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is 1) 2026-04-16 08:47:43.117207 CEST - Step 'Destroy' took 40.86 seconds, which is longer than the warning threshold of 15 seconds 2026-04-16 08:47:43.117226 CEST - Wait 2s before retry 2026-04-16 08:47:46.234373 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/step_DestroyRetry2_retry2_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-e9bf7b0c tst 2026-04-16 08:47:46.234460 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/destroy_retry2.txt': Deleting tst: task starting # task_id='6c1f2e84bfbf4d2aaf3cc96b50028cfb' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6 Failure: INTERNAL_ERROR task_id='6c1f2e84bfbf4d2aaf3cc96b50028cfb' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d9508-f37f-7320-b150-f61564fd39e6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-16T06:47:46+00:00.txt 2026-04-16 08:47:46.234488 CEST - Error in test step 'Destroy (Retry 2)': "slices bi destroy" failed (return value is 1) 2026-04-16 08:47:46.234512 CEST - Wait 2s before retry 2026-04-16 08:48:31.244483 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/step_DestroyRetry3_retry3_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-e9bf7b0c tst 2026-04-16 08:48:31.244587 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2c944760-725f-434a-898e-df200a9f45bc/destroy_retry3.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 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | task starting 0.0% / task starting 0.0% - task starting 0.0% \ task starting 0.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting 10.0% \ task starting 10.0% | task starting 10.0% / task starting 10.0% - task starting Deleting tst: task starting # task_id='95215e7eabed4160b5dc58275b6d5a68' tas… 1… Destroy failure ❌ ❌ Failed to destroy tst with ID r_fr-sophia2-bi-vm1_01kpaghwvzecgb2m7p2njftef6 Failure: INTERNAL_ERROR task_id='95215e7eabed4160b5dc58275b6d5a68' task_name='slices_bi_refimpl_backend.tasks.compute_resource:delete_compute_resou rce' labels={} labels_types={} args=[UUID('019d9508-f37f-7320-b150-f61564fd39e6'), 'status_change:api_initiated:delete'] kwargs={'initiator': 'user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g'} Details stored at: /root/.slices/cli_error_details/err_2026-04-16T06:48:31+00:00.txt 2026-04-16 08:48:31.244619 CEST - Error in test step 'Destroy (Retry 3)': "slices bi destroy" failed (return value is 1) 2026-04-16 08:48:31.244639 CEST - Step 'Destroy' took 43.01 seconds, which is longer than the warning threshold of 15 seconds