Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.32s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 274ms 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 2.00s + 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-10 14:16:59.707981 UTC - Installing Slices CLI (latest stable version). 2026-07-10 14:16:59.708213 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 14:17:07.902508 UTC - Slices CLI installed. 2026-07-10 14:17:07.903453 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-10 14:17:07.906060 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-10 14:17:07.907151 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 14:17:07.907287 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-10 14:17:07.907315 UTC - Slices CLI exe: 'slices' 2026-07-10 14:17:07.907332 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 14:17:07.907450 UTC - Run: slices --version 2026-07-10 14:17:14.788584 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-10 14:17:14.792826 UTC - Run: slices pubkey list --format text 2026-07-10 14:17:17.591979 UTC - Pubkey already registered 2026-07-10 14:17:17.592234 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 14:17:19.165651 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 14:17:19.165752 UTC - Check List Flavors 2026-07-10 14:17:19.165899 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-10 14:17:21.447961 UTC - Check List DiskImages 2026-07-10 14:17:21.448229 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-10 14:17:22.377797 UTC - Requesting resources 2026-07-10 14:17:22.378317 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 14:17:22.378438 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5f7e875c --user-data /tmp/tmpdoy5806w 2026-07-10 14:17:27.955508 UTC - Resource ID: r_gr-volos-bi-vm1_01kx667xwpes9r9jasj0zx8ker 2026-07-10 14:17:27.955680 UTC - Waiting until resource ready 2026-07-10 14:17:29.956094 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5f7e875c tst 2026-07-10 14:17:30.872178 UTC - Status: BOOTING 2026-07-10 14:17:33.789116 UTC - Status: BOOTING 2026-07-10 14:17:36.755658 UTC - Status: BOOTING 2026-07-10 14:17:40.419524 UTC - Status: BOOTING 2026-07-10 14:17:43.335786 UTC - Status: BOOTING 2026-07-10 14:17:46.413536 UTC - Status: BOOTING 2026-07-10 14:17:49.709524 UTC - Status: BOOTING 2026-07-10 14:17:52.998758 UTC - Status: BOOTING 2026-07-10 14:17:56.400535 UTC - Status: BOOTING 2026-07-10 14:17:59.939179 UTC - Status: BOOTING 2026-07-10 14:18:03.106147 UTC - Status: BOOTING 2026-07-10 14:18:06.425171 UTC - Status: BOOTING 2026-07-10 14:18:09.657294 UTC - Status: UP 2026-07-10 14:18:09.657378 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx667ty5ebe9m271csb1kywq 2026-07-10 14:18:09.657416 UTC - Validate resources 2026-07-10 14:18:10.633019 UTC - The fields of the created resource were validated. 2026-07-10 14:18:10.633110 UTC - Check if resources are registered in experiment 2026-07-10 14:18:10.633262 UTC - Run: slices experiment list-resources --format json tst-5f7e875c 2026-07-10 14:18:11.908151 UTC - Status (on expauth): UP 2026-07-10 14:18:11.908289 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 14:18:11.908422 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-5f7e875c 2026-07-10 14:18:15.843968 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5f7e875c tst 2026-07-10 14:18:16.922316 UTC - Run: slices experiment list-resources --format json tst-5f7e875c 2026-07-10 14:18:17.647973 UTC - expires_at (on expauth): 2026-07-10T17:18:00Z (correctly extended) 2026-07-10 14:18:17.648075 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 14:18:17.648214 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-5f7e875c 2026-07-10 14:18:21.595094 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5f7e875c tst 2026-07-10 14:18:23.032599 UTC - Run: slices experiment list-resources --format json tst-5f7e875c 2026-07-10 14:18:23.877945 UTC - expires_at (on expauth): 2026-07-10T18:18:00Z (correctly extended) 2026-07-10 14:18:23.878051 UTC - Testing ssh login 2026-07-10 14:18:23.891780 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5f7e875c tst 2026-07-10 14:18:25.335258 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5f7e875c tst 2026-07-10 14:18:27.513720 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5f7e875c tst 2026-07-10 14:18:29.342466 UTC - Logging in using 'slices bi ssh' 2026-07-10 14:18:29.342536 UTC - Forcing IPv4 only. 2026-07-10 14:18:29.342685 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-5f7e875c tst -- -4 uname -a 2026-07-10 14:18:33.940076 UTC - Forcing IPv4 only. 2026-07-10 14:18:33.940259 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-5f7e875c tst -- -4 uptime 2026-07-10 14:18:37.670674 UTC - CLI SSH Test passed. 2026-07-10 14:18:37.670740 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-10 14:18:37.670760 UTC - Uptime: 14:18:37 up 0 min, 1 user, load average: 0.90, 0.32, 0.11 2026-07-10 14:18:37.670792 UTC - Logging in using SSH over SSH proxy 2026-07-10 14:18:37.670926 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5f7e875c tst 2026-07-10 14:18:39.438962 UTC - Added paramiko HostKeyEntry for 10.64.45.239 2026-07-10 14:18:39.439501 UTC - Added paramiko HostKeyEntry for 10.64.45.239 2026-07-10 14:18:39.439612 UTC - Added paramiko HostKeyEntry for 10.64.45.239 2026-07-10 14:18:39.439898 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 14:18:39.439989 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 14:18:39.440033 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 14:18:39.440050 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-10 14:18:40.178463 UTC - Connecting to 10.64.45.239:22 over proxy 2026-07-10 14:18:41.948351 UTC - SSH Test output: 2026-07-10 14:18:41.948441 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-10 14:18:41.948487 UTC - Uptime: 14:18:41 up 1 min, 1 user, load average: 0.83, 0.31, 0.11 2026-07-10 14:18:42.258082 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 14:18:42.258155 UTC - lsb_release matches expected value 2026-07-10 14:18:42.258175 UTC - SSH Test passed. 2026-07-10 14:18:42.566133 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 14:18:06 +0000! 2026-07-10 14:18:42.574054 UTC - Destroying tst-5f7e875c tst 2026-07-10 14:18:42.574298 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-5f7e875c tst