Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.98s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 276ms 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.34s + 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.2 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev187+g005dd054e (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-25 04:08:41.897919 UTC - Installing Slices CLI (latest stable version). 2026-07-25 04:08:41.898473 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 04:08:48.980303 UTC - Slices CLI installed. 2026-07-25 04:08:48.987758 UTC - PATH: {'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-25 04:08:48.990201 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-25 04:08:48.991237 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 04:08:48.991361 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-25 04:08:48.991389 UTC - Slices CLI exe: 'slices' 2026-07-25 04:08:48.991407 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 04:08:48.991521 UTC - Run: slices --version 2026-07-25 04:08:55.862434 UTC - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-07-25 04:08:55.865765 UTC - Run: slices pubkey list --format text 2026-07-25 04:08:57.781076 UTC - Pubkey already registered 2026-07-25 04:08:57.781328 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 04:08:59.505988 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 04:08:59.506231 UTC - Check List Flavors 2026-07-25 04:08:59.507174 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-25 04:09:00.695864 UTC - Check List DiskImages 2026-07-25 04:09:00.696120 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-25 04:09:01.685089 UTC - Requesting resources 2026-07-25 04:09:01.685595 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 04:09:01.685705 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-8aaee31e --user-data /tmp/tmpc4sbtsiv 2026-07-25 04:09:11.365958 UTC - Resource ID: r_gr-volos-bi-vm1_01kybqcxg8e49btjk7fpf8zftb 2026-07-25 04:09:11.369971 UTC - Waiting until resource ready 2026-07-25 04:09:13.370683 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8aaee31e tst 2026-07-25 04:09:15.846262 UTC - Status: BOOTING 2026-07-25 04:09:19.127067 UTC - Status: BOOTING 2026-07-25 04:09:22.761796 UTC - Status: BOOTING 2026-07-25 04:09:27.596545 UTC - Status: BOOTING 2026-07-25 04:09:32.487191 UTC - Status: BOOTING 2026-07-25 04:09:37.342216 UTC - Status: BOOTING 2026-07-25 04:09:41.367164 UTC - Status: BOOTING 2026-07-25 04:09:44.283595 UTC - Status: BOOTING 2026-07-25 04:09:47.769464 UTC - Status: BOOTING 2026-07-25 04:09:51.387890 UTC - Status: BOOTING 2026-07-25 04:09:54.410829 UTC - Status: UP 2026-07-25 04:09:54.410903 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kybqcn9ve5hsmp4f7xq6s5kn 2026-07-25 04:09:54.410943 UTC - Validate resources 2026-07-25 04:09:55.684639 UTC - The fields of the created resource were validated. 2026-07-25 04:09:55.684748 UTC - Check if resources are registered in experiment 2026-07-25 04:09:55.684896 UTC - Run: slices experiment list-resources --format json tst-8aaee31e 2026-07-25 04:09:56.350167 UTC - Status (on expauth): UP 2026-07-25 04:09:56.350307 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 04:09:56.350437 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-8aaee31e 2026-07-25 04:09:58.472148 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8aaee31e tst 2026-07-25 04:09:59.338035 UTC - Run: slices experiment list-resources --format json tst-8aaee31e 2026-07-25 04:10:00.005499 UTC - expires_at (on expauth): 2026-07-25T07:09:00Z (correctly extended) 2026-07-25 04:10:00.005589 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 04:10:00.005728 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-8aaee31e 2026-07-25 04:10:02.295893 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8aaee31e tst 2026-07-25 04:10:03.419317 UTC - Run: slices experiment list-resources --format json tst-8aaee31e 2026-07-25 04:10:04.925886 UTC - expires_at (on expauth): 2026-07-25T08:10:00Z (correctly extended) 2026-07-25 04:10:04.926013 UTC - Testing ssh login 2026-07-25 04:10:04.950750 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8aaee31e tst 2026-07-25 04:10:06.074765 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8aaee31e tst 2026-07-25 04:10:07.251272 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8aaee31e tst 2026-07-25 04:10:09.215149 UTC - Logging in using 'slices bi ssh' 2026-07-25 04:10:09.215222 UTC - Forcing IPv4 only. 2026-07-25 04:10:09.215384 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-8aaee31e tst -- -4 uname -a 2026-07-25 04:10:12.793356 UTC - Forcing IPv4 only. 2026-07-25 04:10:12.793538 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-8aaee31e tst -- -4 uptime 2026-07-25 04:10:16.474713 UTC - CLI SSH Test passed. 2026-07-25 04:10:16.474784 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-25 04:10:16.474804 UTC - Uptime: 04:10:15 up 0 min, 1 user, load average: 0.93, 0.32, 0.11 2026-07-25 04:10:16.474839 UTC - Logging in using SSH over SSH proxy 2026-07-25 04:10:16.474975 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8aaee31e tst 2026-07-25 04:10:18.607558 UTC - Added paramiko HostKeyEntry for 10.64.45.230 2026-07-25 04:10:18.607771 UTC - Added paramiko HostKeyEntry for 10.64.45.230 2026-07-25 04:10:18.607830 UTC - Added paramiko HostKeyEntry for 10.64.45.230 2026-07-25 04:10:18.608124 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-25 04:10:18.608211 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-25 04:10:18.608255 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-25 04:10:18.608272 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-25 04:10:19.349462 UTC - Connecting to 10.64.45.230:22 over proxy 2026-07-25 04:10:20.870743 UTC - SSH Test output: 2026-07-25 04:10:20.870815 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-25 04:10:20.870836 UTC - Uptime: 04:10:19 up 0 min, 1 user, load average: 0.86, 0.31, 0.11 2026-07-25 04:10:21.176550 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 04:10:21.176624 UTC - lsb_release matches expected value 2026-07-25 04:10:21.176644 UTC - SSH Test passed. 2026-07-25 04:10:21.481711 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 04:09:51 +0000! 2026-07-25 04:10:21.492949 UTC - Destroying tst-8aaee31e tst 2026-07-25 04:10:21.493150 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-8aaee31e tst