Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.32s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 195ms 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 707ms + 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-13 20:28:40.371601 UTC - Installing Slices CLI (latest stable version). 2026-07-13 20:28:40.371842 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 20:28:45.850492 UTC - Slices CLI installed. 2026-07-13 20:28:45.850776 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-13 20:28:45.852322 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-13 20:28:45.853187 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 20:28:45.853310 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-13 20:28:45.853337 UTC - Slices CLI exe: 'slices' 2026-07-13 20:28:45.853354 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 20:28:45.853499 UTC - Run: slices --version 2026-07-13 20:28:48.774684 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-13 20:28:48.775136 UTC - Run: slices pubkey list --format text 2026-07-13 20:28:49.591904 UTC - Pubkey already registered 2026-07-13 20:28:49.592189 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 20:28:50.258032 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 20:28:50.258124 UTC - Check List Flavors 2026-07-13 20:28:50.258292 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-13 20:28:51.726469 UTC - Check List DiskImages 2026-07-13 20:28:51.726739 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-13 20:28:52.392842 UTC - Requesting resources 2026-07-13 20:28:52.393573 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 20:28:52.393737 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-9be34664 --user-data /tmp/tmpzch9q6jr 2026-07-13 20:28:55.667271 UTC - Resource ID: r_be-gent1-bi-vm1_01kxejp8b3e8vr9zzceyfmv4kc 2026-07-13 20:28:55.667441 UTC - Waiting until resource ready 2026-07-13 20:28:57.667831 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9be34664 tst 2026-07-13 20:28:58.434092 UTC - Status: IMAGING 2026-07-13 20:29:01.302097 UTC - Status: IMAGING 2026-07-13 20:29:04.119984 UTC - Status: IMAGING 2026-07-13 20:29:07.187653 UTC - Status: BOOTING 2026-07-13 20:29:09.953961 UTC - Status: BOOTING 2026-07-13 20:29:12.771389 UTC - Status: BOOTING 2026-07-13 20:29:15.538126 UTC - Status: BOOTING 2026-07-13 20:29:18.305208 UTC - Status: UP 2026-07-13 20:29:18.305353 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxejp7e2f6haxcng73jend2q 2026-07-13 20:29:18.305452 UTC - Validate resources 2026-07-13 20:29:19.072456 UTC - The fields of the created resource were validated. 2026-07-13 20:29:19.072588 UTC - Check if resources are registered in experiment 2026-07-13 20:29:19.072842 UTC - Run: slices experiment list-resources --format json tst-9be34664 2026-07-13 20:29:19.838718 UTC - Status (on expauth): UP 2026-07-13 20:29:19.838860 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 20:29:19.839002 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-9be34664 2026-07-13 20:29:22.711116 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9be34664 tst 2026-07-13 20:29:23.477650 UTC - Run: slices experiment list-resources --format json tst-9be34664 2026-07-13 20:29:24.144228 UTC - expires_at (on expauth): 2026-07-13T23:29:00Z (correctly extended) 2026-07-13 20:29:24.144357 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 20:29:24.144599 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-9be34664 2026-07-13 20:29:26.867967 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9be34664 tst 2026-07-13 20:29:27.735004 UTC - Run: slices experiment list-resources --format json tst-9be34664 2026-07-13 20:29:28.451763 UTC - expires_at (on expauth): 2026-07-14T00:29:00Z (correctly extended) 2026-07-13 20:29:28.451880 UTC - Testing ssh login 2026-07-13 20:29:28.463280 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9be34664 tst 2026-07-13 20:29:29.229508 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9be34664 tst 2026-07-13 20:29:30.045578 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9be34664 tst 2026-07-13 20:29:30.912091 UTC - Logging in using 'slices bi ssh' 2026-07-13 20:29:30.912221 UTC - Forcing IPv4 only. 2026-07-13 20:29:30.912479 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9be34664 tst -- -4 uname -a 2026-07-13 20:29:31.930163 UTC - Forcing IPv4 only. 2026-07-13 20:29:31.930355 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9be34664 tst -- -4 uptime 2026-07-13 20:29:32.796579 UTC - CLI SSH Test passed. 2026-07-13 20:29:32.796656 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-13 20:29:32.796677 UTC - Uptime: 22:29:32 up 0 min, 1 user, load average: 0.68, 0.17, 0.06 2026-07-13 20:29:32.796711 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-13 20:29:32.797188 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:32.797366 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:32.797439 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:32.797629 UTC - Connecting to 10.10.222.19:22 2026-07-13 20:29:33.036906 UTC - SSH Test output: 2026-07-13 20:29:33.036978 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-13 20:29:33.036998 UTC - Uptime: 22:29:32 up 0 min, 1 user, load average: 0.68, 0.17, 0.06 2026-07-13 20:29:33.088841 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-13 20:29:33.136096 UTC - /etc/debian_version: 13.1 2026-07-13 20:29:33.136185 UTC - lsb_release matches expected value 2026-07-13 20:29:33.136220 UTC - /etc/debian_version matches expected value 2026-07-13 20:29:33.136242 UTC - SSH Test passed. 2026-07-13 20:29:33.183999 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 22:29:17 +0200! 2026-07-13 20:29:33.184196 UTC - Logging in using SSH over SSH proxy 2026-07-13 20:29:33.184399 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9be34664 tst 2026-07-13 20:29:34.000800 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:34.001041 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:34.001119 UTC - Added paramiko HostKeyEntry for 10.10.222.19 2026-07-13 20:29:34.001491 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:29:34.001570 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:29:34.001741 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:29:34.001772 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-13 20:29:34.269893 UTC - Connecting to 10.10.222.19:22 over proxy 2026-07-13 20:29:34.505826 UTC - SSH Test output: 2026-07-13 20:29:34.505901 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-13 20:29:34.505920 UTC - Uptime: 22:29:34 up 0 min, 2 users, load average: 0.63, 0.17, 0.06 2026-07-13 20:29:34.557960 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-13 20:29:34.605371 UTC - /etc/debian_version: 13.1 2026-07-13 20:29:34.605457 UTC - lsb_release matches expected value 2026-07-13 20:29:34.605481 UTC - /etc/debian_version matches expected value 2026-07-13 20:29:34.605496 UTC - SSH Test passed. 2026-07-13 20:29:34.654251 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 22:29:17 +0200! 2026-07-13 20:29:34.661216 UTC - Destroying tst-9be34664 tst 2026-07-13 20:29:34.661400 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-9be34664 tst