Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.24s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 202ms 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 870ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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-23 13:08:21.934183 UTC - Installing Slices CLI (latest stable version). 2026-07-23 13:08:21.934416 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 13:08:30.197943 UTC - Slices CLI installed. 2026-07-23 13:08:30.199166 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-23 13:08:30.201245 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-23 13:08:30.202257 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 13:08:30.202441 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-23 13:08:30.202537 UTC - Slices CLI exe: 'slices' 2026-07-23 13:08:30.202565 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 13:08:30.202994 UTC - Run: slices --version 2026-07-23 13:08:35.265959 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-23 13:08:35.266454 UTC - Run: slices pubkey list --format text 2026-07-23 13:08:37.317322 UTC - Pubkey already registered 2026-07-23 13:08:37.317553 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 13:08:38.702385 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 13:08:38.702485 UTC - Check List Flavors 2026-07-23 13:08:38.702660 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-23 13:08:39.986332 UTC - Check List DiskImages 2026-07-23 13:08:39.991522 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-23 13:08:41.111328 UTC - Requesting resources 2026-07-23 13:08:41.114908 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 13:08:41.115070 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-a1d592b8 --user-data /tmp/tmpjg87kudm 2026-07-23 13:08:47.611735 UTC - Resource ID: r_gr-volos-bi-vm1_01ky7hfh3cf00rd1g3qwwdy4g5 2026-07-23 13:08:47.612079 UTC - Waiting until resource ready 2026-07-23 13:08:49.612397 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-a1d592b8 tst 2026-07-23 13:08:51.953072 UTC - Status: BOOTING 2026-07-23 13:08:55.276569 UTC - Status: BOOTING 2026-07-23 13:08:58.649905 UTC - Status: BOOTING 2026-07-23 13:09:02.913061 UTC - Status: BOOTING 2026-07-23 13:09:07.246857 UTC - Status: BOOTING 2026-07-23 13:09:11.348372 UTC - Status: BOOTING 2026-07-23 13:09:15.743992 UTC - Status: BOOTING 2026-07-23 13:09:20.251207 UTC - Status: BOOTING 2026-07-23 13:09:24.480541 UTC - Status: BOOTING 2026-07-23 13:09:29.763511 UTC - Status: UP 2026-07-23 13:09:29.767180 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky7hfc6aetpb69t6wj5rc8qa 2026-07-23 13:09:29.767292 UTC - Validate resources 2026-07-23 13:09:30.885268 UTC - The fields of the created resource were validated. 2026-07-23 13:09:30.885357 UTC - Check if resources are registered in experiment 2026-07-23 13:09:30.885511 UTC - Run: slices experiment list-resources --format json tst-a1d592b8 2026-07-23 13:09:32.236686 UTC - Status (on expauth): UP 2026-07-23 13:09:32.236889 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 13:09:32.237045 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-a1d592b8 2026-07-23 13:09:34.906884 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-a1d592b8 tst 2026-07-23 13:09:36.234660 UTC - Run: slices experiment list-resources --format json tst-a1d592b8 2026-07-23 13:09:37.053172 UTC - expires_at (on expauth): 2026-07-23T16:09:00Z (correctly extended) 2026-07-23 13:09:37.053919 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 13:09:37.054091 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-a1d592b8 2026-07-23 13:09:41.168912 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-a1d592b8 tst 2026-07-23 13:09:43.209804 UTC - Run: slices experiment list-resources --format json tst-a1d592b8 2026-07-23 13:09:44.414482 UTC - expires_at (on expauth): 2026-07-23T17:09:00Z (correctly extended) 2026-07-23 13:09:44.414597 UTC - Testing ssh login 2026-07-23 13:09:44.441551 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a1d592b8 tst 2026-07-23 13:09:46.454290 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a1d592b8 tst 2026-07-23 13:09:48.089427 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-a1d592b8 tst 2026-07-23 13:09:49.105510 UTC - Logging in using 'slices bi ssh' 2026-07-23 13:09:49.105577 UTC - Forcing IPv4 only. 2026-07-23 13:09:49.105699 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-a1d592b8 tst -- -4 uname -a 2026-07-23 13:09:53.355463 UTC - Forcing IPv4 only. 2026-07-23 13:09:53.355642 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-a1d592b8 tst -- -4 uptime 2026-07-23 13:09:56.725479 UTC - CLI SSH Test passed. 2026-07-23 13:09:56.725546 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-23 13:09:56.725565 UTC - Uptime: 13:09:56 up 0 min, 1 user, load average: 0.83, 0.28, 0.09 2026-07-23 13:09:56.725597 UTC - Logging in using SSH over SSH proxy 2026-07-23 13:09:56.725730 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-a1d592b8 tst 2026-07-23 13:09:58.575628 UTC - Added paramiko HostKeyEntry for 10.64.45.203 2026-07-23 13:09:58.576057 UTC - Added paramiko HostKeyEntry for 10.64.45.203 2026-07-23 13:09:58.576152 UTC - Added paramiko HostKeyEntry for 10.64.45.203 2026-07-23 13:09:58.576432 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-23 13:09:58.576527 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-23 13:09:58.576571 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-23 13:09:58.576589 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-23 13:09:59.319691 UTC - Connecting to 10.64.45.203:22 over proxy 2026-07-23 13:10:00.729681 UTC - SSH Test output: 2026-07-23 13:10:00.729753 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-23 13:10:00.729775 UTC - Uptime: 13:09:59 up 1 min, 1 user, load average: 0.76, 0.27, 0.09 2026-07-23 13:10:01.032334 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 13:10:01.032408 UTC - lsb_release matches expected value 2026-07-23 13:10:01.032427 UTC - SSH Test passed. 2026-07-23 13:10:01.340186 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 13:09:23 +0000! 2026-07-23 13:10:01.351310 UTC - Destroying tst-a1d592b8 tst 2026-07-23 13:10:01.351614 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-a1d592b8 tst