Run Test: slices-bi-singlenode-login 2026-06-08 00:28:02.879824 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpp0mbipob.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-08 00:28:07.054325 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/cce44c21-3d18-4753-a5b3-fcbef15100a5/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-08 00:28:15.241348 CEST - Run: slices --version 2026-06-08 00:28:15.455779 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-08 00:28:15.456092 CEST - Run: slices pubkey list --format text 2026-06-08 00:28:16.021355 CEST - Pubkey already registered 2026-06-08 00:28:16.021632 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-08 00:28:16.586893 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-08 00:28:16.587008 CEST - Check List Flavors 2026-06-08 00:28:16.587186 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-06-08 00:28:17.553601 CEST - Check List DiskImages 2026-06-08 00:28:17.553807 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-06-08 00:28:18.369935 CEST - Requesting resources 2026-06-08 00:28:18.370252 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-06-08 00:28:18.370344 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b82c4871 --user-data /tmp/tmpjy6yq17v 2026-06-08 00:28:22.195191 CEST - Resource ID: r_gr-volos-bi-vm1_01ktj372rnet8sk7ta0dxmtyvp 2026-06-08 00:28:22.195323 CEST - Waiting until resource ready 2026-06-08 00:28:24.195827 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b82c4871 tst 2026-06-08 00:28:25.262788 CEST - Status: IMAGING 2026-06-08 00:28:28.179602 CEST - Status: IMAGING 2026-06-08 00:28:31.096304 CEST - Status: IMAGING 2026-06-08 00:28:34.013248 CEST - Status: BOOTING 2026-06-08 00:28:36.930440 CEST - Status: BOOTING 2026-06-08 00:28:39.847586 CEST - Status: BOOTING 2026-06-08 00:28:42.764283 CEST - Status: BOOTING 2026-06-08 00:28:45.681167 CEST - Status: BOOTING 2026-06-08 00:28:48.598264 CEST - Status: BOOTING 2026-06-08 00:28:51.515649 CEST - Status: BOOTING 2026-06-08 00:28:54.382092 CEST - Status: BOOTING 2026-06-08 00:28:57.349042 CEST - Status: BOOTING 2026-06-08 00:29:00.316033 CEST - Status: BOOTING 2026-06-08 00:29:03.333293 CEST - Status: BOOTING 2026-06-08 00:29:06.300195 CEST - Status: BOOTING 2026-06-08 00:29:09.217625 CEST - Status: BOOTING 2026-06-08 00:29:12.134657 CEST - Status: BOOTING 2026-06-08 00:29:15.101591 CEST - Status: BOOTING 2026-06-08 00:29:17.968260 CEST - Status: BOOTING 2026-06-08 00:29:20.835008 CEST - Status: BOOTING 2026-06-08 00:29:23.701550 CEST - Status: BOOTING 2026-06-08 00:29:26.618328 CEST - Status: BOOTING 2026-06-08 00:29:29.535765 CEST - Status: BOOTING 2026-06-08 00:29:32.402032 CEST - Status: BOOTING 2026-06-08 00:29:35.268536 CEST - Status: BOOTING 2026-06-08 00:29:38.235593 CEST - Status: BOOTING 2026-06-08 00:29:41.152364 CEST - Status: BOOTING 2026-06-08 00:29:44.069029 CEST - Status: BOOTING 2026-06-08 00:29:46.985844 CEST - Status: BOOTING 2026-06-08 00:29:50.253712 CEST - Status: BOOTING 2026-06-08 00:29:53.270967 CEST - Status: BOOTING 2026-06-08 00:29:56.187835 CEST - Status: BOOTING 2026-06-08 00:29:59.154867 CEST - Status: BOOTING 2026-06-08 00:30:02.071862 CEST - Status: BOOTING 2026-06-08 00:30:05.038798 CEST - Status: BOOTING 2026-06-08 00:30:08.005787 CEST - Status: BOOTING 2026-06-08 00:30:10.972925 CEST - Status: BOOTING 2026-06-08 00:30:13.889669 CEST - Status: BOOTING 2026-06-08 00:30:16.856994 CEST - Status: BOOTING 2026-06-08 00:30:19.774197 CEST - Status: UP 2026-06-08 00:30:19.774289 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ktj37073fb7a2b9n4w2yap10 2026-06-08 00:30:19.774332 CEST - Validate resources 2026-06-08 00:30:20.690955 CEST - The fields of the created resource were validated. 2026-06-08 00:30:20.691031 CEST - Check if resources are registered in experiment 2026-06-08 00:30:20.691196 CEST - Run: slices experiment list-resources --format json tst-b82c4871 2026-06-08 00:30:21.407049 CEST - Status (on expauth): BOOTING 2026-06-08 00:30:24.073175 CEST - Status (on expauth): UP 2026-06-08 00:30:24.073240 CEST - "slices experiment list-resources" was not ready immediately. 2026-06-08 00:30:24.073279 CEST - It was ready after: 3.382154 seconds. (Note: used wait steps of 2s) 2026-06-08 00:30:24.073376 CEST - Testing extend expires_at (all resources in experiment) 2026-06-08 00:30:24.073522 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-b82c4871 2026-06-08 00:30:26.895474 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b82c4871 tst 2026-06-08 00:30:27.862109 CEST - Run: slices experiment list-resources --format json tst-b82c4871 2026-06-08 00:30:28.578085 CEST - expires_at (on expauth): 2026-06-08T01:30:00Z (correctly extended) 2026-06-08 00:30:28.578155 CEST - Testing extend expires_at (single resource in experiment) 2026-06-08 00:30:28.578304 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-b82c4871 2026-06-08 00:30:31.249835 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b82c4871 tst 2026-06-08 00:30:32.166437 CEST - Run: slices experiment list-resources --format json tst-b82c4871 2026-06-08 00:30:32.882652 CEST - expires_at (on expauth): 2026-06-08T02:30:00Z (correctly extended) 2026-06-08 00:30:32.882806 CEST - Testing ssh login 2026-06-08 00:30:32.894994 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b82c4871 tst 2026-06-08 00:30:34.012239 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b82c4871 tst 2026-06-08 00:30:35.079312 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b82c4871 tst 2026-06-08 00:30:36.146735 CEST - Logging in using 'slices bi ssh' 2026-06-08 00:30:36.146839 CEST - Forcing IPv4 only. 2026-06-08 00:30:36.147035 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b82c4871 tst -- -4 uname -a 2026-06-08 00:30:40.122551 CEST - Forcing IPv4 only. 2026-06-08 00:30:40.122760 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b82c4871 tst -- -4 uptime 2026-06-08 00:30:43.345212 CEST - CLI SSH Test passed. 2026-06-08 00:30:43.345272 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-08 00:30:43.345287 CEST - Uptime: 22:30:43 up 2 min, 2 users, load average: 0.79, 0.51, 0.20 2026-06-08 00:30:43.345318 CEST - Logging in using SSH over SSH proxy 2026-06-08 00:30:43.345477 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b82c4871 tst 2026-06-08 00:30:44.462533 CEST - Added paramiko HostKeyEntry for 10.64.45.157 2026-06-08 00:30:44.462642 CEST - Added paramiko HostKeyEntry for 10.64.45.157 2026-06-08 00:30:44.462798 CEST - Added paramiko HostKeyEntry for 10.64.45.157 2026-06-08 00:30:44.463065 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-08 00:30:44.463149 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-08 00:30:44.463190 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-06-08 00:30:44.463208 CEST - Connecting to proxy 194.177.207.42:11122 2026-06-08 00:30:45.197642 CEST - Connecting to 10.64.45.157:22 over proxy 2026-06-08 00:30:46.605367 CEST - SSH Test output: 2026-06-08 00:30:46.605435 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-08 00:30:46.605450 CEST - Uptime: 22:30:46 up 2 min, 1 user, load average: 0.79, 0.51, 0.20 2026-06-08 00:30:46.910783 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-08 00:30:46.910874 CEST - lsb_release matches expected value 2026-06-08 00:30:46.910891 CEST - SSH Test passed. 2026-06-08 00:30:47.212640 CEST - Cloud-init user-data: Hello World. The time is now Sun, 07 Jun 2026 22:30:14 +0000! 2026-06-08 00:30:47.219910 CEST - Destroying tst-b82c4871 tst 2026-06-08 00:30:47.220137 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-b82c4871 tst