Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.39s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 177ms 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 217ms + 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-09 06:24:43.370404 UTC - Installing Slices CLI (latest stable version). 2026-07-09 06:24:43.370615 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 06:24:47.995030 UTC - Slices CLI installed. 2026-07-09 06:24:47.995209 UTC - Slices CLI exe: 'slices' 2026-07-09 06:24:47.995237 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 06:24:47.995394 UTC - Run: slices --version 2026-07-09 06:24:50.567764 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-09 06:24:50.568116 UTC - Run: slices pubkey list --format text 2026-07-09 06:24:51.434128 UTC - Pubkey already registered 2026-07-09 06:24:51.434463 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 06:24:52.049944 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 06:24:52.050056 UTC - Check List Flavors 2026-07-09 06:24:52.050230 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-09 06:24:53.166770 UTC - Check List DiskImages 2026-07-09 06:24:53.166956 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-09 06:24:53.732220 UTC - Requesting resources 2026-07-09 06:24:53.732699 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 06:24:53.732859 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-814e69d6 --user-data /tmp/tmpyksw5ruo 2026-07-09 06:24:56.102397 UTC - Resource ID: r_be-gent1-bi-vm1_01kx2rsznfed3rsxjdhmvq0jdq 2026-07-09 06:24:56.102541 UTC - Waiting until resource ready 2026-07-09 06:24:58.102965 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-814e69d6 tst 2026-07-09 06:24:58.768541 UTC - Status: IMAGING 2026-07-09 06:25:01.484366 UTC - Status: IMAGING 2026-07-09 06:25:04.200579 UTC - Status: IMAGING 2026-07-09 06:25:07.017730 UTC - Status: BOOTING 2026-07-09 06:25:09.733729 UTC - Status: BOOTING 2026-07-09 06:25:12.449984 UTC - Status: BOOTING 2026-07-09 06:25:15.166008 UTC - Status: BOOTING 2026-07-09 06:25:17.882786 UTC - Status: UP 2026-07-09 06:25:17.882924 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx2rsyxnebf80jsvhzk9793j 2026-07-09 06:25:17.883012 UTC - Validate resources 2026-07-09 06:25:18.600133 UTC - The fields of the created resource were validated. 2026-07-09 06:25:18.600236 UTC - Check if resources are registered in experiment 2026-07-09 06:25:18.600405 UTC - Run: slices experiment list-resources --format json tst-814e69d6 2026-07-09 06:25:19.215876 UTC - Status (on expauth): UP 2026-07-09 06:25:19.216087 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 06:25:19.216248 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-814e69d6 2026-07-09 06:25:22.338135 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-814e69d6 tst 2026-07-09 06:25:23.104339 UTC - Run: slices experiment list-resources --format json tst-814e69d6 2026-07-09 06:25:23.770114 UTC - expires_at (on expauth): 2026-07-09T09:25:00Z (correctly extended) 2026-07-09 06:25:23.770213 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 06:25:23.770363 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-814e69d6 2026-07-09 06:25:27.207275 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-814e69d6 tst 2026-07-09 06:25:28.316021 UTC - Run: slices experiment list-resources --format json tst-814e69d6 2026-07-09 06:25:29.852598 UTC - expires_at (on expauth): 2026-07-09T10:25:00Z (correctly extended) 2026-07-09 06:25:29.852774 UTC - Testing ssh login 2026-07-09 06:25:29.864791 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-814e69d6 tst 2026-07-09 06:25:30.630623 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-814e69d6 tst 2026-07-09 06:25:31.346058 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-814e69d6 tst 2026-07-09 06:25:32.383578 UTC - Logging in using 'slices bi ssh' 2026-07-09 06:25:32.383645 UTC - Forcing IPv4 only. 2026-07-09 06:25:32.383759 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-814e69d6 tst -- -4 uname -a 2026-07-09 06:25:33.726664 UTC - Forcing IPv4 only. 2026-07-09 06:25:33.726841 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-814e69d6 tst -- -4 uptime 2026-07-09 06:25:34.966347 UTC - CLI SSH Test passed. 2026-07-09 06:25:34.966552 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-09 06:25:34.966572 UTC - Uptime: 08:25:33 up 0 min, 1 user, load average: 0.06, 0.01, 0.00 2026-07-09 06:25:34.966605 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-09 06:25:34.967082 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:34.967263 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:34.967322 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:34.967484 UTC - Connecting to 10.10.217.201:22 2026-07-09 06:25:35.202781 UTC - SSH Test output: 2026-07-09 06:25:35.202853 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-09 06:25:35.202878 UTC - Uptime: 08:25:33 up 0 min, 1 user, load average: 0.06, 0.01, 0.00 2026-07-09 06:25:35.254659 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-09 06:25:35.302475 UTC - /etc/debian_version: 13.1 2026-07-09 06:25:35.302565 UTC - lsb_release matches expected value 2026-07-09 06:25:35.302591 UTC - /etc/debian_version matches expected value 2026-07-09 06:25:35.302605 UTC - SSH Test passed. 2026-07-09 06:25:35.361215 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 08:25:15 +0200! 2026-07-09 06:25:35.361445 UTC - Logging in using SSH over SSH proxy 2026-07-09 06:25:35.361632 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-814e69d6 tst 2026-07-09 06:25:36.228675 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:36.228854 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:36.228909 UTC - Added paramiko HostKeyEntry for 10.10.217.201 2026-07-09 06:25:36.229155 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 06:25:36.229206 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 06:25:36.229311 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 06:25:36.229332 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-09 06:25:36.500605 UTC - Connecting to 10.10.217.201:22 over proxy 2026-07-09 06:25:36.745502 UTC - SSH Test output: 2026-07-09 06:25:36.745581 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-09 06:25:36.745612 UTC - Uptime: 08:25:35 up 0 min, 2 users, load average: 0.06, 0.01, 0.00 2026-07-09 06:25:36.799009 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-09 06:25:36.846989 UTC - /etc/debian_version: 13.1 2026-07-09 06:25:36.847062 UTC - lsb_release matches expected value 2026-07-09 06:25:36.847082 UTC - /etc/debian_version matches expected value 2026-07-09 06:25:36.847102 UTC - SSH Test passed. 2026-07-09 06:25:36.895220 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 08:25:15 +0200! 2026-07-09 06:25:36.902631 UTC - Destroying tst-814e69d6 tst 2026-07-09 06:25:36.902839 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-814e69d6 tst