Run Test: slices-bi-singlenode-login 2026-03-16 16:30:16.411230 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-16 16:30:19.509293 CET - Run: slices --version 2026-03-16 16:30:19.702733 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-16 16:30:19.702969 CET - Run: slices pubkey list --format text 2026-03-16 16:30:20.367618 CET - Pubkey already registered 2026-03-16 16:30:20.367830 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-16 16:30:20.897292 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-16 16:30:20.897402 CET - Check List Flavors 2026-03-16 16:30:20.897635 CET - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-03-16 16:30:21.870036 CET - Check List DiskImages 2026-03-16 16:30:21.870225 CET - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-03-16 16:30:22.599343 CET - Requesting resources 2026-03-16 16:30:22.599801 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-16 16:30:22.599890 CET - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-7403dbb9 --user-data /tmp/tmpmrg6gjg4 2026-03-16 16:30:26.556842 CET - Resource ID: r_gr-volos-bi-vm1_01kkvma5xjfbgrbe56nmeq171x 2026-03-16 16:30:26.556957 CET - Waiting until resource ready 2026-03-16 16:30:28.557325 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7403dbb9 tst 2026-03-16 16:30:29.413876 CET - Status: IMAGING 2026-03-16 16:30:32.328027 CET - Status: IMAGING 2026-03-16 16:30:35.153970 CET - Status: IMAGING 2026-03-16 16:30:37.993043 CET - Status: BOOTING 2026-03-16 16:30:40.816840 CET - Status: BOOTING 2026-03-16 16:30:43.637897 CET - Status: BOOTING 2026-03-16 16:30:46.468038 CET - Status: BOOTING 2026-03-16 16:30:49.307097 CET - Status: BOOTING 2026-03-16 16:30:52.129556 CET - Status: BOOTING 2026-03-16 16:30:54.943422 CET - Status: BOOTING 2026-03-16 16:30:57.766761 CET - Status: BOOTING 2026-03-16 16:31:00.579228 CET - Status: BOOTING 2026-03-16 16:31:03.400686 CET - Status: BOOTING 2026-03-16 16:31:06.220312 CET - Status: BOOTING 2026-03-16 16:31:09.033894 CET - Status: BOOTING 2026-03-16 16:31:11.862447 CET - Status: BOOTING 2026-03-16 16:31:14.686935 CET - Status: BOOTING 2026-03-16 16:31:17.502065 CET - Status: BOOTING 2026-03-16 16:31:20.345575 CET - Status: BOOTING 2026-03-16 16:31:23.175667 CET - Status: BOOTING 2026-03-16 16:31:26.006289 CET - Status: BOOTING 2026-03-16 16:31:28.845598 CET - Status: BOOTING 2026-03-16 16:31:31.678378 CET - Status: BOOTING 2026-03-16 16:31:34.495128 CET - Status: BOOTING 2026-03-16 16:31:37.349319 CET - Status: BOOTING 2026-03-16 16:31:40.179176 CET - Status: BOOTING 2026-03-16 16:31:43.003573 CET - Status: BOOTING 2026-03-16 16:31:45.836017 CET - Status: BOOTING 2026-03-16 16:31:48.688092 CET - Status: BOOTING 2026-03-16 16:31:51.522711 CET - Status: BOOTING 2026-03-16 16:31:54.388867 CET - Status: BOOTING 2026-03-16 16:31:57.208575 CET - Status: BOOTING 2026-03-16 16:32:00.045239 CET - Status: BOOTING 2026-03-16 16:32:02.867658 CET - Status: BOOTING 2026-03-16 16:32:05.679908 CET - Status: BOOTING 2026-03-16 16:32:08.501739 CET - Status: BOOTING 2026-03-16 16:32:11.333541 CET - Status: BOOTING 2026-03-16 16:32:14.156651 CET - Status: BOOTING 2026-03-16 16:32:16.985467 CET - Status: BOOTING 2026-03-16 16:32:19.836318 CET - Status: BOOTING 2026-03-16 16:32:22.705879 CET - Status: BOOTING 2026-03-16 16:32:25.525715 CET - Status: BOOTING 2026-03-16 16:32:28.362298 CET - Status: BOOTING 2026-03-16 16:32:31.186263 CET - Status: BOOTING 2026-03-16 16:32:34.010958 CET - Status: BOOTING 2026-03-16 16:32:36.845821 CET - Status: UP 2026-03-16 16:32:36.845862 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kkvma3areq2bjk1tmvxqxpem 2026-03-16 16:32:36.845895 CET - Validate resources 2026-03-16 16:32:39.092763 CET - The fields of the created resource were validated. 2026-03-16 16:32:39.092823 CET - Check if resources are registered in experiment 2026-03-16 16:32:39.092986 CET - Run: slices experiment list-resources --format json tst-7403dbb9 2026-03-16 16:32:39.737413 CET - Status (on expauth): UP 2026-03-16 16:32:39.737566 CET - Testing extend expires_at (all resources in experiment) 2026-03-16 16:32:39.737703 CET - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-7403dbb9 2026-03-16 16:32:42.117894 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7403dbb9 tst 2026-03-16 16:32:42.941756 CET - Testing extend expires_at (single resource in experiment) 2026-03-16 16:32:42.942021 CET - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-7403dbb9 2026-03-16 16:32:45.265963 CET - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7403dbb9 tst 2026-03-16 16:32:46.090350 CET - Testing ssh login 2026-03-16 16:32:46.100516 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-7403dbb9 tst 2026-03-16 16:32:47.053102 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-7403dbb9 tst 2026-03-16 16:32:48.005465 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-7403dbb9 tst 2026-03-16 16:32:48.969392 CET - Logging in using 'slices bi ssh' 2026-03-16 16:32:48.969469 CET - Forcing IPv4 only. 2026-03-16 16:32:48.969695 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-7403dbb9 tst -- -4 uname -a 2026-03-16 16:32:52.025556 CET - Forcing IPv4 only. 2026-03-16 16:32:52.025712 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-7403dbb9 tst -- -4 uptime 2026-03-16 16:32:54.512248 CET - CLI SSH Test passed. 2026-03-16 16:32:54.512303 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-16 16:32:54.512313 CET - Uptime: 15:32:54 up 2 min, 1 user, load average: 1.45, 0.65, 0.25 2026-03-16 16:32:54.512347 CET - Logging in using SSH over SSH proxy 2026-03-16 16:32:54.512511 CET - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-7403dbb9 tst 2026-03-16 16:32:55.519918 CET - Added paramiko HostKeyEntry for 10.64.45.249 2026-03-16 16:32:55.520027 CET - Added paramiko HostKeyEntry for 10.64.45.249 2026-03-16 16:32:55.520176 CET - Added paramiko HostKeyEntry for 10.64.45.249 2026-03-16 16:32:55.520509 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-16 16:32:55.520617 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-16 16:32:55.520666 CET - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-16 16:32:55.520678 CET - Connecting to proxy 194.177.207.42:11122 2026-03-16 16:32:56.277862 CET - Connecting to 10.64.45.249:22 over proxy 2026-03-16 16:32:57.685478 CET - SSH Test output: 2026-03-16 16:32:57.685542 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-16 16:32:57.685554 CET - Uptime: 15:32:57 up 2 min, 1 user, load average: 1.45, 0.65, 0.25 2026-03-16 16:32:57.988604 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-16 16:32:57.988683 CET - lsb_release matches expected value 2026-03-16 16:32:57.988693 CET - SSH Test passed. 2026-03-16 16:32:58.291293 CET - Cloud-init user-data: Hello World. The time is now Mon, 16 Mar 2026 15:32:32 +0000! 2026-03-16 16:32:58.298151 CET - Destroying tst-7403dbb9 tst 2026-03-16 16:32:58.298343 CET - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-7403dbb9 tst