Run Test: slices-bi-singlenode-login 2026-05-07 08:03:54.149232 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-07 08:03:57.220325 CEST - Run: slices --version 2026-05-07 08:03:57.436606 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-07 08:03:57.436962 CEST - Run: slices pubkey list --format text 2026-05-07 08:03:58.001999 CEST - Pubkey already registered 2026-05-07 08:03:58.002302 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-07 08:03:58.567577 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-07 08:03:58.567689 CEST - Check List Flavors 2026-05-07 08:03:58.567855 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-05-07 08:03:59.834705 CEST - Check List DiskImages 2026-05-07 08:03:59.834897 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-05-07 08:04:00.600356 CEST - Requesting resources 2026-05-07 08:04:00.600845 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-05-07 08:04:00.600939 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ba9527f0 --user-data /tmp/tmpctzrrvfl 2026-05-07 08:04:07.281320 CEST - Resource ID: r_gr-volos-bi-vm1_01kr0gjk00fqhsc2yny8mzxnvd 2026-05-07 08:04:07.281445 CEST - Waiting until resource ready 2026-05-07 08:04:09.281931 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ba9527f0 tst 2026-05-07 08:04:10.198290 CEST - Status: IMAGING 2026-05-07 08:04:13.064843 CEST - Status: IMAGING 2026-05-07 08:04:15.931360 CEST - Status: IMAGING 2026-05-07 08:04:18.797677 CEST - Status: BOOTING 2026-05-07 08:04:21.613817 CEST - Status: BOOTING 2026-05-07 08:04:24.429988 CEST - Status: BOOTING 2026-05-07 08:04:27.296193 CEST - Status: BOOTING 2026-05-07 08:04:30.112470 CEST - Status: BOOTING 2026-05-07 08:04:33.029522 CEST - Status: BOOTING 2026-05-07 08:04:35.845626 CEST - Status: BOOTING 2026-05-07 08:04:38.661815 CEST - Status: BOOTING 2026-05-07 08:04:41.477927 CEST - Status: BOOTING 2026-05-07 08:04:44.294016 CEST - Status: BOOTING 2026-05-07 08:04:47.110388 CEST - Status: BOOTING 2026-05-07 08:04:49.926853 CEST - Status: BOOTING 2026-05-07 08:04:52.793810 CEST - Status: BOOTING 2026-05-07 08:04:55.660094 CEST - Status: BOOTING 2026-05-07 08:04:58.526364 CEST - Status: BOOTING 2026-05-07 08:05:01.342269 CEST - Status: BOOTING 2026-05-07 08:05:04.158412 CEST - Status: BOOTING 2026-05-07 08:05:07.024782 CEST - Status: BOOTING 2026-05-07 08:05:09.891777 CEST - Status: BOOTING 2026-05-07 08:05:12.758819 CEST - Status: BOOTING 2026-05-07 08:05:15.625176 CEST - Status: BOOTING 2026-05-07 08:05:18.441318 CEST - Status: BOOTING 2026-05-07 08:05:21.307415 CEST - Status: BOOTING 2026-05-07 08:05:24.173753 CEST - Status: BOOTING 2026-05-07 08:05:27.039961 CEST - Status: BOOTING 2026-05-07 08:05:29.906256 CEST - Status: BOOTING 2026-05-07 08:05:32.773068 CEST - Status: BOOTING 2026-05-07 08:05:35.639331 CEST - Status: BOOTING 2026-05-07 08:05:38.505587 CEST - Status: BOOTING 2026-05-07 08:05:41.371933 CEST - Status: BOOTING 2026-05-07 08:05:44.238270 CEST - Status: BOOTING 2026-05-07 08:05:47.104513 CEST - Status: BOOTING 2026-05-07 08:05:49.920735 CEST - Status: BOOTING 2026-05-07 08:05:52.736932 CEST - Status: BOOTING 2026-05-07 08:05:55.603069 CEST - Status: BOOTING 2026-05-07 08:05:58.469362 CEST - Status: BOOTING 2026-05-07 08:06:01.335670 CEST - Status: BOOTING 2026-05-07 08:06:04.201869 CEST - Status: UP 2026-05-07 08:06:04.201926 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kr0gjdnyfazbvpvrk7p6w575 2026-05-07 08:06:04.201972 CEST - Validate resources 2026-05-07 08:06:05.068230 CEST - The fields of the created resource were validated. 2026-05-07 08:06:05.068304 CEST - Check if resources are registered in experiment 2026-05-07 08:06:05.068465 CEST - Run: slices experiment list-resources --format json tst-ba9527f0 2026-05-07 08:06:05.683941 CEST - Status (on expauth): UP 2026-05-07 08:06:05.684087 CEST - Testing extend expires_at (all resources in experiment) 2026-05-07 08:06:05.684212 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-ba9527f0 2026-05-07 08:06:08.504330 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ba9527f0 tst 2026-05-07 08:06:09.320099 CEST - Testing extend expires_at (single resource in experiment) 2026-05-07 08:06:09.320271 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-ba9527f0 2026-05-07 08:06:12.040971 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ba9527f0 tst 2026-05-07 08:06:12.906961 CEST - Testing ssh login 2026-05-07 08:06:12.917214 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ba9527f0 tst 2026-05-07 08:06:13.883697 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ba9527f0 tst 2026-05-07 08:06:14.850243 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ba9527f0 tst 2026-05-07 08:06:15.816940 CEST - Logging in using 'slices bi ssh' 2026-05-07 08:06:15.817023 CEST - Forcing IPv4 only. 2026-05-07 08:06:15.817169 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ba9527f0 tst -- -4 uname -a 2026-05-07 08:06:18.888795 CEST - Forcing IPv4 only. 2026-05-07 08:06:18.888996 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ba9527f0 tst -- -4 uptime 2026-05-07 08:06:21.509237 CEST - CLI SSH Test passed. 2026-05-07 08:06:21.509311 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-07 08:06:21.509324 CEST - Uptime: 06:06:21 up 1 min, 1 user, load average: 1.05, 0.59, 0.23 2026-05-07 08:06:21.509359 CEST - Logging in using SSH over SSH proxy 2026-05-07 08:06:21.509553 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ba9527f0 tst 2026-05-07 08:06:22.476360 CEST - Added paramiko HostKeyEntry for 10.64.45.222 2026-05-07 08:06:22.476469 CEST - Added paramiko HostKeyEntry for 10.64.45.222 2026-05-07 08:06:22.476622 CEST - Added paramiko HostKeyEntry for 10.64.45.222 2026-05-07 08:06:22.476904 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-07 08:06:22.476986 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-07 08:06:22.477025 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-07 08:06:22.477037 CEST - Connecting to proxy 194.177.207.42:11122 2026-05-07 08:06:23.217984 CEST - Connecting to 10.64.45.222:22 over proxy 2026-05-07 08:06:24.715815 CEST - SSH Test output: 2026-05-07 08:06:24.715863 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-07 08:06:24.715914 CEST - Uptime: 06:06:24 up 1 min, 1 user, load average: 1.05, 0.59, 0.23 2026-05-07 08:06:25.021465 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-07 08:06:25.021532 CEST - lsb_release matches expected value 2026-05-07 08:06:25.021546 CEST - SSH Test passed. 2026-05-07 08:06:25.329532 CEST - Cloud-init user-data: Hello World. The time is now Thu, 07 May 2026 06:05:59 +0000! 2026-05-07 08:06:25.335872 CEST - Destroying tst-ba9527f0 tst 2026-05-07 08:06:25.336049 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-ba9527f0 tst