Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.36s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 228ms 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 406ms + 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.4 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-21 04:27:34.561875 UTC - Installing Slices CLI (latest stable version). 2026-07-21 04:27:34.562105 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 04:27:39.791531 UTC - Slices CLI installed. 2026-07-21 04:27:39.791786 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-21 04:27:39.794060 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-21 04:27:39.795016 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 04:27:39.795158 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-21 04:27:39.795191 UTC - Slices CLI exe: 'slices' 2026-07-21 04:27:39.795209 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 04:27:39.795348 UTC - Run: slices --version 2026-07-21 04:27:42.765889 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-21 04:27:42.766182 UTC - Run: slices pubkey list --format text 2026-07-21 04:27:43.581712 UTC - Pubkey already registered 2026-07-21 04:27:43.582001 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 04:27:44.247362 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 04:27:44.247482 UTC - Check List Flavors 2026-07-21 04:27:44.247740 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-21 04:27:45.665329 UTC - Check List DiskImages 2026-07-21 04:27:45.665522 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-21 04:27:46.431690 UTC - Requesting resources 2026-07-21 04:27:46.432215 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-21 04:27:46.432331 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-56b6cdac --user-data /tmp/tmpnw57g_3c 2026-07-21 04:27:49.904979 UTC - Resource ID: r_be-gent1-bi-vm1_01ky1ew642fv2bm2sry6jg6thq 2026-07-21 04:27:49.905134 UTC - Waiting until resource ready 2026-07-21 04:27:51.905521 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-56b6cdac tst 2026-07-21 04:27:52.671165 UTC - Status: IMAGING 2026-07-21 04:27:55.337762 UTC - Status: IMAGING 2026-07-21 04:27:58.103837 UTC - Status: IMAGING 2026-07-21 04:28:00.870411 UTC - Status: BOOTING 2026-07-21 04:28:03.636549 UTC - Status: BOOTING 2026-07-21 04:28:06.402642 UTC - Status: BOOTING 2026-07-21 04:28:09.118848 UTC - Status: BOOTING 2026-07-21 04:28:11.834728 UTC - Status: UP 2026-07-21 04:28:11.834826 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky1ew521epg9mv6htzxtrh8p 2026-07-21 04:28:11.834872 UTC - Validate resources 2026-07-21 04:28:12.550773 UTC - The fields of the created resource were validated. 2026-07-21 04:28:12.550885 UTC - Check if resources are registered in experiment 2026-07-21 04:28:12.551052 UTC - Run: slices experiment list-resources --format json tst-56b6cdac 2026-07-21 04:28:13.216450 UTC - Status (on expauth): UP 2026-07-21 04:28:13.216588 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 04:28:13.216740 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-56b6cdac 2026-07-21 04:28:16.143497 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-56b6cdac tst 2026-07-21 04:28:16.859416 UTC - Run: slices experiment list-resources --format json tst-56b6cdac 2026-07-21 04:28:17.524967 UTC - expires_at (on expauth): 2026-07-21T07:28:00Z (correctly extended) 2026-07-21 04:28:17.525062 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 04:28:17.525207 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-56b6cdac 2026-07-21 04:28:20.446821 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-56b6cdac tst 2026-07-21 04:28:21.162848 UTC - Run: slices experiment list-resources --format json tst-56b6cdac 2026-07-21 04:28:21.778180 UTC - expires_at (on expauth): 2026-07-21T08:28:00Z (correctly extended) 2026-07-21 04:28:21.778316 UTC - Testing ssh login 2026-07-21 04:28:21.791963 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-56b6cdac tst 2026-07-21 04:28:22.557754 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-56b6cdac tst 2026-07-21 04:28:23.273451 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-56b6cdac tst 2026-07-21 04:28:23.989038 UTC - Logging in using 'slices bi ssh' 2026-07-21 04:28:23.989113 UTC - Forcing IPv4 only. 2026-07-21 04:28:23.989259 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-56b6cdac tst -- -4 uname -a 2026-07-21 04:28:24.955235 UTC - Forcing IPv4 only. 2026-07-21 04:28:24.955428 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-56b6cdac tst -- -4 uptime 2026-07-21 04:28:25.771485 UTC - CLI SSH Test passed. 2026-07-21 04:28:25.771590 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-21 04:28:25.771612 UTC - Uptime: 06:28:24 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-21 04:28:25.771661 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-21 04:28:25.772273 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:25.772477 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:25.772554 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:25.772829 UTC - Connecting to 10.10.220.105:22 2026-07-21 04:28:25.999247 UTC - SSH Test output: 2026-07-21 04:28:25.999323 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-21 04:28:25.999349 UTC - Uptime: 06:28:24 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-21 04:28:26.050979 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-21 04:28:26.098952 UTC - /etc/debian_version: 13.1 2026-07-21 04:28:26.099061 UTC - lsb_release matches expected value 2026-07-21 04:28:26.099084 UTC - /etc/debian_version matches expected value 2026-07-21 04:28:26.099100 UTC - SSH Test passed. 2026-07-21 04:28:26.142651 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 06:28:09 +0200! 2026-07-21 04:28:26.142854 UTC - Logging in using SSH over SSH proxy 2026-07-21 04:28:26.143031 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-56b6cdac tst 2026-07-21 04:28:26.959165 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:26.959313 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:26.959363 UTC - Added paramiko HostKeyEntry for 10.10.220.105 2026-07-21 04:28:26.959601 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:28:26.959650 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:28:26.959751 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:28:26.959771 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-21 04:28:27.221901 UTC - Connecting to 10.10.220.105:22 over proxy 2026-07-21 04:28:27.451452 UTC - SSH Test output: 2026-07-21 04:28:27.451526 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-21 04:28:27.451546 UTC - Uptime: 06:28:26 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-21 04:28:27.500612 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-21 04:28:27.546925 UTC - /etc/debian_version: 13.1 2026-07-21 04:28:27.546999 UTC - lsb_release matches expected value 2026-07-21 04:28:27.547019 UTC - /etc/debian_version matches expected value 2026-07-21 04:28:27.547033 UTC - SSH Test passed. 2026-07-21 04:28:27.595439 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 06:28:09 +0200! 2026-07-21 04:28:27.602499 UTC - Destroying tst-56b6cdac tst 2026-07-21 04:28:27.602682 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-56b6cdac tst