Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.45s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 207ms 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 765ms + 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==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.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-07-29 20:50:45.956968 UTC - Installing Slices CLI (latest stable version). 2026-07-29 20:50:45.957235 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-29 20:50:52.241669 UTC - Slices CLI installed. 2026-07-29 20:50:52.242227 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-29 20:50:52.249018 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-07-29 20:50:52.249127 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-07-29 20:50:52.249207 UTC - which slices: /app/.venv/bin/slices 2026-07-29 20:50:52.249226 UTC - Slices CLI exe: 'slices' 2026-07-29 20:50:52.249242 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-29 20:50:52.249425 UTC - Run: slices --version 2026-07-29 20:50:55.391350 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-07-29 20:50:55.391663 UTC - Run: slices pubkey list --format text 2026-07-29 20:50:56.157195 UTC - Pubkey already registered 2026-07-29 20:50:56.157493 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-29 20:50:56.975804 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-29 20:50:56.976204 UTC - Check List Flavors 2026-07-29 20:50:56.976407 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-29 20:50:57.691739 UTC - Check List DiskImages 2026-07-29 20:50:57.691919 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-29 20:50:58.558557 UTC - Requesting resources 2026-07-29 20:50:58.559050 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-29 20:50:58.559164 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-c2cc78fb --user-data /tmp/tmp56eiar03 2026-07-29 20:50:59.843796 UTC - Resource ID: r_be-gent1-bi-vm1_01kyqta5fmfw59x4e2jn0v0szd 2026-07-29 20:50:59.843997 UTC - Waiting until resource ready 2026-07-29 20:51:01.844347 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2cc78fb tst 2026-07-29 20:51:02.761317 UTC - Status: IMAGING 2026-07-29 20:51:05.682333 UTC - Status: IMAGING 2026-07-29 20:51:08.448564 UTC - Status: IMAGING 2026-07-29 20:51:11.267948 UTC - Status: BOOTING 2026-07-29 20:51:14.090085 UTC - Status: BOOTING 2026-07-29 20:51:16.907091 UTC - Status: BOOTING 2026-07-29 20:51:19.890237 UTC - Status: BOOTING 2026-07-29 20:51:22.817444 UTC - Status: UP 2026-07-29 20:51:22.817530 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyqta56efykt5jg5vfdvxq6h 2026-07-29 20:51:22.817573 UTC - Validate resources 2026-07-29 20:51:23.587291 UTC - The fields of the created resource were validated. 2026-07-29 20:51:23.587397 UTC - Check if resources are registered in experiment 2026-07-29 20:51:23.587543 UTC - Run: slices experiment list-resources --format json tst-c2cc78fb 2026-07-29 20:51:24.353731 UTC - Status (on expauth): UP 2026-07-29 20:51:24.353868 UTC - Testing extend expires_at (all resources in experiment) 2026-07-29 20:51:24.353983 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-c2cc78fb 2026-07-29 20:51:25.824682 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2cc78fb tst 2026-07-29 20:51:26.540815 UTC - Run: slices experiment list-resources --format json tst-c2cc78fb 2026-07-29 20:51:27.256469 UTC - expires_at (on expauth): 2026-07-29T23:51:00Z (correctly extended) 2026-07-29 20:51:27.258202 UTC - Testing extend expires_at (single resource in experiment) 2026-07-29 20:51:27.258413 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-c2cc78fb 2026-07-29 20:51:28.675547 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c2cc78fb tst 2026-07-29 20:51:29.391212 UTC - Run: slices experiment list-resources --format json tst-c2cc78fb 2026-07-29 20:51:30.056540 UTC - expires_at (on expauth): 2026-07-30T00:51:00Z (correctly extended) 2026-07-29 20:51:30.056650 UTC - Testing ssh login 2026-07-29 20:51:30.071559 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c2cc78fb tst 2026-07-29 20:51:30.893686 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c2cc78fb tst 2026-07-29 20:51:31.710692 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c2cc78fb tst 2026-07-29 20:51:32.537844 UTC - Logging in using 'slices bi ssh' 2026-07-29 20:51:32.538135 UTC - Forcing IPv4 only. 2026-07-29 20:51:32.538269 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c2cc78fb tst -- -4 uname -a 2026-07-29 20:51:33.663634 UTC - Forcing IPv4 only. 2026-07-29 20:51:33.663864 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c2cc78fb tst -- -4 uptime 2026-07-29 20:51:34.530348 UTC - CLI SSH Test passed. 2026-07-29 20:51:34.530458 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-29 20:51:34.530491 UTC - Uptime: 22:51:33 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-29 20:51:34.530538 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-29 20:51:34.531114 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:34.531342 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:34.531430 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:34.531628 UTC - Connecting to 10.10.217.25:22 2026-07-29 20:51:34.767270 UTC - SSH Test output: 2026-07-29 20:51:34.767340 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-29 20:51:34.767373 UTC - Uptime: 22:51:33 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-29 20:51:34.819565 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-29 20:51:34.868781 UTC - /etc/debian_version: 13.1 2026-07-29 20:51:34.868892 UTC - lsb_release matches expected value 2026-07-29 20:51:34.868917 UTC - /etc/debian_version matches expected value 2026-07-29 20:51:34.868933 UTC - SSH Test passed. 2026-07-29 20:51:34.915551 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 22:51:19 +0200! 2026-07-29 20:51:34.915698 UTC - Logging in using SSH over SSH proxy 2026-07-29 20:51:34.915837 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c2cc78fb tst 2026-07-29 20:51:35.736091 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:35.736245 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:35.736301 UTC - Added paramiko HostKeyEntry for 10.10.217.25 2026-07-29 20:51:35.736554 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 20:51:35.736613 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 20:51:35.736774 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 20:51:35.736807 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-29 20:51:36.018175 UTC - Connecting to 10.10.217.25:22 over proxy 2026-07-29 20:51:36.247780 UTC - SSH Test output: 2026-07-29 20:51:36.247853 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-29 20:51:36.247873 UTC - Uptime: 22:51:35 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-29 20:51:36.299987 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-29 20:51:36.347770 UTC - /etc/debian_version: 13.1 2026-07-29 20:51:36.347845 UTC - lsb_release matches expected value 2026-07-29 20:51:36.347864 UTC - /etc/debian_version matches expected value 2026-07-29 20:51:36.347879 UTC - SSH Test passed. 2026-07-29 20:51:36.395740 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 22:51:19 +0200! 2026-07-29 20:51:36.403063 UTC - Destroying tst-c2cc78fb tst 2026-07-29 20:51:36.403239 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-c2cc78fb tst