Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.69s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 435ms 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 657ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.3 + 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-25 13:03:43.805687 UTC - Installing Slices CLI (latest stable version). 2026-07-25 13:03:43.806065 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 13:03:50.547383 UTC - Slices CLI installed. 2026-07-25 13:03:50.547586 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-25 13:03:50.549591 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-25 13:03:50.550473 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 13:03:50.550627 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-25 13:03:50.550657 UTC - Slices CLI exe: 'slices' 2026-07-25 13:03:50.550673 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 13:03:50.550787 UTC - Run: slices --version 2026-07-25 13:03:55.561425 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-25 13:03:55.561712 UTC - Run: slices pubkey list --format text 2026-07-25 13:03:57.032872 UTC - Pubkey already registered 2026-07-25 13:03:57.033132 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 13:03:58.154911 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 13:03:58.155023 UTC - Check List Flavors 2026-07-25 13:03:58.155177 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-25 13:03:59.194302 UTC - Check List DiskImages 2026-07-25 13:03:59.194552 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-25 13:03:59.960435 UTC - Requesting resources 2026-07-25 13:03:59.961167 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 13:03:59.961292 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3abbd773 --user-data /tmp/tmpzybnmlyk 2026-07-25 13:04:02.334759 UTC - Resource ID: r_it-genoa1-bi-vm1_01kycp08g8fd8vr3k1ps6ccst5 2026-07-25 13:04:02.334917 UTC - Waiting until resource ready 2026-07-25 13:04:04.335248 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3abbd773 tst 2026-07-25 13:04:05.624805 UTC - Status: IMAGING 2026-07-25 13:04:09.102089 UTC - Status: IMAGING 2026-07-25 13:04:12.441349 UTC - Status: BOOTING 2026-07-25 13:04:15.728500 UTC - Status: BOOTING 2026-07-25 13:04:18.662104 UTC - Status: BOOTING 2026-07-25 13:04:22.171497 UTC - Status: BOOTING 2026-07-25 13:04:25.629609 UTC - Status: BOOTING 2026-07-25 13:04:29.951341 UTC - Status: BOOTING 2026-07-25 13:04:33.515826 UTC - Status: BOOTING 2026-07-25 13:04:36.751305 UTC - Status: BOOTING 2026-07-25 13:04:39.885558 UTC - Status: UP 2026-07-25 13:04:39.885642 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kycp0794f4wag8p8cs17w325 2026-07-25 13:04:39.885880 UTC - Validate resources 2026-07-25 13:04:40.803086 UTC - The fields of the created resource were validated. 2026-07-25 13:04:40.803181 UTC - Check if resources are registered in experiment 2026-07-25 13:04:40.803333 UTC - Run: slices experiment list-resources --format json tst-3abbd773 2026-07-25 13:04:41.468512 UTC - Status (on expauth): UP 2026-07-25 13:04:41.468649 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 13:04:41.468794 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-3abbd773 2026-07-25 13:04:42.935801 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3abbd773 tst 2026-07-25 13:04:43.752617 UTC - Run: slices experiment list-resources --format json tst-3abbd773 2026-07-25 13:04:44.419120 UTC - expires_at (on expauth): 2026-07-25T16:04:00Z (correctly extended) 2026-07-25 13:04:44.419215 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 13:04:44.419353 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-3abbd773 2026-07-25 13:04:45.937823 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3abbd773 tst 2026-07-25 13:04:47.070615 UTC - Run: slices experiment list-resources --format json tst-3abbd773 2026-07-25 13:04:47.844458 UTC - expires_at (on expauth): 2026-07-25T17:04:00Z (correctly extended) 2026-07-25 13:04:47.844910 UTC - Testing ssh login 2026-07-25 13:04:47.868108 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3abbd773 tst 2026-07-25 13:04:49.103905 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3abbd773 tst 2026-07-25 13:04:50.070902 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-3abbd773 tst 2026-07-25 13:04:51.093053 UTC - Logging in using 'slices bi ssh' 2026-07-25 13:04:51.093153 UTC - Forcing IPv4 only. 2026-07-25 13:04:51.093336 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-3abbd773 tst -- -4 uname -a 2026-07-25 13:04:54.261079 UTC - Forcing IPv4 only. 2026-07-25 13:04:54.261259 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-3abbd773 tst -- -4 uptime 2026-07-25 13:04:56.346945 UTC - CLI SSH Test passed. 2026-07-25 13:04:56.347029 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-25 13:04:56.347048 UTC - Uptime: 13:04:56 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-07-25 13:04:56.347083 UTC - Logging in using SSH over SSH proxy 2026-07-25 13:04:56.347226 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-3abbd773 tst 2026-07-25 13:04:57.314248 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 13:04:57.314371 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 13:04:57.314521 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 13:04:57.314736 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 13:04:57.314851 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 13:04:57.314922 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 13:04:57.314944 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-25 13:04:57.783828 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-25 13:04:58.577402 UTC - SSH Test output: 2026-07-25 13:04:58.577471 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-25 13:04:58.577491 UTC - Uptime: 13:04:58 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-07-25 13:04:58.743574 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 13:04:58.743662 UTC - lsb_release matches expected value 2026-07-25 13:04:58.743686 UTC - SSH Test passed. 2026-07-25 13:04:58.910585 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 13:04:36 +0000! 2026-07-25 13:04:58.918285 UTC - Destroying tst-3abbd773 tst 2026-07-25 13:04:58.918477 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-3abbd773 tst