Run Test: slices-bi-singlenode-login 2026-05-12 08:13:57.020034 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-12 08:14:00.191110 CEST - Run: slices --version 2026-05-12 08:14:00.405610 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.1 Slices clientlib bi v5.1.2 2026-05-12 08:14:00.405891 CEST - Run: slices pubkey list --format text 2026-05-12 08:14:01.071246 CEST - Pubkey already registered 2026-05-12 08:14:01.071478 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-12 08:14:01.636866 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-12 08:14:01.637021 CEST - Check List Flavors 2026-05-12 08:14:01.637237 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-05-12 08:14:03.104865 CEST - Check List DiskImages 2026-05-12 08:14:03.105114 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-05-12 08:14:03.921025 CEST - Requesting resources 2026-05-12 08:14:03.921484 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-05-12 08:14:03.921581 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-066825f1 --user-data /tmp/tmp8czb660t 2026-05-12 08:14:08.947823 CEST - Resource ID: r_gr-volos-bi-vm1_01krdd4hk3f89agnxs7hjqdnjn 2026-05-12 08:14:08.947946 CEST - Waiting until resource ready 2026-05-12 08:14:10.948304 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-066825f1 tst 2026-05-12 08:14:11.914792 CEST - Status: IMAGING 2026-05-12 08:14:14.831288 CEST - Status: IMAGING 2026-05-12 08:14:17.747856 CEST - Status: IMAGING 2026-05-12 08:14:20.664135 CEST - Status: BOOTING 2026-05-12 08:14:23.631736 CEST - Status: BOOTING 2026-05-12 08:14:26.549692 CEST - Status: BOOTING 2026-05-12 08:14:29.516174 CEST - Status: BOOTING 2026-05-12 08:14:32.433195 CEST - Status: BOOTING 2026-05-12 08:14:35.399854 CEST - Status: BOOTING 2026-05-12 08:14:38.317081 CEST - Status: BOOTING 2026-05-12 08:14:41.183448 CEST - Status: BOOTING 2026-05-12 08:14:44.049593 CEST - Status: BOOTING 2026-05-12 08:14:46.915736 CEST - Status: BOOTING 2026-05-12 08:14:49.832044 CEST - Status: BOOTING 2026-05-12 08:14:52.698371 CEST - Status: BOOTING 2026-05-12 08:14:55.564693 CEST - Status: BOOTING 2026-05-12 08:14:58.531316 CEST - Status: BOOTING 2026-05-12 08:15:01.497871 CEST - Status: BOOTING 2026-05-12 08:15:04.464849 CEST - Status: BOOTING 2026-05-12 08:15:07.331653 CEST - Status: BOOTING 2026-05-12 08:15:10.348398 CEST - Status: BOOTING 2026-05-12 08:15:13.214777 CEST - Status: BOOTING 2026-05-12 08:15:16.182041 CEST - Status: BOOTING 2026-05-12 08:15:19.149058 CEST - Status: BOOTING 2026-05-12 08:15:22.015953 CEST - Status: BOOTING 2026-05-12 08:15:24.933142 CEST - Status: BOOTING 2026-05-12 08:15:27.799976 CEST - Status: BOOTING 2026-05-12 08:15:30.717030 CEST - Status: BOOTING 2026-05-12 08:15:33.633575 CEST - Status: BOOTING 2026-05-12 08:15:36.500118 CEST - Status: BOOTING 2026-05-12 08:15:39.416677 CEST - Status: BOOTING 2026-05-12 08:15:42.333612 CEST - Status: BOOTING 2026-05-12 08:15:45.300659 CEST - Status: BOOTING 2026-05-12 08:15:48.367800 CEST - Status: BOOTING 2026-05-12 08:15:51.284430 CEST - Status: BOOTING 2026-05-12 08:15:54.201260 CEST - Status: BOOTING 2026-05-12 08:15:57.067779 CEST - Status: BOOTING 2026-05-12 08:15:59.983937 CEST - Status: BOOTING 2026-05-12 08:16:02.850180 CEST - Status: BOOTING 2026-05-12 08:16:05.716402 CEST - Status: BOOTING 2026-05-12 08:16:08.583238 CEST - Status: UP 2026-05-12 08:16:08.583295 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01krdd4efgeq9t3ce4e8zxfjyn 2026-05-12 08:16:08.583341 CEST - Validate resources 2026-05-12 08:16:09.599986 CEST - The fields of the created resource were validated. 2026-05-12 08:16:09.600064 CEST - Check if resources are registered in experiment 2026-05-12 08:16:09.600235 CEST - Run: slices experiment list-resources --format json tst-066825f1 2026-05-12 08:16:10.215837 CEST - Status (on expauth): UP 2026-05-12 08:16:10.216077 CEST - Testing extend expires_at (all resources in experiment) 2026-05-12 08:16:10.216312 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-066825f1 2026-05-12 08:16:14.391955 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-066825f1 tst 2026-05-12 08:16:16.311543 CEST - Testing extend expires_at (single resource in experiment) 2026-05-12 08:16:16.311800 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-066825f1 2026-05-12 08:16:19.835372 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-066825f1 tst 2026-05-12 08:16:20.751598 CEST - Testing ssh login 2026-05-12 08:16:20.763034 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-066825f1 tst 2026-05-12 08:16:21.729169 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-066825f1 tst 2026-05-12 08:16:22.695305 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-066825f1 tst 2026-05-12 08:16:23.711853 CEST - Logging in using 'slices bi ssh' 2026-05-12 08:16:23.711933 CEST - Forcing IPv4 only. 2026-05-12 08:16:23.712071 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-066825f1 tst -- -4 uname -a 2026-05-12 08:16:27.836243 CEST - Forcing IPv4 only. 2026-05-12 08:16:27.836409 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-066825f1 tst -- -4 uptime 2026-05-12 08:16:30.807151 CEST - CLI SSH Test passed. 2026-05-12 08:16:30.807204 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-05-12 08:16:30.807217 CEST - Uptime: 06:16:30 up 2 min, 1 user, load average: 0.74, 0.38, 0.15 2026-05-12 08:16:30.807254 CEST - Logging in using SSH over SSH proxy 2026-05-12 08:16:30.807445 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-066825f1 tst 2026-05-12 08:16:31.874375 CEST - Added paramiko HostKeyEntry for 10.64.45.146 2026-05-12 08:16:31.874497 CEST - Added paramiko HostKeyEntry for 10.64.45.146 2026-05-12 08:16:31.874642 CEST - Added paramiko HostKeyEntry for 10.64.45.146 2026-05-12 08:16:31.874914 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-12 08:16:31.875015 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-12 08:16:31.875055 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-12 08:16:31.875068 CEST - Connecting to proxy 194.177.207.42:11122 2026-05-12 08:16:32.771829 CEST - Connecting to 10.64.45.146:22 over proxy 2026-05-12 08:16:34.503415 CEST - SSH Test output: 2026-05-12 08:16:34.503476 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-05-12 08:16:34.503490 CEST - Uptime: 06:16:34 up 2 min, 1 user, load average: 0.68, 0.37, 0.14 2026-05-12 08:16:34.881056 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-12 08:16:34.881110 CEST - lsb_release matches expected value 2026-05-12 08:16:34.881122 CEST - SSH Test passed. 2026-05-12 08:16:35.258022 CEST - Cloud-init user-data: Hello World. The time is now Tue, 12 May 2026 06:16:03 +0000! 2026-05-12 08:16:35.264902 CEST - Destroying tst-066825f1 tst 2026-05-12 08:16:35.265082 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-066825f1 tst