Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.85s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 192ms 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 250ms + 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-16 20:22:17.123300 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:22:17.123546 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:22:22.112209 UTC - Slices CLI installed. 2026-07-16 20:22:22.112497 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-16 20:22:22.117825 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-16 20:22:22.119269 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:22:22.119440 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-16 20:22:22.119471 UTC - Slices CLI exe: 'slices' 2026-07-16 20:22:22.119488 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:22:22.119632 UTC - Run: slices --version 2026-07-16 20:22:24.739605 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-16 20:22:24.739917 UTC - Run: slices pubkey list --format text 2026-07-16 20:22:25.505376 UTC - Pubkey already registered 2026-07-16 20:22:25.505666 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:22:26.070704 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:22:26.070808 UTC - Check List Flavors 2026-07-16 20:22:26.070968 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-16 20:22:27.488149 UTC - Check List DiskImages 2026-07-16 20:22:27.488328 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-16 20:22:28.053454 UTC - Requesting resources 2026-07-16 20:22:28.053999 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:22:28.054140 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-c8c39be3 --user-data /tmp/tmpsaq60cvk 2026-07-16 20:22:31.425384 UTC - Resource ID: r_be-gent1-bi-vm1_01kxp9gp49fndbgzvs7f5xmb6q 2026-07-16 20:22:31.425539 UTC - Waiting until resource ready 2026-07-16 20:22:33.425943 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c8c39be3 tst 2026-07-16 20:22:34.091363 UTC - Status: IMAGING 2026-07-16 20:22:36.807547 UTC - Status: IMAGING 2026-07-16 20:22:39.573894 UTC - Status: IMAGING 2026-07-16 20:22:42.239864 UTC - Status: BOOTING 2026-07-16 20:22:44.956107 UTC - Status: BOOTING 2026-07-16 20:22:47.722339 UTC - Status: BOOTING 2026-07-16 20:22:50.388262 UTC - Status: BOOTING 2026-07-16 20:22:53.104273 UTC - Status: UP 2026-07-16 20:22:53.104369 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp9gn38egkt8mb7k5ag0grf 2026-07-16 20:22:53.104413 UTC - Validate resources 2026-07-16 20:22:53.826549 UTC - The fields of the created resource were validated. 2026-07-16 20:22:53.826683 UTC - Check if resources are registered in experiment 2026-07-16 20:22:53.826895 UTC - Run: slices experiment list-resources --format json tst-c8c39be3 2026-07-16 20:22:54.442143 UTC - Status (on expauth): UP 2026-07-16 20:22:54.442298 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:22:54.442444 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-c8c39be3 2026-07-16 20:22:57.212766 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c8c39be3 tst 2026-07-16 20:22:57.978884 UTC - Run: slices experiment list-resources --format json tst-c8c39be3 2026-07-16 20:22:58.600949 UTC - expires_at (on expauth): 2026-07-16T23:22:00Z (correctly extended) 2026-07-16 20:22:58.601049 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:22:58.601201 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-c8c39be3 2026-07-16 20:23:01.471347 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c8c39be3 tst 2026-07-16 20:23:02.187100 UTC - Run: slices experiment list-resources --format json tst-c8c39be3 2026-07-16 20:23:02.802318 UTC - expires_at (on expauth): 2026-07-17T00:22:00Z (correctly extended) 2026-07-16 20:23:02.802446 UTC - Testing ssh login 2026-07-16 20:23:02.816147 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c8c39be3 tst 2026-07-16 20:23:03.581921 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c8c39be3 tst 2026-07-16 20:23:04.297556 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c8c39be3 tst 2026-07-16 20:23:05.013486 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:23:05.013582 UTC - Forcing IPv4 only. 2026-07-16 20:23:05.013751 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c8c39be3 tst -- -4 uname -a 2026-07-16 20:23:05.980089 UTC - Forcing IPv4 only. 2026-07-16 20:23:05.980273 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c8c39be3 tst -- -4 uptime 2026-07-16 20:23:06.796276 UTC - CLI SSH Test passed. 2026-07-16 20:23:06.796349 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-16 20:23:06.796367 UTC - Uptime: 22:23:06 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-16 20:23:06.796402 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-16 20:23:06.796933 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:06.797118 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:06.797177 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:06.797355 UTC - Connecting to 10.10.217.216:22 2026-07-16 20:23:07.041409 UTC - SSH Test output: 2026-07-16 20:23:07.041480 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-16 20:23:07.041504 UTC - Uptime: 22:23:06 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-16 20:23:07.092569 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-16 20:23:07.140175 UTC - /etc/debian_version: 13.1 2026-07-16 20:23:07.140247 UTC - lsb_release matches expected value 2026-07-16 20:23:07.140266 UTC - /etc/debian_version matches expected value 2026-07-16 20:23:07.140282 UTC - SSH Test passed. 2026-07-16 20:23:07.188865 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 22:22:52 +0200! 2026-07-16 20:23:07.189002 UTC - Logging in using SSH over SSH proxy 2026-07-16 20:23:07.189143 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c8c39be3 tst 2026-07-16 20:23:07.954981 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:07.955152 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:07.955210 UTC - Added paramiko HostKeyEntry for 10.10.217.216 2026-07-16 20:23:07.955456 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:23:07.955510 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:23:07.955609 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:23:07.955630 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-16 20:23:08.245924 UTC - Connecting to 10.10.217.216:22 over proxy 2026-07-16 20:23:08.497723 UTC - SSH Test output: 2026-07-16 20:23:08.497793 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-16 20:23:08.497812 UTC - Uptime: 22:23:08 up 0 min, 2 users, load average: 0.12, 0.03, 0.01 2026-07-16 20:23:08.552895 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-16 20:23:08.601996 UTC - /etc/debian_version: 13.1 2026-07-16 20:23:08.602070 UTC - lsb_release matches expected value 2026-07-16 20:23:08.602091 UTC - /etc/debian_version matches expected value 2026-07-16 20:23:08.602109 UTC - SSH Test passed. 2026-07-16 20:23:08.649070 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 22:22:52 +0200! 2026-07-16 20:23:08.656281 UTC - Destroying tst-c8c39be3 tst 2026-07-16 20:23:08.656490 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-c8c39be3 tst