Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.84s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 367ms 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 679ms + 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-04 20:05:03.852965 UTC - Installing Slices CLI (latest stable version). 2026-08-04 20:05:03.854249 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-04 20:05:11.358818 UTC - Slices CLI installed. 2026-08-04 20:05:11.359745 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-04 20:05:11.362694 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-04 20:05:11.362767 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-04 20:05:11.362836 UTC - which slices: /app/.venv/bin/slices 2026-08-04 20:05:11.362856 UTC - Slices CLI exe: 'slices' 2026-08-04 20:05:11.362872 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-04 20:05:11.363011 UTC - Run: slices --version 2026-08-04 20:05:16.949197 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-04 20:05:16.949474 UTC - Run: slices pubkey list --format text 2026-08-04 20:05:18.661650 UTC - Pubkey already registered 2026-08-04 20:05:18.661884 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-04 20:05:19.629997 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-04 20:05:19.630096 UTC - Check List Flavors 2026-08-04 20:05:19.630267 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-08-04 20:05:21.222873 UTC - Check List DiskImages 2026-08-04 20:05:21.223090 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-08-04 20:05:22.451231 UTC - Requesting resources 2026-08-04 20:05:22.456913 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-04 20:05:22.457091 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-2600bf42 --user-data /tmp/tmp8lr5hyy3 2026-08-04 20:05:24.499493 UTC - Resource ID: r_it-genoa1-bi-vm1_01kz76301nfa0adzy4z46zrmh2 2026-08-04 20:05:24.499678 UTC - Waiting until resource ready 2026-08-04 20:05:26.500039 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-2600bf42 tst 2026-08-04 20:05:27.829075 UTC - Status: IMAGING 2026-08-04 20:05:30.862537 UTC - Status: IMAGING 2026-08-04 20:05:33.861095 UTC - Status: BOOTING 2026-08-04 20:05:36.878011 UTC - Status: BOOTING 2026-08-04 20:05:40.549095 UTC - Status: BOOTING 2026-08-04 20:05:43.920065 UTC - Status: BOOTING 2026-08-04 20:05:47.421257 UTC - Status: BOOTING 2026-08-04 20:05:50.750030 UTC - Status: BOOTING 2026-08-04 20:05:53.717167 UTC - Status: BOOTING 2026-08-04 20:05:56.798020 UTC - Status: BOOTING 2026-08-04 20:05:59.814613 UTC - Status: UP 2026-08-04 20:05:59.814693 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz762z8jfx7vbkd3fpjmf83b 2026-08-04 20:05:59.814736 UTC - Validate resources 2026-08-04 20:06:00.697175 UTC - The fields of the created resource were validated. 2026-08-04 20:06:00.697284 UTC - Check if resources are registered in experiment 2026-08-04 20:06:00.697444 UTC - Run: slices experiment list-resources --format json tst-2600bf42 2026-08-04 20:06:01.563420 UTC - Status (on expauth): UP 2026-08-04 20:06:01.563565 UTC - Testing extend expires_at (all resources in experiment) 2026-08-04 20:06:01.563696 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-2600bf42 2026-08-04 20:06:03.591308 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-2600bf42 tst 2026-08-04 20:06:04.661321 UTC - Run: slices experiment list-resources --format json tst-2600bf42 2026-08-04 20:06:05.975618 UTC - expires_at (on expauth): 2026-08-04T23:06:00Z (correctly extended) 2026-08-04 20:06:05.975717 UTC - Testing extend expires_at (single resource in experiment) 2026-08-04 20:06:05.975856 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-2600bf42 2026-08-04 20:06:07.357195 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-2600bf42 tst 2026-08-04 20:06:08.273755 UTC - Run: slices experiment list-resources --format json tst-2600bf42 2026-08-04 20:06:09.342770 UTC - expires_at (on expauth): 2026-08-05T00:06:00Z (correctly extended) 2026-08-04 20:06:09.342899 UTC - Testing ssh login 2026-08-04 20:06:09.362851 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2600bf42 tst 2026-08-04 20:06:10.434261 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2600bf42 tst 2026-08-04 20:06:11.450596 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2600bf42 tst 2026-08-04 20:06:12.571380 UTC - Logging in using 'slices bi ssh' 2026-08-04 20:06:12.571466 UTC - Forcing IPv4 only. 2026-08-04 20:06:12.571626 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-2600bf42 tst -- -4 uname -a 2026-08-04 20:06:15.257503 UTC - Forcing IPv4 only. 2026-08-04 20:06:15.257728 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-2600bf42 tst -- -4 uptime 2026-08-04 20:06:17.535654 UTC - CLI SSH Test passed. 2026-08-04 20:06:17.535730 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-04 20:06:17.535750 UTC - Uptime: 20:06:17 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-08-04 20:06:17.535784 UTC - Logging in using SSH over SSH proxy 2026-08-04 20:06:17.535925 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2600bf42 tst 2026-08-04 20:06:18.855083 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 20:06:18.855239 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 20:06:18.855399 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 20:06:18.855651 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 20:06:18.855789 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 20:06:18.855889 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 20:06:18.855916 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-08-04 20:06:19.521010 UTC - Connecting to 172.30.44.3:22 over proxy 2026-08-04 20:06:20.402571 UTC - SSH Test output: 2026-08-04 20:06:20.402652 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-04 20:06:20.402673 UTC - Uptime: 20:06:20 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-08-04 20:06:20.590268 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-04 20:06:20.590346 UTC - lsb_release matches expected value 2026-08-04 20:06:20.590367 UTC - SSH Test passed. 2026-08-04 20:06:20.775427 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 20:05:58 +0000! 2026-08-04 20:06:20.786884 UTC - Destroying tst-2600bf42 tst 2026-08-04 20:06:20.787098 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-2600bf42 tst