Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.12s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 190ms 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 225ms + 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.dev187+g005dd054e (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 20:19:28.480843 UTC - Installing Slices CLI (latest stable version). 2026-07-09 20:19:28.481056 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 20:19:33.657113 UTC - Slices CLI installed. 2026-07-09 20:19:33.657326 UTC - PATH: {'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-07-09 20:19:33.658441 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-09 20:19:33.659187 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-09 20:19:33.659303 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-07-09 20:19:33.659329 UTC - Slices CLI exe: 'slices' 2026-07-09 20:19:33.659345 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 20:19:33.659464 UTC - Run: slices --version 2026-07-09 20:19:36.228626 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 20:19:36.228943 UTC - Run: slices pubkey list --format text 2026-07-09 20:19:37.044951 UTC - Pubkey already registered 2026-07-09 20:19:37.045272 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 20:19:37.610622 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 20:19:37.610727 UTC - Check List Flavors 2026-07-09 20:19:37.610911 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-09 20:19:38.877828 UTC - Check List DiskImages 2026-07-09 20:19:38.878024 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-09 20:19:39.493840 UTC - Requesting resources 2026-07-09 20:19:39.494386 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 20:19:39.494542 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-be5f6f5f --user-data /tmp/tmpgn548m_b 2026-07-09 20:19:42.064592 UTC - Resource ID: r_be-gent1-bi-vm1_01kx48jfqpfa5vrajxxmxyed3a 2026-07-09 20:19:42.064777 UTC - Waiting until resource ready 2026-07-09 20:19:44.065174 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-be5f6f5f tst 2026-07-09 20:19:44.780869 UTC - Status: IMAGING 2026-07-09 20:19:47.547223 UTC - Status: IMAGING 2026-07-09 20:19:50.264264 UTC - Status: IMAGING 2026-07-09 20:19:53.030729 UTC - Status: BOOTING 2026-07-09 20:19:55.797304 UTC - Status: BOOTING 2026-07-09 20:19:58.564199 UTC - Status: BOOTING 2026-07-09 20:20:01.330824 UTC - Status: BOOTING 2026-07-09 20:20:04.097085 UTC - Status: UP 2026-07-09 20:20:04.097169 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx48jf18fw0bpgb0m4eb5mg9 2026-07-09 20:20:04.097209 UTC - Validate resources 2026-07-09 20:20:04.863397 UTC - The fields of the created resource were validated. 2026-07-09 20:20:04.863493 UTC - Check if resources are registered in experiment 2026-07-09 20:20:04.863643 UTC - Run: slices experiment list-resources --format json tst-be5f6f5f 2026-07-09 20:20:05.529130 UTC - Status (on expauth): UP 2026-07-09 20:20:05.529281 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 20:20:05.529407 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-be5f6f5f 2026-07-09 20:20:08.351126 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-be5f6f5f tst 2026-07-09 20:20:09.072268 UTC - Run: slices experiment list-resources --format json tst-be5f6f5f 2026-07-09 20:20:09.737910 UTC - expires_at (on expauth): 2026-07-09T23:20:00Z (correctly extended) 2026-07-09 20:20:09.738006 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 20:20:09.738156 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-be5f6f5f 2026-07-09 20:20:12.809471 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-be5f6f5f tst 2026-07-09 20:20:13.525313 UTC - Run: slices experiment list-resources --format json tst-be5f6f5f 2026-07-09 20:20:14.190874 UTC - expires_at (on expauth): 2026-07-10T00:20:00Z (correctly extended) 2026-07-09 20:20:14.190985 UTC - Testing ssh login 2026-07-09 20:20:14.201854 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-be5f6f5f tst 2026-07-09 20:20:14.967828 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-be5f6f5f tst 2026-07-09 20:20:15.683668 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-be5f6f5f tst 2026-07-09 20:20:16.500055 UTC - Logging in using 'slices bi ssh' 2026-07-09 20:20:16.500132 UTC - Forcing IPv4 only. 2026-07-09 20:20:16.500267 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-be5f6f5f tst -- -4 uname -a 2026-07-09 20:20:17.517148 UTC - Forcing IPv4 only. 2026-07-09 20:20:17.517384 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-be5f6f5f tst -- -4 uptime 2026-07-09 20:20:18.383584 UTC - CLI SSH Test passed. 2026-07-09 20:20:18.383662 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-09 20:20:18.383683 UTC - Uptime: 22:20:17 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-09 20:20:18.383717 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-09 20:20:18.384167 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:18.384328 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:18.384386 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:18.384555 UTC - Connecting to 10.10.221.171:22 2026-07-09 20:20:18.629465 UTC - SSH Test output: 2026-07-09 20:20:18.629537 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-09 20:20:18.629563 UTC - Uptime: 22:20:17 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-09 20:20:18.683373 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-09 20:20:18.730745 UTC - /etc/debian_version: 13.1 2026-07-09 20:20:18.730822 UTC - lsb_release matches expected value 2026-07-09 20:20:18.730843 UTC - /etc/debian_version matches expected value 2026-07-09 20:20:18.730858 UTC - SSH Test passed. 2026-07-09 20:20:18.777557 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 22:20:02 +0200! 2026-07-09 20:20:18.777702 UTC - Logging in using SSH over SSH proxy 2026-07-09 20:20:18.777865 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-be5f6f5f tst 2026-07-09 20:20:19.494263 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:19.494431 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:19.494482 UTC - Added paramiko HostKeyEntry for 10.10.221.171 2026-07-09 20:20:19.494711 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 20:20:19.494770 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 20:20:19.494870 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 20:20:19.494890 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-09 20:20:19.766427 UTC - Connecting to 10.10.221.171:22 over proxy 2026-07-09 20:20:20.001007 UTC - SSH Test output: 2026-07-09 20:20:20.001085 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-09 20:20:20.001105 UTC - Uptime: 22:20:19 up 0 min, 2 users, load average: 0.12, 0.03, 0.01 2026-07-09 20:20:20.053081 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-09 20:20:20.101159 UTC - /etc/debian_version: 13.1 2026-07-09 20:20:20.101241 UTC - lsb_release matches expected value 2026-07-09 20:20:20.101263 UTC - /etc/debian_version matches expected value 2026-07-09 20:20:20.101278 UTC - SSH Test passed. 2026-07-09 20:20:20.149099 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 22:20:02 +0200! 2026-07-09 20:20:20.155621 UTC - Destroying tst-be5f6f5f tst 2026-07-09 20:20:20.155828 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-be5f6f5f tst