Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.31s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 193ms 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 533ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.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-07-30 13:44:34.977840 UTC - Installing Slices CLI (latest stable version). 2026-07-30 13:44:34.978057 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 13:44:40.105046 UTC - Slices CLI installed. 2026-07-30 13:44:40.105600 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-30 13:44:40.108156 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-07-30 13:44:40.108238 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-07-30 13:44:40.108325 UTC - which slices: /app/.venv/bin/slices 2026-07-30 13:44:40.108347 UTC - Slices CLI exe: 'slices' 2026-07-30 13:44:40.108363 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 13:44:40.108543 UTC - Run: slices --version 2026-07-30 13:44:42.677883 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-07-30 13:44:42.678193 UTC - Run: slices pubkey list --format text 2026-07-30 13:44:43.393700 UTC - Pubkey already registered 2026-07-30 13:44:43.393950 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 13:44:43.959527 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 13:44:43.959628 UTC - Check List Flavors 2026-07-30 13:44:43.959793 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-30 13:44:44.625340 UTC - Check List DiskImages 2026-07-30 13:44:44.625528 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-30 13:44:45.240999 UTC - Requesting resources 2026-07-30 13:44:45.241522 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 13:44:45.241637 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-0d9b8a0d --user-data /tmp/tmplw8gt7if 2026-07-30 13:44:46.257996 UTC - Resource ID: r_be-gent1-bi-vm1_01kysmae93f7g9a9gzp828eb7d 2026-07-30 13:44:46.258215 UTC - Waiting until resource ready 2026-07-30 13:44:48.258704 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-0d9b8a0d tst 2026-07-30 13:44:48.974818 UTC - Status: IMAGING 2026-07-30 13:44:51.691720 UTC - Status: IMAGING 2026-07-30 13:44:54.408046 UTC - Status: IMAGING 2026-07-30 13:44:57.225027 UTC - Status: BOOTING 2026-07-30 13:44:59.891008 UTC - Status: BOOTING 2026-07-30 13:45:02.657795 UTC - Status: BOOTING 2026-07-30 13:45:05.474752 UTC - Status: BOOTING 2026-07-30 13:45:08.241186 UTC - Status: UP 2026-07-30 13:45:08.241279 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kysmae2nev8v9wd2x3yhppsh 2026-07-30 13:45:08.241322 UTC - Validate resources 2026-07-30 13:45:08.957189 UTC - The fields of the created resource were validated. 2026-07-30 13:45:08.957281 UTC - Check if resources are registered in experiment 2026-07-30 13:45:08.957441 UTC - Run: slices experiment list-resources --format json tst-0d9b8a0d 2026-07-30 13:45:09.622793 UTC - Status (on expauth): UP 2026-07-30 13:45:09.622928 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 13:45:09.623058 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-0d9b8a0d 2026-07-30 13:45:11.090926 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-0d9b8a0d tst 2026-07-30 13:45:11.807444 UTC - Run: slices experiment list-resources --format json tst-0d9b8a0d 2026-07-30 13:45:12.473126 UTC - expires_at (on expauth): 2026-07-30T16:45:00Z (correctly extended) 2026-07-30 13:45:12.473222 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 13:45:12.473396 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-0d9b8a0d 2026-07-30 13:45:13.891177 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-0d9b8a0d tst 2026-07-30 13:45:14.607522 UTC - Run: slices experiment list-resources --format json tst-0d9b8a0d 2026-07-30 13:45:15.223141 UTC - expires_at (on expauth): 2026-07-30T17:45:00Z (correctly extended) 2026-07-30 13:45:15.223339 UTC - Testing ssh login 2026-07-30 13:45:15.238130 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0d9b8a0d tst 2026-07-30 13:45:16.104287 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0d9b8a0d tst 2026-07-30 13:45:16.870802 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-0d9b8a0d tst 2026-07-30 13:45:17.637199 UTC - Logging in using 'slices bi ssh' 2026-07-30 13:45:17.637293 UTC - Forcing IPv4 only. 2026-07-30 13:45:17.637448 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-0d9b8a0d tst -- -4 uname -a 2026-07-30 13:45:18.604030 UTC - Forcing IPv4 only. 2026-07-30 13:45:18.604275 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-0d9b8a0d tst -- -4 uptime 2026-07-30 13:45:19.420591 UTC - CLI SSH Test passed. 2026-07-30 13:45:19.420691 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-30 13:45:19.420712 UTC - Uptime: 15:45:18 up 0 min, 1 user, load average: 0.20, 0.05, 0.02 2026-07-30 13:45:19.420785 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-30 13:45:19.421408 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:19.421625 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:19.421687 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:19.421947 UTC - Connecting to 10.10.221.121:22 2026-07-30 13:45:19.649563 UTC - SSH Test output: 2026-07-30 13:45:19.649659 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-30 13:45:19.649681 UTC - Uptime: 15:45:18 up 0 min, 1 user, load average: 0.20, 0.05, 0.02 2026-07-30 13:45:19.701974 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-30 13:45:19.749598 UTC - /etc/debian_version: 13.1 2026-07-30 13:45:19.749718 UTC - lsb_release matches expected value 2026-07-30 13:45:19.749749 UTC - /etc/debian_version matches expected value 2026-07-30 13:45:19.749774 UTC - SSH Test passed. 2026-07-30 13:45:19.797391 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 15:45:06 +0200! 2026-07-30 13:45:19.797618 UTC - Logging in using SSH over SSH proxy 2026-07-30 13:45:19.797841 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-0d9b8a0d tst 2026-07-30 13:45:20.514349 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:20.514535 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:20.514589 UTC - Added paramiko HostKeyEntry for 10.10.221.121 2026-07-30 13:45:20.514863 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 13:45:20.514915 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 13:45:20.515020 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 13:45:20.515041 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-30 13:45:20.623800 UTC - Connecting to 10.10.221.121:22 over proxy 2026-07-30 13:45:20.860614 UTC - SSH Test output: 2026-07-30 13:45:20.860686 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-30 13:45:20.860705 UTC - Uptime: 15:45:19 up 0 min, 2 users, load average: 0.19, 0.05, 0.01 2026-07-30 13:45:20.912798 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-30 13:45:20.960851 UTC - /etc/debian_version: 13.1 2026-07-30 13:45:20.960929 UTC - lsb_release matches expected value 2026-07-30 13:45:20.960951 UTC - /etc/debian_version matches expected value 2026-07-30 13:45:20.960974 UTC - SSH Test passed. 2026-07-30 13:45:21.008350 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 15:45:06 +0200! 2026-07-30 13:45:21.015402 UTC - Destroying tst-0d9b8a0d tst 2026-07-30 13:45:21.015580 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-0d9b8a0d tst