Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.40s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 205ms 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 1.19s + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-10 14:31:52.824638 UTC - Installing Slices CLI (latest stable version). 2026-07-10 14:31:52.824950 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 14:31:59.521246 UTC - Slices CLI installed. 2026-07-10 14:31:59.524166 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-10 14:31:59.529245 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-10 14:31:59.538742 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 14:31:59.538932 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-10 14:31:59.538966 UTC - Slices CLI exe: 'slices' 2026-07-10 14:31:59.538987 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 14:31:59.539148 UTC - Run: slices --version 2026-07-10 14:32:02.821249 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-10 14:32:02.821525 UTC - Run: slices pubkey list --format text 2026-07-10 14:32:03.637012 UTC - Pubkey already registered 2026-07-10 14:32:03.637228 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 14:32:04.252384 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 14:32:04.252491 UTC - Check List Flavors 2026-07-10 14:32:04.252668 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-10 14:32:05.469478 UTC - Check List DiskImages 2026-07-10 14:32:05.469791 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-10 14:32:06.235746 UTC - Requesting resources 2026-07-10 14:32:06.236347 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 14:32:06.236467 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-dd3b7dd2 --user-data /tmp/tmp93w9t25g 2026-07-10 14:32:09.264143 UTC - Resource ID: r_be-gent1-bi-vm1_01kx672tmgebwrgyaeeke2ad9j 2026-07-10 14:32:09.264324 UTC - Waiting until resource ready 2026-07-10 14:32:11.264698 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:12.085234 UTC - Status: IMAGING 2026-07-10 14:32:15.087282 UTC - Status: IMAGING 2026-07-10 14:32:17.954089 UTC - Status: IMAGING 2026-07-10 14:32:21.147506 UTC - Status: BOOTING 2026-07-10 14:32:23.914272 UTC - Status: BOOTING 2026-07-10 14:32:27.058890 UTC - Status: BOOTING 2026-07-10 14:32:29.774874 UTC - Status: UP 2026-07-10 14:32:29.774959 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx672sv2eyvspnjpybyfc7gf 2026-07-10 14:32:29.775016 UTC - Validate resources 2026-07-10 14:32:30.541146 UTC - The fields of the created resource were validated. 2026-07-10 14:32:30.541279 UTC - Check if resources are registered in experiment 2026-07-10 14:32:30.541461 UTC - Run: slices experiment list-resources --format json tst-dd3b7dd2 2026-07-10 14:32:31.790332 UTC - Status (on expauth): UP 2026-07-10 14:32:31.790582 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 14:32:31.790807 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-dd3b7dd2 2026-07-10 14:32:34.821875 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:35.537572 UTC - Run: slices experiment list-resources --format json tst-dd3b7dd2 2026-07-10 14:32:36.252847 UTC - expires_at (on expauth): 2026-07-10T17:32:00Z (correctly extended) 2026-07-10 14:32:36.252950 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 14:32:36.253089 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-dd3b7dd2 2026-07-10 14:32:39.434670 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:40.301240 UTC - Run: slices experiment list-resources --format json tst-dd3b7dd2 2026-07-10 14:32:41.439055 UTC - expires_at (on expauth): 2026-07-10T18:32:00Z (correctly extended) 2026-07-10 14:32:41.439168 UTC - Testing ssh login 2026-07-10 14:32:41.467370 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:42.725427 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:43.939578 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:44.811786 UTC - Logging in using 'slices bi ssh' 2026-07-10 14:32:44.811872 UTC - Forcing IPv4 only. 2026-07-10 14:32:44.811996 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-dd3b7dd2 tst -- -4 uname -a 2026-07-10 14:32:45.948186 UTC - Forcing IPv4 only. 2026-07-10 14:32:45.948349 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-dd3b7dd2 tst -- -4 uptime 2026-07-10 14:32:46.979905 UTC - CLI SSH Test passed. 2026-07-10 14:32:46.979974 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-10 14:32:46.979994 UTC - Uptime: 16:32:45 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-10 14:32:46.980027 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-10 14:32:46.980447 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:46.980614 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:46.980670 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:46.980874 UTC - Connecting to 10.10.223.209:22 2026-07-10 14:32:47.208195 UTC - SSH Test output: 2026-07-10 14:32:47.208261 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-10 14:32:47.208282 UTC - Uptime: 16:32:45 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-10 14:32:47.256495 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-10 14:32:47.304295 UTC - /etc/debian_version: 13.1 2026-07-10 14:32:47.304370 UTC - lsb_release matches expected value 2026-07-10 14:32:47.304392 UTC - /etc/debian_version matches expected value 2026-07-10 14:32:47.304407 UTC - SSH Test passed. 2026-07-10 14:32:47.347946 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 16:32:27 +0200! 2026-07-10 14:32:47.348084 UTC - Logging in using SSH over SSH proxy 2026-07-10 14:32:47.348223 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-dd3b7dd2 tst 2026-07-10 14:32:48.683218 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:48.683369 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:48.683472 UTC - Added paramiko HostKeyEntry for 10.10.223.209 2026-07-10 14:32:48.683690 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 14:32:48.683737 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 14:32:48.683847 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-10 14:32:48.683868 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-10 14:32:48.810419 UTC - Connecting to 10.10.223.209:22 over proxy 2026-07-10 14:32:49.037554 UTC - SSH Test output: 2026-07-10 14:32:49.037619 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-10 14:32:49.037638 UTC - Uptime: 16:32:47 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-10 14:32:49.101204 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-10 14:32:49.137131 UTC - /etc/debian_version: 13.1 2026-07-10 14:32:49.137201 UTC - lsb_release matches expected value 2026-07-10 14:32:49.137222 UTC - /etc/debian_version matches expected value 2026-07-10 14:32:49.137241 UTC - SSH Test passed. 2026-07-10 14:32:49.185271 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 16:32:27 +0200! 2026-07-10 14:32:49.208962 UTC - Destroying tst-dd3b7dd2 tst 2026-07-10 14:32:49.209174 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-dd3b7dd2 tst