Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.63s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 296ms 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.07s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.4 + msgspec==0.21.1 + packaging==26.3 + 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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev192+gcac2d3fe6 (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-08-08 20:04:13.721402 UTC - Installing Slices CLI (latest stable version). 2026-08-08 20:04:13.721673 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 20:04:19.108505 UTC - Slices CLI installed. 2026-08-08 20:04:19.109015 UTC - OS release: PRETTY_NAME="Debian GNU/Linux 13 (trixie)" NAME="Debian GNU/Linux" VERSION_ID="13" VERSION="13 (trixie)" VERSION_CODENAME=trixie DEBIAN_VERSION_FULL=13.5 ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" 2026-08-08 20:04:19.113112 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-08 20:04:19.113228 UTC - env: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/:/root/slices-cli-venv/bin', 'COLUMNS': '200'} 2026-08-08 20:04:19.113310 UTC - which slices: /app/.venv/bin/slices 2026-08-08 20:04:19.113333 UTC - Slices CLI exe: 'slices' 2026-08-08 20:04:19.113349 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 20:04:19.113506 UTC - Run: slices --version 2026-08-08 20:04:22.138924 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-08 20:04:22.139252 UTC - Run: slices pubkey list --format text 2026-08-08 20:04:22.957576 UTC - Pubkey already registered 2026-08-08 20:04:22.957844 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 20:04:23.578253 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 20:04:23.578355 UTC - Check List Flavors 2026-08-08 20:04:23.578535 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-08-08 20:04:24.345516 UTC - Check List DiskImages 2026-08-08 20:04:24.345709 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-08-08 20:04:25.115670 UTC - Requesting resources 2026-08-08 20:04:25.116339 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 20:04:25.116648 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-52ed08e8 --user-data /tmp/tmpekbgaw6o 2026-08-08 20:04:26.652449 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kzhfm3mxe9sb3hf257mmnm8b 2026-08-08 20:04:26.652649 UTC - Waiting until resource ready 2026-08-08 20:04:28.653062 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-52ed08e8 tst 2026-08-08 20:04:29.779803 UTC - Status: IMAGING 2026-08-08 20:04:32.648091 UTC - Status: BOOTING 2026-08-08 20:04:35.522069 UTC - Status: BOOTING 2026-08-08 20:04:38.638615 UTC - Status: BOOTING 2026-08-08 20:04:41.562555 UTC - Status: BOOTING 2026-08-08 20:04:44.635538 UTC - Status: BOOTING 2026-08-08 20:04:47.859594 UTC - Status: BOOTING 2026-08-08 20:04:51.242011 UTC - Status: BOOTING 2026-08-08 20:04:54.281120 UTC - Status: UP 2026-08-08 20:04:54.281235 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzhfm36hfffb02737438dnpk 2026-08-08 20:04:54.281283 UTC - Validate resources 2026-08-08 20:04:55.400790 UTC - The fields of the created resource were validated. 2026-08-08 20:04:55.400901 UTC - Check if resources are registered in experiment 2026-08-08 20:04:55.401072 UTC - Run: slices experiment list-resources --format json tst-52ed08e8 2026-08-08 20:04:56.322892 UTC - Status (on expauth): UP 2026-08-08 20:04:56.323196 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 20:04:56.323341 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-52ed08e8 2026-08-08 20:04:57.801229 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-52ed08e8 tst 2026-08-08 20:04:58.968405 UTC - Run: slices experiment list-resources --format json tst-52ed08e8 2026-08-08 20:05:00.085899 UTC - expires_at (on expauth): 2026-08-08T23:04:00Z (correctly extended) 2026-08-08 20:05:00.086004 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 20:05:00.086157 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-52ed08e8 2026-08-08 20:05:01.510311 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-52ed08e8 tst 2026-08-08 20:05:02.377013 UTC - Run: slices experiment list-resources --format json tst-52ed08e8 2026-08-08 20:05:03.092533 UTC - expires_at (on expauth): 2026-08-09T00:05:00Z (correctly extended) 2026-08-08 20:05:03.092792 UTC - Testing ssh login 2026-08-08 20:05:03.107556 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-52ed08e8 tst 2026-08-08 20:05:04.230455 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-52ed08e8 tst 2026-08-08 20:05:05.246772 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-52ed08e8 tst 2026-08-08 20:05:06.416269 UTC - Logging in using 'slices bi ssh' 2026-08-08 20:05:06.416361 UTC - Forcing IPv4 only. 2026-08-08 20:05:06.416490 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-52ed08e8 tst -- -4 uname -a 2026-08-08 20:05:09.623751 UTC - Forcing IPv4 only. 2026-08-08 20:05:09.624150 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-52ed08e8 tst -- -4 uptime 2026-08-08 20:05:12.051905 UTC - CLI SSH Test passed. 2026-08-08 20:05:12.051998 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-08-08 20:05:12.052017 UTC - Uptime: 20:05:11 up 0 min, 1 user, load average: 0.69, 0.21, 0.07 2026-08-08 20:05:12.052050 UTC - Logging in using SSH over SSH proxy 2026-08-08 20:05:12.052203 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-52ed08e8 tst 2026-08-08 20:05:13.349873 UTC - Added paramiko HostKeyEntry for 172.28.10.3 2026-08-08 20:05:13.350070 UTC - Added paramiko HostKeyEntry for 172.28.10.3 2026-08-08 20:05:13.350130 UTC - Added paramiko HostKeyEntry for 172.28.10.3 2026-08-08 20:05:13.350416 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 20:05:13.350507 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 20:05:13.350551 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 20:05:13.350568 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-08 20:05:13.768525 UTC - Connecting to 172.28.10.3:22 over proxy 2026-08-08 20:05:14.466753 UTC - SSH Test output: 2026-08-08 20:05:14.466852 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-08-08 20:05:14.466885 UTC - Uptime: 20:05:13 up 0 min, 1 user, load average: 0.69, 0.21, 0.07 2026-08-08 20:05:14.601705 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 20:05:14.601780 UTC - lsb_release matches expected value 2026-08-08 20:05:14.601799 UTC - SSH Test passed. 2026-08-08 20:05:14.754303 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 20:04:49 +0000! 2026-08-08 20:05:14.764588 UTC - Destroying tst-52ed08e8 tst 2026-08-08 20:05:14.764875 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-52ed08e8 tst