Run Test: slices-bi-singlenode-login 2026-04-01 08:03:18.868482 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-04-01 08:03:22.029190 CEST - Run: slices --version 2026-04-01 08:03:22.230152 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.0 Slices clientlib bi v5.1.2 2026-04-01 08:03:22.230398 CEST - Run: slices pubkey list --format text 2026-04-01 08:03:22.817140 CEST - Pubkey already registered 2026-04-01 08:03:22.817352 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-04-01 08:03:23.359987 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-04-01 08:03:23.360120 CEST - Check List Flavors 2026-04-01 08:03:23.360291 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-04-01 08:03:24.342027 CEST - Check List DiskImages 2026-04-01 08:03:24.342208 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-04-01 08:03:25.101454 CEST - Requesting resources 2026-04-01 08:03:25.101915 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-04-01 08:03:25.102020 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ff832cb5 --user-data /tmp/tmpm_gzurv8 2026-04-01 08:03:30.209147 CEST - Resource ID: r_gr-volos-bi-vm1_01kn3t7jtdej9rmz0qrt12z20g 2026-04-01 08:03:30.209254 CEST - Waiting until resource ready 2026-04-01 08:03:32.209740 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ff832cb5 tst 2026-04-01 08:03:33.044087 CEST - Status: IMAGING 2026-04-01 08:03:35.869288 CEST - Status: IMAGING 2026-04-01 08:03:38.725103 CEST - Status: IMAGING 2026-04-01 08:03:41.560584 CEST - Status: BOOTING 2026-04-01 08:03:44.394849 CEST - Status: BOOTING 2026-04-01 08:03:47.208966 CEST - Status: BOOTING 2026-04-01 08:03:50.047851 CEST - Status: BOOTING 2026-04-01 08:03:52.885021 CEST - Status: BOOTING 2026-04-01 08:03:55.726316 CEST - Status: BOOTING 2026-04-01 08:03:58.548245 CEST - Status: BOOTING 2026-04-01 08:04:01.387217 CEST - Status: BOOTING 2026-04-01 08:04:04.209190 CEST - Status: BOOTING 2026-04-01 08:04:07.040461 CEST - Status: BOOTING 2026-04-01 08:04:09.891971 CEST - Status: BOOTING 2026-04-01 08:04:12.754739 CEST - Status: BOOTING 2026-04-01 08:04:15.684379 CEST - Status: BOOTING 2026-04-01 08:04:18.523724 CEST - Status: BOOTING 2026-04-01 08:04:21.364588 CEST - Status: BOOTING 2026-04-01 08:04:24.240057 CEST - Status: BOOTING 2026-04-01 08:04:27.125010 CEST - Status: BOOTING 2026-04-01 08:04:29.954090 CEST - Status: BOOTING 2026-04-01 08:04:32.769426 CEST - Status: BOOTING 2026-04-01 08:04:35.620005 CEST - Status: BOOTING 2026-04-01 08:04:38.467557 CEST - Status: BOOTING 2026-04-01 08:04:41.298292 CEST - Status: BOOTING 2026-04-01 08:04:44.134193 CEST - Status: BOOTING 2026-04-01 08:04:46.959047 CEST - Status: BOOTING 2026-04-01 08:04:49.787726 CEST - Status: BOOTING 2026-04-01 08:04:52.617703 CEST - Status: BOOTING 2026-04-01 08:04:55.471948 CEST - Status: BOOTING 2026-04-01 08:04:58.313158 CEST - Status: BOOTING 2026-04-01 08:05:01.148556 CEST - Status: BOOTING 2026-04-01 08:05:03.983482 CEST - Status: BOOTING 2026-04-01 08:05:06.801799 CEST - Status: BOOTING 2026-04-01 08:05:09.631571 CEST - Status: BOOTING 2026-04-01 08:05:12.468514 CEST - Status: BOOTING 2026-04-01 08:05:15.356839 CEST - Status: BOOTING 2026-04-01 08:05:18.204648 CEST - Status: BOOTING 2026-04-01 08:05:21.068189 CEST - Status: UP 2026-04-01 08:05:21.068238 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kn3t7ey0ez4swbxmgk7yw1yk 2026-04-01 08:05:21.068270 CEST - Validate resources 2026-04-01 08:05:21.891078 CEST - The fields of the created resource were validated. 2026-04-01 08:05:21.891143 CEST - Check if resources are registered in experiment 2026-04-01 08:05:21.891296 CEST - Run: slices experiment list-resources --format json tst-ff832cb5 2026-04-01 08:05:22.528077 CEST - Status (on expauth): UP 2026-04-01 08:05:22.528235 CEST - Testing extend expires_at (all resources in experiment) 2026-04-01 08:05:22.528366 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-ff832cb5 2026-04-01 08:05:24.884981 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ff832cb5 tst 2026-04-01 08:05:25.723662 CEST - Testing extend expires_at (single resource in experiment) 2026-04-01 08:05:25.723827 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-ff832cb5 2026-04-01 08:05:28.043370 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ff832cb5 tst 2026-04-01 08:05:28.879218 CEST - Testing ssh login 2026-04-01 08:05:28.888929 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ff832cb5 tst 2026-04-01 08:05:29.844018 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ff832cb5 tst 2026-04-01 08:05:30.789640 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ff832cb5 tst 2026-04-01 08:05:31.732309 CEST - Logging in using 'slices bi ssh' 2026-04-01 08:05:31.732362 CEST - Forcing IPv4 only. 2026-04-01 08:05:31.732499 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ff832cb5 tst -- -4 uname -a 2026-04-01 08:05:35.773552 CEST - Forcing IPv4 only. 2026-04-01 08:05:35.773703 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ff832cb5 tst -- -4 uptime 2026-04-01 08:05:38.372319 CEST - CLI SSH Test passed. 2026-04-01 08:05:38.372363 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-01 08:05:38.372373 CEST - Uptime: 06:05:38 up 1 min, 1 user, load average: 0.82, 0.35, 0.13 2026-04-01 08:05:38.372401 CEST - Logging in using SSH over SSH proxy 2026-04-01 08:05:38.372546 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ff832cb5 tst 2026-04-01 08:05:39.352437 CEST - Added paramiko HostKeyEntry for 10.64.45.42 2026-04-01 08:05:39.352524 CEST - Added paramiko HostKeyEntry for 10.64.45.42 2026-04-01 08:05:39.352654 CEST - Added paramiko HostKeyEntry for 10.64.45.42 2026-04-01 08:05:39.352924 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-01 08:05:39.352997 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-01 08:05:39.353028 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-04-01 08:05:39.353038 CEST - Connecting to proxy 194.177.207.42:11122 2026-04-01 08:05:40.099585 CEST - Connecting to 10.64.45.42:22 over proxy 2026-04-01 08:05:41.609914 CEST - SSH Test output: 2026-04-01 08:05:41.609955 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-01 08:05:41.609965 CEST - Uptime: 06:05:41 up 1 min, 1 user, load average: 0.75, 0.35, 0.13 2026-04-01 08:05:41.914396 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-04-01 08:05:41.914447 CEST - lsb_release matches expected value 2026-04-01 08:05:41.914456 CEST - SSH Test passed. 2026-04-01 08:05:42.220009 CEST - Cloud-init user-data: Hello World. The time is now Wed, 01 Apr 2026 06:05:17 +0000! 2026-04-01 08:05:42.226448 CEST - Destroying tst-ff832cb5 tst 2026-04-01 08:05:42.226619 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-ff832cb5 tst