Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.47s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 247ms 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 302ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + 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.3 + 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-10 06:09:31.390602 UTC - Installing Slices CLI (latest stable version). 2026-08-10 06:09:31.391026 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 06:09:37.879563 UTC - Slices CLI installed. 2026-08-10 06:09:37.880545 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-10 06:09:37.885468 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-10 06:09:37.885561 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-10 06:09:37.885661 UTC - which slices: /app/.venv/bin/slices 2026-08-10 06:09:37.885684 UTC - Slices CLI exe: 'slices' 2026-08-10 06:09:37.885700 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 06:09:37.885878 UTC - Run: slices --version 2026-08-10 06:09:41.080985 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-10 06:09:41.081273 UTC - Run: slices pubkey list --format text 2026-08-10 06:09:41.846673 UTC - Pubkey already registered 2026-08-10 06:09:41.846910 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 06:09:42.462147 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 06:09:42.462250 UTC - Check List Flavors 2026-08-10 06:09:42.462398 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-10 06:09:43.231884 UTC - Check List DiskImages 2026-08-10 06:09:43.232064 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-10 06:09:43.848543 UTC - Requesting resources 2026-08-10 06:09:43.849059 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 06:09:43.849168 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-72bb9bf5 --user-data /tmp/tmparfxiw07 2026-08-10 06:09:44.915164 UTC - Resource ID: r_be-gent1-bi-vm1_01kzn4n5snfz08dbbctvbq1m57 2026-08-10 06:09:44.915321 UTC - Waiting until resource ready 2026-08-10 06:09:46.915745 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72bb9bf5 tst 2026-08-10 06:09:47.681616 UTC - Status: IMAGING 2026-08-10 06:09:50.400607 UTC - Status: IMAGING 2026-08-10 06:09:53.216556 UTC - Status: IMAGING 2026-08-10 06:09:55.932599 UTC - Status: BOOTING 2026-08-10 06:09:58.648762 UTC - Status: BOOTING 2026-08-10 06:10:01.415100 UTC - Status: BOOTING 2026-08-10 06:10:04.181465 UTC - Status: BOOTING 2026-08-10 06:10:06.948037 UTC - Status: INITIALIZING 2026-08-10 06:10:09.664185 UTC - Status: UP 2026-08-10 06:10:09.664267 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzn4n5k2fta8kknf8d14xtke 2026-08-10 06:10:09.664313 UTC - Validate resources 2026-08-10 06:10:10.430484 UTC - The fields of the created resource were validated. 2026-08-10 06:10:10.430580 UTC - Check if resources are registered in experiment 2026-08-10 06:10:10.430759 UTC - Run: slices experiment list-resources --format json tst-72bb9bf5 2026-08-10 06:10:11.096321 UTC - Status (on expauth): UP 2026-08-10 06:10:11.096472 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 06:10:11.096621 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-72bb9bf5 2026-08-10 06:10:12.564439 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72bb9bf5 tst 2026-08-10 06:10:13.280947 UTC - Run: slices experiment list-resources --format json tst-72bb9bf5 2026-08-10 06:10:13.896274 UTC - expires_at (on expauth): 2026-08-10T09:10:00Z (correctly extended) 2026-08-10 06:10:13.896368 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 06:10:13.896533 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-72bb9bf5 2026-08-10 06:10:15.313877 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72bb9bf5 tst 2026-08-10 06:10:16.029715 UTC - Run: slices experiment list-resources --format json tst-72bb9bf5 2026-08-10 06:10:16.645065 UTC - expires_at (on expauth): 2026-08-10T10:10:00Z (correctly extended) 2026-08-10 06:10:16.645174 UTC - Testing ssh login 2026-08-10 06:10:16.658207 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-72bb9bf5 tst 2026-08-10 06:10:17.424216 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-72bb9bf5 tst 2026-08-10 06:10:18.190767 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-72bb9bf5 tst 2026-08-10 06:10:18.906516 UTC - Logging in using 'slices bi ssh' 2026-08-10 06:10:18.906588 UTC - Forcing IPv4 only. 2026-08-10 06:10:18.906713 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-72bb9bf5 tst -- -4 uname -a 2026-08-10 06:10:19.923170 UTC - Forcing IPv4 only. 2026-08-10 06:10:19.923398 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-72bb9bf5 tst -- -4 uptime 2026-08-10 06:10:20.739595 UTC - CLI SSH Test passed. 2026-08-10 06:10:20.739673 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-10 06:10:20.739693 UTC - Uptime: 08:10:19 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-08-10 06:10:20.739740 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-10 06:10:20.740230 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:20.740428 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:20.740489 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:20.740669 UTC - Connecting to 10.10.218.202:22 2026-08-10 06:10:20.981912 UTC - SSH Test output: 2026-08-10 06:10:20.981987 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-10 06:10:20.982013 UTC - Uptime: 08:10:19 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-08-10 06:10:21.034970 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 06:10:21.080873 UTC - /etc/debian_version: 13.1 2026-08-10 06:10:21.080949 UTC - lsb_release matches expected value 2026-08-10 06:10:21.080969 UTC - /etc/debian_version matches expected value 2026-08-10 06:10:21.080984 UTC - SSH Test passed. 2026-08-10 06:10:21.128551 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 08:10:06 +0200! 2026-08-10 06:10:21.128703 UTC - Logging in using SSH over SSH proxy 2026-08-10 06:10:21.128915 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-72bb9bf5 tst 2026-08-10 06:10:21.895176 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:21.895336 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:21.895392 UTC - Added paramiko HostKeyEntry for 10.10.218.202 2026-08-10 06:10:21.895632 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 06:10:21.895681 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 06:10:21.895780 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 06:10:21.895816 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-10 06:10:22.166199 UTC - Connecting to 10.10.218.202:22 over proxy 2026-08-10 06:10:22.406816 UTC - SSH Test output: 2026-08-10 06:10:22.406892 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-10 06:10:22.406911 UTC - Uptime: 08:10:21 up 0 min, 2 users, load average: 0.19, 0.05, 0.01 2026-08-10 06:10:22.461014 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 06:10:22.509547 UTC - /etc/debian_version: 13.1 2026-08-10 06:10:22.509625 UTC - lsb_release matches expected value 2026-08-10 06:10:22.509647 UTC - /etc/debian_version matches expected value 2026-08-10 06:10:22.509663 UTC - SSH Test passed. 2026-08-10 06:10:22.556978 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 08:10:06 +0200! 2026-08-10 06:10:22.564853 UTC - Destroying tst-72bb9bf5 tst 2026-08-10 06:10:22.565050 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-72bb9bf5 tst