Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.56s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 2 packages in 398ms 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 754ms + 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-03 21:21:42.493408 UTC - Installing Slices CLI (latest stable version). 2026-08-03 21:21:42.493922 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-03 21:21:48.460636 UTC - Slices CLI installed. 2026-08-03 21:21:48.462455 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-03 21:21:48.467953 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-03 21:21:48.468392 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-03 21:21:48.468516 UTC - which slices: /app/.venv/bin/slices 2026-08-03 21:21:48.469120 UTC - Slices CLI exe: 'slices' 2026-08-03 21:21:48.469140 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-03 21:21:48.469295 UTC - Run: slices --version 2026-08-03 21:21:52.614721 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-03 21:21:52.615185 UTC - Run: slices pubkey list --format text 2026-08-03 21:21:53.882400 UTC - Pubkey already registered 2026-08-03 21:21:53.882662 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-03 21:21:54.752446 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-03 21:21:54.752544 UTC - Check List Flavors 2026-08-03 21:21:54.752751 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-03 21:21:55.576971 UTC - Check List DiskImages 2026-08-03 21:21:55.577161 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-03 21:21:56.529883 UTC - Requesting resources 2026-08-03 21:21:56.530926 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-03 21:21:56.531042 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-aacabd8b --user-data /tmp/tmpopiunkrk 2026-08-03 21:21:58.032779 UTC - Resource ID: r_be-gent1-bi-vm1_01kz4r2f0mee3rq9wz36gtckft 2026-08-03 21:21:58.033217 UTC - Waiting until resource ready 2026-08-03 21:22:00.033725 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aacabd8b tst 2026-08-03 21:22:00.999930 UTC - Status: IMAGING 2026-08-03 21:22:03.817017 UTC - Status: IMAGING 2026-08-03 21:22:06.583114 UTC - Status: IMAGING 2026-08-03 21:22:09.562283 UTC - Status: BOOTING 2026-08-03 21:22:12.431282 UTC - Status: BOOTING 2026-08-03 21:22:15.528773 UTC - Status: BOOTING 2026-08-03 21:22:18.402557 UTC - Status: BOOTING 2026-08-03 21:22:21.383895 UTC - Status: INITIALIZING 2026-08-03 21:22:24.202410 UTC - Status: UP 2026-08-03 21:22:24.202516 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz4r2eqdfwvsz8kmvryxrbvb 2026-08-03 21:22:24.202569 UTC - Validate resources 2026-08-03 21:22:24.971370 UTC - The fields of the created resource were validated. 2026-08-03 21:22:24.971512 UTC - Check if resources are registered in experiment 2026-08-03 21:22:24.971744 UTC - Run: slices experiment list-resources --format json tst-aacabd8b 2026-08-03 21:22:25.737770 UTC - Status (on expauth): UP 2026-08-03 21:22:25.737949 UTC - Testing extend expires_at (all resources in experiment) 2026-08-03 21:22:25.738113 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-aacabd8b 2026-08-03 21:22:27.309240 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aacabd8b tst 2026-08-03 21:22:28.229204 UTC - Run: slices experiment list-resources --format json tst-aacabd8b 2026-08-03 21:22:29.202089 UTC - expires_at (on expauth): 2026-08-04T00:22:00Z (correctly extended) 2026-08-03 21:22:29.202356 UTC - Testing extend expires_at (single resource in experiment) 2026-08-03 21:22:29.202591 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-aacabd8b 2026-08-03 21:22:30.881838 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aacabd8b tst 2026-08-03 21:22:31.767958 UTC - Run: slices experiment list-resources --format json tst-aacabd8b 2026-08-03 21:22:32.640905 UTC - expires_at (on expauth): 2026-08-04T01:22:00Z (correctly extended) 2026-08-03 21:22:32.641748 UTC - Testing ssh login 2026-08-03 21:22:32.660851 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-aacabd8b tst 2026-08-03 21:22:33.529358 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-aacabd8b tst 2026-08-03 21:22:34.412065 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-aacabd8b tst 2026-08-03 21:22:35.579093 UTC - Logging in using 'slices bi ssh' 2026-08-03 21:22:35.579335 UTC - Forcing IPv4 only. 2026-08-03 21:22:35.579499 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-aacabd8b tst -- -4 uname -a 2026-08-03 21:22:37.003254 UTC - Forcing IPv4 only. 2026-08-03 21:22:37.003461 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-aacabd8b tst -- -4 uptime 2026-08-03 21:22:38.086867 UTC - CLI SSH Test passed. 2026-08-03 21:22:38.086951 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-03 21:22:38.086971 UTC - Uptime: 23:22:36 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-08-03 21:22:38.087006 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-03 21:22:38.087477 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:38.087653 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:38.087709 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:38.087889 UTC - Connecting to 10.10.211.192:22 2026-08-03 21:22:38.341104 UTC - SSH Test output: 2026-08-03 21:22:38.341180 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-03 21:22:38.341220 UTC - Uptime: 23:22:36 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-08-03 21:22:38.398831 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-03 21:22:38.447833 UTC - /etc/debian_version: 13.1 2026-08-03 21:22:38.447916 UTC - lsb_release matches expected value 2026-08-03 21:22:38.447940 UTC - /etc/debian_version matches expected value 2026-08-03 21:22:38.447958 UTC - SSH Test passed. 2026-08-03 21:22:38.498459 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 23:22:19 +0200! 2026-08-03 21:22:38.498633 UTC - Logging in using SSH over SSH proxy 2026-08-03 21:22:38.498814 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-aacabd8b tst 2026-08-03 21:22:39.388194 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:39.388392 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:39.388485 UTC - Added paramiko HostKeyEntry for 10.10.211.192 2026-08-03 21:22:39.388749 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 21:22:39.388804 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 21:22:39.388924 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 21:22:39.388954 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-03 21:22:39.672959 UTC - Connecting to 10.10.211.192:22 over proxy 2026-08-03 21:22:39.937895 UTC - SSH Test output: 2026-08-03 21:22:39.937984 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-03 21:22:39.938006 UTC - Uptime: 23:22:38 up 0 min, 2 users, load average: 0.11, 0.03, 0.01 2026-08-03 21:22:39.998477 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-03 21:22:40.046749 UTC - /etc/debian_version: 13.1 2026-08-03 21:22:40.046824 UTC - lsb_release matches expected value 2026-08-03 21:22:40.046845 UTC - /etc/debian_version matches expected value 2026-08-03 21:22:40.046861 UTC - SSH Test passed. 2026-08-03 21:22:40.098161 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 23:22:19 +0200! 2026-08-03 21:22:40.127311 UTC - Destroying tst-aacabd8b tst 2026-08-03 21:22:40.127528 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-aacabd8b tst