Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.34s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 282ms 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 1.25s + 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-09 17:55:18.877972 UTC - Installing Slices CLI (latest stable version). 2026-08-09 17:55:18.878241 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 17:55:24.064367 UTC - Slices CLI installed. 2026-08-09 17:55:24.064653 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-09 17:55:24.068124 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-09 17:55:24.068263 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-09 17:55:24.068345 UTC - which slices: /app/.venv/bin/slices 2026-08-09 17:55:24.068368 UTC - Slices CLI exe: 'slices' 2026-08-09 17:55:24.068384 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 17:55:24.068528 UTC - Run: slices --version 2026-08-09 17:55:26.943401 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-09 17:55:26.943673 UTC - Run: slices pubkey list --format text 2026-08-09 17:55:28.361869 UTC - Pubkey already registered 2026-08-09 17:55:28.362151 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 17:55:28.977949 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 17:55:28.978039 UTC - Check List Flavors 2026-08-09 17:55:28.978229 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-08-09 17:55:29.901099 UTC - Check List DiskImages 2026-08-09 17:55:29.901320 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-08-09 17:55:30.767199 UTC - Requesting resources 2026-08-09 17:55:30.767684 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-09 17:55:30.767795 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-988aa46a --user-data /tmp/tmp_4yzg0rf 2026-08-09 17:55:34.342081 UTC - Resource ID: r_gr-volos-bi-vm1_01kzktmvh6e3rreh0mrgzakpma 2026-08-09 17:55:34.342317 UTC - Waiting until resource ready 2026-08-09 17:55:36.342711 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-988aa46a tst 2026-08-09 17:55:37.575546 UTC - Status: IMAGING 2026-08-09 17:55:40.805122 UTC - Status: BOOTING 2026-08-09 17:55:43.922058 UTC - Status: BOOTING 2026-08-09 17:55:46.993724 UTC - Status: BOOTING 2026-08-09 17:55:50.268232 UTC - Status: BOOTING 2026-08-09 17:55:53.791797 UTC - Status: BOOTING 2026-08-09 17:55:56.728763 UTC - Status: BOOTING 2026-08-09 17:55:59.695485 UTC - Status: BOOTING 2026-08-09 17:56:02.880076 UTC - Status: BOOTING 2026-08-09 17:56:06.690931 UTC - Status: BOOTING 2026-08-09 17:56:09.669182 UTC - Status: BOOTING 2026-08-09 17:56:12.889655 UTC - Status: BOOTING 2026-08-09 17:56:16.288846 UTC - Status: UP 2026-08-09 17:56:16.288969 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzktms3bfx2bevdfqjy23x3c 2026-08-09 17:56:16.289018 UTC - Validate resources 2026-08-09 17:56:17.408321 UTC - The fields of the created resource were validated. 2026-08-09 17:56:17.408418 UTC - Check if resources are registered in experiment 2026-08-09 17:56:17.408556 UTC - Run: slices experiment list-resources --format json tst-988aa46a 2026-08-09 17:56:18.181288 UTC - Status (on expauth): UP 2026-08-09 17:56:18.181424 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 17:56:18.181537 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-988aa46a 2026-08-09 17:56:20.164910 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-988aa46a tst 2026-08-09 17:56:21.482993 UTC - Run: slices experiment list-resources --format json tst-988aa46a 2026-08-09 17:56:23.021996 UTC - expires_at (on expauth): 2026-08-09T20:56:00Z (correctly extended) 2026-08-09 17:56:23.023286 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 17:56:23.023459 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-988aa46a 2026-08-09 17:56:25.720617 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-988aa46a tst 2026-08-09 17:56:27.750877 UTC - Run: slices experiment list-resources --format json tst-988aa46a 2026-08-09 17:56:28.674011 UTC - expires_at (on expauth): 2026-08-09T21:56:00Z (correctly extended) 2026-08-09 17:56:28.674290 UTC - Testing ssh login 2026-08-09 17:56:28.694342 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-988aa46a tst 2026-08-09 17:56:30.345644 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-988aa46a tst 2026-08-09 17:56:31.575119 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-988aa46a tst 2026-08-09 17:56:32.649522 UTC - Logging in using 'slices bi ssh' 2026-08-09 17:56:32.649645 UTC - Forcing IPv4 only. 2026-08-09 17:56:32.649799 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-988aa46a tst -- -4 uname -a 2026-08-09 17:56:38.033889 UTC - Forcing IPv4 only. 2026-08-09 17:56:38.034088 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-988aa46a tst -- -4 uptime 2026-08-09 17:56:41.423458 UTC - CLI SSH Test passed. 2026-08-09 17:56:41.423688 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-09 17:56:41.423720 UTC - Uptime: 17:56:41 up 0 min, 1 user, load average: 0.90, 0.30, 0.10 2026-08-09 17:56:41.423756 UTC - Logging in using SSH over SSH proxy 2026-08-09 17:56:41.423905 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-988aa46a tst 2026-08-09 17:56:43.965400 UTC - Added paramiko HostKeyEntry for 10.64.45.198 2026-08-09 17:56:43.965593 UTC - Added paramiko HostKeyEntry for 10.64.45.198 2026-08-09 17:56:43.965663 UTC - Added paramiko HostKeyEntry for 10.64.45.198 2026-08-09 17:56:43.965937 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 17:56:43.966028 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 17:56:43.966072 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 17:56:43.966089 UTC - Connecting to proxy 194.177.207.42:11122 2026-08-09 17:56:44.698022 UTC - Connecting to 10.64.45.198:22 over proxy 2026-08-09 17:56:46.196579 UTC - SSH Test output: 2026-08-09 17:56:46.196659 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-09 17:56:46.196683 UTC - Uptime: 17:56:45 up 0 min, 1 user, load average: 0.83, 0.29, 0.10 2026-08-09 17:56:46.496111 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 17:56:46.496195 UTC - lsb_release matches expected value 2026-08-09 17:56:46.496216 UTC - SSH Test passed. 2026-08-09 17:56:46.796333 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 17:56:15 +0000! 2026-08-09 17:56:46.812746 UTC - Destroying tst-988aa46a tst 2026-08-09 17:56:46.812945 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-988aa46a tst