Run Test: slices-bi-singlenode-login 2026-04-28 09:43:30.022810 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-28 09:43:33.293468 CEST - Run: slices --version 2026-04-28 09:43:33.507884 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-04-28 09:43:33.508126 CEST - Run: slices pubkey list --format text 2026-04-28 09:43:34.022929 CEST - Pubkey already registered 2026-04-28 09:43:34.023160 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-28 09:43:34.538156 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-28 09:43:34.538277 CEST - Check List Flavors 2026-04-28 09:43:34.538438 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-28 09:43:39.713142 CEST - Step 'List Flavors' took 5.17 seconds, which is longer than the warning threshold of 5 seconds 2026-04-28 09:43:39.713242 CEST - Check List DiskImages 2026-04-28 09:43:39.713412 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-28 09:43:40.428806 CEST - Requesting resources 2026-04-28 09:43:40.429317 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-28 09:43:40.429408 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-d66a3708 --user-data /tmp/tmp2iwxwjd9 2026-04-28 09:43:43.098315 CEST - Resource ID: r_gr-volos-bi-vm1_01kq9gpfr6f6hrv7gam1x3wzf8 2026-04-28 09:43:43.098465 CEST - Waiting until resource ready 2026-04-28 09:43:45.098853 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-d66a3708 tst 2026-04-28 09:43:45.914357 CEST - Status: IMAGING 2026-04-28 09:43:48.730465 CEST - Status: IMAGING 2026-04-28 09:43:51.546559 CEST - Status: IMAGING 2026-04-28 09:43:54.362482 CEST - Status: BOOTING 2026-04-28 09:43:57.178431 CEST - Status: BOOTING 2026-04-28 09:43:59.994177 CEST - Status: BOOTING 2026-04-28 09:44:02.810061 CEST - Status: BOOTING 2026-04-28 09:44:05.625887 CEST - Status: BOOTING 2026-04-28 09:44:08.441866 CEST - Status: BOOTING 2026-04-28 09:44:11.257817 CEST - Status: BOOTING 2026-04-28 09:44:14.074003 CEST - Status: BOOTING 2026-04-28 09:44:16.940339 CEST - Status: BOOTING 2026-04-28 09:44:19.756267 CEST - Status: BOOTING 2026-04-28 09:44:22.572308 CEST - Status: BOOTING 2026-04-28 09:44:25.438526 CEST - Status: BOOTING 2026-04-28 09:44:28.254333 CEST - Status: BOOTING 2026-04-28 09:44:31.070320 CEST - Status: BOOTING 2026-04-28 09:44:33.936331 CEST - Status: BOOTING 2026-04-28 09:44:36.802343 CEST - Status: BOOTING 2026-04-28 09:44:39.668561 CEST - Status: BOOTING 2026-04-28 09:44:42.484626 CEST - Status: BOOTING 2026-04-28 09:44:45.300491 CEST - Status: BOOTING 2026-04-28 09:44:48.116741 CEST - Status: BOOTING 2026-04-28 09:44:50.932706 CEST - Status: BOOTING 2026-04-28 09:44:53.748576 CEST - Status: BOOTING 2026-04-28 09:44:56.564612 CEST - Status: BOOTING 2026-04-28 09:44:59.380555 CEST - Status: BOOTING 2026-04-28 09:45:02.196611 CEST - Status: BOOTING 2026-04-28 09:45:05.012660 CEST - Status: BOOTING 2026-04-28 09:45:07.878785 CEST - Status: BOOTING 2026-04-28 09:45:10.744924 CEST - Status: BOOTING 2026-04-28 09:45:13.561002 CEST - Status: BOOTING 2026-04-28 09:45:16.376934 CEST - Status: BOOTING 2026-04-28 09:45:19.193069 CEST - Status: BOOTING 2026-04-28 09:45:22.008939 CEST - Status: BOOTING 2026-04-28 09:45:24.824829 CEST - Status: BOOTING 2026-04-28 09:45:27.640754 CEST - Status: BOOTING 2026-04-28 09:45:30.506764 CEST - Status: BOOTING 2026-04-28 09:45:33.322564 CEST - Status: UP 2026-04-28 09:45:33.322629 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kq9gpe9yek5stbb2ras6q7a4 2026-04-28 09:45:33.322672 CEST - Validate resources 2026-04-28 09:45:34.138380 CEST - The fields of the created resource were validated. 2026-04-28 09:45:34.138454 CEST - Check if resources are registered in experiment 2026-04-28 09:45:34.138610 CEST - Run: slices experiment list-resources --format json tst-d66a3708 2026-04-28 09:45:34.703483 CEST - Status (on expauth): UP 2026-04-28 09:45:34.703619 CEST - Testing extend expires_at (all resources in experiment) 2026-04-28 09:45:34.703737 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-d66a3708 2026-04-28 09:45:37.122368 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-d66a3708 tst 2026-04-28 09:45:37.937826 CEST - Testing extend expires_at (single resource in experiment) 2026-04-28 09:45:37.937999 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-d66a3708 2026-04-28 09:45:40.957942 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-d66a3708 tst 2026-04-28 09:45:41.873928 CEST - Testing ssh login 2026-04-28 09:45:41.884578 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d66a3708 tst 2026-04-28 09:45:42.850486 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d66a3708 tst 2026-04-28 09:45:43.766641 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-d66a3708 tst 2026-04-28 09:45:44.682576 CEST - Logging in using 'slices bi ssh' 2026-04-28 09:45:44.682657 CEST - Forcing IPv4 only. 2026-04-28 09:45:44.682797 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-d66a3708 tst -- -4 uname -a 2026-04-28 09:45:47.852922 CEST - Forcing IPv4 only. 2026-04-28 09:45:47.853081 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-d66a3708 tst -- -4 uptime 2026-04-28 09:45:50.321823 CEST - CLI SSH Test passed. 2026-04-28 09:45:50.321876 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-04-28 09:45:50.321889 CEST - Uptime: 07:45:50 up 1 min, 2 users, load average: 1.33, 0.70, 0.27 2026-04-28 09:45:50.321918 CEST - Logging in using SSH over SSH proxy 2026-04-28 09:45:50.322064 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-d66a3708 tst 2026-04-28 09:45:51.288378 CEST - Added paramiko HostKeyEntry for 10.64.45.225 2026-04-28 09:45:51.288487 CEST - Added paramiko HostKeyEntry for 10.64.45.225 2026-04-28 09:45:51.288626 CEST - Added paramiko HostKeyEntry for 10.64.45.225 2026-04-28 09:45:51.288889 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-28 09:45:51.288969 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-28 09:45:51.289008 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-28 09:45:51.289019 CEST - Connecting to proxy 194.177.207.42:11122 2026-04-28 09:45:52.025182 CEST - Connecting to 10.64.45.225:22 over proxy 2026-04-28 09:45:53.427363 CEST - SSH Test output: 2026-04-28 09:45:53.427411 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-04-28 09:45:53.427423 CEST - Uptime: 07:45:53 up 1 min, 1 user, load average: 1.33, 0.70, 0.27 2026-04-28 09:45:53.732056 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-04-28 09:45:53.732111 CEST - lsb_release matches expected value 2026-04-28 09:45:53.732124 CEST - SSH Test passed. 2026-04-28 09:45:54.035906 CEST - Cloud-init user-data: Hello World. The time is now Tue, 28 Apr 2026 07:45:28 +0000! 2026-04-28 09:45:54.042249 CEST - Destroying tst-d66a3708 tst 2026-04-28 09:45:54.042417 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-d66a3708 tst