Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 301ms 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 821ms + 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 20:08:53.969540 UTC - Installing Slices CLI (latest stable version). 2026-07-21 20:08:53.970040 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 20:09:06.479294 UTC - Slices CLI installed. 2026-07-21 20:09:06.479553 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 20:09:06.482022 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 20:09:06.483139 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 20:09:06.483294 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 20:09:06.483323 UTC - Slices CLI exe: 'slices' 2026-07-21 20:09:06.483340 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 20:09:06.483455 UTC - Run: slices --version 2026-07-21 20:09:12.909528 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 20:09:12.909901 UTC - Run: slices pubkey list --format text 2026-07-21 20:09:14.752232 UTC - Pubkey already registered 2026-07-21 20:09:14.752485 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 20:09:16.183830 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 20:09:16.183992 UTC - Check List Flavors 2026-07-21 20:09:16.184242 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-21 20:09:19.997350 UTC - Check List DiskImages 2026-07-21 20:09:19.997575 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-21 20:09:21.740001 UTC - Requesting resources 2026-07-21 20:09:21.740608 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-21 20:09:21.740783 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-83ec1d36 --user-data /tmp/tmpkkflf3ua 2026-07-21 20:09:29.417719 UTC - Resource ID: r_gr-volos-bi-vm1_01ky34rcxsetsa0fk9yx3t8ch2 2026-07-21 20:09:29.419511 UTC - Waiting until resource ready 2026-07-21 20:09:31.420068 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-83ec1d36 tst 2026-07-21 20:09:34.742763 UTC - Status: BOOTING 2026-07-21 20:09:39.609551 UTC - Status: BOOTING 2026-07-21 20:09:44.289716 UTC - Status: BOOTING 2026-07-21 20:09:48.246621 UTC - Status: BOOTING 2026-07-21 20:09:52.222171 UTC - Status: BOOTING 2026-07-21 20:09:56.762721 UTC - Status: BOOTING 2026-07-21 20:10:00.923595 UTC - Status: BOOTING 2026-07-21 20:10:04.455554 UTC - Status: BOOTING 2026-07-21 20:10:07.574620 UTC - Status: BOOTING 2026-07-21 20:10:11.060774 UTC - Status: UP 2026-07-21 20:10:11.060890 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky34r9rwehp8fpwf331cqdtm 2026-07-21 20:10:11.060930 UTC - Validate resources 2026-07-21 20:10:12.449920 UTC - The fields of the created resource were validated. 2026-07-21 20:10:12.450019 UTC - Check if resources are registered in experiment 2026-07-21 20:10:12.450188 UTC - Run: slices experiment list-resources --format json tst-83ec1d36 2026-07-21 20:10:13.595246 UTC - Status (on expauth): UP 2026-07-21 20:10:13.595373 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 20:10:13.595485 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-83ec1d36 2026-07-21 20:10:23.855724 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-83ec1d36 tst 2026-07-21 20:10:26.472202 UTC - Run: slices experiment list-resources --format json tst-83ec1d36 2026-07-21 20:10:28.599453 UTC - expires_at (on expauth): 2026-07-21T23:10:00Z (correctly extended) 2026-07-21 20:10:28.599563 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 20:10:28.599701 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-83ec1d36 2026-07-21 20:10:35.620473 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-83ec1d36 tst 2026-07-21 20:10:38.079889 UTC - Run: slices experiment list-resources --format json tst-83ec1d36 2026-07-21 20:10:39.979200 UTC - expires_at (on expauth): 2026-07-22T00:10:00Z (correctly extended) 2026-07-21 20:10:39.979352 UTC - Testing ssh login 2026-07-21 20:10:40.007679 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-83ec1d36 tst 2026-07-21 20:10:42.529343 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-83ec1d36 tst 2026-07-21 20:10:44.789034 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-83ec1d36 tst 2026-07-21 20:10:47.668109 UTC - Logging in using 'slices bi ssh' 2026-07-21 20:10:47.668211 UTC - Forcing IPv4 only. 2026-07-21 20:10:47.668373 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-83ec1d36 tst -- -4 uname -a 2026-07-21 20:10:52.888521 UTC - Forcing IPv4 only. 2026-07-21 20:10:52.888891 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-83ec1d36 tst -- -4 uptime 2026-07-21 20:10:57.416835 UTC - CLI SSH Test passed. 2026-07-21 20:10:57.416927 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 20:10:57.416947 UTC - Uptime: 20:10:56 up 1 min, 1 user, load average: 0.35, 0.14, 0.05 2026-07-21 20:10:57.416982 UTC - Logging in using SSH over SSH proxy 2026-07-21 20:10:57.417135 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-83ec1d36 tst 2026-07-21 20:11:00.531898 UTC - Added paramiko HostKeyEntry for 10.64.45.155 2026-07-21 20:11:00.532187 UTC - Added paramiko HostKeyEntry for 10.64.45.155 2026-07-21 20:11:00.532261 UTC - Added paramiko HostKeyEntry for 10.64.45.155 2026-07-21 20:11:00.532679 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-21 20:11:00.532831 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-21 20:11:00.532886 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-21 20:11:00.532904 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-21 20:11:01.277387 UTC - Connecting to 10.64.45.155:22 over proxy 2026-07-21 20:11:02.716984 UTC - SSH Test output: 2026-07-21 20:11:02.717053 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 20:11:02.717075 UTC - Uptime: 20:11:02 up 1 min, 1 user, load average: 0.32, 0.14, 0.05 2026-07-21 20:11:03.023996 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-21 20:11:03.024081 UTC - lsb_release matches expected value 2026-07-21 20:11:03.024105 UTC - SSH Test passed. 2026-07-21 20:11:03.334123 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 20:10:06 +0000! 2026-07-21 20:11:03.350721 UTC - Destroying tst-83ec1d36 tst 2026-07-21 20:11:03.350910 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-83ec1d36 tst