Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 8.85s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 738ms 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 916ms + 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:12:41.785136 UTC - Installing Slices CLI (latest stable version). 2026-07-10 14:12:41.785417 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 14:12:48.218414 UTC - Slices CLI installed. 2026-07-10 14:12:48.222708 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:12:48.226079 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:12:48.227480 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 14:12:48.227650 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:12:48.227682 UTC - Slices CLI exe: 'slices' 2026-07-10 14:12:48.227700 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 14:12:48.227825 UTC - Run: slices --version 2026-07-10 14:12:55.304221 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:12:55.305102 UTC - Run: slices pubkey list --format text 2026-07-10 14:12:57.424499 UTC - Pubkey already registered 2026-07-10 14:12:57.424762 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 14:13:00.005018 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 14:13:00.005141 UTC - Check List Flavors 2026-07-10 14:13:00.005304 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-10 14:13:03.466250 UTC - Check List DiskImages 2026-07-10 14:13:03.466472 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-10 14:13:05.462319 UTC - Requesting resources 2026-07-10 14:13:05.462858 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 14:13:05.462983 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-53043054 --user-data /tmp/tmpahxqivni 2026-07-10 14:13:09.971027 UTC - Resource ID: r_it-genoa1-bi-vm1_01kx6601qaen1b6efh38eb2bz1 2026-07-10 14:13:09.971190 UTC - Waiting until resource ready 2026-07-10 14:13:11.971467 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-53043054 tst 2026-07-10 14:13:14.231853 UTC - Status: IMAGING 2026-07-10 14:13:18.569943 UTC - Status: BOOTING 2026-07-10 14:13:22.156622 UTC - Status: BOOTING 2026-07-10 14:13:26.088641 UTC - Status: BOOTING 2026-07-10 14:13:30.898966 UTC - Status: BOOTING 2026-07-10 14:13:34.993253 UTC - Status: BOOTING 2026-07-10 14:13:38.425011 UTC - Status: BOOTING 2026-07-10 14:13:43.019304 UTC - Status: BOOTING 2026-07-10 14:13:46.702166 UTC - Status: UP 2026-07-10 14:13:46.702283 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx6600cbewksb9jhz30vzbt7 2026-07-10 14:13:46.702333 UTC - Validate resources 2026-07-10 14:13:47.670964 UTC - The fields of the created resource were validated. 2026-07-10 14:13:47.671057 UTC - Check if resources are registered in experiment 2026-07-10 14:13:47.671225 UTC - Run: slices experiment list-resources --format json tst-53043054 2026-07-10 14:13:48.488941 UTC - Status (on expauth): UP 2026-07-10 14:13:48.489092 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 14:13:48.489235 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-53043054 2026-07-10 14:13:51.466657 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-53043054 tst 2026-07-10 14:13:53.097422 UTC - Run: slices experiment list-resources --format json tst-53043054 2026-07-10 14:13:53.713918 UTC - expires_at (on expauth): 2026-07-10T17:13:00Z (correctly extended) 2026-07-10 14:13:53.714033 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 14:13:53.714198 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-53043054 2026-07-10 14:13:56.785996 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-53043054 tst 2026-07-10 14:13:57.601988 UTC - Run: slices experiment list-resources --format json tst-53043054 2026-07-10 14:13:58.369151 UTC - expires_at (on expauth): 2026-07-10T18:13:00Z (correctly extended) 2026-07-10 14:13:58.369291 UTC - Testing ssh login 2026-07-10 14:13:58.390196 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-53043054 tst 2026-07-10 14:13:59.631957 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-53043054 tst 2026-07-10 14:14:00.648927 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-53043054 tst 2026-07-10 14:14:01.715269 UTC - Logging in using 'slices bi ssh' 2026-07-10 14:14:01.715342 UTC - Forcing IPv4 only. 2026-07-10 14:14:01.715483 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-53043054 tst -- -4 uname -a 2026-07-10 14:14:04.435584 UTC - Forcing IPv4 only. 2026-07-10 14:14:04.435761 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-53043054 tst -- -4 uptime 2026-07-10 14:14:06.454189 UTC - CLI SSH Test passed. 2026-07-10 14:14:06.454265 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-10 14:14:06.454284 UTC - Uptime: 14:14:06 up 0 min, 1 user, load average: 0.31, 0.09, 0.03 2026-07-10 14:14:06.454319 UTC - Logging in using SSH over SSH proxy 2026-07-10 14:14:06.454467 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-53043054 tst 2026-07-10 14:14:07.378484 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 14:14:07.378615 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 14:14:07.378787 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 14:14:07.379036 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 14:14:07.379205 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 14:14:07.379292 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 14:14:07.379317 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-10 14:14:07.888484 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-10 14:14:08.767192 UTC - SSH Test output: 2026-07-10 14:14:08.767266 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-10 14:14:08.767287 UTC - Uptime: 14:14:08 up 0 min, 1 user, load average: 0.28, 0.09, 0.03 2026-07-10 14:14:08.954854 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 14:14:08.954931 UTC - lsb_release matches expected value 2026-07-10 14:14:08.954951 UTC - SSH Test passed. 2026-07-10 14:14:09.140247 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 14:13:44 +0000! 2026-07-10 14:14:09.147306 UTC - Destroying tst-53043054 tst 2026-07-10 14:14:09.147488 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-53043054 tst