Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 11.27s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 222ms 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 263ms + 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-06 19:10:21.861256 UTC - Installing Slices CLI (latest stable version). 2026-08-06 19:10:21.861504 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-06 19:10:26.948318 UTC - Slices CLI installed. 2026-08-06 19:10:26.948570 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-06 19:10:26.949866 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-06 19:10:26.949936 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-06 19:10:26.950001 UTC - which slices: /app/.venv/bin/slices 2026-08-06 19:10:26.950020 UTC - Slices CLI exe: 'slices' 2026-08-06 19:10:26.950036 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-06 19:10:26.950167 UTC - Run: slices --version 2026-08-06 19:10:29.721675 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-06 19:10:29.721993 UTC - Run: slices pubkey list --format text 2026-08-06 19:10:30.487501 UTC - Pubkey already registered 2026-08-06 19:10:30.487773 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-06 19:10:31.106968 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-06 19:10:31.107100 UTC - Check List Flavors 2026-08-06 19:10:31.107304 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-06 19:10:31.873462 UTC - Check List DiskImages 2026-08-06 19:10:31.873671 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-06 19:10:32.539492 UTC - Requesting resources 2026-08-06 19:10:32.540150 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-06 19:10:32.540302 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-afb000c1 --user-data /tmp/tmp5f0g4kk5 2026-08-06 19:10:33.606522 UTC - Resource ID: r_be-gent1-bi-vm1_01kzc7r0g1e6ht84y58y8eymx3 2026-08-06 19:10:33.606753 UTC - Waiting until resource ready 2026-08-06 19:10:35.607118 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-afb000c1 tst 2026-08-06 19:10:36.372939 UTC - Status: IMAGING 2026-08-06 19:10:39.088886 UTC - Status: IMAGING 2026-08-06 19:10:41.854838 UTC - Status: IMAGING 2026-08-06 19:10:44.670846 UTC - Status: BOOTING 2026-08-06 19:10:47.437950 UTC - Status: BOOTING 2026-08-06 19:10:50.203938 UTC - Status: BOOTING 2026-08-06 19:10:53.239942 UTC - Status: BOOTING 2026-08-06 19:10:56.221070 UTC - Status: INITIALIZING 2026-08-06 19:10:59.297334 UTC - Status: INITIALIZING 2026-08-06 19:11:02.221235 UTC - Status: UP 2026-08-06 19:11:02.221310 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzc7r090fv7ae5gp4tezrxz0 2026-08-06 19:11:02.221351 UTC - Validate resources 2026-08-06 19:11:03.095273 UTC - The fields of the created resource were validated. 2026-08-06 19:11:03.095365 UTC - Check if resources are registered in experiment 2026-08-06 19:11:03.095508 UTC - Run: slices experiment list-resources --format json tst-afb000c1 2026-08-06 19:11:04.185090 UTC - Status (on expauth): UP 2026-08-06 19:11:04.185235 UTC - Testing extend expires_at (all resources in experiment) 2026-08-06 19:11:04.185362 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-afb000c1 2026-08-06 19:11:05.967514 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-afb000c1 tst 2026-08-06 19:11:07.555500 UTC - Run: slices experiment list-resources --format json tst-afb000c1 2026-08-06 19:11:08.839503 UTC - expires_at (on expauth): 2026-08-06T22:11:00Z (correctly extended) 2026-08-06 19:11:08.839621 UTC - Testing extend expires_at (single resource in experiment) 2026-08-06 19:11:08.839814 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-afb000c1 2026-08-06 19:11:10.726833 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-afb000c1 tst 2026-08-06 19:11:11.593150 UTC - Run: slices experiment list-resources --format json tst-afb000c1 2026-08-06 19:11:12.511128 UTC - expires_at (on expauth): 2026-08-06T23:11:00Z (correctly extended) 2026-08-06 19:11:12.511264 UTC - Testing ssh login 2026-08-06 19:11:12.533023 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-afb000c1 tst 2026-08-06 19:11:13.502304 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-afb000c1 tst 2026-08-06 19:11:14.773379 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-afb000c1 tst 2026-08-06 19:11:15.942686 UTC - Logging in using 'slices bi ssh' 2026-08-06 19:11:15.942757 UTC - Forcing IPv4 only. 2026-08-06 19:11:15.942879 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-afb000c1 tst -- -4 uname -a 2026-08-06 19:11:17.761300 UTC - Forcing IPv4 only. 2026-08-06 19:11:17.761475 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-afb000c1 tst -- -4 uptime 2026-08-06 19:11:18.835945 UTC - CLI SSH Test passed. 2026-08-06 19:11:18.836016 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-06 19:11:18.836036 UTC - Uptime: 21:11:18 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-08-06 19:11:18.836070 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-06 19:11:18.836529 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:18.836739 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:18.836807 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:18.836987 UTC - Connecting to 10.10.211.49:22 2026-08-06 19:11:19.075134 UTC - SSH Test output: 2026-08-06 19:11:19.075208 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-06 19:11:19.075235 UTC - Uptime: 21:11:19 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-08-06 19:11:19.124256 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-06 19:11:19.124345 UTC - lsb_release matches expected value 2026-08-06 19:11:19.124373 UTC - SSH Test passed. 2026-08-06 19:11:19.169219 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 21:10:59 +0200! 2026-08-06 19:11:19.169355 UTC - Logging in using SSH over SSH proxy 2026-08-06 19:11:19.169497 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-afb000c1 tst 2026-08-06 19:11:19.985814 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:19.986006 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:19.986068 UTC - Added paramiko HostKeyEntry for 10.10.211.49 2026-08-06 19:11:19.986338 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:11:19.986419 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:11:19.986569 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:11:19.986597 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-06 19:11:20.293089 UTC - Connecting to 10.10.211.49:22 over proxy 2026-08-06 19:11:20.557082 UTC - SSH Test output: 2026-08-06 19:11:20.557155 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-06 19:11:20.557181 UTC - Uptime: 21:11:20 up 0 min, 2 users, load average: 0.19, 0.05, 0.01 2026-08-06 19:11:20.614995 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-06 19:11:20.615078 UTC - lsb_release matches expected value 2026-08-06 19:11:20.615105 UTC - SSH Test passed. 2026-08-06 19:11:20.661810 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 21:10:59 +0200! 2026-08-06 19:11:20.674272 UTC - Destroying tst-afb000c1 tst 2026-08-06 19:11:20.674520 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-afb000c1 tst