Run Test: slices-bi-singlenode-login 2026-06-09 00:43:34.636995 CEST - Run: /usr/local/bin/uv pip compile --pre --upgrade /tmp/tmp2oclrchy.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-09 00:43:39.462431 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/4918075b-359f-4ff3-ad55-72f1d57cc928/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-09 00:43:43.486733 CEST - Run: slices --version 2026-06-09 00:43:45.454879 CEST - version: Slices CLI v2026.2.0b3 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-06-09 00:43:45.455138 CEST - Run: slices pubkey list --format text 2026-06-09 00:43:46.020373 CEST - Pubkey already registered 2026-06-09 00:43:46.020671 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-09 00:43:46.586056 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-09 00:43:46.586818 CEST - Check List Flavors 2026-06-09 00:43:46.587023 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-06-09 00:43:47.252427 CEST - Check List DiskImages 2026-06-09 00:43:47.252617 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-06-09 00:43:47.868032 CEST - Requesting resources 2026-06-09 00:43:47.868365 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 24.04.3' --flavor pc --duration 2h --experiment tst-8147a182 2026-06-09 00:43:49.837219 CEST - Resource ID: r_be-gent1-bi-baremetal1_01ktmpg3rpe7ysa5t6agzdttqm 2026-06-09 00:43:49.837358 CEST - Waiting until resource ready 2026-06-09 00:43:51.837795 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8147a182 tst 2026-06-09 00:43:52.553710 CEST - Status: IMAGING 2026-06-09 00:43:55.320204 CEST - Status: IMAGING 2026-06-09 00:43:57.986226 CEST - Status: IMAGING 2026-06-09 00:44:00.752449 CEST - Status: IMAGING 2026-06-09 00:44:03.518500 CEST - Status: IMAGING 2026-06-09 00:44:08.841025 CEST - Status: IMAGING 2026-06-09 00:44:11.607101 CEST - Status: IMAGING 2026-06-09 00:44:14.373400 CEST - Status: IMAGING 2026-06-09 00:44:17.139748 CEST - Status: IMAGING 2026-06-09 00:44:19.905955 CEST - Status: IMAGING 2026-06-09 00:44:22.672095 CEST - Status: IMAGING 2026-06-09 00:44:25.388288 CEST - Status: IMAGING 2026-06-09 00:44:28.154746 CEST - Status: IMAGING 2026-06-09 00:44:30.922012 CEST - Status: IMAGING 2026-06-09 00:44:33.688708 CEST - Status: IMAGING 2026-06-09 00:44:36.505283 CEST - Status: IMAGING 2026-06-09 00:44:39.372105 CEST - Status: IMAGING 2026-06-09 00:44:42.139345 CEST - Status: UP 2026-06-09 00:44:42.139440 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ktmpg34de8yvx906j24txy7g 2026-06-09 00:44:42.139490 CEST - Validate resources 2026-06-09 00:44:42.855641 CEST - The fields of the created resource were validated. 2026-06-09 00:44:42.855716 CEST - Check if resources are registered in experiment 2026-06-09 00:44:42.855876 CEST - Run: slices experiment list-resources --format json tst-8147a182 2026-06-09 00:44:43.471275 CEST - Status (on expauth): UP 2026-06-09 00:44:43.471428 CEST - Testing extend expires_at (all resources in experiment) 2026-06-09 00:44:43.471556 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-8147a182 2026-06-09 00:44:47.445289 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8147a182 tst 2026-06-09 00:44:48.211052 CEST - Run: slices experiment list-resources --format json tst-8147a182 2026-06-09 00:44:48.826614 CEST - expires_at (on expauth): 2026-06-09T01:44:00Z (correctly extended) 2026-06-09 00:44:48.826684 CEST - Testing extend expires_at (single resource in experiment) 2026-06-09 00:44:48.826823 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-8147a182 2026-06-09 00:44:53.804197 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8147a182 tst 2026-06-09 00:44:54.620469 CEST - Run: slices experiment list-resources --format json tst-8147a182 2026-06-09 00:44:55.235811 CEST - expires_at (on expauth): 2026-06-09T02:44:00Z (correctly extended) 2026-06-09 00:44:55.235907 CEST - Testing ssh login 2026-06-09 00:44:55.246013 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8147a182 tst 2026-06-09 00:44:55.961625 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8147a182 tst 2026-06-09 00:44:56.627252 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8147a182 tst 2026-06-09 00:44:57.343090 CEST - Logging in using 'slices bi ssh' 2026-06-09 00:44:57.343175 CEST - Forcing IPv4 only. 2026-06-09 00:44:57.343346 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8147a182 tst -- -4 uname -a 2026-06-09 00:44:58.309463 CEST - Forcing IPv4 only. 2026-06-09 00:44:58.309631 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8147a182 tst -- -4 uptime 2026-06-09 00:44:59.175560 CEST - CLI SSH Test passed. 2026-06-09 00:44:59.175624 CEST - Uname: Linux tst.ba4ktj.fed4fire-eu.wall1.ilabt.imec.be 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-06-09 00:44:59.175639 CEST - Uptime: 00:44:59 up 0 min, 1 user, load average: 0.23, 0.06, 0.02 2026-06-09 00:44:59.175667 CEST - Logging in using 'slices bi ssh' 2026-06-09 00:44:59.175719 CEST - Forcing IPv6 only. 2026-06-09 00:44:59.175843 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8147a182 tst -- -6 uname -a 2026-06-09 00:45:00.092487 CEST - Forcing IPv6 only. 2026-06-09 00:45:00.092852 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8147a182 tst -- -6 uptime 2026-06-09 00:45:01.009636 CEST - CLI SSH Test passed. 2026-06-09 00:45:01.009693 CEST - Uname: Linux tst.ba4ktj.fed4fire-eu.wall1.ilabt.imec.be 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-06-09 00:45:01.009707 CEST - Uptime: 00:45:00 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-06-09 00:45:01.009748 CEST - Logging in using SSH (extra test: ignore SSH proxy) 2026-06-09 00:45:01.010198 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:01.010380 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:01.010440 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:01.010597 CEST - Connecting to n078-02.wall1.ilabt.imec.be:22 2026-06-09 00:45:01.369572 CEST - SSH Test output: 2026-06-09 00:45:01.369625 CEST - Uname: Linux tst.ba4ktj.fed4fire-eu.wall1.ilabt.imec.be 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-06-09 00:45:01.369641 CEST - Uptime: 00:45:01 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-06-09 00:45:01.424190 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-09 00:45:01.424247 CEST - lsb_release matches expected value 2026-06-09 00:45:01.424262 CEST - SSH Test passed. 2026-06-09 00:45:01.424334 CEST - Logging in using SSH over SSH proxy 2026-06-09 00:45:01.424494 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8147a182 tst 2026-06-09 00:45:02.140398 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:02.140567 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:02.140614 CEST - Added paramiko HostKeyEntry for n078-02.wall1.ilabt.imec.be 2026-06-09 00:45:02.140833 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-09 00:45:02.140874 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-09 00:45:02.140968 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-06-09 00:45:02.140982 CEST - Connecting to proxy bastion2.slices-be.eu:22 2026-06-09 00:45:02.325384 CEST - Connecting to n078-02.wall1.ilabt.imec.be:22 over proxy 2026-06-09 00:45:02.625886 CEST - SSH Test output: 2026-06-09 00:45:02.625951 CEST - Uname: Linux tst.ba4ktj.fed4fire-eu.wall1.ilabt.imec.be 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-06-09 00:45:02.625966 CEST - Uptime: 00:45:02 up 0 min, 2 users, load average: 0.21, 0.06, 0.02 2026-06-09 00:45:02.684024 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-09 00:45:02.684080 CEST - lsb_release matches expected value 2026-06-09 00:45:02.684094 CEST - SSH Test passed. 2026-06-09 00:45:02.690596 CEST - Destroying tst-8147a182 tst 2026-06-09 00:45:02.690755 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-8147a182 tst 2026-06-09 00:46:02.700058 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/4918075b-359f-4ff3-ad55-72f1d57cc928/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-pre-venv/bin/slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-8147a182 tst 2026-06-09 00:46:02.700240 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/4918075b-359f-4ff3-ad55-72f1d57cc928/destroy.txt': 10.0% / task starting 10.0% - task starting 30.0% \ task starting 30.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting ***Terminating process due to timeout*** ***Process terminated*** 2026-06-09 00:46:02.700306 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is -15) 2026-06-09 00:46:02.700329 CEST - Step 'Destroy' took 60.01 seconds, which is longer than the warning threshold of 15 seconds 2026-06-09 00:46:02.700353 CEST - Wait 2s before retry 2026-06-09 00:47:04.709450 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/4918075b-359f-4ff3-ad55-72f1d57cc928/step_DestroyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-pre-venv/bin/slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-8147a182 tst 2026-06-09 00:47:04.709647 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/4918075b-359f-4ff3-ad55-72f1d57cc928/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% \ 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% - 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% / 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% | 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% \ 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% - 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% / 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% | 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% \ 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% - 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 ***Terminating process due to timeout*** ***Process terminated*** 2026-06-09 00:47:04.709702 CEST - Error in test step 'Destroy (Retry 1)': "slices bi destroy" failed (return value is -15) 2026-06-09 00:47:04.709725 CEST - Step 'Destroy' took 60.01 seconds, which is longer than the warning threshold of 15 seconds 2026-06-09 00:47:04.709745 CEST - Wait 2s before retry