Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.53s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 691ms 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 567ms + 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-10 04:04:12.316742 UTC - Installing Slices CLI (latest stable version). 2026-08-10 04:04:12.317168 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 04:04:18.529342 UTC - Slices CLI installed. 2026-08-10 04:04:18.529911 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-10 04:04:18.535048 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-10 04:04:18.535149 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-10 04:04:18.535237 UTC - which slices: /app/.venv/bin/slices 2026-08-10 04:04:18.535259 UTC - Slices CLI exe: 'slices' 2026-08-10 04:04:18.535275 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 04:04:18.535411 UTC - Run: slices --version 2026-08-10 04:04:21.616406 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-10 04:04:21.616896 UTC - Run: slices pubkey list --format text 2026-08-10 04:04:22.435656 UTC - Pubkey already registered 2026-08-10 04:04:22.435922 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 04:04:23.226256 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 04:04:23.226657 UTC - Check List Flavors 2026-08-10 04:04:23.226861 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-08-10 04:04:24.095783 UTC - Check List DiskImages 2026-08-10 04:04:24.095961 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-08-10 04:04:24.863024 UTC - Requesting resources 2026-08-10 04:04:24.863524 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 04:04:24.863634 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-88f6e7c6 --user-data /tmp/tmpc7o80en5 2026-08-10 04:04:26.241080 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kzmxfqarfpgrxmk6vnwe5dh5 2026-08-10 04:04:26.241262 UTC - Waiting until resource ready 2026-08-10 04:04:28.242236 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-88f6e7c6 tst 2026-08-10 04:04:29.328546 UTC - Status: IMAGING 2026-08-10 04:04:32.500555 UTC - Status: BOOTING 2026-08-10 04:04:35.369586 UTC - Status: BOOTING 2026-08-10 04:04:38.436650 UTC - Status: BOOTING 2026-08-10 04:04:41.806680 UTC - Status: BOOTING 2026-08-10 04:04:45.193431 UTC - Status: BOOTING 2026-08-10 04:04:48.109935 UTC - Status: BOOTING 2026-08-10 04:04:51.481085 UTC - Status: UP 2026-08-10 04:04:51.484863 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzmxfpy6ey7s03md5vhd3asr 2026-08-10 04:04:51.484980 UTC - Validate resources 2026-08-10 04:04:52.982571 UTC - The fields of the created resource were validated. 2026-08-10 04:04:52.982680 UTC - Check if resources are registered in experiment 2026-08-10 04:04:52.982817 UTC - Run: slices experiment list-resources --format json tst-88f6e7c6 2026-08-10 04:04:54.167786 UTC - Status (on expauth): UP 2026-08-10 04:04:54.167974 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 04:04:54.168118 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-88f6e7c6 2026-08-10 04:04:55.487433 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-88f6e7c6 tst 2026-08-10 04:04:56.754300 UTC - Run: slices experiment list-resources --format json tst-88f6e7c6 2026-08-10 04:04:57.520134 UTC - expires_at (on expauth): 2026-08-10T07:04:00Z (correctly extended) 2026-08-10 04:04:57.520288 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 04:04:57.520513 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-88f6e7c6 2026-08-10 04:04:58.897958 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-88f6e7c6 tst 2026-08-10 04:05:00.521634 UTC - Run: slices experiment list-resources --format json tst-88f6e7c6 2026-08-10 04:05:01.588575 UTC - expires_at (on expauth): 2026-08-10T08:04:00Z (correctly extended) 2026-08-10 04:05:01.588875 UTC - Testing ssh login 2026-08-10 04:05:01.613590 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-88f6e7c6 tst 2026-08-10 04:05:03.037909 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-88f6e7c6 tst 2026-08-10 04:05:04.515724 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-88f6e7c6 tst 2026-08-10 04:05:06.213386 UTC - Logging in using 'slices bi ssh' 2026-08-10 04:05:06.213662 UTC - Forcing IPv4 only. 2026-08-10 04:05:06.213845 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-88f6e7c6 tst -- -4 uname -a 2026-08-10 04:05:09.775842 UTC - Forcing IPv4 only. 2026-08-10 04:05:09.776070 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-88f6e7c6 tst -- -4 uptime 2026-08-10 04:05:12.214027 UTC - CLI SSH Test passed. 2026-08-10 04:05:12.214291 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-10 04:05:12.214338 UTC - Uptime: 04:05:11 up 0 min, 1 user, load average: 0.29, 0.09, 0.03 2026-08-10 04:05:12.214380 UTC - Logging in using SSH over SSH proxy 2026-08-10 04:05:12.214814 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-88f6e7c6 tst 2026-08-10 04:05:14.236962 UTC - Added paramiko HostKeyEntry for 172.28.9.208 2026-08-10 04:05:14.237224 UTC - Added paramiko HostKeyEntry for 172.28.9.208 2026-08-10 04:05:14.237306 UTC - Added paramiko HostKeyEntry for 172.28.9.208 2026-08-10 04:05:14.237627 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 04:05:14.237734 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 04:05:14.237791 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 04:05:14.237812 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-10 04:05:14.635712 UTC - Connecting to 172.28.9.208:22 over proxy 2026-08-10 04:05:15.318059 UTC - SSH Test output: 2026-08-10 04:05:15.318145 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-10 04:05:15.318178 UTC - Uptime: 04:05:14 up 0 min, 1 user, load average: 0.29, 0.09, 0.03 2026-08-10 04:05:15.456862 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-10 04:05:15.456946 UTC - lsb_release matches expected value 2026-08-10 04:05:15.456966 UTC - SSH Test passed. 2026-08-10 04:05:15.593099 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 04:04:48 +0000! 2026-08-10 04:05:15.638860 UTC - Destroying tst-88f6e7c6 tst 2026-08-10 04:05:15.639091 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-88f6e7c6 tst