Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.55s 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 392ms + 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==50.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.dev192+gcac2d3fe6 (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-08-03 18:29:44.304259 UTC - Installing Slices CLI (latest stable version). 2026-08-03 18:29:44.304489 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-03 18:29:49.380313 UTC - Slices CLI installed. 2026-08-03 18:29:49.380590 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-08-03 18:29:49.383696 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-03 18:29:49.383782 UTC - env: {'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-08-03 18:29:49.383856 UTC - which slices: /app/.venv/bin/slices 2026-08-03 18:29:49.383874 UTC - Slices CLI exe: 'slices' 2026-08-03 18:29:49.383889 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-03 18:29:49.384031 UTC - Run: slices --version 2026-08-03 18:29:52.003512 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-03 18:29:52.003800 UTC - Run: slices pubkey list --format text 2026-08-03 18:29:52.769572 UTC - Pubkey already registered 2026-08-03 18:29:52.769833 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-03 18:29:53.485810 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-03 18:29:53.485910 UTC - Check List Flavors 2026-08-03 18:29:53.486078 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-03 18:29:54.251986 UTC - Check List DiskImages 2026-08-03 18:29:54.252173 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-03 18:29:54.917991 UTC - Requesting resources 2026-08-03 18:29:54.918917 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-03 18:29:54.919095 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-b43c19ac --user-data /tmp/tmp3ftxapf2 2026-08-03 18:29:55.985510 UTC - Resource ID: r_be-gent1-bi-vm1_01kz4e7f0hfvtr6tyfbxhxdvws 2026-08-03 18:29:55.985673 UTC - Waiting until resource ready 2026-08-03 18:29:57.986070 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b43c19ac tst 2026-08-03 18:29:58.701655 UTC - Status: IMAGING 2026-08-03 18:30:01.417756 UTC - Status: IMAGING 2026-08-03 18:30:04.284901 UTC - Status: IMAGING 2026-08-03 18:30:07.101635 UTC - Status: BOOTING 2026-08-03 18:30:09.818535 UTC - Status: BOOTING 2026-08-03 18:30:12.534722 UTC - Status: BOOTING 2026-08-03 18:30:15.251126 UTC - Status: BOOTING 2026-08-03 18:30:17.967146 UTC - Status: INITIALIZING 2026-08-03 18:30:20.733539 UTC - Status: UP 2026-08-03 18:30:20.733622 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz4e7esqe4ktr033ash4v8cs 2026-08-03 18:30:20.733667 UTC - Validate resources 2026-08-03 18:30:21.499825 UTC - The fields of the created resource were validated. 2026-08-03 18:30:21.499920 UTC - Check if resources are registered in experiment 2026-08-03 18:30:21.500121 UTC - Run: slices experiment list-resources --format json tst-b43c19ac 2026-08-03 18:30:22.267839 UTC - Status (on expauth): BOOTING 2026-08-03 18:30:24.933835 UTC - Status (on expauth): BOOTING 2026-08-03 18:30:27.599835 UTC - Status (on expauth): BOOTING 2026-08-03 18:30:30.266043 UTC - Status (on expauth): UP 2026-08-03 18:30:30.266145 UTC - "slices experiment list-resources" was not ready immediately. 2026-08-03 18:30:30.266169 UTC - It was ready after: 8.76617 seconds. (Note: used wait steps of 2s) 2026-08-03 18:30:30.266240 UTC - Testing extend expires_at (all resources in experiment) 2026-08-03 18:30:30.266364 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-b43c19ac 2026-08-03 18:30:31.633493 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b43c19ac tst 2026-08-03 18:30:32.349295 UTC - Run: slices experiment list-resources --format json tst-b43c19ac 2026-08-03 18:30:33.014968 UTC - expires_at (on expauth): 2026-08-03T21:30:00Z (correctly extended) 2026-08-03 18:30:33.015065 UTC - Testing extend expires_at (single resource in experiment) 2026-08-03 18:30:33.015229 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-b43c19ac 2026-08-03 18:30:34.432411 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b43c19ac tst 2026-08-03 18:30:35.148585 UTC - Run: slices experiment list-resources --format json tst-b43c19ac 2026-08-03 18:30:35.764506 UTC - expires_at (on expauth): 2026-08-03T22:30:00Z (correctly extended) 2026-08-03 18:30:35.764746 UTC - Testing ssh login 2026-08-03 18:30:35.778478 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b43c19ac tst 2026-08-03 18:30:36.545068 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b43c19ac tst 2026-08-03 18:30:37.361556 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b43c19ac tst 2026-08-03 18:30:38.077376 UTC - Logging in using 'slices bi ssh' 2026-08-03 18:30:38.077460 UTC - Forcing IPv4 only. 2026-08-03 18:30:38.077616 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-b43c19ac tst -- -4 uname -a 2026-08-03 18:30:39.093937 UTC - Forcing IPv4 only. 2026-08-03 18:30:39.094123 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-b43c19ac tst -- -4 uptime 2026-08-03 18:30:39.910141 UTC - CLI SSH Test passed. 2026-08-03 18:30:39.910213 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-08-03 18:30:39.910233 UTC - Uptime: 20:30:39 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-08-03 18:30:39.910266 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-03 18:30:39.910701 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:39.910877 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:39.910936 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:39.911108 UTC - Connecting to 10.10.214.100:22 2026-08-03 18:30:40.150773 UTC - SSH Test output: 2026-08-03 18:30:40.150866 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-08-03 18:30:40.150892 UTC - Uptime: 20:30:39 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-08-03 18:30:40.203334 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-03 18:30:40.250291 UTC - /etc/debian_version: 13.1 2026-08-03 18:30:40.250366 UTC - lsb_release matches expected value 2026-08-03 18:30:40.250390 UTC - /etc/debian_version matches expected value 2026-08-03 18:30:40.250406 UTC - SSH Test passed. 2026-08-03 18:30:40.298504 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 20:30:17 +0200! 2026-08-03 18:30:40.298662 UTC - Logging in using SSH over SSH proxy 2026-08-03 18:30:40.298824 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b43c19ac tst 2026-08-03 18:30:41.014774 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:41.014919 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:41.014968 UTC - Added paramiko HostKeyEntry for 10.10.214.100 2026-08-03 18:30:41.015193 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 18:30:41.015240 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 18:30:41.015337 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-03 18:30:41.015356 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-03 18:30:41.280306 UTC - Connecting to 10.10.214.100:22 over proxy 2026-08-03 18:30:41.516241 UTC - SSH Test output: 2026-08-03 18:30:41.516316 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-08-03 18:30:41.516336 UTC - Uptime: 20:30:40 up 0 min, 2 users, load average: 0.10, 0.03, 0.01 2026-08-03 18:30:41.569303 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-03 18:30:41.616830 UTC - /etc/debian_version: 13.1 2026-08-03 18:30:41.616910 UTC - lsb_release matches expected value 2026-08-03 18:30:41.616931 UTC - /etc/debian_version matches expected value 2026-08-03 18:30:41.616946 UTC - SSH Test passed. 2026-08-03 18:30:41.664032 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 20:30:17 +0200! 2026-08-03 18:30:41.672259 UTC - Destroying tst-b43c19ac tst 2026-08-03 18:30:41.672441 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-b43c19ac tst