Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.53s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 323ms 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 966ms + 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-09 20:05:49.818570 UTC - Installing Slices CLI (latest stable version). 2026-08-09 20:05:49.818837 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 20:05:55.508608 UTC - Slices CLI installed. 2026-08-09 20:05:55.509018 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-09 20:05:55.510205 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-09 20:05:55.510271 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-09 20:05:55.510338 UTC - which slices: /app/.venv/bin/slices 2026-08-09 20:05:55.510358 UTC - Slices CLI exe: 'slices' 2026-08-09 20:05:55.510373 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 20:05:55.510521 UTC - Run: slices --version 2026-08-09 20:05:59.049471 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-09 20:05:59.049868 UTC - Run: slices pubkey list --format text 2026-08-09 20:06:00.323040 UTC - Pubkey already registered 2026-08-09 20:06:00.323272 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 20:06:01.608880 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 20:06:01.608993 UTC - Check List Flavors 2026-08-09 20:06:01.609149 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-08-09 20:06:02.675205 UTC - Check List DiskImages 2026-08-09 20:06:02.675392 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-08-09 20:06:03.795692 UTC - Requesting resources 2026-08-09 20:06:03.796178 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-09 20:06:03.796290 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-72b18188 --user-data /tmp/tmpth5f6goq 2026-08-09 20:06:05.769583 UTC - Resource ID: r_it-genoa1-bi-vm1_01kzm23vg6e3gvesycx3z8hta7 2026-08-09 20:06:05.769751 UTC - Waiting until resource ready 2026-08-09 20:06:07.770131 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-72b18188 tst 2026-08-09 20:06:08.686241 UTC - Status: IMAGING 2026-08-09 20:06:11.603015 UTC - Status: IMAGING 2026-08-09 20:06:14.472098 UTC - Status: BOOTING 2026-08-09 20:06:17.398423 UTC - Status: BOOTING 2026-08-09 20:06:20.365377 UTC - Status: BOOTING 2026-08-09 20:06:23.333732 UTC - Status: BOOTING 2026-08-09 20:06:26.308848 UTC - Status: BOOTING 2026-08-09 20:06:29.325618 UTC - Status: BOOTING 2026-08-09 20:06:32.629258 UTC - Status: BOOTING 2026-08-09 20:06:35.716982 UTC - Status: BOOTING 2026-08-09 20:06:39.079508 UTC - Status: BOOTING 2026-08-09 20:06:42.410075 UTC - Status: UP 2026-08-09 20:06:42.410190 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzm23trqe9xtdhandcehqkh1 2026-08-09 20:06:42.410252 UTC - Validate resources 2026-08-09 20:06:43.742248 UTC - The fields of the created resource were validated. 2026-08-09 20:06:43.742373 UTC - Check if resources are registered in experiment 2026-08-09 20:06:43.742554 UTC - Run: slices experiment list-resources --format json tst-72b18188 2026-08-09 20:06:44.719989 UTC - Status (on expauth): UP 2026-08-09 20:06:44.720138 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 20:06:44.720272 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-72b18188 2026-08-09 20:06:46.464933 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-72b18188 tst 2026-08-09 20:06:47.939158 UTC - Run: slices experiment list-resources --format json tst-72b18188 2026-08-09 20:06:49.099762 UTC - expires_at (on expauth): 2026-08-09T23:06:00Z (correctly extended) 2026-08-09 20:06:49.100041 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 20:06:49.100213 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-72b18188 2026-08-09 20:06:50.983099 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-72b18188 tst 2026-08-09 20:06:52.514838 UTC - Run: slices experiment list-resources --format json tst-72b18188 2026-08-09 20:06:53.436000 UTC - expires_at (on expauth): 2026-08-10T00:06:00Z (correctly extended) 2026-08-09 20:06:53.436186 UTC - Testing ssh login 2026-08-09 20:06:53.468428 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-72b18188 tst 2026-08-09 20:06:54.638671 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-72b18188 tst 2026-08-09 20:06:55.859459 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-72b18188 tst 2026-08-09 20:06:56.926742 UTC - Logging in using 'slices bi ssh' 2026-08-09 20:06:56.926827 UTC - Forcing IPv4 only. 2026-08-09 20:06:56.926961 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-72b18188 tst -- -4 uname -a 2026-08-09 20:07:00.099244 UTC - Forcing IPv4 only. 2026-08-09 20:07:00.099433 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-72b18188 tst -- -4 uptime 2026-08-09 20:07:02.268648 UTC - CLI SSH Test passed. 2026-08-09 20:07:02.268761 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-09 20:07:02.268792 UTC - Uptime: 20:07:02 up 0 min, 1 user, load average: 0.20, 0.06, 0.02 2026-08-09 20:07:02.268842 UTC - Logging in using SSH over SSH proxy 2026-08-09 20:07:02.269034 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-72b18188 tst 2026-08-09 20:07:03.492111 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-09 20:07:03.492261 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-09 20:07:03.492441 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-09 20:07:03.492765 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-09 20:07:03.492900 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-09 20:07:03.492988 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-09 20:07:03.493011 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-08-09 20:07:04.040352 UTC - Connecting to 172.30.44.3:22 over proxy 2026-08-09 20:07:04.921610 UTC - SSH Test output: 2026-08-09 20:07:04.921683 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-09 20:07:04.921703 UTC - Uptime: 20:07:04 up 0 min, 1 user, load average: 0.19, 0.06, 0.02 2026-08-09 20:07:05.108637 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 20:07:05.108735 UTC - lsb_release matches expected value 2026-08-09 20:07:05.108759 UTC - SSH Test passed. 2026-08-09 20:07:05.294718 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 20:06:40 +0000! 2026-08-09 20:07:05.302373 UTC - Destroying tst-72b18188 tst 2026-08-09 20:07:05.302578 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-72b18188 tst