Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.19s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 755ms 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 941ms + 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 20:14:58.325090 UTC - Installing Slices CLI (latest stable version). 2026-08-09 20:14:58.325325 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 20:15:07.211377 UTC - Slices CLI installed. 2026-08-09 20:15:07.211662 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 20:15:07.213739 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 20:15:07.213834 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 20:15:07.213998 UTC - which slices: /app/.venv/bin/slices 2026-08-09 20:15:07.214026 UTC - Slices CLI exe: 'slices' 2026-08-09 20:15:07.214124 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 20:15:07.214348 UTC - Run: slices --version 2026-08-09 20:15:11.645936 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 20:15:11.646397 UTC - Run: slices pubkey list --format text 2026-08-09 20:15:13.175557 UTC - Pubkey already registered 2026-08-09 20:15:13.175814 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 20:15:14.092264 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 20:15:14.092366 UTC - Check List Flavors 2026-08-09 20:15:14.092509 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-08-09 20:15:15.683208 UTC - Check List DiskImages 2026-08-09 20:15:15.683390 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-08-09 20:15:16.961044 UTC - Requesting resources 2026-08-09 20:15:16.961731 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-09 20:15:16.961845 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-2a97100b --user-data /tmp/tmpxi4_mfpu 2026-08-09 20:15:20.400183 UTC - Resource ID: r_gr-volos-bi-vm1_01kzm2ms21fv0rkw3phay7pz3d 2026-08-09 20:15:20.400395 UTC - Waiting until resource ready 2026-08-09 20:15:22.400788 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-2a97100b tst 2026-08-09 20:15:23.592271 UTC - Status: IMAGING 2026-08-09 20:15:26.932553 UTC - Status: BOOTING 2026-08-09 20:15:29.955640 UTC - Status: BOOTING 2026-08-09 20:15:34.499906 UTC - Status: BOOTING 2026-08-09 20:15:37.629970 UTC - Status: BOOTING 2026-08-09 20:15:40.933430 UTC - Status: BOOTING 2026-08-09 20:15:44.169121 UTC - Status: BOOTING 2026-08-09 20:15:47.816602 UTC - Status: BOOTING 2026-08-09 20:15:51.849471 UTC - Status: BOOTING 2026-08-09 20:15:54.970299 UTC - Status: BOOTING 2026-08-09 20:15:58.868532 UTC - Status: BOOTING 2026-08-09 20:16:03.034308 UTC - Status: UP 2026-08-09 20:16:03.034394 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzm2mpqae3aaw0m334kt1f5r 2026-08-09 20:16:03.034438 UTC - Validate resources 2026-08-09 20:16:05.119835 UTC - The fields of the created resource were validated. 2026-08-09 20:16:05.119942 UTC - Check if resources are registered in experiment 2026-08-09 20:16:05.120110 UTC - Run: slices experiment list-resources --format json tst-2a97100b 2026-08-09 20:16:07.237827 UTC - Status (on expauth): UP 2026-08-09 20:16:07.238035 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 20:16:07.238198 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-2a97100b 2026-08-09 20:16:09.470187 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-2a97100b tst 2026-08-09 20:16:11.759409 UTC - Run: slices experiment list-resources --format json tst-2a97100b 2026-08-09 20:16:12.840306 UTC - expires_at (on expauth): 2026-08-09T23:16:00Z (correctly extended) 2026-08-09 20:16:12.842451 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 20:16:12.842659 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-2a97100b 2026-08-09 20:16:15.457594 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-2a97100b tst 2026-08-09 20:16:17.707715 UTC - Run: slices experiment list-resources --format json tst-2a97100b 2026-08-09 20:16:19.698734 UTC - expires_at (on expauth): 2026-08-10T00:16:00Z (correctly extended) 2026-08-09 20:16:19.698850 UTC - Testing ssh login 2026-08-09 20:16:19.768738 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2a97100b tst 2026-08-09 20:16:22.224382 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2a97100b tst 2026-08-09 20:16:25.543322 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2a97100b tst 2026-08-09 20:16:28.010523 UTC - Logging in using 'slices bi ssh' 2026-08-09 20:16:28.011635 UTC - Forcing IPv4 only. 2026-08-09 20:16:28.011834 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-2a97100b tst -- -4 uname -a 2026-08-09 20:16:34.895756 UTC - Forcing IPv4 only. 2026-08-09 20:16:34.895945 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-2a97100b tst -- -4 uptime 2026-08-09 20:16:38.452430 UTC - CLI SSH Test passed. 2026-08-09 20:16:38.452506 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 20:16:38.452526 UTC - Uptime: 20:16:38 up 1 min, 1 user, load average: 0.56, 0.20, 0.07 2026-08-09 20:16:38.452560 UTC - Logging in using SSH over SSH proxy 2026-08-09 20:16:38.452699 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2a97100b tst 2026-08-09 20:16:40.640346 UTC - Added paramiko HostKeyEntry for 10.64.45.199 2026-08-09 20:16:40.640568 UTC - Added paramiko HostKeyEntry for 10.64.45.199 2026-08-09 20:16:40.640641 UTC - Added paramiko HostKeyEntry for 10.64.45.199 2026-08-09 20:16:40.641050 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 20:16:40.641256 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 20:16:40.641310 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-09 20:16:40.641328 UTC - Connecting to proxy 194.177.207.42:11122 2026-08-09 20:16:41.373286 UTC - Connecting to 10.64.45.199:22 over proxy 2026-08-09 20:16:42.759761 UTC - SSH Test output: 2026-08-09 20:16:42.759842 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 20:16:42.759865 UTC - Uptime: 20:16:42 up 1 min, 1 user, load average: 0.52, 0.20, 0.07 2026-08-09 20:16:43.062323 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 20:16:43.062423 UTC - lsb_release matches expected value 2026-08-09 20:16:43.062468 UTC - SSH Test passed. 2026-08-09 20:16:43.360645 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 20:15:59 +0000! 2026-08-09 20:16:43.374682 UTC - Destroying tst-2a97100b tst 2026-08-09 20:16:43.374940 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-2a97100b tst