Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 12.07s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 356ms 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 1.01s + 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-09 06:07:25.823411 UTC - Installing Slices CLI (latest stable version). 2026-07-09 06:07:25.823630 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 06:08:02.487297 UTC - Slices CLI installed. 2026-07-09 06:08:02.487550 UTC - Slices CLI exe: 'slices' 2026-07-09 06:08:02.487581 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 06:08:02.487737 UTC - Run: slices --version 2026-07-09 06:08:08.505350 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-09 06:08:08.505625 UTC - Run: slices pubkey list --format text 2026-07-09 06:08:10.464905 UTC - Pubkey already registered 2026-07-09 06:08:10.465155 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 06:08:11.943010 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 06:08:11.943106 UTC - Check List Flavors 2026-07-09 06:08:11.943285 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-09 06:08:13.921842 UTC - Check List DiskImages 2026-07-09 06:08:13.922020 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-09 06:08:15.516410 UTC - Requesting resources 2026-07-09 06:08:15.516958 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 06:08:15.517083 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-929301ed --user-data /tmp/tmp3cqmgyfa 2026-07-09 06:08:18.011266 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kx2qvgtme48sjpjt81y3evvt 2026-07-09 06:08:18.011429 UTC - Waiting until resource ready 2026-07-09 06:08:20.011742 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-929301ed tst 2026-07-09 06:08:21.314729 UTC - Status: IMAGING 2026-07-09 06:08:24.906518 UTC - Status: BOOTING 2026-07-09 06:08:27.828883 UTC - Status: BOOTING 2026-07-09 06:08:30.895905 UTC - Status: BOOTING 2026-07-09 06:08:34.448463 UTC - Status: BOOTING 2026-07-09 06:08:37.503585 UTC - Status: BOOTING 2026-07-09 06:08:40.543737 UTC - Status: BOOTING 2026-07-09 06:08:44.289110 UTC - Status: UP 2026-07-09 06:08:44.289282 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx2qvfzxep886wvs3beet51r 2026-07-09 06:08:44.290028 UTC - Validate resources 2026-07-09 06:08:45.265415 UTC - The fields of the created resource were validated. 2026-07-09 06:08:45.265517 UTC - Check if resources are registered in experiment 2026-07-09 06:08:45.265691 UTC - Run: slices experiment list-resources --format json tst-929301ed 2026-07-09 06:08:46.081517 UTC - Status (on expauth): UP 2026-07-09 06:08:46.081655 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 06:08:46.081781 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-929301ed 2026-07-09 06:08:48.253153 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-929301ed tst 2026-07-09 06:08:49.123817 UTC - Run: slices experiment list-resources --format json tst-929301ed 2026-07-09 06:08:49.940031 UTC - expires_at (on expauth): 2026-07-09T09:08:00Z (correctly extended) 2026-07-09 06:08:49.940126 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 06:08:49.940281 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-929301ed 2026-07-09 06:08:52.058485 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-929301ed tst 2026-07-09 06:08:53.358918 UTC - Run: slices experiment list-resources --format json tst-929301ed 2026-07-09 06:08:54.024216 UTC - expires_at (on expauth): 2026-07-09T10:08:00Z (correctly extended) 2026-07-09 06:08:54.024356 UTC - Testing ssh login 2026-07-09 06:08:54.035469 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-929301ed tst 2026-07-09 06:08:55.156561 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-929301ed tst 2026-07-09 06:08:56.559978 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-929301ed tst 2026-07-09 06:08:57.639265 UTC - Logging in using 'slices bi ssh' 2026-07-09 06:08:57.639361 UTC - Forcing IPv4 only. 2026-07-09 06:08:57.639524 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-929301ed tst -- -4 uname -a 2026-07-09 06:09:00.182797 UTC - Forcing IPv4 only. 2026-07-09 06:09:00.183031 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-929301ed tst -- -4 uptime 2026-07-09 06:09:01.901259 UTC - CLI SSH Test passed. 2026-07-09 06:09:01.901378 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-09 06:09:01.901400 UTC - Uptime: 06:09:01 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-09 06:09:01.901443 UTC - Logging in using SSH over SSH proxy 2026-07-09 06:09:01.901642 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-929301ed tst 2026-07-09 06:09:02.918451 UTC - Added paramiko HostKeyEntry for 10.123.24.139 2026-07-09 06:09:02.918659 UTC - Added paramiko HostKeyEntry for 10.123.24.139 2026-07-09 06:09:02.918719 UTC - Added paramiko HostKeyEntry for 10.123.24.139 2026-07-09 06:09:02.918996 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-09 06:09:02.919076 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-09 06:09:02.919173 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-09 06:09:02.919194 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-09 06:09:03.303827 UTC - Connecting to 10.123.24.139:22 over proxy 2026-07-09 06:09:03.961906 UTC - SSH Test output: 2026-07-09 06:09:03.961976 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-09 06:09:03.961997 UTC - Uptime: 06:09:03 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-09 06:09:04.096110 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-09 06:09:04.096184 UTC - lsb_release matches expected value 2026-07-09 06:09:04.096220 UTC - SSH Test passed. 2026-07-09 06:09:04.231688 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 06:08:40 +0000! 2026-07-09 06:09:04.240681 UTC - Destroying tst-929301ed tst 2026-07-09 06:09:04.240918 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-929301ed tst