Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.13s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 176ms 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 222ms + 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.dev179+g419876b58 (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-08 15:24:48.904888 UTC - Installing Slices CLI (latest stable version). 2026-07-08 15:24:48.905155 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-08 15:24:54.332370 UTC - Slices CLI installed. 2026-07-08 15:24:54.332613 UTC - Slices CLI exe: 'slices' 2026-07-08 15:24:54.332644 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-08 15:24:54.332889 UTC - Run: slices --version 2026-07-08 15:24:57.153278 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-08 15:24:57.153567 UTC - Run: slices pubkey list --format text 2026-07-08 15:24:57.919108 UTC - Pubkey already registered 2026-07-08 15:24:57.919418 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-08 15:24:58.534883 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-08 15:24:58.535016 UTC - Check List Flavors 2026-07-08 15:24:58.535249 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-08 15:24:59.652085 UTC - Check List DiskImages 2026-07-08 15:24:59.652277 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-08 15:25:00.318503 UTC - Requesting resources 2026-07-08 15:25:00.319027 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-08 15:25:00.319141 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-68bec5c5 --user-data /tmp/tmpj9bn50hz 2026-07-08 15:25:03.440917 UTC - Resource ID: r_be-gent1-bi-vm1_01kx15a8bgfbss1cw3hast9kff 2026-07-08 15:25:03.441086 UTC - Waiting until resource ready 2026-07-08 15:25:05.441473 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68bec5c5 tst 2026-07-08 15:25:06.257434 UTC - Status: IMAGING 2026-07-08 15:25:09.124595 UTC - Status: IMAGING 2026-07-08 15:25:11.891429 UTC - Status: IMAGING 2026-07-08 15:25:14.758471 UTC - Status: BOOTING 2026-07-08 15:25:17.575816 UTC - Status: BOOTING 2026-07-08 15:25:20.241882 UTC - Status: BOOTING 2026-07-08 15:25:23.007951 UTC - Status: BOOTING 2026-07-08 15:25:25.824317 UTC - Status: UP 2026-07-08 15:25:25.824401 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx15a7byfywb423prx0p7m00 2026-07-08 15:25:25.824451 UTC - Validate resources 2026-07-08 15:25:26.640914 UTC - The fields of the created resource were validated. 2026-07-08 15:25:26.641011 UTC - Check if resources are registered in experiment 2026-07-08 15:25:26.641180 UTC - Run: slices experiment list-resources --format json tst-68bec5c5 2026-07-08 15:25:27.356934 UTC - Status (on expauth): UP 2026-07-08 15:25:27.357084 UTC - Testing extend expires_at (all resources in experiment) 2026-07-08 15:25:27.357223 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-68bec5c5 2026-07-08 15:25:30.468439 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68bec5c5 tst 2026-07-08 15:25:32.021009 UTC - Run: slices experiment list-resources --format json tst-68bec5c5 2026-07-08 15:25:32.687210 UTC - expires_at (on expauth): 2026-07-08T18:25:00Z (correctly extended) 2026-07-08 15:25:32.687337 UTC - Testing extend expires_at (single resource in experiment) 2026-07-08 15:25:32.687502 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-68bec5c5 2026-07-08 15:25:35.866802 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68bec5c5 tst 2026-07-08 15:25:37.411206 UTC - Run: slices experiment list-resources --format json tst-68bec5c5 2026-07-08 15:25:38.430402 UTC - expires_at (on expauth): 2026-07-08T19:25:00Z (correctly extended) 2026-07-08 15:25:38.430553 UTC - Testing ssh login 2026-07-08 15:25:38.442748 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-68bec5c5 tst 2026-07-08 15:25:39.609547 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-68bec5c5 tst 2026-07-08 15:25:41.446733 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-68bec5c5 tst 2026-07-08 15:25:43.098905 UTC - Logging in using 'slices bi ssh' 2026-07-08 15:25:43.098982 UTC - Forcing IPv4 only. 2026-07-08 15:25:43.099106 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-68bec5c5 tst -- -4 uname -a 2026-07-08 15:25:44.962033 UTC - Forcing IPv4 only. 2026-07-08 15:25:44.962307 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-68bec5c5 tst -- -4 uptime 2026-07-08 15:25:46.396672 UTC - CLI SSH Test passed. 2026-07-08 15:25:46.396945 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-08 15:25:46.396970 UTC - Uptime: 17:25:44 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-08 15:25:46.397009 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-08 15:25:46.397473 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:46.397640 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:46.397697 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:46.397887 UTC - Connecting to 10.10.220.188:22 2026-07-08 15:25:46.651319 UTC - SSH Test output: 2026-07-08 15:25:46.651397 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-08 15:25:46.651418 UTC - Uptime: 17:25:44 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-08 15:25:46.681023 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-08 15:25:46.723314 UTC - /etc/debian_version: 13.1 2026-07-08 15:25:46.723389 UTC - lsb_release matches expected value 2026-07-08 15:25:46.723414 UTC - /etc/debian_version matches expected value 2026-07-08 15:25:46.723430 UTC - SSH Test passed. 2026-07-08 15:25:46.769118 UTC - Cloud-init user-data: Hello World. The time is now Wed, 08 Jul 2026 17:25:22 +0200! 2026-07-08 15:25:46.769261 UTC - Logging in using SSH over SSH proxy 2026-07-08 15:25:46.769406 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-68bec5c5 tst 2026-07-08 15:25:48.448470 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:48.448631 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:48.448684 UTC - Added paramiko HostKeyEntry for 10.10.220.188 2026-07-08 15:25:48.448992 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 15:25:48.449057 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 15:25:48.449175 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 15:25:48.449198 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-08 15:25:48.600337 UTC - Connecting to 10.10.220.188:22 over proxy 2026-07-08 15:25:48.832453 UTC - SSH Test output: 2026-07-08 15:25:48.832525 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-08 15:25:48.832546 UTC - Uptime: 17:25:46 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-08 15:25:48.884418 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-08 15:25:48.931902 UTC - /etc/debian_version: 13.1 2026-07-08 15:25:48.931995 UTC - lsb_release matches expected value 2026-07-08 15:25:48.932020 UTC - /etc/debian_version matches expected value 2026-07-08 15:25:48.932040 UTC - SSH Test passed. 2026-07-08 15:25:48.985346 UTC - Cloud-init user-data: Hello World. The time is now Wed, 08 Jul 2026 17:25:22 +0200! 2026-07-08 15:25:49.023113 UTC - Destroying tst-68bec5c5 tst 2026-07-08 15:25:49.023298 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-68bec5c5 tst