Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.04s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 196ms 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 268ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-19 13:27:45.595860 UTC - Installing Slices CLI (latest stable version). 2026-07-19 13:27:45.596080 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 13:27:50.621358 UTC - Slices CLI installed. 2026-07-19 13:27:50.621585 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-19 13:27:50.624008 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-19 13:27:50.624974 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 13:27:50.625091 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-19 13:27:50.625117 UTC - Slices CLI exe: 'slices' 2026-07-19 13:27:50.625134 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 13:27:50.625276 UTC - Run: slices --version 2026-07-19 13:27:53.295183 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-19 13:27:53.295452 UTC - Run: slices pubkey list --format text 2026-07-19 13:27:54.010512 UTC - Pubkey already registered 2026-07-19 13:27:54.010782 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 13:27:54.576021 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 13:27:54.576155 UTC - Check List Flavors 2026-07-19 13:27:54.576349 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-19 13:27:55.843757 UTC - Check List DiskImages 2026-07-19 13:27:55.843961 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-19 13:27:56.459254 UTC - Requesting resources 2026-07-19 13:27:56.459782 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 13:27:56.459901 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-bf8446fe --user-data /tmp/tmpteay5lzw 2026-07-19 13:27:59.580813 UTC - Resource ID: r_be-gent1-bi-vm1_01kxx8zt6cev4ry4n334n3zpca 2026-07-19 13:27:59.580971 UTC - Waiting until resource ready 2026-07-19 13:28:01.581370 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-bf8446fe tst 2026-07-19 13:28:02.246928 UTC - Status: IMAGING 2026-07-19 13:28:04.963301 UTC - Status: IMAGING 2026-07-19 13:28:07.629326 UTC - Status: IMAGING 2026-07-19 13:28:10.295556 UTC - Status: BOOTING 2026-07-19 13:28:12.961321 UTC - Status: BOOTING 2026-07-19 13:28:15.678044 UTC - Status: BOOTING 2026-07-19 13:28:18.444560 UTC - Status: BOOTING 2026-07-19 13:28:21.110402 UTC - Status: UP 2026-07-19 13:28:21.110490 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxx8zs5cft6s9neazwn5zfhd 2026-07-19 13:28:21.110549 UTC - Validate resources 2026-07-19 13:28:21.776565 UTC - The fields of the created resource were validated. 2026-07-19 13:28:21.776703 UTC - Check if resources are registered in experiment 2026-07-19 13:28:21.776927 UTC - Run: slices experiment list-resources --format json tst-bf8446fe 2026-07-19 13:28:22.392333 UTC - Status (on expauth): UP 2026-07-19 13:28:22.392483 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 13:28:22.392615 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-bf8446fe 2026-07-19 13:28:25.114026 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-bf8446fe tst 2026-07-19 13:28:25.781665 UTC - Run: slices experiment list-resources --format json tst-bf8446fe 2026-07-19 13:28:26.447068 UTC - expires_at (on expauth): 2026-07-19T16:28:00Z (correctly extended) 2026-07-19 13:28:26.447184 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 13:28:26.447362 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-bf8446fe 2026-07-19 13:28:29.318160 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-bf8446fe tst 2026-07-19 13:28:29.983827 UTC - Run: slices experiment list-resources --format json tst-bf8446fe 2026-07-19 13:28:30.599023 UTC - expires_at (on expauth): 2026-07-19T17:28:00Z (correctly extended) 2026-07-19 13:28:30.599156 UTC - Testing ssh login 2026-07-19 13:28:30.612316 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-bf8446fe tst 2026-07-19 13:28:31.328277 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-bf8446fe tst 2026-07-19 13:28:32.043837 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-bf8446fe tst 2026-07-19 13:28:32.759568 UTC - Logging in using 'slices bi ssh' 2026-07-19 13:28:32.759643 UTC - Forcing IPv4 only. 2026-07-19 13:28:32.759791 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-bf8446fe tst -- -4 uname -a 2026-07-19 13:28:33.675650 UTC - Forcing IPv4 only. 2026-07-19 13:28:33.675851 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-bf8446fe tst -- -4 uptime 2026-07-19 13:28:34.491611 UTC - CLI SSH Test passed. 2026-07-19 13:28:34.491695 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-19 13:28:34.491718 UTC - Uptime: 15:28:33 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-19 13:28:34.491751 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-19 13:28:34.492190 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:34.492360 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:34.492417 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:34.492595 UTC - Connecting to 10.10.220.89:22 2026-07-19 13:28:34.719676 UTC - SSH Test output: 2026-07-19 13:28:34.719760 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-19 13:28:34.719781 UTC - Uptime: 15:28:33 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-19 13:28:34.767626 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-19 13:28:34.811493 UTC - /etc/debian_version: 13.1 2026-07-19 13:28:34.811567 UTC - lsb_release matches expected value 2026-07-19 13:28:34.811587 UTC - /etc/debian_version matches expected value 2026-07-19 13:28:34.811602 UTC - SSH Test passed. 2026-07-19 13:28:34.855179 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 15:28:19 +0200! 2026-07-19 13:28:34.855317 UTC - Logging in using SSH over SSH proxy 2026-07-19 13:28:34.855468 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-bf8446fe tst 2026-07-19 13:28:35.571050 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:35.571197 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:35.571247 UTC - Added paramiko HostKeyEntry for 10.10.220.89 2026-07-19 13:28:35.571474 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 13:28:35.571525 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 13:28:35.571628 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 13:28:35.571648 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-19 13:28:35.845580 UTC - Connecting to 10.10.220.89:22 over proxy 2026-07-19 13:28:36.080143 UTC - SSH Test output: 2026-07-19 13:28:36.080215 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-19 13:28:36.080233 UTC - Uptime: 15:28:35 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-19 13:28:36.132237 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-19 13:28:36.179958 UTC - /etc/debian_version: 13.1 2026-07-19 13:28:36.180033 UTC - lsb_release matches expected value 2026-07-19 13:28:36.180056 UTC - /etc/debian_version matches expected value 2026-07-19 13:28:36.180071 UTC - SSH Test passed. 2026-07-19 13:28:36.228204 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 15:28:19 +0200! 2026-07-19 13:28:36.234899 UTC - Destroying tst-bf8446fe tst 2026-07-19 13:28:36.235071 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-bf8446fe tst