Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.40s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 631ms 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 979ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-18 13:04:01.938531 UTC - Installing Slices CLI (latest stable version). 2026-07-18 13:04:01.939111 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 13:04:09.419594 UTC - Slices CLI installed. 2026-07-18 13:04:09.419991 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-18 13:04:09.422659 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-18 13:04:09.430849 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 13:04:09.431000 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-18 13:04:09.431028 UTC - Slices CLI exe: 'slices' 2026-07-18 13:04:09.431045 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 13:04:09.431242 UTC - Run: slices --version 2026-07-18 13:04:13.189083 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-18 13:04:13.189392 UTC - Run: slices pubkey list --format text 2026-07-18 13:04:14.386507 UTC - Pubkey already registered 2026-07-18 13:04:14.386750 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 13:04:15.841326 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 13:04:15.841429 UTC - Check List Flavors 2026-07-18 13:04:15.841576 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-18 13:04:17.782133 UTC - Check List DiskImages 2026-07-18 13:04:17.782347 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-18 13:04:18.749063 UTC - Requesting resources 2026-07-18 13:04:18.750007 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 13:04:18.750135 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5bac743d --user-data /tmp/tmpo6rm9de2 2026-07-18 13:04:23.689170 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxtn7wbafg08vsjv6baxcqdx 2026-07-18 13:04:23.689354 UTC - Waiting until resource ready 2026-07-18 13:04:25.689717 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5bac743d tst 2026-07-18 13:04:27.084522 UTC - Status: IMAGING 2026-07-18 13:04:29.954762 UTC - Status: IMAGING 2026-07-18 13:04:32.934591 UTC - Status: BOOTING 2026-07-18 13:04:36.015741 UTC - Status: BOOTING 2026-07-18 13:04:39.419197 UTC - Status: BOOTING 2026-07-18 13:04:43.119149 UTC - Status: BOOTING 2026-07-18 13:04:46.561510 UTC - Status: BOOTING 2026-07-18 13:04:49.578416 UTC - Status: BOOTING 2026-07-18 13:04:52.816345 UTC - Status: BOOTING 2026-07-18 13:04:55.955418 UTC - Status: BOOTING 2026-07-18 13:04:59.126224 UTC - Status: BOOTING 2026-07-18 13:05:02.096502 UTC - Status: UP 2026-07-18 13:05:02.096590 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxtn7tcrfjgvmxehmvc4jyrr 2026-07-18 13:05:02.096636 UTC - Validate resources 2026-07-18 13:05:03.298834 UTC - The fields of the created resource were validated. 2026-07-18 13:05:03.299045 UTC - Check if resources are registered in experiment 2026-07-18 13:05:03.299412 UTC - Run: slices experiment list-resources --format json tst-5bac743d 2026-07-18 13:05:04.330844 UTC - Status (on expauth): UP 2026-07-18 13:05:04.330986 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 13:05:04.331110 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-5bac743d 2026-07-18 13:05:07.675164 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5bac743d tst 2026-07-18 13:05:08.491077 UTC - Run: slices experiment list-resources --format json tst-5bac743d 2026-07-18 13:05:09.157156 UTC - expires_at (on expauth): 2026-07-18T16:05:00Z (correctly extended) 2026-07-18 13:05:09.157254 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 13:05:09.157422 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-5bac743d 2026-07-18 13:05:12.177248 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5bac743d tst 2026-07-18 13:05:13.200083 UTC - Run: slices experiment list-resources --format json tst-5bac743d 2026-07-18 13:05:13.865624 UTC - expires_at (on expauth): 2026-07-18T17:05:00Z (correctly extended) 2026-07-18 13:05:13.865774 UTC - Testing ssh login 2026-07-18 13:05:13.884274 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5bac743d tst 2026-07-18 13:05:14.850544 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5bac743d tst 2026-07-18 13:05:15.816608 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5bac743d tst 2026-07-18 13:05:16.782808 UTC - Logging in using 'slices bi ssh' 2026-07-18 13:05:16.782905 UTC - Forcing IPv4 only. 2026-07-18 13:05:16.783056 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-5bac743d tst -- -4 uname -a 2026-07-18 13:05:19.553181 UTC - Forcing IPv4 only. 2026-07-18 13:05:19.554134 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-5bac743d tst -- -4 uptime 2026-07-18 13:05:21.322062 UTC - CLI SSH Test passed. 2026-07-18 13:05:21.322132 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-07-18 13:05:21.322151 UTC - Uptime: 13:05:21 up 0 min, 1 user, load average: 0.72, 0.20, 0.07 2026-07-18 13:05:21.322185 UTC - Logging in using SSH over SSH proxy 2026-07-18 13:05:21.322343 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5bac743d tst 2026-07-18 13:05:22.239208 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-18 13:05:22.239352 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-18 13:05:22.239520 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-18 13:05:22.239733 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-18 13:05:22.239852 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-18 13:05:22.239928 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-18 13:05:22.239950 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-18 13:05:22.740436 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-18 13:05:23.618701 UTC - SSH Test output: 2026-07-18 13:05:23.618789 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-07-18 13:05:23.618809 UTC - Uptime: 13:05:23 up 0 min, 1 user, load average: 0.72, 0.20, 0.07 2026-07-18 13:05:23.805370 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 13:05:23.805441 UTC - lsb_release matches expected value 2026-07-18 13:05:23.805459 UTC - SSH Test passed. 2026-07-18 13:05:23.991614 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 13:04:59 +0000! 2026-07-18 13:05:23.998765 UTC - Destroying tst-5bac743d tst 2026-07-18 13:05:23.998942 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-5bac743d tst