Run Test: slices-bi-singlenode-login 2026-03-12 07:32:07.375223 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-12 07:32:10.443792 CET - Run: slices --version 2026-03-12 07:32:10.633893 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-12 07:32:10.634161 CET - Run: slices pubkey list --format text 2026-03-12 07:32:11.295351 CET - Pubkey already registered 2026-03-12 07:32:11.295594 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-12 07:32:11.803322 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-12 07:32:11.803447 CET - Check List Flavors 2026-03-12 07:32:11.803642 CET - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-03-12 07:32:13.243432 CET - Check List DiskImages 2026-03-12 07:32:13.243639 CET - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-03-12 07:32:13.970729 CET - Requesting resources 2026-03-12 07:32:13.971199 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-12 07:32:13.971289 CET - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-cc08bb62 --user-data /tmp/tmp_ray74ag 2026-03-12 07:32:18.968912 CET - Resource ID: r_gr-volos-bi-vm1_01kkgbxz27f8899j7j770pz6xg 2026-03-12 07:32:18.969043 CET - Waiting until resource ready 2026-03-12 07:32:20.969444 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-cc08bb62 tst 2026-03-12 07:32:21.769868 CET - Status: IMAGING 2026-03-12 07:32:24.574459 CET - Status: IMAGING 2026-03-12 07:32:27.389355 CET - Status: IMAGING 2026-03-12 07:32:30.190024 CET - Status: BOOTING 2026-03-12 07:32:32.994616 CET - Status: BOOTING 2026-03-12 07:32:35.796901 CET - Status: BOOTING 2026-03-12 07:32:38.642341 CET - Status: BOOTING 2026-03-12 07:32:41.467332 CET - Status: BOOTING 2026-03-12 07:32:44.289573 CET - Status: BOOTING 2026-03-12 07:32:47.133367 CET - Status: BOOTING 2026-03-12 07:32:49.980290 CET - Status: BOOTING 2026-03-12 07:32:52.789233 CET - Status: BOOTING 2026-03-12 07:32:55.618883 CET - Status: BOOTING 2026-03-12 07:32:58.455849 CET - Status: BOOTING 2026-03-12 07:33:01.270700 CET - Status: BOOTING 2026-03-12 07:33:04.110967 CET - Status: BOOTING 2026-03-12 07:33:06.921508 CET - Status: BOOTING 2026-03-12 07:33:09.738706 CET - Status: BOOTING 2026-03-12 07:33:12.553669 CET - Status: BOOTING 2026-03-12 07:33:15.374974 CET - Status: BOOTING 2026-03-12 07:33:18.187057 CET - Status: BOOTING 2026-03-12 07:33:21.082259 CET - Status: BOOTING 2026-03-12 07:33:23.909929 CET - Status: BOOTING 2026-03-12 07:33:26.735015 CET - Status: BOOTING 2026-03-12 07:33:29.565652 CET - Status: BOOTING 2026-03-12 07:33:32.403532 CET - Status: BOOTING 2026-03-12 07:33:35.230710 CET - Status: BOOTING 2026-03-12 07:33:38.037827 CET - Status: BOOTING 2026-03-12 07:33:40.852531 CET - Status: BOOTING 2026-03-12 07:33:43.661009 CET - Status: BOOTING 2026-03-12 07:33:46.472938 CET - Status: BOOTING 2026-03-12 07:33:49.276808 CET - Status: BOOTING 2026-03-12 07:33:52.092653 CET - Status: BOOTING 2026-03-12 07:33:54.896964 CET - Status: BOOTING 2026-03-12 07:33:57.695139 CET - Status: BOOTING 2026-03-12 07:34:00.493857 CET - Status: BOOTING 2026-03-12 07:34:03.330070 CET - Status: UP 2026-03-12 07:34:03.330123 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kkgbxvajev9b649a81w5z5nt 2026-03-12 07:34:03.330174 CET - Check if resources are registered in experiment 2026-03-12 07:34:03.330351 CET - Run: slices experiment list-resources --format json tst-cc08bb62 2026-03-12 07:34:03.936974 CET - Status (on expauth): UP 2026-03-12 07:34:03.937112 CET - Testing extend expires_at (all resources in experiment) 2026-03-12 07:34:03.937241 CET - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-cc08bb62 2026-03-12 07:34:06.227582 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-cc08bb62 tst 2026-03-12 07:34:07.032621 CET - Testing extend expires_at (single resource in experiment) 2026-03-12 07:34:07.032815 CET - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-cc08bb62 2026-03-12 07:34:09.242000 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-cc08bb62 tst 2026-03-12 07:34:10.043561 CET - Testing ssh login 2026-03-12 07:34:10.053382 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cc08bb62 tst 2026-03-12 07:34:10.980214 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cc08bb62 tst 2026-03-12 07:34:11.909419 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-cc08bb62 tst 2026-03-12 07:34:12.857995 CET - Logging in using 'slices bi ssh' 2026-03-12 07:34:12.858053 CET - Forcing IPv4 only. 2026-03-12 07:34:12.858191 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-cc08bb62 tst -- -4 uname -a 2026-03-12 07:34:17.092333 CET - Forcing IPv4 only. 2026-03-12 07:34:17.092500 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-cc08bb62 tst -- -4 uptime 2026-03-12 07:34:19.574386 CET - CLI SSH Test passed. 2026-03-12 07:34:19.574432 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-12 07:34:19.574442 CET - Uptime: 06:34:19 up 1 min, 1 user, load average: 0.74, 0.30, 0.11 2026-03-12 07:34:19.574480 CET - Logging in using SSH over SSH proxy 2026-03-12 07:34:19.574628 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-cc08bb62 tst 2026-03-12 07:34:20.502747 CET - Added paramiko HostKeyEntry for 10.64.45.142 2026-03-12 07:34:20.502865 CET - Added paramiko HostKeyEntry for 10.64.45.142 2026-03-12 07:34:20.503014 CET - Added paramiko HostKeyEntry for 10.64.45.142 2026-03-12 07:34:20.503284 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-12 07:34:20.503361 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-12 07:34:20.503393 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-12 07:34:20.503403 CET - Connecting to proxy 194.177.207.42:11122 2026-03-12 07:34:21.257603 CET - Connecting to 10.64.45.142:22 over proxy 2026-03-12 07:34:22.728194 CET - SSH Test output: 2026-03-12 07:34:22.728243 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-12 07:34:22.728255 CET - Uptime: 06:34:22 up 1 min, 1 user, load average: 0.74, 0.30, 0.11 2026-03-12 07:34:23.031400 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-12 07:34:23.031472 CET - lsb_release matches expected value 2026-03-12 07:34:23.031483 CET - SSH Test passed. 2026-03-12 07:34:23.334622 CET - Cloud-init user-data: Hello World. The time is now Thu, 12 Mar 2026 06:33:59 +0000! 2026-03-12 07:34:23.341686 CET - Destroying tst-cc08bb62 tst 2026-03-12 07:34:23.341863 CET - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-cc08bb62 tst