Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 279ms 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.09s + annotated-types==0.8.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.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-07-30 04:11:32.937326 UTC - Installing Slices CLI (latest stable version). 2026-07-30 04:11:32.937720 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 04:11:39.060986 UTC - Slices CLI installed. 2026-07-30 04:11:39.062698 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-30 04:11:39.066844 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-07-30 04:11:39.067103 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-07-30 04:11:39.067225 UTC - which slices: /app/.venv/bin/slices 2026-07-30 04:11:39.067254 UTC - Slices CLI exe: 'slices' 2026-07-30 04:11:39.067281 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 04:11:39.067739 UTC - Run: slices --version 2026-07-30 04:11:42.999137 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-07-30 04:11:42.999620 UTC - Run: slices pubkey list --format text 2026-07-30 04:11:43.820115 UTC - Pubkey already registered 2026-07-30 04:11:43.820403 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 04:11:44.687863 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 04:11:44.687963 UTC - Check List Flavors 2026-07-30 04:11:44.688098 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-30 04:11:46.314597 UTC - Check List DiskImages 2026-07-30 04:11:46.315599 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-30 04:11:47.514607 UTC - Requesting resources 2026-07-30 04:11:47.515162 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 04:11:47.515309 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-4a9b3ce7 --user-data /tmp/tmpgc3lwb7g 2026-07-30 04:11:52.430663 UTC - Resource ID: r_gr-volos-bi-vm1_01kyrkhdz2fp882qpbdq1ksh3p 2026-07-30 04:11:52.444710 UTC - Waiting until resource ready 2026-07-30 04:11:54.445135 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-4a9b3ce7 tst 2026-07-30 04:11:56.043307 UTC - Status: IMAGING 2026-07-30 04:11:59.113792 UTC - Status: BOOTING 2026-07-30 04:12:02.269197 UTC - Status: BOOTING 2026-07-30 04:12:05.239740 UTC - Status: BOOTING 2026-07-30 04:12:08.715193 UTC - Status: BOOTING 2026-07-30 04:12:11.731935 UTC - Status: BOOTING 2026-07-30 04:12:15.273609 UTC - Status: BOOTING 2026-07-30 04:12:18.195262 UTC - Status: BOOTING 2026-07-30 04:12:21.212280 UTC - Status: BOOTING 2026-07-30 04:12:24.300074 UTC - Status: BOOTING 2026-07-30 04:12:27.392649 UTC - Status: BOOTING 2026-07-30 04:12:30.564962 UTC - Status: BOOTING 2026-07-30 04:12:33.745974 UTC - Status: BOOTING 2026-07-30 04:12:36.820009 UTC - Status: UP 2026-07-30 04:12:36.820099 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyrkhaeqf1fbttes3cayav17 2026-07-30 04:12:36.820145 UTC - Validate resources 2026-07-30 04:12:37.788435 UTC - The fields of the created resource were validated. 2026-07-30 04:12:37.788566 UTC - Check if resources are registered in experiment 2026-07-30 04:12:37.788844 UTC - Run: slices experiment list-resources --format json tst-4a9b3ce7 2026-07-30 04:12:38.554869 UTC - Status (on expauth): UP 2026-07-30 04:12:38.555002 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 04:12:38.555119 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-4a9b3ce7 2026-07-30 04:12:40.431570 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:41.752758 UTC - Run: slices experiment list-resources --format json tst-4a9b3ce7 2026-07-30 04:12:42.568811 UTC - expires_at (on expauth): 2026-07-30T07:12:00Z (correctly extended) 2026-07-30 04:12:42.569057 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 04:12:42.569223 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-4a9b3ce7 2026-07-30 04:12:44.695017 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:46.134767 UTC - Run: slices experiment list-resources --format json tst-4a9b3ce7 2026-07-30 04:12:46.951414 UTC - expires_at (on expauth): 2026-07-30T08:12:00Z (correctly extended) 2026-07-30 04:12:46.952470 UTC - Testing ssh login 2026-07-30 04:12:46.976907 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:48.195464 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:49.423464 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:50.969939 UTC - Logging in using 'slices bi ssh' 2026-07-30 04:12:50.985370 UTC - Forcing IPv4 only. 2026-07-30 04:12:50.985698 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-4a9b3ce7 tst -- -4 uname -a 2026-07-30 04:12:55.112090 UTC - Forcing IPv4 only. 2026-07-30 04:12:55.112278 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-4a9b3ce7 tst -- -4 uptime 2026-07-30 04:12:58.083621 UTC - CLI SSH Test passed. 2026-07-30 04:12:58.083704 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-30 04:12:58.083725 UTC - Uptime: 04:12:57 up 0 min, 1 user, load average: 0.36, 0.10, 0.04 2026-07-30 04:12:58.083760 UTC - Logging in using SSH over SSH proxy 2026-07-30 04:12:58.083889 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-4a9b3ce7 tst 2026-07-30 04:12:59.382977 UTC - Added paramiko HostKeyEntry for 10.64.45.81 2026-07-30 04:12:59.383211 UTC - Added paramiko HostKeyEntry for 10.64.45.81 2026-07-30 04:12:59.383275 UTC - Added paramiko HostKeyEntry for 10.64.45.81 2026-07-30 04:12:59.383600 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-30 04:12:59.383715 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-30 04:12:59.383768 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-30 04:12:59.383786 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-30 04:13:00.171259 UTC - Connecting to 10.64.45.81:22 over proxy 2026-07-30 04:13:01.742789 UTC - SSH Test output: 2026-07-30 04:13:01.742865 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-30 04:13:01.742885 UTC - Uptime: 04:13:01 up 0 min, 1 user, load average: 0.33, 0.10, 0.04 2026-07-30 04:13:02.085291 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 04:13:02.085384 UTC - lsb_release matches expected value 2026-07-30 04:13:02.085408 UTC - SSH Test passed. 2026-07-30 04:13:02.408466 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 04:12:32 +0000! 2026-07-30 04:13:02.452832 UTC - Destroying tst-4a9b3ce7 tst 2026-07-30 04:13:02.453054 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-4a9b3ce7 tst