Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.68s 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 236ms + annotated-types==0.7.0 + anyio==4.14.2 + 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-13 04:21:54.786102 UTC - Installing Slices CLI (latest stable version). 2026-07-13 04:21:54.786353 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 04:22:00.061884 UTC - Slices CLI installed. 2026-07-13 04:22:00.062081 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-13 04:22:00.064478 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-13 04:22:00.065497 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 04:22:00.065635 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-13 04:22:00.065674 UTC - Slices CLI exe: 'slices' 2026-07-13 04:22:00.065691 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 04:22:00.065838 UTC - Run: slices --version 2026-07-13 04:22:02.584863 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-13 04:22:02.585174 UTC - Run: slices pubkey list --format text 2026-07-13 04:22:03.350633 UTC - Pubkey already registered 2026-07-13 04:22:03.350887 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 04:22:03.966181 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 04:22:03.966280 UTC - Check List Flavors 2026-07-13 04:22:03.966441 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-13 04:22:05.233545 UTC - Check List DiskImages 2026-07-13 04:22:05.233731 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-13 04:22:05.798732 UTC - Requesting resources 2026-07-13 04:22:05.799228 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 04:22:05.799345 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-c2f884bf --user-data /tmp/tmp6hjmth_5 2026-07-13 04:22:08.672358 UTC - Resource ID: r_be-gent1-bi-vm1_01kxcvc0xmfrsacw3wkp1nd7ms 2026-07-13 04:22:08.672506 UTC - Waiting until resource ready 2026-07-13 04:22:10.672999 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2f884bf tst 2026-07-13 04:22:11.438858 UTC - Status: IMAGING 2026-07-13 04:22:14.104657 UTC - Status: IMAGING 2026-07-13 04:22:16.820703 UTC - Status: IMAGING 2026-07-13 04:22:19.486629 UTC - Status: BOOTING 2026-07-13 04:22:22.152291 UTC - Status: BOOTING 2026-07-13 04:22:24.818067 UTC - Status: BOOTING 2026-07-13 04:22:27.484013 UTC - Status: BOOTING 2026-07-13 04:22:30.149854 UTC - Status: UP 2026-07-13 04:22:30.149943 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxcvc006fh8bm9ntjaxwjd95 2026-07-13 04:22:30.150003 UTC - Validate resources 2026-07-13 04:22:30.815760 UTC - The fields of the created resource were validated. 2026-07-13 04:22:30.815854 UTC - Check if resources are registered in experiment 2026-07-13 04:22:30.816018 UTC - Run: slices experiment list-resources --format json tst-c2f884bf 2026-07-13 04:22:31.431575 UTC - Status (on expauth): UP 2026-07-13 04:22:31.431734 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 04:22:31.431877 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-c2f884bf 2026-07-13 04:22:34.152127 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2f884bf tst 2026-07-13 04:22:34.867864 UTC - Run: slices experiment list-resources --format json tst-c2f884bf 2026-07-13 04:22:35.483389 UTC - expires_at (on expauth): 2026-07-13T07:22:00Z (correctly extended) 2026-07-13 04:22:35.483485 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 04:22:35.483627 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-c2f884bf 2026-07-13 04:22:38.053384 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2f884bf tst 2026-07-13 04:22:38.769253 UTC - Run: slices experiment list-resources --format json tst-c2f884bf 2026-07-13 04:22:39.384567 UTC - expires_at (on expauth): 2026-07-13T08:22:00Z (correctly extended) 2026-07-13 04:22:39.384700 UTC - Testing ssh login 2026-07-13 04:22:39.397244 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c2f884bf tst 2026-07-13 04:22:40.112817 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c2f884bf tst 2026-07-13 04:22:40.778420 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c2f884bf tst 2026-07-13 04:22:41.494216 UTC - Logging in using 'slices bi ssh' 2026-07-13 04:22:41.494289 UTC - Forcing IPv4 only. 2026-07-13 04:22:41.494416 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c2f884bf tst -- -4 uname -a 2026-07-13 04:22:42.410388 UTC - Forcing IPv4 only. 2026-07-13 04:22:42.410571 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c2f884bf tst -- -4 uptime 2026-07-13 04:22:43.226236 UTC - CLI SSH Test passed. 2026-07-13 04:22:43.226311 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-13 04:22:43.226331 UTC - Uptime: 06:22:42 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-13 04:22:43.226366 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-13 04:22:43.226786 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:43.226943 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:43.227017 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:43.227204 UTC - Connecting to 10.10.223.49:22 2026-07-13 04:22:43.448580 UTC - SSH Test output: 2026-07-13 04:22:43.448653 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-13 04:22:43.448673 UTC - Uptime: 06:22:42 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-13 04:22:43.501295 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-13 04:22:43.549115 UTC - /etc/debian_version: 13.1 2026-07-13 04:22:43.549186 UTC - lsb_release matches expected value 2026-07-13 04:22:43.549207 UTC - /etc/debian_version matches expected value 2026-07-13 04:22:43.549223 UTC - SSH Test passed. 2026-07-13 04:22:43.597073 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 06:22:27 +0200! 2026-07-13 04:22:43.597210 UTC - Logging in using SSH over SSH proxy 2026-07-13 04:22:43.597355 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c2f884bf tst 2026-07-13 04:22:44.313262 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:44.313496 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:44.313566 UTC - Added paramiko HostKeyEntry for 10.10.223.49 2026-07-13 04:22:44.313845 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 04:22:44.313894 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 04:22:44.314015 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 04:22:44.314039 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-13 04:22:44.580000 UTC - Connecting to 10.10.223.49:22 over proxy 2026-07-13 04:22:44.816650 UTC - SSH Test output: 2026-07-13 04:22:44.816748 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-13 04:22:44.816771 UTC - Uptime: 06:22:43 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-13 04:22:44.869758 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-13 04:22:44.917204 UTC - /etc/debian_version: 13.1 2026-07-13 04:22:44.917307 UTC - lsb_release matches expected value 2026-07-13 04:22:44.917330 UTC - /etc/debian_version matches expected value 2026-07-13 04:22:44.917348 UTC - SSH Test passed. 2026-07-13 04:22:44.964086 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 06:22:27 +0200! 2026-07-13 04:22:44.971000 UTC - Destroying tst-c2f884bf tst 2026-07-13 04:22:44.971192 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-c2f884bf tst