Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.66s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 792ms 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 895ms + annotated-types==0.7.0 + anyio==4.14.2 + 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-16 13:07:28.543739 UTC - Installing Slices CLI (latest stable version). 2026-07-16 13:07:28.544119 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 13:07:40.151430 UTC - Slices CLI installed. 2026-07-16 13:07:40.151805 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-16 13:07:40.154506 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-16 13:07:40.156797 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 13:07:40.156946 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-16 13:07:40.156980 UTC - Slices CLI exe: 'slices' 2026-07-16 13:07:40.156999 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 13:07:40.157129 UTC - Run: slices --version 2026-07-16 13:07:45.991107 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-16 13:07:45.991395 UTC - Run: slices pubkey list --format text 2026-07-16 13:07:47.623394 UTC - Pubkey already registered 2026-07-16 13:07:47.623655 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 13:07:48.705325 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 13:07:48.705414 UTC - Check List Flavors 2026-07-16 13:07:48.705552 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-16 13:07:50.624288 UTC - Check List DiskImages 2026-07-16 13:07:50.624665 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-16 13:07:51.608773 UTC - Requesting resources 2026-07-16 13:07:51.618730 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 13:07:51.618892 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5085bfbf --user-data /tmp/tmpaltali_n 2026-07-16 13:07:58.367481 UTC - Resource ID: r_gr-volos-bi-vm1_01kxngn013engbzyzykxfvpyvb 2026-07-16 13:07:58.368903 UTC - Waiting until resource ready 2026-07-16 13:08:00.369307 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5085bfbf tst 2026-07-16 13:08:01.385517 UTC - Status: IMAGING 2026-07-16 13:08:04.908028 UTC - Status: BOOTING 2026-07-16 13:08:08.550315 UTC - Status: BOOTING 2026-07-16 13:08:12.603427 UTC - Status: BOOTING 2026-07-16 13:08:15.569713 UTC - Status: BOOTING 2026-07-16 13:08:18.695172 UTC - Status: BOOTING 2026-07-16 13:08:22.291123 UTC - Status: BOOTING 2026-07-16 13:08:26.597913 UTC - Status: BOOTING 2026-07-16 13:08:29.997206 UTC - Status: BOOTING 2026-07-16 13:08:33.063812 UTC - Status: BOOTING 2026-07-16 13:08:36.103096 UTC - Status: BOOTING 2026-07-16 13:08:39.785468 UTC - Status: BOOTING 2026-07-16 13:08:43.777387 UTC - Status: BOOTING 2026-07-16 13:08:46.743619 UTC - Status: UP 2026-07-16 13:08:46.743703 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxngmwn1fnbrhv7n99xk1v50 2026-07-16 13:08:46.743772 UTC - Validate resources 2026-07-16 13:08:47.767523 UTC - The fields of the created resource were validated. 2026-07-16 13:08:47.767611 UTC - Check if resources are registered in experiment 2026-07-16 13:08:47.767770 UTC - Run: slices experiment list-resources --format json tst-5085bfbf 2026-07-16 13:08:49.095253 UTC - Status (on expauth): UP 2026-07-16 13:08:49.095386 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 13:08:49.095509 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-5085bfbf 2026-07-16 13:08:53.298815 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5085bfbf tst 2026-07-16 13:08:54.553277 UTC - Run: slices experiment list-resources --format json tst-5085bfbf 2026-07-16 13:08:55.951603 UTC - expires_at (on expauth): 2026-07-16T16:08:00Z (correctly extended) 2026-07-16 13:08:55.951716 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 13:08:55.951869 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-5085bfbf 2026-07-16 13:09:00.122183 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-5085bfbf tst 2026-07-16 13:09:01.554269 UTC - Run: slices experiment list-resources --format json tst-5085bfbf 2026-07-16 13:09:02.273364 UTC - expires_at (on expauth): 2026-07-16T17:08:00Z (correctly extended) 2026-07-16 13:09:02.273490 UTC - Testing ssh login 2026-07-16 13:09:02.285560 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5085bfbf tst 2026-07-16 13:09:03.457357 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5085bfbf tst 2026-07-16 13:09:05.099368 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5085bfbf tst 2026-07-16 13:09:06.630965 UTC - Logging in using 'slices bi ssh' 2026-07-16 13:09:06.631031 UTC - Forcing IPv4 only. 2026-07-16 13:09:06.631158 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-5085bfbf tst -- -4 uname -a 2026-07-16 13:09:12.458756 UTC - Forcing IPv4 only. 2026-07-16 13:09:12.459234 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-5085bfbf tst -- -4 uptime 2026-07-16 13:09:16.880439 UTC - CLI SSH Test passed. 2026-07-16 13:09:16.880521 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-16 13:09:16.880541 UTC - Uptime: 13:09:16 up 1 min, 1 user, load average: 0.75, 0.26, 0.09 2026-07-16 13:09:16.880575 UTC - Logging in using SSH over SSH proxy 2026-07-16 13:09:16.880749 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5085bfbf tst 2026-07-16 13:09:18.882086 UTC - Added paramiko HostKeyEntry for 10.64.45.227 2026-07-16 13:09:18.882307 UTC - Added paramiko HostKeyEntry for 10.64.45.227 2026-07-16 13:09:18.882370 UTC - Added paramiko HostKeyEntry for 10.64.45.227 2026-07-16 13:09:18.882658 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-16 13:09:18.882748 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-16 13:09:18.882791 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-16 13:09:18.882808 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-16 13:09:19.637485 UTC - Connecting to 10.64.45.227:22 over proxy 2026-07-16 13:09:21.133481 UTC - SSH Test output: 2026-07-16 13:09:21.133562 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-16 13:09:21.133582 UTC - Uptime: 13:09:20 up 1 min, 1 user, load average: 0.69, 0.26, 0.09 2026-07-16 13:09:21.450017 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 13:09:21.450121 UTC - lsb_release matches expected value 2026-07-16 13:09:21.450154 UTC - SSH Test passed. 2026-07-16 13:09:21.759538 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 13:08:45 +0000! 2026-07-16 13:09:21.766803 UTC - Destroying tst-5085bfbf tst 2026-07-16 13:09:21.766999 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-5085bfbf tst