Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.10s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 191ms 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 350ms + 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-20 04:24:00.416335 UTC - Installing Slices CLI (latest stable version). 2026-07-20 04:24:00.418016 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 04:24:05.093516 UTC - Slices CLI installed. 2026-07-20 04:24:05.093749 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-20 04:24:05.095040 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-20 04:24:05.095874 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 04:24:05.096017 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-20 04:24:05.096048 UTC - Slices CLI exe: 'slices' 2026-07-20 04:24:05.096067 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 04:24:05.096208 UTC - Run: slices --version 2026-07-20 04:24:07.916532 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-20 04:24:07.916854 UTC - Run: slices pubkey list --format text 2026-07-20 04:24:08.732793 UTC - Pubkey already registered 2026-07-20 04:24:08.733095 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 04:24:09.350078 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 04:24:09.350207 UTC - Check List Flavors 2026-07-20 04:24:09.350436 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-20 04:24:10.668653 UTC - Check List DiskImages 2026-07-20 04:24:10.668913 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-20 04:24:11.284545 UTC - Requesting resources 2026-07-20 04:24:11.285158 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 04:24:11.285289 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-68ebd77b --user-data /tmp/tmppscyhdhe 2026-07-20 04:24:14.257054 UTC - Resource ID: r_be-gent1-bi-vm1_01kxyw8wgsfpnta0kzpvex8m85 2026-07-20 04:24:14.257247 UTC - Waiting until resource ready 2026-07-20 04:24:16.257649 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68ebd77b tst 2026-07-20 04:24:16.973833 UTC - Status: IMAGING 2026-07-20 04:24:19.692901 UTC - Status: IMAGING 2026-07-20 04:24:22.409158 UTC - Status: IMAGING 2026-07-20 04:24:25.125336 UTC - Status: BOOTING 2026-07-20 04:24:27.841643 UTC - Status: BOOTING 2026-07-20 04:24:30.557782 UTC - Status: BOOTING 2026-07-20 04:24:33.324183 UTC - Status: BOOTING 2026-07-20 04:24:36.040325 UTC - Status: UP 2026-07-20 04:24:36.040457 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxyw8vm6eystez47qsqw1ncy 2026-07-20 04:24:36.040504 UTC - Validate resources 2026-07-20 04:24:36.756544 UTC - The fields of the created resource were validated. 2026-07-20 04:24:36.756645 UTC - Check if resources are registered in experiment 2026-07-20 04:24:36.756835 UTC - Run: slices experiment list-resources --format json tst-68ebd77b 2026-07-20 04:24:37.422295 UTC - Status (on expauth): UP 2026-07-20 04:24:37.422461 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 04:24:37.422602 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-68ebd77b 2026-07-20 04:24:40.243387 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68ebd77b tst 2026-07-20 04:24:40.959117 UTC - Run: slices experiment list-resources --format json tst-68ebd77b 2026-07-20 04:24:41.624709 UTC - expires_at (on expauth): 2026-07-20T07:24:00Z (correctly extended) 2026-07-20 04:24:41.624833 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 04:24:41.624987 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-68ebd77b 2026-07-20 04:24:44.646511 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-68ebd77b tst 2026-07-20 04:24:45.362416 UTC - Run: slices experiment list-resources --format json tst-68ebd77b 2026-07-20 04:24:45.977878 UTC - expires_at (on expauth): 2026-07-20T08:24:00Z (correctly extended) 2026-07-20 04:24:45.978014 UTC - Testing ssh login 2026-07-20 04:24:45.989234 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-68ebd77b tst 2026-07-20 04:24:46.704962 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-68ebd77b tst 2026-07-20 04:24:47.470751 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-68ebd77b tst 2026-07-20 04:24:48.186365 UTC - Logging in using 'slices bi ssh' 2026-07-20 04:24:48.186446 UTC - Forcing IPv4 only. 2026-07-20 04:24:48.186588 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-68ebd77b tst -- -4 uname -a 2026-07-20 04:24:49.152649 UTC - Forcing IPv4 only. 2026-07-20 04:24:49.152870 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-68ebd77b tst -- -4 uptime 2026-07-20 04:24:49.968580 UTC - CLI SSH Test passed. 2026-07-20 04:24:49.968662 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-20 04:24:49.968682 UTC - Uptime: 06:24:48 up 0 min, 1 user, load average: 0.20, 0.05, 0.02 2026-07-20 04:24:49.968733 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-20 04:24:49.969215 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:49.969405 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:49.969468 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:49.969655 UTC - Connecting to 10.10.216.246:22 2026-07-20 04:24:50.204134 UTC - SSH Test output: 2026-07-20 04:24:50.204209 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-20 04:24:50.204234 UTC - Uptime: 06:24:48 up 0 min, 1 user, load average: 0.20, 0.05, 0.02 2026-07-20 04:24:50.255725 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-20 04:24:50.303475 UTC - /etc/debian_version: 13.1 2026-07-20 04:24:50.303584 UTC - lsb_release matches expected value 2026-07-20 04:24:50.303624 UTC - /etc/debian_version matches expected value 2026-07-20 04:24:50.303642 UTC - SSH Test passed. 2026-07-20 04:24:50.351632 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 06:24:33 +0200! 2026-07-20 04:24:50.351804 UTC - Logging in using SSH over SSH proxy 2026-07-20 04:24:50.351966 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-68ebd77b tst 2026-07-20 04:24:51.118123 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:51.118307 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:51.118387 UTC - Added paramiko HostKeyEntry for 10.10.216.246 2026-07-20 04:24:51.118690 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:24:51.118769 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:24:51.118912 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:24:51.118938 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-20 04:24:51.407681 UTC - Connecting to 10.10.216.246:22 over proxy 2026-07-20 04:24:51.643448 UTC - SSH Test output: 2026-07-20 04:24:51.643524 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-20 04:24:51.643544 UTC - Uptime: 06:24:50 up 0 min, 2 users, load average: 0.20, 0.05, 0.02 2026-07-20 04:24:51.696281 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-20 04:24:51.743365 UTC - /etc/debian_version: 13.1 2026-07-20 04:24:51.743451 UTC - lsb_release matches expected value 2026-07-20 04:24:51.743474 UTC - /etc/debian_version matches expected value 2026-07-20 04:24:51.743490 UTC - SSH Test passed. 2026-07-20 04:24:51.790665 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 06:24:33 +0200! 2026-07-20 04:24:51.797457 UTC - Destroying tst-68ebd77b tst 2026-07-20 04:24:51.797649 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-68ebd77b tst