Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 12.95s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 231ms warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance. If the cache and target directories are on different filesystems, hardlinking may not be supported. If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning. Installed 32 packages in 588ms + annotated-types==0.7.0 + anyio==4.14.1 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + cffi==2.1.0 + cryptography==49.0.0 + dnspython==2.8.0 + h11==0.16.0 + httpcore==1.0.9 + httpx==0.28.1 + httpx-oauth==0.17.0 + idna==3.18 + invoke==3.0.3 + joserfc==1.7.3 + msgspec==0.21.1 + packaging==26.2 + paramiko==4.0.0 + pycparser==3.0 + pydantic==2.13.4 + pydantic-core==2.46.4 + pyjwt==2.13.0 + pynacl==1.6.2 + pytz==2026.2 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev179+g419876b58 (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-08 22:07:35.582630 UTC - Installing Slices CLI (latest stable version). 2026-07-08 22:07:35.583096 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-08 22:08:15.699015 UTC - Slices CLI installed. 2026-07-08 22:08:15.699271 UTC - Slices CLI exe: 'slices' 2026-07-08 22:08:15.699303 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-08 22:08:15.699460 UTC - Run: slices --version 2026-07-08 22:08:18.687831 UTC - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-07-08 22:08:18.688307 UTC - Run: slices pubkey list --format text 2026-07-08 22:08:19.704461 UTC - Pubkey already registered 2026-07-08 22:08:19.704748 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-08 22:08:20.748983 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-08 22:08:20.749087 UTC - Check List Flavors 2026-07-08 22:08:20.749242 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-08 22:08:23.073896 UTC - Check List DiskImages 2026-07-08 22:08:23.074202 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-08 22:08:24.677109 UTC - Requesting resources 2026-07-08 22:08:24.677705 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-08 22:08:24.677908 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-c1fb1536 --user-data /tmp/tmpwd0i3unq 2026-07-08 22:08:33.867125 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kx1wd3atfkgvmaxy02dadg43 2026-07-08 22:08:33.867274 UTC - Waiting until resource ready 2026-07-08 22:08:35.867607 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c1fb1536 tst 2026-07-08 22:08:36.683144 UTC - Status: IMAGING 2026-07-08 22:08:39.557968 UTC - Status: BOOTING 2026-07-08 22:08:42.381376 UTC - Status: BOOTING 2026-07-08 22:08:45.497637 UTC - Status: BOOTING 2026-07-08 22:08:48.320518 UTC - Status: BOOTING 2026-07-08 22:08:51.136405 UTC - Status: BOOTING 2026-07-08 22:08:54.429923 UTC - Status: BOOTING 2026-07-08 22:08:57.245882 UTC - Status: UP 2026-07-08 22:08:57.245972 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx1wd15te76tfn2xynaqsbsw 2026-07-08 22:08:57.246014 UTC - Validate resources 2026-07-08 22:08:58.165611 UTC - The fields of the created resource were validated. 2026-07-08 22:08:58.165699 UTC - Check if resources are registered in experiment 2026-07-08 22:08:58.165868 UTC - Run: slices experiment list-resources --format json tst-c1fb1536 2026-07-08 22:08:59.215556 UTC - Status (on expauth): UP 2026-07-08 22:08:59.215704 UTC - Testing extend expires_at (all resources in experiment) 2026-07-08 22:08:59.215830 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-c1fb1536 2026-07-08 22:09:02.197375 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c1fb1536 tst 2026-07-08 22:09:03.513939 UTC - Run: slices experiment list-resources --format json tst-c1fb1536 2026-07-08 22:09:04.432359 UTC - expires_at (on expauth): 2026-07-09T01:08:00Z (correctly extended) 2026-07-08 22:09:04.432450 UTC - Testing extend expires_at (single resource in experiment) 2026-07-08 22:09:04.432588 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-c1fb1536 2026-07-08 22:09:08.254611 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c1fb1536 tst 2026-07-08 22:09:09.221805 UTC - Run: slices experiment list-resources --format json tst-c1fb1536 2026-07-08 22:09:10.304687 UTC - expires_at (on expauth): 2026-07-09T02:09:00Z (correctly extended) 2026-07-08 22:09:10.304876 UTC - Testing ssh login 2026-07-08 22:09:10.324955 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c1fb1536 tst 2026-07-08 22:09:11.627311 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c1fb1536 tst 2026-07-08 22:09:12.593255 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c1fb1536 tst 2026-07-08 22:09:13.559137 UTC - Logging in using 'slices bi ssh' 2026-07-08 22:09:13.559211 UTC - Forcing IPv4 only. 2026-07-08 22:09:13.559371 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-c1fb1536 tst -- -4 uname -a 2026-07-08 22:09:16.060614 UTC - Forcing IPv4 only. 2026-07-08 22:09:16.060807 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-c1fb1536 tst -- -4 uptime 2026-07-08 22:09:18.355424 UTC - CLI SSH Test passed. 2026-07-08 22:09:18.355500 UTC - 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-07-08 22:09:18.355518 UTC - Uptime: 22:09:17 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-08 22:09:18.355549 UTC - Logging in using SSH over SSH proxy 2026-07-08 22:09:18.355717 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c1fb1536 tst 2026-07-08 22:09:19.737536 UTC - Added paramiko HostKeyEntry for 10.123.152.131 2026-07-08 22:09:19.737729 UTC - Added paramiko HostKeyEntry for 10.123.152.131 2026-07-08 22:09:19.737789 UTC - Added paramiko HostKeyEntry for 10.123.152.131 2026-07-08 22:09:19.738110 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 22:09:19.738204 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 22:09:19.738247 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 22:09:19.738266 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-08 22:09:20.124146 UTC - Connecting to 10.123.152.131:22 over proxy 2026-07-08 22:09:20.782084 UTC - SSH Test output: 2026-07-08 22:09:20.782153 UTC - 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-07-08 22:09:20.782174 UTC - Uptime: 22:09:19 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-08 22:09:20.917565 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-08 22:09:20.917641 UTC - lsb_release matches expected value 2026-07-08 22:09:20.917668 UTC - SSH Test passed. 2026-07-08 22:09:21.055540 UTC - Cloud-init user-data: Hello World. The time is now Wed, 08 Jul 2026 22:08:55 +0000! 2026-07-08 22:09:21.072353 UTC - Destroying tst-c1fb1536 tst 2026-07-08 22:09:21.072527 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-c1fb1536 tst