Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.38s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 867ms 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 881ms + 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 13:02:21.076634 UTC - Installing Slices CLI (latest stable version). 2026-08-08 13:02:21.076965 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 13:02:29.282386 UTC - Slices CLI installed. 2026-08-08 13:02:29.282850 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 13:02:29.285255 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 13:02:29.285328 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 13:02:29.285409 UTC - which slices: /app/.venv/bin/slices 2026-08-08 13:02:29.285428 UTC - Slices CLI exe: 'slices' 2026-08-08 13:02:29.285445 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 13:02:29.285572 UTC - Run: slices --version 2026-08-08 13:02:32.822541 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 13:02:32.822829 UTC - Run: slices pubkey list --format text 2026-08-08 13:02:33.739055 UTC - Pubkey already registered 2026-08-08 13:02:33.739383 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 13:02:34.756601 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 13:02:34.756751 UTC - Check List Flavors 2026-08-08 13:02:34.756922 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-08-08 13:02:35.925264 UTC - Check List DiskImages 2026-08-08 13:02:35.925469 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-08-08 13:02:37.094309 UTC - Requesting resources 2026-08-08 13:02:37.094975 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 13:02:37.095090 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-30392da9 --user-data /tmp/tmpj0v7jg50 2026-08-08 13:02:38.762659 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kzgqfrzwf8hsr29d0vym5ckz 2026-08-08 13:02:38.762832 UTC - Waiting until resource ready 2026-08-08 13:02:40.763134 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-30392da9 tst 2026-08-08 13:02:41.895909 UTC - Status: IMAGING 2026-08-08 13:02:44.929066 UTC - Status: BOOTING 2026-08-08 13:02:48.564675 UTC - Status: BOOTING 2026-08-08 13:02:51.481137 UTC - Status: BOOTING 2026-08-08 13:02:54.778016 UTC - Status: BOOTING 2026-08-08 13:02:58.456271 UTC - Status: BOOTING 2026-08-08 13:03:02.101703 UTC - Status: BOOTING 2026-08-08 13:03:05.479099 UTC - Status: BOOTING 2026-08-08 13:03:09.070616 UTC - Status: UP 2026-08-08 13:03:09.070713 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzgqfrkmf6ft5x2dbkh5wf2t 2026-08-08 13:03:09.070761 UTC - Validate resources 2026-08-08 13:03:10.037687 UTC - The fields of the created resource were validated. 2026-08-08 13:03:10.037805 UTC - Check if resources are registered in experiment 2026-08-08 13:03:10.037971 UTC - Run: slices experiment list-resources --format json tst-30392da9 2026-08-08 13:03:11.766586 UTC - Status (on expauth): UP 2026-08-08 13:03:11.766726 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 13:03:11.766836 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-30392da9 2026-08-08 13:03:14.659228 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-30392da9 tst 2026-08-08 13:03:16.657840 UTC - Run: slices experiment list-resources --format json tst-30392da9 2026-08-08 13:03:18.302808 UTC - expires_at (on expauth): 2026-08-08T16:03:00Z (correctly extended) 2026-08-08 13:03:18.303076 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 13:03:18.303302 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-30392da9 2026-08-08 13:03:20.732939 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-30392da9 tst 2026-08-08 13:03:23.063623 UTC - Run: slices experiment list-resources --format json tst-30392da9 2026-08-08 13:03:24.749300 UTC - expires_at (on expauth): 2026-08-08T17:03:00Z (correctly extended) 2026-08-08 13:03:24.749963 UTC - Testing ssh login 2026-08-08 13:03:24.806488 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-30392da9 tst 2026-08-08 13:03:27.043374 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-30392da9 tst 2026-08-08 13:03:29.115820 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-30392da9 tst 2026-08-08 13:03:30.948808 UTC - Logging in using 'slices bi ssh' 2026-08-08 13:03:30.948886 UTC - Forcing IPv4 only. 2026-08-08 13:03:30.949006 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-30392da9 tst -- -4 uname -a 2026-08-08 13:03:33.672198 UTC - Forcing IPv4 only. 2026-08-08 13:03:33.672383 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-30392da9 tst -- -4 uptime 2026-08-08 13:03:35.892657 UTC - CLI SSH Test passed. 2026-08-08 13:03:35.892847 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 13:03:35.892875 UTC - Uptime: 13:03:35 up 0 min, 1 user, load average: 0.33, 0.10, 0.03 2026-08-08 13:03:35.892910 UTC - Logging in using SSH over SSH proxy 2026-08-08 13:03:35.893044 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-30392da9 tst 2026-08-08 13:03:37.368013 UTC - Added paramiko HostKeyEntry for 172.28.9.18 2026-08-08 13:03:37.368231 UTC - Added paramiko HostKeyEntry for 172.28.9.18 2026-08-08 13:03:37.368293 UTC - Added paramiko HostKeyEntry for 172.28.9.18 2026-08-08 13:03:37.368620 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 13:03:37.368755 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 13:03:37.368806 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-08 13:03:37.368823 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-08 13:03:37.747658 UTC - Connecting to 172.28.9.18:22 over proxy 2026-08-08 13:03:38.416434 UTC - SSH Test output: 2026-08-08 13:03:38.416508 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 13:03:38.416527 UTC - Uptime: 13:03:38 up 0 min, 1 user, load average: 0.33, 0.10, 0.03 2026-08-08 13:03:38.562674 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 13:03:38.562749 UTC - lsb_release matches expected value 2026-08-08 13:03:38.562769 UTC - SSH Test passed. 2026-08-08 13:03:38.701248 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 13:03:05 +0000! 2026-08-08 13:03:38.721015 UTC - Destroying tst-30392da9 tst 2026-08-08 13:03:38.721200 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-30392da9 tst