Run Test: slices-bi-singlenode-login 2026-05-11 08:02:56.883572 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-11 08:03:00.104706 CEST - Run: slices --version 2026-05-11 08:03:00.319280 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-11 08:03:00.319560 CEST - Run: slices pubkey list --format text 2026-05-11 08:03:00.884592 CEST - Pubkey already registered 2026-05-11 08:03:00.884836 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-11 08:03:01.450131 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-11 08:03:01.450296 CEST - Check List Flavors 2026-05-11 08:03:01.450551 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-05-11 08:03:05.524642 CEST - Check List DiskImages 2026-05-11 08:03:05.524846 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-05-11 08:03:06.290379 CEST - Requesting resources 2026-05-11 08:03:06.290843 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-05-11 08:03:06.290962 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b516b0e1 --user-data /tmp/tmpaqekq0mr 2026-05-11 08:03:12.419834 CEST - Resource ID: r_gr-volos-bi-vm1_01krat3sehe48r0jnzayqh168m 2026-05-11 08:03:12.419960 CEST - Waiting until resource ready 2026-05-11 08:03:14.420342 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b516b0e1 tst 2026-05-11 08:03:15.336523 CEST - Status: IMAGING 2026-05-11 08:03:18.202721 CEST - Status: IMAGING 2026-05-11 08:03:21.018979 CEST - Status: IMAGING 2026-05-11 08:03:23.835001 CEST - Status: IMAGING 2026-05-11 08:03:26.651212 CEST - Status: BOOTING 2026-05-11 08:03:29.568156 CEST - Status: BOOTING 2026-05-11 08:03:32.434874 CEST - Status: BOOTING 2026-05-11 08:03:35.351275 CEST - Status: BOOTING 2026-05-11 08:03:38.217963 CEST - Status: BOOTING 2026-05-11 08:03:41.034177 CEST - Status: BOOTING 2026-05-11 08:03:43.900448 CEST - Status: BOOTING 2026-05-11 08:03:46.816928 CEST - Status: BOOTING 2026-05-11 08:03:49.633782 CEST - Status: BOOTING 2026-05-11 08:03:52.500465 CEST - Status: BOOTING 2026-05-11 08:03:55.417525 CEST - Status: BOOTING 2026-05-11 08:03:58.334690 CEST - Status: BOOTING 2026-05-11 08:04:01.201039 CEST - Status: BOOTING 2026-05-11 08:04:04.017176 CEST - Status: BOOTING 2026-05-11 08:04:06.833325 CEST - Status: BOOTING 2026-05-11 08:04:09.749843 CEST - Status: BOOTING 2026-05-11 08:04:12.666756 CEST - Status: BOOTING 2026-05-11 08:04:15.533483 CEST - Status: BOOTING 2026-05-11 08:04:18.400092 CEST - Status: BOOTING 2026-05-11 08:04:21.266954 CEST - Status: BOOTING 2026-05-11 08:04:24.083233 CEST - Status: BOOTING 2026-05-11 08:04:26.899275 CEST - Status: BOOTING 2026-05-11 08:04:29.765503 CEST - Status: BOOTING 2026-05-11 08:04:32.632142 CEST - Status: BOOTING 2026-05-11 08:04:35.549125 CEST - Status: BOOTING 2026-05-11 08:04:38.465756 CEST - Status: BOOTING 2026-05-11 08:04:41.332077 CEST - Status: BOOTING 2026-05-11 08:04:44.148764 CEST - Status: BOOTING 2026-05-11 08:04:47.065300 CEST - Status: BOOTING 2026-05-11 08:04:49.981543 CEST - Status: BOOTING 2026-05-11 08:04:52.797719 CEST - Status: BOOTING 2026-05-11 08:04:55.714068 CEST - Status: BOOTING 2026-05-11 08:04:58.580607 CEST - Status: BOOTING 2026-05-11 08:05:01.547812 CEST - Status: BOOTING 2026-05-11 08:05:04.364398 CEST - Status: UP 2026-05-11 08:05:04.364475 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01krat3nctfqwsd4k45z9mt9cy 2026-05-11 08:05:04.364517 CEST - Validate resources 2026-05-11 08:05:05.231020 CEST - The fields of the created resource were validated. 2026-05-11 08:05:05.231115 CEST - Check if resources are registered in experiment 2026-05-11 08:05:05.231277 CEST - Run: slices experiment list-resources --format json tst-b516b0e1 2026-05-11 08:05:05.846592 CEST - Status (on expauth): UP 2026-05-11 08:05:05.846733 CEST - Testing extend expires_at (all resources in experiment) 2026-05-11 08:05:05.846862 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-b516b0e1 2026-05-11 08:05:09.068653 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b516b0e1 tst 2026-05-11 08:05:09.884276 CEST - Testing extend expires_at (single resource in experiment) 2026-05-11 08:05:09.884465 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-b516b0e1 2026-05-11 08:05:13.055649 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b516b0e1 tst 2026-05-11 08:05:13.921612 CEST - Testing ssh login 2026-05-11 08:05:13.932328 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b516b0e1 tst 2026-05-11 08:05:14.898464 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b516b0e1 tst 2026-05-11 08:05:15.865048 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b516b0e1 tst 2026-05-11 08:05:16.831381 CEST - Logging in using 'slices bi ssh' 2026-05-11 08:05:16.831446 CEST - Forcing IPv4 only. 2026-05-11 08:05:16.831566 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b516b0e1 tst -- -4 uname -a 2026-05-11 08:05:21.354966 CEST - Forcing IPv4 only. 2026-05-11 08:05:21.355126 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b516b0e1 tst -- -4 uptime 2026-05-11 08:05:23.924941 CEST - CLI SSH Test passed. 2026-05-11 08:05:23.924997 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-11 08:05:23.925012 CEST - Uptime: 06:05:23 up 1 min, 1 user, load average: 0.63, 0.27, 0.10 2026-05-11 08:05:23.925039 CEST - Logging in using SSH over SSH proxy 2026-05-11 08:05:23.925187 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b516b0e1 tst 2026-05-11 08:05:24.891872 CEST - Added paramiko HostKeyEntry for 10.64.45.100 2026-05-11 08:05:24.891975 CEST - Added paramiko HostKeyEntry for 10.64.45.100 2026-05-11 08:05:24.892111 CEST - Added paramiko HostKeyEntry for 10.64.45.100 2026-05-11 08:05:24.892371 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-11 08:05:24.892456 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-11 08:05:24.892493 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-05-11 08:05:24.892505 CEST - Connecting to proxy 194.177.207.42:11122 2026-05-11 08:05:25.645997 CEST - Connecting to 10.64.45.100:22 over proxy 2026-05-11 08:05:27.284891 CEST - SSH Test output: 2026-05-11 08:05:27.284960 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-11 08:05:27.284973 CEST - Uptime: 06:05:26 up 1 min, 1 user, load average: 0.63, 0.27, 0.10 2026-05-11 08:05:27.618007 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-11 08:05:27.618080 CEST - lsb_release matches expected value 2026-05-11 08:05:27.618097 CEST - SSH Test passed. 2026-05-11 08:05:27.935994 CEST - Cloud-init user-data: Hello World. The time is now Mon, 11 May 2026 06:04:59 +0000! 2026-05-11 08:05:27.942421 CEST - Destroying tst-b516b0e1 tst 2026-05-11 08:05:27.942588 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-b516b0e1 tst