Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.75s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 393ms 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 997ms + 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.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-08-04 09:16:17.400857 UTC - Installing Slices CLI (latest stable version). 2026-08-04 09:16:17.401494 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-04 09:16:23.489453 UTC - Slices CLI installed. 2026-08-04 09:16:23.518061 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-04 09:16:23.549327 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-04 09:16:23.549480 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-04 09:16:23.549625 UTC - which slices: /app/.venv/bin/slices 2026-08-04 09:16:23.549656 UTC - Slices CLI exe: 'slices' 2026-08-04 09:16:23.549739 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-04 09:16:23.549969 UTC - Run: slices --version 2026-08-04 09:16:28.582840 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-04 09:16:28.583193 UTC - Run: slices pubkey list --format text 2026-08-04 09:16:31.314679 UTC - Pubkey already registered 2026-08-04 09:16:31.315171 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-04 09:16:32.081575 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-04 09:16:32.081680 UTC - Check List Flavors 2026-08-04 09:16:32.081832 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-04 09:16:32.947765 UTC - Check List DiskImages 2026-08-04 09:16:32.947975 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-04 09:16:33.965842 UTC - Requesting resources 2026-08-04 09:16:33.966800 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-04 09:16:33.966945 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-2174ec23 --user-data /tmp/tmp4my0hs12 2026-08-04 09:16:35.283764 UTC - Resource ID: r_be-gent1-bi-vm1_01kz60yzcjf1x8vpgpegz1t4jd 2026-08-04 09:16:35.283913 UTC - Waiting until resource ready 2026-08-04 09:16:37.284232 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2174ec23 tst 2026-08-04 09:16:38.274321 UTC - Status: IMAGING 2026-08-04 09:16:41.103189 UTC - Status: IMAGING 2026-08-04 09:16:44.182690 UTC - Status: IMAGING 2026-08-04 09:16:47.102482 UTC - Status: BOOTING 2026-08-04 09:16:50.529269 UTC - Status: BOOTING 2026-08-04 09:16:53.649813 UTC - Status: BOOTING 2026-08-04 09:16:56.667799 UTC - Status: INITIALIZING 2026-08-04 09:16:59.737784 UTC - Status: UP 2026-08-04 09:16:59.738068 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz60yz4gfa5vf34ryzme1e2x 2026-08-04 09:16:59.738151 UTC - Validate resources 2026-08-04 09:17:00.864888 UTC - The fields of the created resource were validated. 2026-08-04 09:17:00.865012 UTC - Check if resources are registered in experiment 2026-08-04 09:17:00.865200 UTC - Run: slices experiment list-resources --format json tst-2174ec23 2026-08-04 09:17:01.732229 UTC - Status (on expauth): UP 2026-08-04 09:17:01.732389 UTC - Testing extend expires_at (all resources in experiment) 2026-08-04 09:17:01.732514 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-2174ec23 2026-08-04 09:17:03.702215 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2174ec23 tst 2026-08-04 09:17:04.668960 UTC - Run: slices experiment list-resources --format json tst-2174ec23 2026-08-04 09:17:05.645778 UTC - expires_at (on expauth): 2026-08-04T12:17:00Z (correctly extended) 2026-08-04 09:17:05.646100 UTC - Testing extend expires_at (single resource in experiment) 2026-08-04 09:17:05.646291 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-2174ec23 2026-08-04 09:17:07.532015 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2174ec23 tst 2026-08-04 09:17:08.450134 UTC - Run: slices experiment list-resources --format json tst-2174ec23 2026-08-04 09:17:09.316470 UTC - expires_at (on expauth): 2026-08-04T13:17:00Z (correctly extended) 2026-08-04 09:17:09.316627 UTC - Testing ssh login 2026-08-04 09:17:09.373488 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2174ec23 tst 2026-08-04 09:17:10.615086 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2174ec23 tst 2026-08-04 09:17:11.943805 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2174ec23 tst 2026-08-04 09:17:13.062503 UTC - Logging in using 'slices bi ssh' 2026-08-04 09:17:13.062768 UTC - Forcing IPv4 only. 2026-08-04 09:17:13.062902 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2174ec23 tst -- -4 uname -a 2026-08-04 09:17:14.380350 UTC - Forcing IPv4 only. 2026-08-04 09:17:14.380540 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2174ec23 tst -- -4 uptime 2026-08-04 09:17:15.497002 UTC - CLI SSH Test passed. 2026-08-04 09:17:15.497078 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-08-04 09:17:15.497099 UTC - Uptime: 11:17:13 up 0 min, 1 user, load average: 0.23, 0.06, 0.02 2026-08-04 09:17:15.497141 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-04 09:17:15.497713 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:15.497908 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:15.497966 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:15.498153 UTC - Connecting to 10.10.215.243:22 2026-08-04 09:17:15.729247 UTC - SSH Test output: 2026-08-04 09:17:15.729318 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-08-04 09:17:15.729339 UTC - Uptime: 11:17:14 up 0 min, 1 user, load average: 0.23, 0.06, 0.02 2026-08-04 09:17:15.781622 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-04 09:17:15.831598 UTC - /etc/debian_version: 13.1 2026-08-04 09:17:15.831693 UTC - lsb_release matches expected value 2026-08-04 09:17:15.831722 UTC - /etc/debian_version matches expected value 2026-08-04 09:17:15.831746 UTC - SSH Test passed. 2026-08-04 09:17:15.877432 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 11:16:54 +0200! 2026-08-04 09:17:15.877583 UTC - Logging in using SSH over SSH proxy 2026-08-04 09:17:15.877752 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2174ec23 tst 2026-08-04 09:17:17.214729 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:17.214905 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:17.214976 UTC - Added paramiko HostKeyEntry for 10.10.215.243 2026-08-04 09:17:17.215239 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-04 09:17:17.215293 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-04 09:17:17.215395 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-04 09:17:17.215416 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-04 09:17:17.484032 UTC - Connecting to 10.10.215.243:22 over proxy 2026-08-04 09:17:17.742416 UTC - SSH Test output: 2026-08-04 09:17:17.742485 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-08-04 09:17:17.742503 UTC - Uptime: 11:17:16 up 0 min, 2 users, load average: 0.23, 0.06, 0.02 2026-08-04 09:17:17.795141 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-04 09:17:17.841713 UTC - /etc/debian_version: 13.1 2026-08-04 09:17:17.841792 UTC - lsb_release matches expected value 2026-08-04 09:17:17.841815 UTC - /etc/debian_version matches expected value 2026-08-04 09:17:17.841831 UTC - SSH Test passed. 2026-08-04 09:17:17.894225 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 11:16:54 +0200! 2026-08-04 09:17:17.908555 UTC - Destroying tst-2174ec23 tst 2026-08-04 09:17:17.909101 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-2174ec23 tst