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 180ms 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 662ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.3.post1 + 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-27 04:29:53.144004 UTC - Installing Slices CLI (latest stable version). 2026-07-27 04:29:53.144226 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 04:29:58.170480 UTC - Slices CLI installed. 2026-07-27 04:29:58.170721 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-27 04:29:58.173110 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-27 04:29:58.174018 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 04:29:58.174174 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-27 04:29:58.174206 UTC - Slices CLI exe: 'slices' 2026-07-27 04:29:58.174226 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 04:29:58.174379 UTC - Run: slices --version 2026-07-27 04:30:00.844049 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-27 04:30:00.844366 UTC - Run: slices pubkey list --format text 2026-07-27 04:30:01.559671 UTC - Pubkey already registered 2026-07-27 04:30:01.559938 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 04:30:02.125071 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 04:30:02.125171 UTC - Check List Flavors 2026-07-27 04:30:02.125332 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-27 04:30:02.790769 UTC - Check List DiskImages 2026-07-27 04:30:02.790980 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-27 04:30:03.406265 UTC - Requesting resources 2026-07-27 04:30:03.406782 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 04:30:03.406919 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-cf77ff77 --user-data /tmp/tmpssdnvku9 2026-07-27 04:30:04.523114 UTC - Resource ID: r_be-gent1-bi-vm1_01kygxckk1etdakqqk6jgp1kgs 2026-07-27 04:30:04.523269 UTC - Waiting until resource ready 2026-07-27 04:30:06.523641 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf77ff77 tst 2026-07-27 04:30:07.239250 UTC - Status: IMAGING 2026-07-27 04:30:10.005524 UTC - Status: IMAGING 2026-07-27 04:30:12.721892 UTC - Status: IMAGING 2026-07-27 04:30:15.437926 UTC - Status: BOOTING 2026-07-27 04:30:18.204500 UTC - Status: BOOTING 2026-07-27 04:30:20.970857 UTC - Status: BOOTING 2026-07-27 04:30:23.889724 UTC - Status: BOOTING 2026-07-27 04:30:26.756912 UTC - Status: UP 2026-07-27 04:30:26.757029 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kygxckazf85tqh992chw7zjf 2026-07-27 04:30:26.757074 UTC - Validate resources 2026-07-27 04:30:27.581545 UTC - The fields of the created resource were validated. 2026-07-27 04:30:27.581646 UTC - Check if resources are registered in experiment 2026-07-27 04:30:27.581808 UTC - Run: slices experiment list-resources --format json tst-cf77ff77 2026-07-27 04:30:28.302747 UTC - Status (on expauth): INACTIVE 2026-07-27 04:30:31.087748 UTC - Status (on expauth): UP 2026-07-27 04:30:31.088019 UTC - "slices experiment list-resources" was not ready immediately. 2026-07-27 04:30:31.088075 UTC - It was ready after: 3.506319 seconds. (Note: used wait steps of 2s) 2026-07-27 04:30:31.088165 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 04:30:31.088328 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-cf77ff77 2026-07-27 04:30:32.713574 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf77ff77 tst 2026-07-27 04:30:33.683122 UTC - Run: slices experiment list-resources --format json tst-cf77ff77 2026-07-27 04:30:34.661012 UTC - expires_at (on expauth): 2026-07-27T07:30:00Z (correctly extended) 2026-07-27 04:30:34.661149 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 04:30:34.661351 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-cf77ff77 2026-07-27 04:30:36.335104 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf77ff77 tst 2026-07-27 04:30:37.053077 UTC - Run: slices experiment list-resources --format json tst-cf77ff77 2026-07-27 04:30:37.718992 UTC - expires_at (on expauth): 2026-07-27T08:30:00Z (correctly extended) 2026-07-27 04:30:37.719310 UTC - Testing ssh login 2026-07-27 04:30:37.734452 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cf77ff77 tst 2026-07-27 04:30:38.551216 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cf77ff77 tst 2026-07-27 04:30:39.317894 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-cf77ff77 tst 2026-07-27 04:30:40.083819 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:30:40.083897 UTC - Forcing IPv4 only. 2026-07-27 04:30:40.084031 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cf77ff77 tst -- -4 uname -a 2026-07-27 04:30:41.105433 UTC - Forcing IPv4 only. 2026-07-27 04:30:41.105657 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cf77ff77 tst -- -4 uptime 2026-07-27 04:30:42.381039 UTC - CLI SSH Test passed. 2026-07-27 04:30:42.381132 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-27 04:30:42.381160 UTC - Uptime: 06:30:41 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-27 04:30:42.381209 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-27 04:30:42.381832 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:42.382119 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:42.382217 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:42.382468 UTC - Connecting to 10.10.222.163:22 2026-07-27 04:30:42.619219 UTC - SSH Test output: 2026-07-27 04:30:42.619288 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-27 04:30:42.619307 UTC - Uptime: 06:30:41 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-27 04:30:42.671781 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-27 04:30:42.719238 UTC - /etc/debian_version: 13.1 2026-07-27 04:30:42.719308 UTC - lsb_release matches expected value 2026-07-27 04:30:42.719327 UTC - /etc/debian_version matches expected value 2026-07-27 04:30:42.719343 UTC - SSH Test passed. 2026-07-27 04:30:42.766880 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:30:25 +0200! 2026-07-27 04:30:42.767019 UTC - Logging in using SSH over SSH proxy 2026-07-27 04:30:42.767166 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-cf77ff77 tst 2026-07-27 04:30:43.856903 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:43.857064 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:43.857117 UTC - Added paramiko HostKeyEntry for 10.10.222.163 2026-07-27 04:30:43.857356 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:30:43.857405 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:30:43.857515 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:30:43.857536 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-27 04:30:44.145984 UTC - Connecting to 10.10.222.163:22 over proxy 2026-07-27 04:30:44.387248 UTC - SSH Test output: 2026-07-27 04:30:44.387320 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-27 04:30:44.387339 UTC - Uptime: 06:30:43 up 0 min, 2 users, load average: 0.11, 0.03, 0.01 2026-07-27 04:30:44.437946 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-27 04:30:44.485952 UTC - /etc/debian_version: 13.1 2026-07-27 04:30:44.486051 UTC - lsb_release matches expected value 2026-07-27 04:30:44.486081 UTC - /etc/debian_version matches expected value 2026-07-27 04:30:44.486105 UTC - SSH Test passed. 2026-07-27 04:30:44.533380 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:30:25 +0200! 2026-07-27 04:30:44.541345 UTC - Destroying tst-cf77ff77 tst 2026-07-27 04:30:44.541524 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-cf77ff77 tst