Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.64s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 676ms 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.05s + 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-08 04:11:00.716807 UTC - Installing Slices CLI (latest stable version). 2026-08-08 04:11:00.717074 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 04:11:07.265090 UTC - Slices CLI installed. 2026-08-08 04:11:07.265722 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-08 04:11:07.268505 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-08 04:11:07.268581 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-08 04:11:07.268658 UTC - which slices: /app/.venv/bin/slices 2026-08-08 04:11:07.268678 UTC - Slices CLI exe: 'slices' 2026-08-08 04:11:07.268694 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 04:11:07.268882 UTC - Run: slices --version 2026-08-08 04:11:11.534287 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-08 04:11:11.534709 UTC - Run: slices pubkey list --format text 2026-08-08 04:11:12.646893 UTC - Pubkey already registered 2026-08-08 04:11:12.647726 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 04:11:13.977996 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 04:11:13.978099 UTC - Check List Flavors 2026-08-08 04:11:13.978248 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-08-08 04:11:17.102431 UTC - Check List DiskImages 2026-08-08 04:11:17.102643 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-08-08 04:11:18.285703 UTC - Requesting resources 2026-08-08 04:11:18.286211 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 04:11:18.286340 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-0acd98c6 --user-data /tmp/tmpamk3l_7z 2026-08-08 04:11:25.188971 UTC - Resource ID: r_gr-volos-bi-vm1_01kzfs32b6fy1v11fmmxs6qc27 2026-08-08 04:11:25.189143 UTC - Waiting until resource ready 2026-08-08 04:11:27.189503 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-0acd98c6 tst 2026-08-08 04:11:29.270637 UTC - Status: BOOTING 2026-08-08 04:11:33.083494 UTC - Status: BOOTING 2026-08-08 04:11:36.982899 UTC - Status: BOOTING 2026-08-08 04:11:40.415741 UTC - Status: BOOTING 2026-08-08 04:11:43.634090 UTC - Status: BOOTING 2026-08-08 04:11:48.108021 UTC - Status: BOOTING 2026-08-08 04:11:51.609562 UTC - Status: BOOTING 2026-08-08 04:11:54.829465 UTC - Status: BOOTING 2026-08-08 04:11:58.006612 UTC - Status: BOOTING 2026-08-08 04:12:01.378273 UTC - Status: BOOTING 2026-08-08 04:12:04.595435 UTC - Status: BOOTING 2026-08-08 04:12:07.908265 UTC - Status: BOOTING 2026-08-08 04:12:11.647558 UTC - Status: UP 2026-08-08 04:12:11.647930 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzfs2wvqegj9jh3t1mj088qz 2026-08-08 04:12:11.648204 UTC - Validate resources 2026-08-08 04:12:13.070902 UTC - The fields of the created resource were validated. 2026-08-08 04:12:13.071020 UTC - Check if resources are registered in experiment 2026-08-08 04:12:13.071246 UTC - Run: slices experiment list-resources --format json tst-0acd98c6 2026-08-08 04:12:14.303773 UTC - Status (on expauth): UP 2026-08-08 04:12:14.303900 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 04:12:14.304011 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-0acd98c6 2026-08-08 04:12:16.226509 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-0acd98c6 tst 2026-08-08 04:12:17.399812 UTC - Run: slices experiment list-resources --format json tst-0acd98c6 2026-08-08 04:12:18.318825 UTC - expires_at (on expauth): 2026-08-08T07:12:00Z (correctly extended) 2026-08-08 04:12:18.319000 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 04:12:18.319208 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-0acd98c6 2026-08-08 04:12:20.087703 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-0acd98c6 tst 2026-08-08 04:12:21.364629 UTC - Run: slices experiment list-resources --format json tst-0acd98c6 2026-08-08 04:12:22.082172 UTC - expires_at (on expauth): 2026-08-08T08:12:00Z (correctly extended) 2026-08-08 04:12:22.082299 UTC - Testing ssh login 2026-08-08 04:12:22.098258 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0acd98c6 tst 2026-08-08 04:12:23.423761 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0acd98c6 tst 2026-08-08 04:12:24.800662 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-0acd98c6 tst 2026-08-08 04:12:26.523267 UTC - Logging in using 'slices bi ssh' 2026-08-08 04:12:26.523359 UTC - Forcing IPv4 only. 2026-08-08 04:12:26.523496 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-0acd98c6 tst -- -4 uname -a 2026-08-08 04:12:29.846426 UTC - Forcing IPv4 only. 2026-08-08 04:12:29.846886 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-0acd98c6 tst -- -4 uptime 2026-08-08 04:12:33.289956 UTC - CLI SSH Test passed. 2026-08-08 04:12:33.290031 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-08 04:12:33.290049 UTC - Uptime: 04:12:33 up 0 min, 1 user, load average: 1.44, 0.47, 0.17 2026-08-08 04:12:33.290085 UTC - Logging in using SSH over SSH proxy 2026-08-08 04:12:33.290220 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-0acd98c6 tst 2026-08-08 04:12:34.964877 UTC - Added paramiko HostKeyEntry for 10.64.45.174 2026-08-08 04:12:34.965114 UTC - Added paramiko HostKeyEntry for 10.64.45.174 2026-08-08 04:12:34.965241 UTC - Added paramiko HostKeyEntry for 10.64.45.174 2026-08-08 04:12:34.965640 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-08 04:12:34.965757 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-08 04:12:34.965806 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-08 04:12:34.965824 UTC - Connecting to proxy 194.177.207.42:11122 2026-08-08 04:12:35.702482 UTC - Connecting to 10.64.45.174:22 over proxy 2026-08-08 04:12:37.197509 UTC - SSH Test output: 2026-08-08 04:12:37.197618 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-08 04:12:37.197644 UTC - Uptime: 04:12:36 up 0 min, 1 user, load average: 1.33, 0.46, 0.16 2026-08-08 04:12:37.503877 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 04:12:37.503978 UTC - lsb_release matches expected value 2026-08-08 04:12:37.504000 UTC - SSH Test passed. 2026-08-08 04:12:37.807902 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 04:12:09 +0000! 2026-08-08 04:12:37.815900 UTC - Destroying tst-0acd98c6 tst 2026-08-08 04:12:37.816116 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-0acd98c6 tst