Run Test: slices-bi-singlenode-login 2026-03-18 07:03:17.164300 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-18 07:03:20.192245 CET - Run: slices --version 2026-03-18 07:03:20.387414 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.3.8 Slices clientlib bi v5.1.2 2026-03-18 07:03:20.387703 CET - Run: slices pubkey list --format text 2026-03-18 07:03:20.962561 CET - Pubkey already registered 2026-03-18 07:03:20.962797 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-18 07:03:21.495628 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-18 07:03:21.495727 CET - Check List Flavors 2026-03-18 07:03:21.495935 CET - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-03-18 07:03:22.491616 CET - Check List DiskImages 2026-03-18 07:03:22.491826 CET - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-03-18 07:03:23.230316 CET - Requesting resources 2026-03-18 07:03:23.230780 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-18 07:03:23.230869 CET - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e5192628 --user-data /tmp/tmp7dx5mpgl 2026-03-18 07:03:26.925763 CET - Resource ID: r_gr-volos-bi-vm1_01kkzrndm1ee89pghwxg46z3pn 2026-03-18 07:03:26.925871 CET - Waiting until resource ready 2026-03-18 07:03:28.926278 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-e5192628 tst 2026-03-18 07:03:29.755392 CET - Status: IMAGING 2026-03-18 07:03:32.596707 CET - Status: IMAGING 2026-03-18 07:03:35.433113 CET - Status: IMAGING 2026-03-18 07:03:38.270205 CET - Status: BOOTING 2026-03-18 07:03:41.098826 CET - Status: BOOTING 2026-03-18 07:03:43.940686 CET - Status: BOOTING 2026-03-18 07:03:46.769654 CET - Status: BOOTING 2026-03-18 07:03:49.630588 CET - Status: BOOTING 2026-03-18 07:03:52.467215 CET - Status: BOOTING 2026-03-18 07:03:55.316890 CET - Status: BOOTING 2026-03-18 07:03:58.194842 CET - Status: BOOTING 2026-03-18 07:04:01.038982 CET - Status: BOOTING 2026-03-18 07:04:03.870875 CET - Status: BOOTING 2026-03-18 07:04:06.711919 CET - Status: BOOTING 2026-03-18 07:04:09.545854 CET - Status: BOOTING 2026-03-18 07:04:12.364662 CET - Status: BOOTING 2026-03-18 07:04:15.193634 CET - Status: BOOTING 2026-03-18 07:04:18.038486 CET - Status: BOOTING 2026-03-18 07:04:20.880490 CET - Status: BOOTING 2026-03-18 07:04:23.714624 CET - Status: BOOTING 2026-03-18 07:04:26.547097 CET - Status: BOOTING 2026-03-18 07:04:29.384600 CET - Status: BOOTING 2026-03-18 07:04:32.220241 CET - Status: BOOTING 2026-03-18 07:04:35.067522 CET - Status: BOOTING 2026-03-18 07:04:37.909081 CET - Status: BOOTING 2026-03-18 07:04:40.752812 CET - Status: BOOTING 2026-03-18 07:04:43.566210 CET - Status: BOOTING 2026-03-18 07:04:46.401038 CET - Status: BOOTING 2026-03-18 07:04:49.222384 CET - Status: BOOTING 2026-03-18 07:04:52.056163 CET - Status: BOOTING 2026-03-18 07:04:54.880272 CET - Status: BOOTING 2026-03-18 07:04:57.711402 CET - Status: BOOTING 2026-03-18 07:05:00.564324 CET - Status: BOOTING 2026-03-18 07:05:03.427253 CET - Status: BOOTING 2026-03-18 07:05:06.307769 CET - Status: BOOTING 2026-03-18 07:05:09.146575 CET - Status: BOOTING 2026-03-18 07:05:11.980385 CET - Status: BOOTING 2026-03-18 07:05:14.808447 CET - Status: BOOTING 2026-03-18 07:05:17.639295 CET - Status: UP 2026-03-18 07:05:17.639338 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kkzrnb2wfagszg9pgaxtg6nr 2026-03-18 07:05:17.639372 CET - Validate resources 2026-03-18 07:05:18.488524 CET - The fields of the created resource were validated. 2026-03-18 07:05:18.488585 CET - Check if resources are registered in experiment 2026-03-18 07:05:18.488769 CET - Run: slices experiment list-resources --format json tst-e5192628 2026-03-18 07:05:19.129057 CET - Status (on expauth): UP 2026-03-18 07:05:19.129183 CET - Testing extend expires_at (all resources in experiment) 2026-03-18 07:05:19.129318 CET - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-e5192628 2026-03-18 07:05:21.361376 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-e5192628 tst 2026-03-18 07:05:22.193315 CET - Testing extend expires_at (single resource in experiment) 2026-03-18 07:05:22.193483 CET - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-e5192628 2026-03-18 07:05:24.442933 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-e5192628 tst 2026-03-18 07:05:25.267643 CET - Testing ssh login 2026-03-18 07:05:25.277111 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e5192628 tst 2026-03-18 07:05:26.224200 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e5192628 tst 2026-03-18 07:05:27.209301 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-e5192628 tst 2026-03-18 07:05:28.188021 CET - Logging in using 'slices bi ssh' 2026-03-18 07:05:28.188075 CET - Forcing IPv4 only. 2026-03-18 07:05:28.188214 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-e5192628 tst -- -4 uname -a 2026-03-18 07:05:31.252658 CET - Forcing IPv4 only. 2026-03-18 07:05:31.252825 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-e5192628 tst -- -4 uptime 2026-03-18 07:05:33.798298 CET - CLI SSH Test passed. 2026-03-18 07:05:33.798340 CET - 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-03-18 07:05:33.798350 CET - Uptime: 06:05:33 up 1 min, 2 users, load average: 2.31, 0.95, 0.36 2026-03-18 07:05:33.798375 CET - Logging in using SSH over SSH proxy 2026-03-18 07:05:33.798531 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-e5192628 tst 2026-03-18 07:05:34.754453 CET - Added paramiko HostKeyEntry for 10.64.45.99 2026-03-18 07:05:34.754567 CET - Added paramiko HostKeyEntry for 10.64.45.99 2026-03-18 07:05:34.754707 CET - Added paramiko HostKeyEntry for 10.64.45.99 2026-03-18 07:05:34.754973 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-18 07:05:34.755051 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-18 07:05:34.755085 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-18 07:05:34.755096 CET - Connecting to proxy 194.177.207.42:11122 2026-03-18 07:05:35.505584 CET - Connecting to 10.64.45.99:22 over proxy 2026-03-18 07:05:36.992091 CET - SSH Test output: 2026-03-18 07:05:36.992135 CET - 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-03-18 07:05:36.992146 CET - Uptime: 06:05:36 up 1 min, 2 users, load average: 2.13, 0.93, 0.35 2026-03-18 07:05:37.296133 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-18 07:05:37.296185 CET - lsb_release matches expected value 2026-03-18 07:05:37.296194 CET - SSH Test passed. 2026-03-18 07:05:37.601510 CET - Cloud-init user-data: Hello World. The time is now Wed, 18 Mar 2026 06:05:14 +0000! 2026-03-18 07:05:37.607928 CET - Destroying tst-e5192628 tst 2026-03-18 07:05:37.608087 CET - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-e5192628 tst