Run Test: slices-bi-singlenode-login 2026-06-06 08:27:56.561341 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpij6kfm_z.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-06 08:28:00.735607 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/60d1875d-8893-4100-8097-492af323af11/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-06 08:28:08.819946 CEST - Run: slices --version 2026-06-06 08:28:09.034486 CEST - version: Slices CLI v2026.1.2 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-06 08:28:09.034772 CEST - Run: slices pubkey list --format text 2026-06-06 08:28:09.650162 CEST - Pubkey already registered 2026-06-06 08:28:09.650396 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-06 08:28:10.265819 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-06 08:28:10.265952 CEST - Check List Flavors 2026-06-06 08:28:10.266159 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-06-06 08:28:11.282996 CEST - Check List DiskImages 2026-06-06 08:28:11.283237 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-06-06 08:28:12.099249 CEST - Requesting resources 2026-06-06 08:28:12.099577 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-06-06 08:28:12.099675 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b66264eb --user-data /tmp/tmpjz5uhr16 2026-06-06 08:28:17.227578 CEST - Resource ID: r_gr-volos-bi-vm1_01ktdswcyyezr8kyzsgzrf48ft 2026-06-06 08:28:17.227707 CEST - Waiting until resource ready 2026-06-06 08:28:19.228137 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b66264eb tst 2026-06-06 08:28:20.094359 CEST - Status: IMAGING 2026-06-06 08:28:23.011360 CEST - Status: IMAGING 2026-06-06 08:28:25.877823 CEST - Status: IMAGING 2026-06-06 08:28:28.795378 CEST - Status: BOOTING 2026-06-06 08:28:31.661886 CEST - Status: BOOTING 2026-06-06 08:28:34.629080 CEST - Status: BOOTING 2026-06-06 08:28:37.495461 CEST - Status: BOOTING 2026-06-06 08:28:40.361961 CEST - Status: BOOTING 2026-06-06 08:28:43.228460 CEST - Status: BOOTING 2026-06-06 08:28:46.094927 CEST - Status: BOOTING 2026-06-06 08:28:49.061953 CEST - Status: BOOTING 2026-06-06 08:28:52.028921 CEST - Status: BOOTING 2026-06-06 08:28:54.945928 CEST - Status: BOOTING 2026-06-06 08:28:57.812285 CEST - Status: BOOTING 2026-06-06 08:29:00.679308 CEST - Status: BOOTING 2026-06-06 08:29:03.646234 CEST - Status: BOOTING 2026-06-06 08:29:06.512635 CEST - Status: BOOTING 2026-06-06 08:29:09.379159 CEST - Status: BOOTING 2026-06-06 08:29:12.245617 CEST - Status: BOOTING 2026-06-06 08:29:15.112057 CEST - Status: BOOTING 2026-06-06 08:29:18.028692 CEST - Status: BOOTING 2026-06-06 08:29:20.945323 CEST - Status: BOOTING 2026-06-06 08:29:23.812092 CEST - Status: BOOTING 2026-06-06 08:29:26.728985 CEST - Status: BOOTING 2026-06-06 08:29:29.646060 CEST - Status: BOOTING 2026-06-06 08:29:32.462283 CEST - Status: BOOTING 2026-06-06 08:29:35.328794 CEST - Status: BOOTING 2026-06-06 08:29:38.245645 CEST - Status: BOOTING 2026-06-06 08:29:41.162714 CEST - Status: BOOTING 2026-06-06 08:29:44.130227 CEST - Status: BOOTING 2026-06-06 08:29:46.996925 CEST - Status: BOOTING 2026-06-06 08:29:49.863360 CEST - Status: BOOTING 2026-06-06 08:29:52.730179 CEST - Status: BOOTING 2026-06-06 08:29:55.697619 CEST - Status: BOOTING 2026-06-06 08:29:58.715379 CEST - Status: BOOTING 2026-06-06 08:30:01.632402 CEST - Status: BOOTING 2026-06-06 08:30:04.649734 CEST - Status: BOOTING 2026-06-06 08:30:07.667907 CEST - Status: BOOTING 2026-06-06 08:30:10.786024 CEST - Status: UP 2026-06-06 08:30:10.786127 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ktdsw954ep2a10rhy7aw3fkf 2026-06-06 08:30:10.786171 CEST - Validate resources 2026-06-06 08:30:11.704533 CEST - The fields of the created resource were validated. 2026-06-06 08:30:11.704646 CEST - Check if resources are registered in experiment 2026-06-06 08:30:11.704893 CEST - Run: slices experiment list-resources --format json tst-b66264eb 2026-06-06 08:30:12.370552 CEST - Status (on expauth): BOOTING 2026-06-06 08:30:15.036757 CEST - Status (on expauth): BOOTING 2026-06-06 08:30:17.702852 CEST - Status (on expauth): BOOTING 2026-06-06 08:30:20.419530 CEST - Status (on expauth): BOOTING 2026-06-06 08:30:23.086270 CEST - Status (on expauth): UP 2026-06-06 08:30:23.086367 CEST - "slices experiment list-resources" was not ready immediately. 2026-06-06 08:30:23.086398 CEST - It was ready after: 11.381661 seconds. (Note: used wait steps of 2s) 2026-06-06 08:30:23.086525 CEST - Testing extend expires_at (all resources in experiment) 2026-06-06 08:30:23.086702 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-b66264eb 2026-06-06 08:30:25.456356 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b66264eb tst 2026-06-06 08:30:26.423168 CEST - Run: slices experiment list-resources --format json tst-b66264eb 2026-06-06 08:30:27.089111 CEST - expires_at (on expauth): 2026-06-06T09:30:00Z (correctly extended) 2026-06-06 08:30:27.089185 CEST - Testing extend expires_at (single resource in experiment) 2026-06-06 08:30:27.089336 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-b66264eb 2026-06-06 08:30:29.509398 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b66264eb tst 2026-06-06 08:30:30.426066 CEST - Run: slices experiment list-resources --format json tst-b66264eb 2026-06-06 08:30:31.091992 CEST - expires_at (on expauth): 2026-06-06T10:30:00Z (correctly extended) 2026-06-06 08:30:31.092104 CEST - Testing ssh login 2026-06-06 08:30:31.104425 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b66264eb tst 2026-06-06 08:30:32.121000 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b66264eb tst 2026-06-06 08:30:33.137784 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b66264eb tst 2026-06-06 08:30:34.103907 CEST - Logging in using 'slices bi ssh' 2026-06-06 08:30:34.103972 CEST - Forcing IPv4 only. 2026-06-06 08:30:34.104104 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b66264eb tst -- -4 uname -a 2026-06-06 08:30:37.174978 CEST - Forcing IPv4 only. 2026-06-06 08:30:37.175152 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b66264eb tst -- -4 uptime 2026-06-06 08:30:39.745571 CEST - CLI SSH Test passed. 2026-06-06 08:30:39.745663 CEST - Uname: Linux tst 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-06 08:30:39.745676 CEST - Uptime: 06:30:39 up 2 min, 1 user, load average: 1.06, 0.44, 0.16 2026-06-06 08:30:39.745706 CEST - Logging in using SSH over SSH proxy 2026-06-06 08:30:39.745860 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b66264eb tst 2026-06-06 08:30:40.762692 CEST - Added paramiko HostKeyEntry for 10.64.45.125 2026-06-06 08:30:40.762808 CEST - Added paramiko HostKeyEntry for 10.64.45.125 2026-06-06 08:30:40.762949 CEST - Added paramiko HostKeyEntry for 10.64.45.125 2026-06-06 08:30:40.763203 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-06 08:30:40.763305 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-06 08:30:40.763350 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-06 08:30:40.763362 CEST - Connecting to proxy 194.177.207.42:11122 2026-06-06 08:30:41.497688 CEST - Connecting to 10.64.45.125:22 over proxy 2026-06-06 08:30:42.974069 CEST - SSH Test output: 2026-06-06 08:30:42.974124 CEST - Uname: Linux tst 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-06 08:30:42.974138 CEST - Uptime: 06:30:42 up 2 min, 1 user, load average: 1.21, 0.48, 0.18 2026-06-06 08:30:43.275276 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-06 08:30:43.275350 CEST - lsb_release matches expected value 2026-06-06 08:30:43.275367 CEST - SSH Test passed. 2026-06-06 08:30:43.578000 CEST - Cloud-init user-data: Hello World. The time is now Sat, 06 Jun 2026 06:30:06 +0000! 2026-06-06 08:30:43.585097 CEST - Destroying tst-b66264eb tst 2026-06-06 08:30:43.585276 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-b66264eb tst