Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.36s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 227ms 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 251ms + 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.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.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-21 04:14:48.052010 UTC - Installing Slices CLI (latest stable version). 2026-07-21 04:14:48.052252 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 04:14:53.879801 UTC - Slices CLI installed. 2026-07-21 04:14:53.880322 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-21 04:14:53.882099 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-21 04:14:53.882866 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 04:14:53.882983 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-21 04:14:53.883010 UTC - Slices CLI exe: 'slices' 2026-07-21 04:14:53.883027 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 04:14:53.883146 UTC - Run: slices --version 2026-07-21 04:14:56.653138 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-21 04:14:56.653480 UTC - Run: slices pubkey list --format text 2026-07-21 04:14:57.419184 UTC - Pubkey already registered 2026-07-21 04:14:57.419499 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 04:14:58.034996 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 04:14:58.035100 UTC - Check List Flavors 2026-07-21 04:14:58.035268 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-21 04:14:59.452622 UTC - Check List DiskImages 2026-07-21 04:14:59.452886 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-21 04:15:00.068294 UTC - Requesting resources 2026-07-21 04:15:00.068814 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-21 04:15:00.068963 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-cf61d892 --user-data /tmp/tmpsvhjrznv 2026-07-21 04:15:04.143182 UTC - Resource ID: r_be-gent1-bi-vm1_01ky1e4tadf0xr7ttwspm7dt1s 2026-07-21 04:15:04.143351 UTC - Waiting until resource ready 2026-07-21 04:15:06.143854 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf61d892 tst 2026-07-21 04:15:06.859810 UTC - Status: IMAGING 2026-07-21 04:15:09.576002 UTC - Status: IMAGING 2026-07-21 04:15:12.292068 UTC - Status: IMAGING 2026-07-21 04:15:15.058642 UTC - Status: BOOTING 2026-07-21 04:15:17.825005 UTC - Status: BOOTING 2026-07-21 04:15:20.591512 UTC - Status: BOOTING 2026-07-21 04:15:23.358152 UTC - Status: BOOTING 2026-07-21 04:15:26.279851 UTC - Status: BOOTING 2026-07-21 04:15:29.148234 UTC - Status: UP 2026-07-21 04:15:29.148342 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky1e4rt9fwh9xzv1a42ast7h 2026-07-21 04:15:29.148388 UTC - Validate resources 2026-07-21 04:15:30.068594 UTC - The fields of the created resource were validated. 2026-07-21 04:15:30.068712 UTC - Check if resources are registered in experiment 2026-07-21 04:15:30.068906 UTC - Run: slices experiment list-resources --format json tst-cf61d892 2026-07-21 04:15:30.784676 UTC - Status (on expauth): UP 2026-07-21 04:15:30.784920 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 04:15:30.785075 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-cf61d892 2026-07-21 04:15:37.334695 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf61d892 tst 2026-07-21 04:15:39.370017 UTC - Run: slices experiment list-resources --format json tst-cf61d892 2026-07-21 04:15:40.626835 UTC - expires_at (on expauth): 2026-07-21T07:15:00Z (correctly extended) 2026-07-21 04:15:40.627205 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 04:15:40.627437 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-cf61d892 2026-07-21 04:15:47.593253 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cf61d892 tst 2026-07-21 04:15:49.133005 UTC - Run: slices experiment list-resources --format json tst-cf61d892 2026-07-21 04:15:50.309886 UTC - expires_at (on expauth): 2026-07-21T08:15:00Z (correctly extended) 2026-07-21 04:15:50.310158 UTC - Testing ssh login 2026-07-21 04:15:50.328211 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cf61d892 tst 2026-07-21 04:15:51.549308 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cf61d892 tst 2026-07-21 04:15:52.315445 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-cf61d892 tst 2026-07-21 04:15:53.134271 UTC - Logging in using 'slices bi ssh' 2026-07-21 04:15:53.134347 UTC - Forcing IPv4 only. 2026-07-21 04:15:53.134471 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cf61d892 tst -- -4 uname -a 2026-07-21 04:15:55.027723 UTC - Forcing IPv4 only. 2026-07-21 04:15:55.027947 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cf61d892 tst -- -4 uptime 2026-07-21 04:15:56.149856 UTC - CLI SSH Test passed. 2026-07-21 04:15:56.149935 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-21 04:15:56.149956 UTC - Uptime: 06:15:54 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-21 04:15:56.149989 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-21 04:15:56.150457 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:56.150658 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:56.150727 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:56.150947 UTC - Connecting to 10.10.223.7:22 2026-07-21 04:15:56.393620 UTC - SSH Test output: 2026-07-21 04:15:56.393709 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-21 04:15:56.393737 UTC - Uptime: 06:15:55 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-21 04:15:56.444633 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-21 04:15:56.444737 UTC - lsb_release matches expected value 2026-07-21 04:15:56.444767 UTC - SSH Test passed. 2026-07-21 04:15:56.490218 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 06:15:27 +0200! 2026-07-21 04:15:56.490366 UTC - Logging in using SSH over SSH proxy 2026-07-21 04:15:56.490513 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-cf61d892 tst 2026-07-21 04:15:57.559851 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:57.560035 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:57.560089 UTC - Added paramiko HostKeyEntry for 10.10.223.7 2026-07-21 04:15:57.560324 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:15:57.560373 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:15:57.560486 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 04:15:57.560507 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-21 04:15:57.847932 UTC - Connecting to 10.10.223.7:22 over proxy 2026-07-21 04:15:58.093015 UTC - SSH Test output: 2026-07-21 04:15:58.093083 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-21 04:15:58.093104 UTC - Uptime: 06:15:56 up 0 min, 2 users, load average: 0.09, 0.03, 0.01 2026-07-21 04:15:58.145724 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-21 04:15:58.145818 UTC - lsb_release matches expected value 2026-07-21 04:15:58.145848 UTC - SSH Test passed. 2026-07-21 04:15:58.193107 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 06:15:27 +0200! 2026-07-21 04:15:58.202474 UTC - Destroying tst-cf61d892 tst 2026-07-21 04:15:58.202720 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-cf61d892 tst