Run Test: slices-bi-singlenode-login 2026-03-30 00:03:12.521371 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-30 00:03:17.780776 CEST - Run: slices --version 2026-03-30 00:03:17.981166 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.4.1 Slices clientlib bi v5.1.2 2026-03-30 00:03:17.981410 CEST - Run: slices pubkey list --format text 2026-03-30 00:03:18.567842 CEST - Pubkey already registered 2026-03-30 00:03:18.568071 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-30 00:03:19.094694 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-03-30 00:03:19.094827 CEST - Check List Flavors 2026-03-30 00:03:19.095018 CEST - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-03-30 00:03:20.307271 CEST - Check List DiskImages 2026-03-30 00:03:20.307455 CEST - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-03-30 00:03:21.041848 CEST - Requesting resources 2026-03-30 00:03:21.042289 CEST - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-03-30 00:03:21.042381 CEST - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-c7f49e3c --user-data /tmp/tmp8ndoyvdx 2026-03-30 00:03:27.627833 CEST - Resource ID: r_gr-volos-bi-vm1_01kmxsz5a3ebgbae9jqpkw1c9e 2026-03-30 00:03:27.628015 CEST - Waiting until resource ready 2026-03-30 00:03:29.628426 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-c7f49e3c tst 2026-03-30 00:03:30.469611 CEST - Status: IMAGING 2026-03-30 00:03:33.304553 CEST - Status: IMAGING 2026-03-30 00:03:36.122206 CEST - Status: IMAGING 2026-03-30 00:03:38.933465 CEST - Status: BOOTING 2026-03-30 00:03:41.761605 CEST - Status: BOOTING 2026-03-30 00:03:44.572185 CEST - Status: BOOTING 2026-03-30 00:03:47.381620 CEST - Status: BOOTING 2026-03-30 00:03:50.191466 CEST - Status: BOOTING 2026-03-30 00:03:53.035485 CEST - Status: BOOTING 2026-03-30 00:03:55.860750 CEST - Status: BOOTING 2026-03-30 00:03:58.738643 CEST - Status: BOOTING 2026-03-30 00:04:01.651133 CEST - Status: BOOTING 2026-03-30 00:04:04.495740 CEST - Status: BOOTING 2026-03-30 00:04:07.360295 CEST - Status: BOOTING 2026-03-30 00:04:10.198010 CEST - Status: BOOTING 2026-03-30 00:04:13.021738 CEST - Status: BOOTING 2026-03-30 00:04:15.858883 CEST - Status: BOOTING 2026-03-30 00:04:18.705048 CEST - Status: BOOTING 2026-03-30 00:04:21.523238 CEST - Status: BOOTING 2026-03-30 00:04:24.332581 CEST - Status: BOOTING 2026-03-30 00:04:27.142519 CEST - Status: BOOTING 2026-03-30 00:04:29.959456 CEST - Status: BOOTING 2026-03-30 00:04:32.773657 CEST - Status: BOOTING 2026-03-30 00:04:35.601917 CEST - Status: BOOTING 2026-03-30 00:04:38.424213 CEST - Status: BOOTING 2026-03-30 00:04:41.243026 CEST - Status: BOOTING 2026-03-30 00:04:44.109764 CEST - Status: BOOTING 2026-03-30 00:04:46.927333 CEST - Status: BOOTING 2026-03-30 00:04:49.746782 CEST - Status: BOOTING 2026-03-30 00:04:52.572907 CEST - Status: BOOTING 2026-03-30 00:04:55.389632 CEST - Status: BOOTING 2026-03-30 00:04:58.203848 CEST - Status: BOOTING 2026-03-30 00:05:01.059249 CEST - Status: BOOTING 2026-03-30 00:05:03.890355 CEST - Status: BOOTING 2026-03-30 00:05:06.716176 CEST - Status: BOOTING 2026-03-30 00:05:09.523260 CEST - Status: BOOTING 2026-03-30 00:05:12.328895 CEST - Status: BOOTING 2026-03-30 00:05:15.136937 CEST - Status: BOOTING 2026-03-30 00:05:17.948279 CEST - Status: BOOTING 2026-03-30 00:05:20.759699 CEST - Status: BOOTING 2026-03-30 00:05:23.589350 CEST - Status: BOOTING 2026-03-30 00:05:26.400314 CEST - Status: BOOTING 2026-03-30 00:05:29.211577 CEST - Status: BOOTING 2026-03-30 00:05:32.036506 CEST - Status: BOOTING 2026-03-30 00:05:34.839618 CEST - Status: BOOTING 2026-03-30 00:05:37.748907 CEST - Status: UP 2026-03-30 00:05:37.748965 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kmxsz044e9xttvh1xgps53xb 2026-03-30 00:05:37.749005 CEST - Validate resources 2026-03-30 00:05:38.641875 CEST - The fields of the created resource were validated. 2026-03-30 00:05:38.641938 CEST - Check if resources are registered in experiment 2026-03-30 00:05:38.642103 CEST - Run: slices experiment list-resources --format json tst-c7f49e3c 2026-03-30 00:05:39.297653 CEST - Status (on expauth): UP 2026-03-30 00:05:39.297781 CEST - Testing extend expires_at (all resources in experiment) 2026-03-30 00:05:39.297938 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-c7f49e3c 2026-03-30 00:05:41.618574 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-c7f49e3c tst 2026-03-30 00:05:42.450979 CEST - Testing extend expires_at (single resource in experiment) 2026-03-30 00:05:42.451162 CEST - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-c7f49e3c 2026-03-30 00:05:44.726679 CEST - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-c7f49e3c tst 2026-03-30 00:05:45.567969 CEST - Testing ssh login 2026-03-30 00:05:45.578502 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c7f49e3c tst 2026-03-30 00:05:46.553013 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c7f49e3c tst 2026-03-30 00:05:47.497445 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c7f49e3c tst 2026-03-30 00:05:48.450815 CEST - Logging in using 'slices bi ssh' 2026-03-30 00:05:48.450872 CEST - Forcing IPv4 only. 2026-03-30 00:05:48.451059 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-c7f49e3c tst -- -4 uname -a 2026-03-30 00:05:53.681648 CEST - Forcing IPv4 only. 2026-03-30 00:05:53.681793 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-c7f49e3c tst -- -4 uptime 2026-03-30 00:05:56.212249 CEST - CLI SSH Test passed. 2026-03-30 00:05:56.212290 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-03-30 00:05:56.212301 CEST - Uptime: 22:05:56 up 2 min, 1 user, load average: 0.88, 0.42, 0.16 2026-03-30 00:05:56.212325 CEST - Logging in using SSH over SSH proxy 2026-03-30 00:05:56.212478 CEST - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c7f49e3c tst 2026-03-30 00:05:57.282864 CEST - Added paramiko HostKeyEntry for 10.64.45.148 2026-03-30 00:05:57.282956 CEST - Added paramiko HostKeyEntry for 10.64.45.148 2026-03-30 00:05:57.283086 CEST - Added paramiko HostKeyEntry for 10.64.45.148 2026-03-30 00:05:57.283340 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-30 00:05:57.283415 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-30 00:05:57.283450 CEST - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-03-30 00:05:57.283478 CEST - Connecting to proxy 194.177.207.42:11122 2026-03-30 00:05:58.021628 CEST - Connecting to 10.64.45.148:22 over proxy 2026-03-30 00:05:59.494978 CEST - SSH Test output: 2026-03-30 00:05:59.495018 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-03-30 00:05:59.495031 CEST - Uptime: 22:05:59 up 2 min, 1 user, load average: 0.81, 0.41, 0.16 2026-03-30 00:05:59.797417 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-03-30 00:05:59.797491 CEST - lsb_release matches expected value 2026-03-30 00:05:59.797525 CEST - SSH Test passed. 2026-03-30 00:06:00.106624 CEST - Cloud-init user-data: Hello World. The time is now Sun, 29 Mar 2026 22:05:32 +0000! 2026-03-30 00:06:00.113008 CEST - Destroying tst-c7f49e3c tst 2026-03-30 00:06:00.113170 CEST - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-c7f49e3c tst