Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.19s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 191ms 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 241ms + 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.3 + 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-06 05:24:24.870994 UTC - Installing Slices CLI (latest stable version). 2026-08-06 05:24:24.871292 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-06 05:24:30.054522 UTC - Slices CLI installed. 2026-08-06 05:24:30.054947 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-06 05:24:30.057986 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-06 05:24:30.058083 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-06 05:24:30.058167 UTC - which slices: /app/.venv/bin/slices 2026-08-06 05:24:30.058186 UTC - Slices CLI exe: 'slices' 2026-08-06 05:24:30.058205 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-06 05:24:30.058377 UTC - Run: slices --version 2026-08-06 05:24:33.031269 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-06 05:24:33.031687 UTC - Run: slices pubkey list --format text 2026-08-06 05:24:33.849118 UTC - Pubkey already registered 2026-08-06 05:24:33.849387 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-06 05:24:34.515175 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-06 05:24:34.515271 UTC - Check List Flavors 2026-08-06 05:24:34.515441 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-06 05:24:35.331664 UTC - Check List DiskImages 2026-08-06 05:24:35.331864 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-06 05:24:35.997541 UTC - Requesting resources 2026-08-06 05:24:35.998051 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-06 05:24:35.998178 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-86e05bcc --user-data /tmp/tmp_6ntj_ie 2026-08-06 05:24:37.114762 UTC - Resource ID: r_be-gent1-bi-vm1_01kzarfnf7fr987dh6ed5v3b3w 2026-08-06 05:24:37.114943 UTC - Waiting until resource ready 2026-08-06 05:24:39.115425 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-86e05bcc tst 2026-08-06 05:24:39.931837 UTC - Status: IMAGING 2026-08-06 05:24:42.698344 UTC - Status: IMAGING 2026-08-06 05:24:45.464867 UTC - Status: IMAGING 2026-08-06 05:24:48.231205 UTC - Status: BOOTING 2026-08-06 05:24:50.997919 UTC - Status: BOOTING 2026-08-06 05:24:53.764331 UTC - Status: BOOTING 2026-08-06 05:24:56.531227 UTC - Status: BOOTING 2026-08-06 05:24:59.298265 UTC - Status: INITIALIZING 2026-08-06 05:25:02.114916 UTC - Status: UP 2026-08-06 05:25:02.115039 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzarfn7te6t99wbf3f25891f 2026-08-06 05:25:02.115100 UTC - Validate resources 2026-08-06 05:25:02.832221 UTC - The fields of the created resource were validated. 2026-08-06 05:25:02.832348 UTC - Check if resources are registered in experiment 2026-08-06 05:25:02.832565 UTC - Run: slices experiment list-resources --format json tst-86e05bcc 2026-08-06 05:25:03.498710 UTC - Status (on expauth): UP 2026-08-06 05:25:03.498873 UTC - Testing extend expires_at (all resources in experiment) 2026-08-06 05:25:03.499028 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-86e05bcc 2026-08-06 05:25:04.916638 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-86e05bcc tst 2026-08-06 05:25:05.733876 UTC - Run: slices experiment list-resources --format json tst-86e05bcc 2026-08-06 05:25:06.449674 UTC - expires_at (on expauth): 2026-08-06T08:25:00Z (correctly extended) 2026-08-06 05:25:06.449799 UTC - Testing extend expires_at (single resource in experiment) 2026-08-06 05:25:06.450032 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-86e05bcc 2026-08-06 05:25:07.867693 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-86e05bcc tst 2026-08-06 05:25:08.635409 UTC - Run: slices experiment list-resources --format json tst-86e05bcc 2026-08-06 05:25:09.301517 UTC - expires_at (on expauth): 2026-08-06T09:25:00Z (correctly extended) 2026-08-06 05:25:09.301714 UTC - Testing ssh login 2026-08-06 05:25:09.316948 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-86e05bcc tst 2026-08-06 05:25:10.183910 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-86e05bcc tst 2026-08-06 05:25:11.150508 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-86e05bcc tst 2026-08-06 05:25:12.016800 UTC - Logging in using 'slices bi ssh' 2026-08-06 05:25:12.016884 UTC - Forcing IPv4 only. 2026-08-06 05:25:12.017049 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-86e05bcc tst -- -4 uname -a 2026-08-06 05:25:13.033603 UTC - Forcing IPv4 only. 2026-08-06 05:25:13.033812 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-86e05bcc tst -- -4 uptime 2026-08-06 05:25:13.899987 UTC - CLI SSH Test passed. 2026-08-06 05:25:13.900061 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-06 05:25:13.900082 UTC - Uptime: 07:25:13 up 0 min, 1 user, load average: 0.44, 0.11, 0.04 2026-08-06 05:25:13.900125 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-06 05:25:13.900555 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:13.900783 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:13.900876 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:13.901079 UTC - Connecting to 10.10.209.82:22 2026-08-06 05:25:14.125817 UTC - SSH Test output: 2026-08-06 05:25:14.125903 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-06 05:25:14.125930 UTC - Uptime: 07:25:13 up 0 min, 1 user, load average: 0.44, 0.11, 0.04 2026-08-06 05:25:14.179121 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-06 05:25:14.225566 UTC - /etc/debian_version: 13.1 2026-08-06 05:25:14.225644 UTC - lsb_release matches expected value 2026-08-06 05:25:14.225666 UTC - /etc/debian_version matches expected value 2026-08-06 05:25:14.225682 UTC - SSH Test passed. 2026-08-06 05:25:14.273187 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 07:24:58 +0200! 2026-08-06 05:25:14.273326 UTC - Logging in using SSH over SSH proxy 2026-08-06 05:25:14.273477 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-86e05bcc tst 2026-08-06 05:25:15.089770 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:15.089932 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:15.089985 UTC - Added paramiko HostKeyEntry for 10.10.209.82 2026-08-06 05:25:15.090253 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 05:25:15.090311 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 05:25:15.090414 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 05:25:15.090442 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-06 05:25:15.208769 UTC - Connecting to 10.10.209.82:22 over proxy 2026-08-06 05:25:15.445503 UTC - SSH Test output: 2026-08-06 05:25:15.445594 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-06 05:25:15.445626 UTC - Uptime: 07:25:14 up 0 min, 2 users, load average: 0.44, 0.11, 0.04 2026-08-06 05:25:15.499113 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-06 05:25:15.545410 UTC - /etc/debian_version: 13.1 2026-08-06 05:25:15.545487 UTC - lsb_release matches expected value 2026-08-06 05:25:15.545509 UTC - /etc/debian_version matches expected value 2026-08-06 05:25:15.545524 UTC - SSH Test passed. 2026-08-06 05:25:15.593100 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 07:24:58 +0200! 2026-08-06 05:25:15.600910 UTC - Destroying tst-86e05bcc tst 2026-08-06 05:25:15.601101 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-86e05bcc tst