Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.77s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 195ms 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 280ms + annotated-types==0.7.0 + anyio==4.14.1 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-11 20:06:58.652694 UTC - Installing Slices CLI (latest stable version). 2026-07-11 20:06:58.652947 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 20:07:04.760496 UTC - Slices CLI installed. 2026-07-11 20:07:04.760892 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-11 20:07:04.763145 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-11 20:07:04.764086 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 20:07:04.764219 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-11 20:07:04.764246 UTC - Slices CLI exe: 'slices' 2026-07-11 20:07:04.764262 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 20:07:04.764380 UTC - Run: slices --version 2026-07-11 20:07:09.521745 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-11 20:07:09.522253 UTC - Run: slices pubkey list --format text 2026-07-11 20:07:11.163197 UTC - Pubkey already registered 2026-07-11 20:07:11.163452 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 20:07:11.982286 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 20:07:11.982380 UTC - Check List Flavors 2026-07-11 20:07:11.982524 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-11 20:07:14.249847 UTC - Check List DiskImages 2026-07-11 20:07:14.250067 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-11 20:07:16.418358 UTC - Requesting resources 2026-07-11 20:07:16.419931 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 20:07:16.420406 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ea0c42ca --user-data /tmp/tmppq7l32ev 2026-07-11 20:07:22.323228 UTC - Resource ID: r_gr-volos-bi-vm1_01kx9cnazdfd09ga3d7ryr9pxk 2026-07-11 20:07:22.323394 UTC - Waiting until resource ready 2026-07-11 20:07:24.324841 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea0c42ca tst 2026-07-11 20:07:25.644849 UTC - Status: IMAGING 2026-07-11 20:07:28.568377 UTC - Status: BOOTING 2026-07-11 20:07:31.484790 UTC - Status: BOOTING 2026-07-11 20:07:34.350734 UTC - Status: BOOTING 2026-07-11 20:07:37.217211 UTC - Status: BOOTING 2026-07-11 20:07:40.134147 UTC - Status: BOOTING 2026-07-11 20:07:43.100808 UTC - Status: BOOTING 2026-07-11 20:07:45.967147 UTC - Status: BOOTING 2026-07-11 20:07:48.837236 UTC - Status: BOOTING 2026-07-11 20:07:51.968097 UTC - Status: BOOTING 2026-07-11 20:07:54.884046 UTC - Status: BOOTING 2026-07-11 20:07:58.575332 UTC - Status: BOOTING 2026-07-11 20:08:02.201277 UTC - Status: BOOTING 2026-07-11 20:08:05.721986 UTC - Status: UP 2026-07-11 20:08:05.722062 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx9cn8y9eefv657shaj5j0m2 2026-07-11 20:08:05.722104 UTC - Validate resources 2026-07-11 20:08:07.879344 UTC - The fields of the created resource were validated. 2026-07-11 20:08:07.879436 UTC - Check if resources are registered in experiment 2026-07-11 20:08:07.879606 UTC - Run: slices experiment list-resources --format json tst-ea0c42ca 2026-07-11 20:08:09.234180 UTC - Status (on expauth): UP 2026-07-11 20:08:09.234373 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 20:08:09.234570 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-ea0c42ca 2026-07-11 20:08:12.942139 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea0c42ca tst 2026-07-11 20:08:14.386026 UTC - Run: slices experiment list-resources --format json tst-ea0c42ca 2026-07-11 20:08:15.595655 UTC - expires_at (on expauth): 2026-07-11T23:08:00Z (correctly extended) 2026-07-11 20:08:15.595755 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 20:08:15.595905 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-ea0c42ca 2026-07-11 20:08:20.761359 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea0c42ca tst 2026-07-11 20:08:22.341330 UTC - Run: slices experiment list-resources --format json tst-ea0c42ca 2026-07-11 20:08:23.065911 UTC - expires_at (on expauth): 2026-07-12T00:08:00Z (correctly extended) 2026-07-11 20:08:23.066025 UTC - Testing ssh login 2026-07-11 20:08:23.079409 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ea0c42ca tst 2026-07-11 20:08:24.145525 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ea0c42ca tst 2026-07-11 20:08:25.937540 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ea0c42ca tst 2026-07-11 20:08:27.318304 UTC - Logging in using 'slices bi ssh' 2026-07-11 20:08:27.318370 UTC - Forcing IPv4 only. 2026-07-11 20:08:27.318503 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea0c42ca tst -- -4 uname -a 2026-07-11 20:08:32.446487 UTC - Forcing IPv4 only. 2026-07-11 20:08:32.446677 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea0c42ca tst -- -4 uptime 2026-07-11 20:08:35.624351 UTC - CLI SSH Test passed. 2026-07-11 20:08:35.624419 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-11 20:08:35.624438 UTC - Uptime: 20:08:35 up 1 min, 1 user, load average: 0.11, 0.04, 0.01 2026-07-11 20:08:35.624471 UTC - Logging in using SSH over SSH proxy 2026-07-11 20:08:35.624615 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ea0c42ca tst 2026-07-11 20:08:37.574718 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-11 20:08:37.574969 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-11 20:08:37.575059 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-11 20:08:37.575362 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 20:08:37.575465 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 20:08:37.575513 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 20:08:37.575530 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-11 20:08:38.321213 UTC - Connecting to 10.64.45.254:22 over proxy 2026-07-11 20:08:39.993432 UTC - SSH Test output: 2026-07-11 20:08:39.993504 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-11 20:08:39.993526 UTC - Uptime: 20:08:39 up 1 min, 1 user, load average: 0.11, 0.04, 0.01 2026-07-11 20:08:40.298905 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 20:08:40.298979 UTC - lsb_release matches expected value 2026-07-11 20:08:40.298998 UTC - SSH Test passed. 2026-07-11 20:08:40.607513 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 20:08:04 +0000! 2026-07-11 20:08:40.614390 UTC - Destroying tst-ea0c42ca tst 2026-07-11 20:08:40.614581 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-ea0c42ca tst