Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.42s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 193ms 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 221ms + 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 22:24:45.463750 UTC - Installing Slices CLI (latest stable version). 2026-07-08 22:24:45.463965 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-08 22:24:51.443666 UTC - Slices CLI installed. 2026-07-08 22:24:51.443978 UTC - Slices CLI exe: 'slices' 2026-07-08 22:24:51.444026 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-08 22:24:51.444297 UTC - Run: slices --version 2026-07-08 22:24:53.913969 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 22:24:53.914246 UTC - Run: slices pubkey list --format text 2026-07-08 22:24:54.680250 UTC - Pubkey already registered 2026-07-08 22:24:54.680563 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-08 22:24:55.296114 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-08 22:24:55.296214 UTC - Check List Flavors 2026-07-08 22:24:55.296373 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-08 22:24:56.112384 UTC - Check List DiskImages 2026-07-08 22:24:56.112572 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-08 22:24:56.728033 UTC - Requesting resources 2026-07-08 22:24:56.728512 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-08 22:24:56.728627 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-5ca56f5d --user-data /tmp/tmpekzd2sxd 2026-07-08 22:24:58.551198 UTC - Resource ID: r_be-gent1-bi-vm1_01kx1xb525eg9sayg1bp1skjxt 2026-07-08 22:24:58.551348 UTC - Waiting until resource ready 2026-07-08 22:25:00.551737 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5ca56f5d tst 2026-07-08 22:25:01.317539 UTC - Status: IMAGING 2026-07-08 22:25:04.083744 UTC - Status: IMAGING 2026-07-08 22:25:06.799850 UTC - Status: IMAGING 2026-07-08 22:25:09.518399 UTC - Status: BOOTING 2026-07-08 22:25:12.235724 UTC - Status: BOOTING 2026-07-08 22:25:14.951657 UTC - Status: BOOTING 2026-07-08 22:25:17.668147 UTC - Status: BOOTING 2026-07-08 22:25:20.334090 UTC - Status: UP 2026-07-08 22:25:20.334168 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx1xb4gye7sbwxrb4jc7d365 2026-07-08 22:25:20.334216 UTC - Validate resources 2026-07-08 22:25:21.050108 UTC - The fields of the created resource were validated. 2026-07-08 22:25:21.050198 UTC - Check if resources are registered in experiment 2026-07-08 22:25:21.050370 UTC - Run: slices experiment list-resources --format json tst-5ca56f5d 2026-07-08 22:25:21.715698 UTC - Status (on expauth): UP 2026-07-08 22:25:21.715852 UTC - Testing extend expires_at (all resources in experiment) 2026-07-08 22:25:21.716004 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-5ca56f5d 2026-07-08 22:25:24.085723 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5ca56f5d tst 2026-07-08 22:25:24.851571 UTC - Run: slices experiment list-resources --format json tst-5ca56f5d 2026-07-08 22:25:25.516995 UTC - expires_at (on expauth): 2026-07-09T01:25:00Z (correctly extended) 2026-07-08 22:25:25.517093 UTC - Testing extend expires_at (single resource in experiment) 2026-07-08 22:25:25.517254 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-5ca56f5d 2026-07-08 22:25:28.394342 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5ca56f5d tst 2026-07-08 22:25:29.379352 UTC - Run: slices experiment list-resources --format json tst-5ca56f5d 2026-07-08 22:25:30.602199 UTC - expires_at (on expauth): 2026-07-09T02:25:00Z (correctly extended) 2026-07-08 22:25:30.602318 UTC - Testing ssh login 2026-07-08 22:25:30.632222 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5ca56f5d tst 2026-07-08 22:25:31.654447 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5ca56f5d tst 2026-07-08 22:25:32.479640 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5ca56f5d tst 2026-07-08 22:25:33.496110 UTC - Logging in using 'slices bi ssh' 2026-07-08 22:25:33.496178 UTC - Forcing IPv4 only. 2026-07-08 22:25:33.496291 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5ca56f5d tst -- -4 uname -a 2026-07-08 22:25:34.868414 UTC - Forcing IPv4 only. 2026-07-08 22:25:34.870120 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5ca56f5d tst -- -4 uptime 2026-07-08 22:25:36.419010 UTC - CLI SSH Test passed. 2026-07-08 22:25:36.419222 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 22:25:36.419255 UTC - Uptime: 00:25:36 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-08 22:25:36.419290 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-08 22:25:36.419745 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:36.419931 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:36.419998 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:36.420160 UTC - Connecting to 10.10.221.170:22 2026-07-08 22:25:36.649342 UTC - SSH Test output: 2026-07-08 22:25:36.659158 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 22:25:36.659204 UTC - Uptime: 00:25:36 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-08 22:25:36.709741 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-08 22:25:36.757307 UTC - /etc/debian_version: 13.1 2026-07-08 22:25:36.757409 UTC - lsb_release matches expected value 2026-07-08 22:25:36.757441 UTC - /etc/debian_version matches expected value 2026-07-08 22:25:36.757463 UTC - SSH Test passed. 2026-07-08 22:25:36.805038 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 00:25:18 +0200! 2026-07-08 22:25:36.805213 UTC - Logging in using SSH over SSH proxy 2026-07-08 22:25:36.805425 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5ca56f5d tst 2026-07-08 22:25:37.731339 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:37.731540 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:37.731592 UTC - Added paramiko HostKeyEntry for 10.10.221.170 2026-07-08 22:25:37.731845 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 22:25:37.731897 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 22:25:37.731999 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-08 22:25:37.732021 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-08 22:25:38.032965 UTC - Connecting to 10.10.221.170:22 over proxy 2026-07-08 22:25:38.275253 UTC - SSH Test output: 2026-07-08 22:25:38.275323 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 22:25:38.275344 UTC - Uptime: 00:25:38 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-08 22:25:38.328827 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-08 22:25:38.376338 UTC - /etc/debian_version: 13.1 2026-07-08 22:25:38.376408 UTC - lsb_release matches expected value 2026-07-08 22:25:38.376429 UTC - /etc/debian_version matches expected value 2026-07-08 22:25:38.376449 UTC - SSH Test passed. 2026-07-08 22:25:38.424128 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 00:25:18 +0200! 2026-07-08 22:25:38.431822 UTC - Destroying tst-5ca56f5d tst 2026-07-08 22:25:38.431995 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-5ca56f5d tst