Run Test: slices-bi-singlenode-login 2026-03-20 23:32:00.388774 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-20 23:32:03.523230 CET - Run: slices --version 2026-03-20 23:32:03.715412 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-20 23:32:03.715662 CET - Run: slices pubkey list --format text 2026-03-20 23:32:04.285651 CET - Pubkey already registered 2026-03-20 23:32:04.285881 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-20 23:32:04.808384 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-20 23:32:04.808489 CET - Check List Flavors 2026-03-20 23:32:04.808670 CET - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-03-20 23:32:05.525350 CET - Check List DiskImages 2026-03-20 23:32:05.525547 CET - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-03-20 23:32:06.212216 CET - Requesting resources 2026-03-20 23:32:06.212697 CET - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-20 23:32:06.212810 CET - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-a4505d52 --user-data /tmp/tmpn6efesqw 2026-03-20 23:32:08.121041 CET - Resource ID: r_fr-sophia2-bi-vm1_01km6p16gbe51v1834qk0d2ykt 2026-03-20 23:32:08.121156 CET - Waiting until resource ready 2026-03-20 23:32:10.121589 CET - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a4505d52 tst 2026-03-20 23:32:10.846197 CET - Status: IMAGING 2026-03-20 23:32:13.573950 CET - Status: IMAGING 2026-03-20 23:32:16.299887 CET - Status: IMAGING 2026-03-20 23:32:19.025228 CET - Status: IMAGING 2026-03-20 23:32:21.760604 CET - Status: IMAGING 2026-03-20 23:32:24.578920 CET - Status: IMAGING 2026-03-20 23:32:27.296614 CET - Status: IMAGING 2026-03-20 23:32:30.015454 CET - Status: BOOTING 2026-03-20 23:32:32.743304 CET - Status: BOOTING 2026-03-20 23:32:35.461944 CET - Status: BOOTING 2026-03-20 23:32:38.195868 CET - Status: BOOTING 2026-03-20 23:32:40.953498 CET - Status: BOOTING 2026-03-20 23:32:43.700818 CET - Status: BOOTING 2026-03-20 23:32:46.433127 CET - Status: BOOTING 2026-03-20 23:32:49.177075 CET - Status: BOOTING 2026-03-20 23:32:51.919238 CET - Status: BOOTING 2026-03-20 23:32:54.699190 CET - Status: BOOTING 2026-03-20 23:32:57.436143 CET - Status: BOOTING 2026-03-20 23:33:00.181188 CET - Status: BOOTING 2026-03-20 23:33:02.906831 CET - Status: BOOTING 2026-03-20 23:33:05.665830 CET - Status: BOOTING 2026-03-20 23:33:08.424981 CET - Status: BOOTING 2026-03-20 23:33:11.174362 CET - Status: BOOTING 2026-03-20 23:33:13.865003 CET - Status: UP 2026-03-20 23:33:13.865076 CET - Experiment ID: exp_expauth.ilabt.imec.be_01km6p15q6fd8s1988qwr8c336 2026-03-20 23:33:13.865109 CET - Validate resources 2026-03-20 23:33:14.558016 CET - The fields of the created resource were validated. 2026-03-20 23:33:14.558072 CET - Check if resources are registered in experiment 2026-03-20 23:33:14.558268 CET - Run: slices experiment list-resources --format json tst-a4505d52 2026-03-20 23:33:15.178909 CET - Status (on expauth): UP 2026-03-20 23:33:15.179038 CET - Testing extend expires_at (all resources in experiment) 2026-03-20 23:33:15.179169 CET - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-a4505d52 2026-03-20 23:33:16.912392 CET - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a4505d52 tst 2026-03-20 23:33:17.601396 CET - Testing extend expires_at (single resource in experiment) 2026-03-20 23:33:17.601574 CET - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-a4505d52 2026-03-20 23:33:19.290025 CET - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a4505d52 tst 2026-03-20 23:33:19.969497 CET - Testing ssh login 2026-03-20 23:33:19.979012 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a4505d52 tst 2026-03-20 23:33:20.781468 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a4505d52 tst 2026-03-20 23:33:21.579453 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-a4505d52 tst 2026-03-20 23:33:22.380506 CET - Logging in using 'slices bi ssh' 2026-03-20 23:33:22.380566 CET - Forcing IPv4 only. 2026-03-20 23:33:22.380706 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-a4505d52 tst -- -4 uname -a 2026-03-20 23:33:24.452862 CET - Forcing IPv4 only. 2026-03-20 23:33:24.453028 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-a4505d52 tst -- -4 uptime 2026-03-20 23:33:25.964491 CET - CLI SSH Test passed. 2026-03-20 23:33:25.964544 CET - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-03-20 23:33:25.964554 CET - Uptime: 22:33:25 up 0 min, 1 user, load average: 1.43, 0.48, 0.17 2026-03-20 23:33:25.964579 CET - Logging in using SSH over SSH proxy 2026-03-20 23:33:25.964783 CET - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-a4505d52 tst 2026-03-20 23:33:26.801835 CET - Added paramiko HostKeyEntry for 10.123.63.150 2026-03-20 23:33:26.801920 CET - Added paramiko HostKeyEntry for 10.123.63.150 2026-03-20 23:33:26.802046 CET - Added paramiko HostKeyEntry for 10.123.63.150 2026-03-20 23:33:26.802290 CET - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-03-20 23:33:26.802363 CET - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-03-20 23:33:26.802396 CET - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-03-20 23:33:26.802406 CET - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-03-20 23:33:27.192636 CET - Connecting to 10.123.63.150:22 over proxy 2026-03-20 23:33:27.886690 CET - SSH Test output: 2026-03-20 23:33:27.886739 CET - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-03-20 23:33:27.886750 CET - Uptime: 22:33:27 up 0 min, 1 user, load average: 1.43, 0.48, 0.17 2026-03-20 23:33:28.024915 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-03-20 23:33:28.024963 CET - lsb_release matches expected value 2026-03-20 23:33:28.024973 CET - SSH Test passed. 2026-03-20 23:33:28.162608 CET - Cloud-init user-data: Hello World. The time is now Fri, 20 Mar 2026 22:33:09 +0000! 2026-03-20 23:33:28.168866 CET - Destroying tst-a4505d52 tst 2026-03-20 23:33:28.169021 CET - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-a4505d52 tst