Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.47s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 347ms 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.88s + 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-07 13:02:56.919408 UTC - Installing Slices CLI (latest stable version). 2026-08-07 13:02:56.920097 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-07 13:03:04.612247 UTC - Slices CLI installed. 2026-08-07 13:03:04.612574 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-07 13:03:04.614952 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-07 13:03:04.615038 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-07 13:03:04.615114 UTC - which slices: /app/.venv/bin/slices 2026-08-07 13:03:04.615134 UTC - Slices CLI exe: 'slices' 2026-08-07 13:03:04.615152 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-07 13:03:04.615307 UTC - Run: slices --version 2026-08-07 13:03:10.326404 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-07 13:03:10.326737 UTC - Run: slices pubkey list --format text 2026-08-07 13:03:11.395941 UTC - Pubkey already registered 2026-08-07 13:03:11.396365 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-07 13:03:12.212690 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-07 13:03:12.212893 UTC - Check List Flavors 2026-08-07 13:03:12.213072 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-08-07 13:03:13.079491 UTC - Check List DiskImages 2026-08-07 13:03:13.079702 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-08-07 13:03:13.865200 UTC - Requesting resources 2026-08-07 13:03:13.866122 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-07 13:03:13.866252 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9a3f89be --user-data /tmp/tmpcuxstnpf 2026-08-07 13:03:15.442062 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kze545sjfq8sdk1wr8k835aa 2026-08-07 13:03:15.442227 UTC - Waiting until resource ready 2026-08-07 13:03:17.442576 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-9a3f89be tst 2026-08-07 13:03:18.717683 UTC - Status: IMAGING 2026-08-07 13:03:21.939463 UTC - Status: BOOTING 2026-08-07 13:03:24.806005 UTC - Status: BOOTING 2026-08-07 13:03:27.936080 UTC - Status: BOOTING 2026-08-07 13:03:30.863933 UTC - Status: BOOTING 2026-08-07 13:03:33.836222 UTC - Status: BOOTING 2026-08-07 13:03:37.785267 UTC - Status: BOOTING 2026-08-07 13:03:41.311891 UTC - Status: UP 2026-08-07 13:03:41.312004 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kze545bgfszsp2y0td7tdx70 2026-08-07 13:03:41.312058 UTC - Validate resources 2026-08-07 13:03:42.332999 UTC - The fields of the created resource were validated. 2026-08-07 13:03:42.333141 UTC - Check if resources are registered in experiment 2026-08-07 13:03:42.333350 UTC - Run: slices experiment list-resources --format json tst-9a3f89be 2026-08-07 13:03:44.019163 UTC - Status (on expauth): UP 2026-08-07 13:03:44.019334 UTC - Testing extend expires_at (all resources in experiment) 2026-08-07 13:03:44.019486 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-9a3f89be 2026-08-07 13:03:46.293302 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-9a3f89be tst 2026-08-07 13:03:48.119906 UTC - Run: slices experiment list-resources --format json tst-9a3f89be 2026-08-07 13:03:49.827914 UTC - expires_at (on expauth): 2026-08-07T16:03:00Z (correctly extended) 2026-08-07 13:03:49.828278 UTC - Testing extend expires_at (single resource in experiment) 2026-08-07 13:03:49.828480 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-9a3f89be 2026-08-07 13:03:51.452657 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-9a3f89be tst 2026-08-07 13:03:53.409538 UTC - Run: slices experiment list-resources --format json tst-9a3f89be 2026-08-07 13:03:54.814873 UTC - expires_at (on expauth): 2026-08-07T17:03:00Z (correctly extended) 2026-08-07 13:03:54.815049 UTC - Testing ssh login 2026-08-07 13:03:54.841101 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-9a3f89be tst 2026-08-07 13:03:56.274488 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9a3f89be tst 2026-08-07 13:03:57.924875 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9a3f89be tst 2026-08-07 13:04:00.062515 UTC - Logging in using 'slices bi ssh' 2026-08-07 13:04:00.062593 UTC - Forcing IPv4 only. 2026-08-07 13:04:00.062720 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-9a3f89be tst -- -4 uname -a 2026-08-07 13:04:04.009489 UTC - Forcing IPv4 only. 2026-08-07 13:04:04.009849 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-9a3f89be tst -- -4 uptime 2026-08-07 13:04:07.254581 UTC - CLI SSH Test passed. 2026-08-07 13:04:07.254668 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-07 13:04:07.254690 UTC - Uptime: 13:04:07 up 0 min, 1 user, load average: 0.22, 0.07, 0.02 2026-08-07 13:04:07.254729 UTC - Logging in using SSH over SSH proxy 2026-08-07 13:04:07.254879 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9a3f89be tst 2026-08-07 13:04:09.758477 UTC - Added paramiko HostKeyEntry for 172.28.9.134 2026-08-07 13:04:09.758711 UTC - Added paramiko HostKeyEntry for 172.28.9.134 2026-08-07 13:04:09.758779 UTC - Added paramiko HostKeyEntry for 172.28.9.134 2026-08-07 13:04:09.759082 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 13:04:09.759176 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 13:04:09.759221 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 13:04:09.759239 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-07 13:04:10.139744 UTC - Connecting to 172.28.9.134:22 over proxy 2026-08-07 13:04:10.813216 UTC - SSH Test output: 2026-08-07 13:04:10.813311 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-07 13:04:10.813337 UTC - Uptime: 13:04:10 up 0 min, 1 user, load average: 0.20, 0.07, 0.02 2026-08-07 13:04:10.947900 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 13:04:10.947991 UTC - lsb_release matches expected value 2026-08-07 13:04:10.948012 UTC - SSH Test passed. 2026-08-07 13:04:11.083485 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 13:03:39 +0000! 2026-08-07 13:04:11.101686 UTC - Destroying tst-9a3f89be tst 2026-08-07 13:04:11.101918 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-9a3f89be tst