Run Test: slices-bi-singlenode-login 2026-06-03 08:27:55.809079 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpz8sl847t.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-03 08:27:59.783960 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/67462427-3878-435b-8075-08d5d5868a06/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-03 08:28:07.721377 CEST - Run: slices --version 2026-06-03 08:28:07.936037 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-03 08:28:07.936336 CEST - Run: slices pubkey list --format text 2026-06-03 08:28:08.551817 CEST - Pubkey already registered 2026-06-03 08:28:08.552112 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-03 08:28:09.167621 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-06-03 08:28:09.167757 CEST - Check List Flavors 2026-06-03 08:28:09.167938 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-06-03 08:28:10.285496 CEST - Check List DiskImages 2026-06-03 08:28:10.285752 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-06-03 08:28:11.101886 CEST - Requesting resources 2026-06-03 08:28:11.102283 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-06-03 08:28:11.102389 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-22ce5777 --user-data /tmp/tmpa0jpad5y 2026-06-03 08:28:16.481790 CEST - Resource ID: r_gr-volos-bi-vm1_01kt62p76jfw8rqtr9364a2ygm 2026-06-03 08:28:16.481950 CEST - Waiting until resource ready 2026-06-03 08:28:18.482466 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-22ce5777 tst 2026-06-03 08:28:19.449131 CEST - Status: IMAGING 2026-06-03 08:28:22.366238 CEST - Status: IMAGING 2026-06-03 08:28:25.283350 CEST - Status: IMAGING 2026-06-03 08:28:28.200191 CEST - Status: BOOTING 2026-06-03 08:28:31.117512 CEST - Status: BOOTING 2026-06-03 08:28:34.034716 CEST - Status: BOOTING 2026-06-03 08:28:36.951997 CEST - Status: BOOTING 2026-06-03 08:28:39.919552 CEST - Status: BOOTING 2026-06-03 08:28:42.837021 CEST - Status: BOOTING 2026-06-03 08:28:45.754067 CEST - Status: BOOTING 2026-06-03 08:28:48.671205 CEST - Status: BOOTING 2026-06-03 08:28:51.588166 CEST - Status: BOOTING 2026-06-03 08:28:54.505214 CEST - Status: BOOTING 2026-06-03 08:28:57.422448 CEST - Status: BOOTING 2026-06-03 08:29:00.289482 CEST - Status: BOOTING 2026-06-03 08:29:03.156046 CEST - Status: BOOTING 2026-06-03 08:29:06.073099 CEST - Status: BOOTING 2026-06-03 08:29:08.990202 CEST - Status: BOOTING 2026-06-03 08:29:11.907271 CEST - Status: BOOTING 2026-06-03 08:29:14.774144 CEST - Status: BOOTING 2026-06-03 08:29:17.640967 CEST - Status: BOOTING 2026-06-03 08:29:20.558053 CEST - Status: BOOTING 2026-06-03 08:29:23.475154 CEST - Status: BOOTING 2026-06-03 08:29:26.392629 CEST - Status: BOOTING 2026-06-03 08:29:29.409850 CEST - Status: BOOTING 2026-06-03 08:29:32.377246 CEST - Status: BOOTING 2026-06-03 08:29:35.294569 CEST - Status: BOOTING 2026-06-03 08:29:38.211579 CEST - Status: BOOTING 2026-06-03 08:29:41.078506 CEST - Status: BOOTING 2026-06-03 08:29:44.045839 CEST - Status: BOOTING 2026-06-03 08:29:47.063331 CEST - Status: BOOTING 2026-06-03 08:29:49.980750 CEST - Status: BOOTING 2026-06-03 08:29:52.948048 CEST - Status: BOOTING 2026-06-03 08:29:55.965324 CEST - Status: BOOTING 2026-06-03 08:29:58.882889 CEST - Status: BOOTING 2026-06-03 08:30:01.950347 CEST - Status: BOOTING 2026-06-03 08:30:04.917446 CEST - Status: UP 2026-06-03 08:30:04.917555 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kt62p3d4e8dt6ma39etc3z00 2026-06-03 08:30:04.917603 CEST - Validate resources 2026-06-03 08:30:05.884646 CEST - The fields of the created resource were validated. 2026-06-03 08:30:05.884781 CEST - Check if resources are registered in experiment 2026-06-03 08:30:05.884999 CEST - Run: slices experiment list-resources --format json tst-22ce5777 2026-06-03 08:30:06.601045 CEST - Status (on expauth): UP 2026-06-03 08:30:06.601235 CEST - Testing extend expires_at (all resources in experiment) 2026-06-03 08:30:06.601423 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-22ce5777 2026-06-03 08:30:09.373608 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-22ce5777 tst 2026-06-03 08:30:10.290448 CEST - Run: slices experiment list-resources --format json tst-22ce5777 2026-06-03 08:30:11.006508 CEST - expires_at (on expauth): 2026-06-03T08:28:00Z (not yet extended) 2026-06-03 08:30:13.823439 CEST - expires_at (on expauth): 2026-06-03T08:28:00Z (not yet extended) 2026-06-03 08:30:16.540394 CEST - expires_at (on expauth): 2026-06-03T08:28:00Z (not yet extended) 2026-06-03 08:30:19.257364 CEST - expires_at (on expauth): 2026-06-03T08:28:00Z (not yet extended) 2026-06-03 08:30:21.982532 CEST - expires_at (on expauth): 2026-06-03T09:30:00Z (correctly extended) 2026-06-03 08:30:21.982631 CEST - "slices experiment list-resources" was not updated immediately after extend expires_at. 2026-06-03 08:30:21.982654 CEST - It was updated after: 11.692316 seconds. (Note: used wait steps of 2s) 2026-06-03 08:30:21.982676 CEST - Testing extend expires_at (single resource in experiment) 2026-06-03 08:30:21.982830 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-22ce5777 2026-06-03 08:30:24.958585 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-22ce5777 tst 2026-06-03 08:30:25.976708 CEST - Run: slices experiment list-resources --format json tst-22ce5777 2026-06-03 08:30:26.754268 CEST - expires_at (on expauth): 2026-06-03T10:30:00Z (correctly extended) 2026-06-03 08:30:26.754392 CEST - Testing ssh login 2026-06-03 08:30:26.766323 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-22ce5777 tst 2026-06-03 08:30:27.883735 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-22ce5777 tst 2026-06-03 08:30:28.951037 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-22ce5777 tst 2026-06-03 08:30:30.128558 CEST - Logging in using 'slices bi ssh' 2026-06-03 08:30:30.128756 CEST - Forcing IPv4 only. 2026-06-03 08:30:30.129015 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-22ce5777 tst -- -4 uname -a 2026-06-03 08:30:33.313475 CEST - Forcing IPv4 only. 2026-06-03 08:30:33.313875 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-22ce5777 tst -- -4 uptime 2026-06-03 08:30:36.035788 CEST - CLI SSH Test passed. 2026-06-03 08:30:36.035874 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-03 08:30:36.035890 CEST - Uptime: 06:30:35 up 2 min, 1 user, load average: 1.19, 0.56, 0.21 2026-06-03 08:30:36.035928 CEST - Logging in using SSH over SSH proxy 2026-06-03 08:30:36.036127 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-22ce5777 tst 2026-06-03 08:30:37.153659 CEST - Added paramiko HostKeyEntry for 10.64.45.254 2026-06-03 08:30:37.153786 CEST - Added paramiko HostKeyEntry for 10.64.45.254 2026-06-03 08:30:37.153931 CEST - Added paramiko HostKeyEntry for 10.64.45.254 2026-06-03 08:30:37.154228 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-03 08:30:37.154312 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-03 08:30:37.154351 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-03 08:30:37.154364 CEST - Connecting to proxy 194.177.207.42:11122 2026-06-03 08:30:37.890784 CEST - Connecting to 10.64.45.254:22 over proxy 2026-06-03 08:30:39.310121 CEST - SSH Test output: 2026-06-03 08:30:39.310184 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-03 08:30:39.310198 CEST - Uptime: 06:30:38 up 2 min, 1 user, load average: 1.19, 0.56, 0.21 2026-06-03 08:30:39.613118 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-03 08:30:39.613199 CEST - lsb_release matches expected value 2026-06-03 08:30:39.613218 CEST - SSH Test passed. 2026-06-03 08:30:39.914903 CEST - Cloud-init user-data: Hello World. The time is now Wed, 03 Jun 2026 06:29:59 +0000! 2026-06-03 08:30:39.922062 CEST - Destroying tst-22ce5777 tst 2026-06-03 08:30:39.922277 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-22ce5777 tst