Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.73s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 589ms 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 896ms + 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 13:25:01.361161 UTC - Installing Slices CLI (latest stable version). 2026-08-09 13:25:01.361454 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 13:25:07.916849 UTC - Slices CLI installed. 2026-08-09 13:25:07.917136 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 13:25:07.921862 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 13:25:07.921940 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 13:25:07.922010 UTC - which slices: /app/.venv/bin/slices 2026-08-09 13:25:07.922042 UTC - Slices CLI exe: 'slices' 2026-08-09 13:25:07.922059 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 13:25:07.922191 UTC - Run: slices --version 2026-08-09 13:25:11.915154 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 13:25:11.915437 UTC - Run: slices pubkey list --format text 2026-08-09 13:25:13.195409 UTC - Pubkey already registered 2026-08-09 13:25:13.195628 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 13:25:13.860815 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 13:25:13.860911 UTC - Check List Flavors 2026-08-09 13:25:13.861058 UTC - Run: slices bi --infra hu-budapest-bi-vm1 flavor list -f json 2026-08-09 13:25:14.727105 UTC - Check List DiskImages 2026-08-09 13:25:14.727323 UTC - Run: slices bi --infra hu-budapest-bi-vm1 diskimage list -f json 2026-08-09 13:25:15.495613 UTC - Requesting resources 2026-08-09 13:25:15.496141 UTC - Run: slices bi --infra hu-budapest-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-dd9cf9f4 2026-08-09 13:25:17.525803 UTC - Resource ID: r_hu-budapest-bi-vm1_01kzkb5yr7fpr8y79tn8r0q7h7 2026-08-09 13:25:17.525952 UTC - Waiting until resource ready 2026-08-09 13:25:19.526244 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-dd9cf9f4 tst 2026-08-09 13:25:20.831233 UTC - Status: IMAGING 2026-08-09 13:25:23.953723 UTC - Status: IMAGING 2026-08-09 13:25:27.267621 UTC - Status: IMAGING 2026-08-09 13:25:31.497213 UTC - Status: IMAGING 2026-08-09 13:25:35.284603 UTC - Status: IMAGING 2026-08-09 13:25:38.458398 UTC - Status: IMAGING 2026-08-09 13:25:42.305823 UTC - Status: IMAGING 2026-08-09 13:25:45.757391 UTC - Status: IMAGING 2026-08-09 13:25:49.157689 UTC - Status: IMAGING 2026-08-09 13:25:52.433291 UTC - Status: IMAGING 2026-08-09 13:25:55.683157 UTC - Status: IMAGING 2026-08-09 13:25:59.373238 UTC - Status: IMAGING 2026-08-09 13:26:02.490597 UTC - Status: IMAGING 2026-08-09 13:26:05.959043 UTC - Status: IMAGING 2026-08-09 13:26:10.273291 UTC - Status: IMAGING 2026-08-09 13:26:13.940915 UTC - Status: IMAGING 2026-08-09 13:26:17.385830 UTC - Status: IMAGING 2026-08-09 13:26:20.679263 UTC - Status: IMAGING 2026-08-09 13:26:23.656178 UTC - Status: IMAGING 2026-08-09 13:26:27.265574 UTC - Status: IMAGING 2026-08-09 13:26:30.503673 UTC - Status: IMAGING 2026-08-09 13:26:33.933484 UTC - Status: IMAGING 2026-08-09 13:26:36.902129 UTC - Status: UP 2026-08-09 13:26:36.902220 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzkb5y2bfcss214s9xm4fwpg 2026-08-09 13:26:36.902273 UTC - Validate resources 2026-08-09 13:26:37.772792 UTC - The fields of the created resource were validated. 2026-08-09 13:26:37.772881 UTC - Check if resources are registered in experiment 2026-08-09 13:26:37.773028 UTC - Run: slices experiment list-resources --format json tst-dd9cf9f4 2026-08-09 13:26:39.040905 UTC - Status (on expauth): UP 2026-08-09 13:26:39.041054 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 13:26:39.041208 UTC - Run: slices bi --infra hu-budapest-bi-vm1 extend --duration 3h --experiment tst-dd9cf9f4 2026-08-09 13:26:40.358449 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-dd9cf9f4 tst 2026-08-09 13:26:41.376002 UTC - Run: slices experiment list-resources --format json tst-dd9cf9f4 2026-08-09 13:26:42.745145 UTC - expires_at (on expauth): 2026-08-09T16:26:00Z (correctly extended) 2026-08-09 13:26:42.745246 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 13:26:42.745421 UTC - Run: slices bi --infra hu-budapest-bi-vm1 extend tst --duration 4h --experiment tst-dd9cf9f4 2026-08-09 13:26:44.354162 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-dd9cf9f4 tst 2026-08-09 13:26:45.531455 UTC - Run: slices experiment list-resources --format json tst-dd9cf9f4 2026-08-09 13:26:47.156015 UTC - expires_at (on expauth): 2026-08-09T17:26:00Z (correctly extended) 2026-08-09 13:26:47.156135 UTC - Testing ssh login 2026-08-09 13:26:47.181033 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-dd9cf9f4 tst 2026-08-09 13:26:49.211259 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-dd9cf9f4 tst 2026-08-09 13:26:50.667365 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-dd9cf9f4 tst 2026-08-09 13:26:52.567234 UTC - Logging in using 'slices bi ssh' 2026-08-09 13:26:52.567309 UTC - Forcing IPv4 only. 2026-08-09 13:26:52.567432 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-dd9cf9f4 tst -- -4 uname -a 2026-08-09 13:26:59.113192 UTC - Forcing IPv4 only. 2026-08-09 13:26:59.113383 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-dd9cf9f4 tst -- -4 uptime 2026-08-09 13:27:01.505945 UTC - CLI SSH Test passed. 2026-08-09 13:27:01.506014 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-b6fa97cc-9ea7-4f9b-9541-f0 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 13:27:01.506044 UTC - Uptime: 13:27:01 up 1 min, 1 user, load average: 0.52, 0.20, 0.07 2026-08-09 13:27:01.506091 UTC - Logging in using SSH over SSH proxy 2026-08-09 13:27:01.506249 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-dd9cf9f4 tst 2026-08-09 13:27:02.883719 UTC - Added paramiko HostKeyEntry for 192.168.0.148 2026-08-09 13:27:02.883862 UTC - Added paramiko HostKeyEntry for 192.168.0.148 2026-08-09 13:27:02.884032 UTC - Added paramiko HostKeyEntry for 192.168.0.148 2026-08-09 13:27:02.884331 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-08-09 13:27:02.884395 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-08-09 13:27:02.884551 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-08-09 13:27:02.884592 UTC - Connecting to proxy 193.225.251.128:22 2026-08-09 13:27:03.416968 UTC - Connecting to 192.168.0.148:22 over proxy 2026-08-09 13:27:04.193723 UTC - SSH Test output: 2026-08-09 13:27:04.193795 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-b6fa97cc-9ea7-4f9b-9541-f0 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 13:27:04.193818 UTC - Uptime: 13:27:04 up 1 min, 1 user, load average: 0.52, 0.20, 0.07 2026-08-09 13:27:04.409837 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 13:27:04.409914 UTC - lsb_release matches expected value 2026-08-09 13:27:04.409933 UTC - SSH Test passed. 2026-08-09 13:27:04.418225 UTC - Destroying tst-dd9cf9f4 tst 2026-08-09 13:27:04.418436 UTC - Run: slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-dd9cf9f4 tst