Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.43s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 227ms 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 239ms + 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-10 20:21:50.488188 UTC - Installing Slices CLI (latest stable version). 2026-07-10 20:21:50.488435 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 20:21:55.514684 UTC - Slices CLI installed. 2026-07-10 20:21:55.514906 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-10 20:21:55.518015 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-10 20:21:55.519024 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 20:21:55.519163 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-10 20:21:55.519193 UTC - Slices CLI exe: 'slices' 2026-07-10 20:21:55.519211 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 20:21:55.519338 UTC - Run: slices --version 2026-07-10 20:21:58.189130 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-10 20:21:58.189485 UTC - Run: slices pubkey list --format text 2026-07-10 20:21:58.955204 UTC - Pubkey already registered 2026-07-10 20:21:58.955483 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 20:21:59.570979 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 20:21:59.571098 UTC - Check List Flavors 2026-07-10 20:21:59.571292 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-10 20:22:00.788574 UTC - Check List DiskImages 2026-07-10 20:22:00.788812 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-10 20:22:01.454415 UTC - Requesting resources 2026-07-10 20:22:01.454956 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 20:22:01.455092 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-ba3c5422 --user-data /tmp/tmphb_q2rsb 2026-07-10 20:22:04.476699 UTC - Resource ID: r_be-gent1-bi-vm1_01kx6v3hsketftnnefnv5v6q9z 2026-07-10 20:22:04.476903 UTC - Waiting until resource ready 2026-07-10 20:22:06.477311 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ba3c5422 tst 2026-07-10 20:22:07.243293 UTC - Status: IMAGING 2026-07-10 20:22:10.059909 UTC - Status: IMAGING 2026-07-10 20:22:12.826369 UTC - Status: IMAGING 2026-07-10 20:22:15.693239 UTC - Status: BOOTING 2026-07-10 20:22:18.460031 UTC - Status: BOOTING 2026-07-10 20:22:21.226392 UTC - Status: BOOTING 2026-07-10 20:22:23.992747 UTC - Status: BOOTING 2026-07-10 20:22:26.763037 UTC - Status: UP 2026-07-10 20:22:26.763713 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx6v3gwjfxqsywx8cwd9ms56 2026-07-10 20:22:26.763804 UTC - Validate resources 2026-07-10 20:22:27.583224 UTC - The fields of the created resource were validated. 2026-07-10 20:22:27.583949 UTC - Check if resources are registered in experiment 2026-07-10 20:22:27.584194 UTC - Run: slices experiment list-resources --format json tst-ba3c5422 2026-07-10 20:22:28.301206 UTC - Status (on expauth): UP 2026-07-10 20:22:28.301374 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 20:22:28.301520 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-ba3c5422 2026-07-10 20:22:31.130432 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ba3c5422 tst 2026-07-10 20:22:31.897972 UTC - Run: slices experiment list-resources --format json tst-ba3c5422 2026-07-10 20:22:32.563644 UTC - expires_at (on expauth): 2026-07-10T23:22:00Z (correctly extended) 2026-07-10 20:22:32.563753 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 20:22:32.563923 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-ba3c5422 2026-07-10 20:22:35.084163 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ba3c5422 tst 2026-07-10 20:22:35.850406 UTC - Run: slices experiment list-resources --format json tst-ba3c5422 2026-07-10 20:22:36.566435 UTC - expires_at (on expauth): 2026-07-11T00:22:00Z (correctly extended) 2026-07-10 20:22:36.566561 UTC - Testing ssh login 2026-07-10 20:22:36.581234 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ba3c5422 tst 2026-07-10 20:22:37.347207 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ba3c5422 tst 2026-07-10 20:22:38.063312 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ba3c5422 tst 2026-07-10 20:22:38.829591 UTC - Logging in using 'slices bi ssh' 2026-07-10 20:22:38.829679 UTC - Forcing IPv4 only. 2026-07-10 20:22:38.829839 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-ba3c5422 tst -- -4 uname -a 2026-07-10 20:22:39.796529 UTC - Forcing IPv4 only. 2026-07-10 20:22:39.796769 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-ba3c5422 tst -- -4 uptime 2026-07-10 20:22:40.663209 UTC - CLI SSH Test passed. 2026-07-10 20:22:40.663309 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-10 20:22:40.663329 UTC - Uptime: 22:22:38 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-10 20:22:40.663368 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-10 20:22:40.663911 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:40.664112 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:40.664175 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:40.664385 UTC - Connecting to 10.10.222.137:22 2026-07-10 20:22:40.896140 UTC - SSH Test output: 2026-07-10 20:22:40.896236 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-10 20:22:40.896264 UTC - Uptime: 22:22:39 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-10 20:22:40.948533 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-10 20:22:40.996063 UTC - /etc/debian_version: 13.1 2026-07-10 20:22:40.996167 UTC - lsb_release matches expected value 2026-07-10 20:22:40.996193 UTC - /etc/debian_version matches expected value 2026-07-10 20:22:40.996211 UTC - SSH Test passed. 2026-07-10 20:22:41.043833 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 22:22:23 +0200! 2026-07-10 20:22:41.044017 UTC - Logging in using SSH over SSH proxy 2026-07-10 20:22:41.044181 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ba3c5422 tst 2026-07-10 20:22:41.861183 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:41.861370 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:41.861428 UTC - Added paramiko HostKeyEntry for 10.10.222.137 2026-07-10 20:22:41.861715 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 20:22:41.861771 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 20:22:41.861912 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 20:22:41.861938 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-10 20:22:42.118989 UTC - Connecting to 10.10.222.137:22 over proxy 2026-07-10 20:22:42.352544 UTC - SSH Test output: 2026-07-10 20:22:42.352633 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-10 20:22:42.352654 UTC - Uptime: 22:22:40 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-10 20:22:42.406645 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-10 20:22:42.456799 UTC - /etc/debian_version: 13.1 2026-07-10 20:22:42.456893 UTC - lsb_release matches expected value 2026-07-10 20:22:42.456915 UTC - /etc/debian_version matches expected value 2026-07-10 20:22:42.456931 UTC - SSH Test passed. 2026-07-10 20:22:42.504405 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 22:22:23 +0200! 2026-07-10 20:22:42.511680 UTC - Destroying tst-ba3c5422 tst 2026-07-10 20:22:42.511886 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-ba3c5422 tst