Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 200ms 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 322ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.2 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev187+g005dd054e (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-18 20:12:55.314024 UTC - Installing Slices CLI (latest stable version). 2026-07-18 20:12:55.314234 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 20:13:01.342690 UTC - Slices CLI installed. 2026-07-18 20:13:01.342966 UTC - PATH: {'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-18 20:13:01.344612 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-18 20:13:01.345686 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 20:13:01.345846 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-18 20:13:01.345876 UTC - Slices CLI exe: 'slices' 2026-07-18 20:13:01.345896 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 20:13:01.346046 UTC - Run: slices --version 2026-07-18 20:13:04.015192 UTC - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-07-18 20:13:04.015850 UTC - Run: slices pubkey list --format text 2026-07-18 20:13:04.781340 UTC - Pubkey already registered 2026-07-18 20:13:04.781594 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 20:13:05.346512 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 20:13:05.346597 UTC - Check List Flavors 2026-07-18 20:13:05.346738 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-18 20:13:06.470953 UTC - Check List DiskImages 2026-07-18 20:13:06.471137 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-18 20:13:07.086705 UTC - Requesting resources 2026-07-18 20:13:07.087381 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 20:13:07.087532 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-6abcd4f3 --user-data /tmp/tmpldvfznm0 2026-07-18 20:13:10.157858 UTC - Resource ID: r_be-gent1-bi-vm1_01kxvds00nf82bcdgjaqraaq4x 2026-07-18 20:13:10.158020 UTC - Waiting until resource ready 2026-07-18 20:13:12.158565 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6abcd4f3 tst 2026-07-18 20:13:12.824341 UTC - Status: IMAGING 2026-07-18 20:13:15.540909 UTC - Status: IMAGING 2026-07-18 20:13:18.206920 UTC - Status: IMAGING 2026-07-18 20:13:20.872625 UTC - Status: BOOTING 2026-07-18 20:13:23.539379 UTC - Status: BOOTING 2026-07-18 20:13:26.255276 UTC - Status: BOOTING 2026-07-18 20:13:28.971077 UTC - Status: BOOTING 2026-07-18 20:13:31.687023 UTC - Status: BOOTING 2026-07-18 20:13:34.353437 UTC - Status: INITIALIZING 2026-07-18 20:13:37.019106 UTC - Status: UP 2026-07-18 20:13:37.019179 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxvdrz53fzyb4rtsbqqpztb9 2026-07-18 20:13:37.019217 UTC - Validate resources 2026-07-18 20:13:37.684898 UTC - The fields of the created resource were validated. 2026-07-18 20:13:37.684989 UTC - Check if resources are registered in experiment 2026-07-18 20:13:37.685137 UTC - Run: slices experiment list-resources --format json tst-6abcd4f3 2026-07-18 20:13:38.301446 UTC - Status (on expauth): UP 2026-07-18 20:13:38.301582 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 20:13:38.301717 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-6abcd4f3 2026-07-18 20:13:40.821252 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6abcd4f3 tst 2026-07-18 20:13:41.487353 UTC - Run: slices experiment list-resources --format json tst-6abcd4f3 2026-07-18 20:13:42.103008 UTC - expires_at (on expauth): 2026-07-18T23:13:00Z (correctly extended) 2026-07-18 20:13:42.103146 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 20:13:42.103345 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-6abcd4f3 2026-07-18 20:13:44.673953 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6abcd4f3 tst 2026-07-18 20:13:45.340134 UTC - Run: slices experiment list-resources --format json tst-6abcd4f3 2026-07-18 20:13:45.955869 UTC - expires_at (on expauth): 2026-07-19T00:13:00Z (correctly extended) 2026-07-18 20:13:45.956039 UTC - Testing ssh login 2026-07-18 20:13:45.968904 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-6abcd4f3 tst 2026-07-18 20:13:46.685223 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6abcd4f3 tst 2026-07-18 20:13:47.401370 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-6abcd4f3 tst 2026-07-18 20:13:48.167615 UTC - Logging in using 'slices bi ssh' 2026-07-18 20:13:48.167711 UTC - Forcing IPv4 only. 2026-07-18 20:13:48.167872 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-6abcd4f3 tst -- -4 uname -a 2026-07-18 20:13:49.236467 UTC - Forcing IPv4 only. 2026-07-18 20:13:49.236770 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-6abcd4f3 tst -- -4 uptime 2026-07-18 20:13:50.103630 UTC - CLI SSH Test passed. 2026-07-18 20:13:50.103704 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-07-18 20:13:50.103725 UTC - Uptime: 22:13:49 up 0 min, 1 user, load average: 1.54, 0.39, 0.13 2026-07-18 20:13:50.103758 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-18 20:13:50.104192 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:50.104394 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:50.104463 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:50.104669 UTC - Connecting to 10.10.221.84:22 2026-07-18 20:13:50.342695 UTC - SSH Test output: 2026-07-18 20:13:50.342780 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-07-18 20:13:50.342849 UTC - Uptime: 22:13:49 up 0 min, 1 user, load average: 1.54, 0.39, 0.13 2026-07-18 20:13:50.391960 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 20:13:50.392039 UTC - lsb_release matches expected value 2026-07-18 20:13:50.392059 UTC - SSH Test passed. 2026-07-18 20:13:50.437199 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 22:13:34 +0200! 2026-07-18 20:13:50.437344 UTC - Logging in using SSH over SSH proxy 2026-07-18 20:13:50.437492 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-6abcd4f3 tst 2026-07-18 20:13:51.153078 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:51.153218 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:51.153269 UTC - Added paramiko HostKeyEntry for 10.10.221.84 2026-07-18 20:13:51.153512 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 20:13:51.153568 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 20:13:51.153670 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 20:13:51.153689 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-18 20:13:51.437177 UTC - Connecting to 10.10.221.84:22 over proxy 2026-07-18 20:13:51.686155 UTC - SSH Test output: 2026-07-18 20:13:51.686242 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-07-18 20:13:51.686268 UTC - Uptime: 22:13:50 up 0 min, 2 users, load average: 1.54, 0.39, 0.13 2026-07-18 20:13:51.738669 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 20:13:51.738746 UTC - lsb_release matches expected value 2026-07-18 20:13:51.738767 UTC - SSH Test passed. 2026-07-18 20:13:51.785360 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 22:13:34 +0200! 2026-07-18 20:13:51.792158 UTC - Destroying tst-6abcd4f3 tst 2026-07-18 20:13:51.792338 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-6abcd4f3 tst