Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.33s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 203ms 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 435ms + 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 04:29:13.437161 UTC - Installing Slices CLI (latest stable version). 2026-08-06 04:29:13.437578 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-06 04:29:20.018925 UTC - Slices CLI installed. 2026-08-06 04:29:20.019679 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 04:29:20.023312 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 04:29:20.023433 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 04:29:20.023524 UTC - which slices: /app/.venv/bin/slices 2026-08-06 04:29:20.023556 UTC - Slices CLI exe: 'slices' 2026-08-06 04:29:20.023573 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-06 04:29:20.023737 UTC - Run: slices --version 2026-08-06 04:29:22.694096 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 04:29:22.694370 UTC - Run: slices pubkey list --format text 2026-08-06 04:29:23.460253 UTC - Pubkey already registered 2026-08-06 04:29:23.460488 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-06 04:29:24.075821 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-06 04:29:24.075917 UTC - Check List Flavors 2026-08-06 04:29:24.076096 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-06 04:29:24.741660 UTC - Check List DiskImages 2026-08-06 04:29:24.741887 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-06 04:29:25.357340 UTC - Requesting resources 2026-08-06 04:29:25.357821 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-06 04:29:25.357934 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-4c91bdef --user-data /tmp/tmp0hlauczr 2026-08-06 04:29:26.424100 UTC - Resource ID: r_be-gent1-bi-vm1_01kzanamc4e639c4y8q7n8fz3v 2026-08-06 04:29:26.424278 UTC - Waiting until resource ready 2026-08-06 04:29:28.424679 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4c91bdef tst 2026-08-06 04:29:29.140524 UTC - Status: IMAGING 2026-08-06 04:29:31.856630 UTC - Status: IMAGING 2026-08-06 04:29:34.573380 UTC - Status: IMAGING 2026-08-06 04:29:37.289704 UTC - Status: BOOTING 2026-08-06 04:29:40.005928 UTC - Status: BOOTING 2026-08-06 04:29:42.722836 UTC - Status: BOOTING 2026-08-06 04:29:45.439749 UTC - Status: BOOTING 2026-08-06 04:29:48.206240 UTC - Status: BOOTING 2026-08-06 04:29:50.972710 UTC - Status: INITIALIZING 2026-08-06 04:29:53.739125 UTC - Status: UP 2026-08-06 04:29:53.739199 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzanam5ge1xvz1np93ehf4wt 2026-08-06 04:29:53.739262 UTC - Validate resources 2026-08-06 04:29:54.505728 UTC - The fields of the created resource were validated. 2026-08-06 04:29:54.505844 UTC - Check if resources are registered in experiment 2026-08-06 04:29:54.506091 UTC - Run: slices experiment list-resources --format json tst-4c91bdef 2026-08-06 04:29:55.172129 UTC - Status (on expauth): UP 2026-08-06 04:29:55.172280 UTC - Testing extend expires_at (all resources in experiment) 2026-08-06 04:29:55.172426 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-4c91bdef 2026-08-06 04:29:56.640866 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4c91bdef tst 2026-08-06 04:29:57.507459 UTC - Run: slices experiment list-resources --format json tst-4c91bdef 2026-08-06 04:29:58.173672 UTC - expires_at (on expauth): 2026-08-06T07:29:00Z (correctly extended) 2026-08-06 04:29:58.173802 UTC - Testing extend expires_at (single resource in experiment) 2026-08-06 04:29:58.173999 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-4c91bdef 2026-08-06 04:29:59.592167 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4c91bdef tst 2026-08-06 04:30:00.358554 UTC - Run: slices experiment list-resources --format json tst-4c91bdef 2026-08-06 04:30:01.024346 UTC - expires_at (on expauth): 2026-08-06T08:29:00Z (correctly extended) 2026-08-06 04:30:01.024478 UTC - Testing ssh login 2026-08-06 04:30:01.040069 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4c91bdef tst 2026-08-06 04:30:01.856319 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4c91bdef tst 2026-08-06 04:30:02.672863 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-4c91bdef tst 2026-08-06 04:30:03.439215 UTC - Logging in using 'slices bi ssh' 2026-08-06 04:30:03.439352 UTC - Forcing IPv4 only. 2026-08-06 04:30:03.439593 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-4c91bdef tst -- -4 uname -a 2026-08-06 04:30:05.159861 UTC - Forcing IPv4 only. 2026-08-06 04:30:05.160166 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-4c91bdef tst -- -4 uptime 2026-08-06 04:30:06.177387 UTC - CLI SSH Test passed. 2026-08-06 04:30:06.177482 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 04:30:06.177514 UTC - Uptime: 06:30:05 up 0 min, 1 user, load average: 0.95, 0.24, 0.08 2026-08-06 04:30:06.177553 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-06 04:30:06.178058 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:06.178242 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:06.178300 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:06.178484 UTC - Connecting to 10.10.215.22:22 2026-08-06 04:30:06.432789 UTC - SSH Test output: 2026-08-06 04:30:06.432867 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 04:30:06.432894 UTC - Uptime: 06:30:05 up 0 min, 1 user, load average: 0.95, 0.24, 0.08 2026-08-06 04:30:06.484441 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-06 04:30:06.484529 UTC - lsb_release matches expected value 2026-08-06 04:30:06.484549 UTC - SSH Test passed. 2026-08-06 04:30:06.529941 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 06:29:51 +0200! 2026-08-06 04:30:06.530090 UTC - Logging in using SSH over SSH proxy 2026-08-06 04:30:06.530241 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-4c91bdef tst 2026-08-06 04:30:07.346548 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:07.346725 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:07.346794 UTC - Added paramiko HostKeyEntry for 10.10.215.22 2026-08-06 04:30:07.347145 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 04:30:07.347218 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 04:30:07.347364 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 04:30:07.347393 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-06 04:30:07.628882 UTC - Connecting to 10.10.215.22:22 over proxy 2026-08-06 04:30:07.889282 UTC - SSH Test output: 2026-08-06 04:30:07.889370 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 04:30:07.889402 UTC - Uptime: 06:30:07 up 0 min, 2 users, load average: 0.95, 0.24, 0.08 2026-08-06 04:30:07.945735 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-06 04:30:07.945827 UTC - lsb_release matches expected value 2026-08-06 04:30:07.945857 UTC - SSH Test passed. 2026-08-06 04:30:07.994044 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 06:29:51 +0200! 2026-08-06 04:30:08.004984 UTC - Destroying tst-4c91bdef tst 2026-08-06 04:30:08.005215 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-4c91bdef tst