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 175ms 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 223ms + 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-19 20:24:56.245655 UTC - Installing Slices CLI (latest stable version). 2026-07-19 20:24:56.245866 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 20:25:01.822178 UTC - Slices CLI installed. 2026-07-19 20:25:01.822358 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 20:25:01.824461 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 20:25:01.825472 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 20:25:01.825610 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 20:25:01.825640 UTC - Slices CLI exe: 'slices' 2026-07-19 20:25:01.825660 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 20:25:01.825796 UTC - Run: slices --version 2026-07-19 20:25:04.445765 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 20:25:04.446080 UTC - Run: slices pubkey list --format text 2026-07-19 20:25:05.211401 UTC - Pubkey already registered 2026-07-19 20:25:05.211642 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 20:25:05.776746 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 20:25:05.776842 UTC - Check List Flavors 2026-07-19 20:25:05.776998 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-19 20:25:07.294680 UTC - Check List DiskImages 2026-07-19 20:25:07.294861 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-19 20:25:07.910112 UTC - Requesting resources 2026-07-19 20:25:07.910585 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 20:25:07.910695 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-90309279 --user-data /tmp/tmpfscnifd4 2026-07-19 20:25:11.684314 UTC - Resource ID: r_be-gent1-bi-vm1_01kxy0vqm4e10s2pr4f7g2xjzm 2026-07-19 20:25:11.684541 UTC - Waiting until resource ready 2026-07-19 20:25:13.685025 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-90309279 tst 2026-07-19 20:25:14.400790 UTC - Status: IMAGING 2026-07-19 20:25:17.066517 UTC - Status: IMAGING 2026-07-19 20:25:19.782756 UTC - Status: IMAGING 2026-07-19 20:25:22.676375 UTC - Status: BOOTING 2026-07-19 20:25:25.653191 UTC - Status: BOOTING 2026-07-19 20:25:28.887877 UTC - Status: BOOTING 2026-07-19 20:25:32.407519 UTC - Status: BOOTING 2026-07-19 20:25:35.873102 UTC - Status: UP 2026-07-19 20:25:35.873174 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxy0vpfbfawt135g3gd0xczv 2026-07-19 20:25:35.873215 UTC - Validate resources 2026-07-19 20:25:37.258850 UTC - The fields of the created resource were validated. 2026-07-19 20:25:37.258951 UTC - Check if resources are registered in experiment 2026-07-19 20:25:37.259123 UTC - Run: slices experiment list-resources --format json tst-90309279 2026-07-19 20:25:38.728178 UTC - Status (on expauth): UP 2026-07-19 20:25:38.728310 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 20:25:38.728424 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-90309279 2026-07-19 20:25:42.750329 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-90309279 tst 2026-07-19 20:25:43.787222 UTC - Run: slices experiment list-resources --format json tst-90309279 2026-07-19 20:25:44.819672 UTC - expires_at (on expauth): 2026-07-19T23:25:00Z (correctly extended) 2026-07-19 20:25:44.819926 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 20:25:44.820238 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-90309279 2026-07-19 20:25:48.253677 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-90309279 tst 2026-07-19 20:25:49.178765 UTC - Run: slices experiment list-resources --format json tst-90309279 2026-07-19 20:25:50.016472 UTC - expires_at (on expauth): 2026-07-20T00:25:00Z (correctly extended) 2026-07-19 20:25:50.016584 UTC - Testing ssh login 2026-07-19 20:25:50.050267 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-90309279 tst 2026-07-19 20:25:51.314577 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-90309279 tst 2026-07-19 20:25:52.233270 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-90309279 tst 2026-07-19 20:25:53.210070 UTC - Logging in using 'slices bi ssh' 2026-07-19 20:25:53.210164 UTC - Forcing IPv4 only. 2026-07-19 20:25:53.210317 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-90309279 tst -- -4 uname -a 2026-07-19 20:25:54.399957 UTC - Forcing IPv4 only. 2026-07-19 20:25:54.400159 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-90309279 tst -- -4 uptime 2026-07-19 20:25:55.366812 UTC - CLI SSH Test passed. 2026-07-19 20:25:55.366883 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 20:25:55.366903 UTC - Uptime: 22:25:55 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-19 20:25:55.366934 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-19 20:25:55.367399 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:55.367570 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:55.367627 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:55.367809 UTC - Connecting to 10.10.218.36:22 2026-07-19 20:25:55.600357 UTC - SSH Test output: 2026-07-19 20:25:55.600431 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 20:25:55.600450 UTC - Uptime: 22:25:55 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-19 20:25:55.652567 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-19 20:25:55.700059 UTC - /etc/debian_version: 13.1 2026-07-19 20:25:55.700128 UTC - lsb_release matches expected value 2026-07-19 20:25:55.700149 UTC - /etc/debian_version matches expected value 2026-07-19 20:25:55.700164 UTC - SSH Test passed. 2026-07-19 20:25:55.743817 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 22:25:32 +0200! 2026-07-19 20:25:55.743941 UTC - Logging in using SSH over SSH proxy 2026-07-19 20:25:55.744092 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-90309279 tst 2026-07-19 20:25:56.976615 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:56.976836 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:56.976918 UTC - Added paramiko HostKeyEntry for 10.10.218.36 2026-07-19 20:25:56.977570 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 20:25:56.977644 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 20:25:56.977754 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-19 20:25:56.977774 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-19 20:25:57.263798 UTC - Connecting to 10.10.218.36:22 over proxy 2026-07-19 20:25:57.495489 UTC - SSH Test output: 2026-07-19 20:25:57.495562 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 20:25:57.495582 UTC - Uptime: 22:25:57 up 0 min, 2 users, load average: 0.05, 0.01, 0.00 2026-07-19 20:25:57.547926 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-19 20:25:57.595036 UTC - /etc/debian_version: 13.1 2026-07-19 20:25:57.595120 UTC - lsb_release matches expected value 2026-07-19 20:25:57.595142 UTC - /etc/debian_version matches expected value 2026-07-19 20:25:57.595157 UTC - SSH Test passed. 2026-07-19 20:25:57.644266 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 22:25:32 +0200! 2026-07-19 20:25:57.651867 UTC - Destroying tst-90309279 tst 2026-07-19 20:25:57.652096 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-90309279 tst