Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 1.22s 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 1.82s + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-13 13:03:37.498466 UTC - Installing Slices CLI (latest stable version). 2026-07-13 13:03:37.499274 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 13:03:45.253204 UTC - Slices CLI installed. 2026-07-13 13:03:45.253430 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-13 13:03:45.254775 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-13 13:03:45.255787 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 13:03:45.255934 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-13 13:03:45.255964 UTC - Slices CLI exe: 'slices' 2026-07-13 13:03:45.255982 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 13:03:45.256113 UTC - Run: slices --version 2026-07-13 13:03:56.537401 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-13 13:03:56.540494 UTC - Run: slices pubkey list --format text 2026-07-13 13:03:58.435394 UTC - Pubkey already registered 2026-07-13 13:03:58.435624 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 13:04:00.178954 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 13:04:00.179062 UTC - Check List Flavors 2026-07-13 13:04:00.179246 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-13 13:04:02.933493 UTC - Check List DiskImages 2026-07-13 13:04:02.933691 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-13 13:04:03.951822 UTC - Requesting resources 2026-07-13 13:04:03.953105 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 13:04:03.953299 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-cf3de42e --user-data /tmp/tmp6jqmvklw 2026-07-13 13:04:10.851514 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxds7we4ethv9eykqamhrc8t 2026-07-13 13:04:10.851690 UTC - Waiting until resource ready 2026-07-13 13:04:12.852068 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-cf3de42e tst 2026-07-13 13:04:14.811335 UTC - Status: IMAGING 2026-07-13 13:04:19.108772 UTC - Status: BOOTING 2026-07-13 13:04:22.869294 UTC - Status: BOOTING 2026-07-13 13:04:26.245026 UTC - Status: BOOTING 2026-07-13 13:04:30.837263 UTC - Status: BOOTING 2026-07-13 13:04:34.221519 UTC - Status: BOOTING 2026-07-13 13:04:37.245304 UTC - Status: BOOTING 2026-07-13 13:04:40.262799 UTC - Status: BOOTING 2026-07-13 13:04:43.330122 UTC - Status: BOOTING 2026-07-13 13:04:46.247018 UTC - Status: BOOTING 2026-07-13 13:04:49.214998 UTC - Status: UP 2026-07-13 13:04:49.215070 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxds7se2fcmvw8ypn6w595qw 2026-07-13 13:04:49.215141 UTC - Validate resources 2026-07-13 13:04:50.450010 UTC - The fields of the created resource were validated. 2026-07-13 13:04:50.450101 UTC - Check if resources are registered in experiment 2026-07-13 13:04:50.450280 UTC - Run: slices experiment list-resources --format json tst-cf3de42e 2026-07-13 13:04:51.374727 UTC - Status (on expauth): UP 2026-07-13 13:04:51.374908 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 13:04:51.375051 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-cf3de42e 2026-07-13 13:04:54.398251 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-cf3de42e tst 2026-07-13 13:04:55.316147 UTC - Run: slices experiment list-resources --format json tst-cf3de42e 2026-07-13 13:04:56.241188 UTC - expires_at (on expauth): 2026-07-13T16:04:00Z (correctly extended) 2026-07-13 13:04:56.241293 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 13:04:56.241437 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-cf3de42e 2026-07-13 13:04:59.373665 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-cf3de42e tst 2026-07-13 13:05:00.290994 UTC - Run: slices experiment list-resources --format json tst-cf3de42e 2026-07-13 13:05:01.057839 UTC - expires_at (on expauth): 2026-07-13T17:04:00Z (correctly extended) 2026-07-13 13:05:01.057965 UTC - Testing ssh login 2026-07-13 13:05:01.074462 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cf3de42e tst 2026-07-13 13:05:02.141500 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cf3de42e tst 2026-07-13 13:05:03.207950 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-cf3de42e tst 2026-07-13 13:05:04.224772 UTC - Logging in using 'slices bi ssh' 2026-07-13 13:05:04.224867 UTC - Forcing IPv4 only. 2026-07-13 13:05:04.225039 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-cf3de42e tst -- -4 uname -a 2026-07-13 13:05:06.947186 UTC - Forcing IPv4 only. 2026-07-13 13:05:06.947384 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-cf3de42e tst -- -4 uptime 2026-07-13 13:05:08.966663 UTC - CLI SSH Test passed. 2026-07-13 13:05:08.966736 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-13 13:05:08.966756 UTC - Uptime: 13:05:08 up 0 min, 1 user, load average: 0.19, 0.06, 0.02 2026-07-13 13:05:08.966790 UTC - Logging in using SSH over SSH proxy 2026-07-13 13:05:08.966932 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-cf3de42e tst 2026-07-13 13:05:10.034363 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-13 13:05:10.034485 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-13 13:05:10.034635 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-13 13:05:10.034870 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-13 13:05:10.034994 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-13 13:05:10.035073 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-13 13:05:10.035097 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-13 13:05:10.532799 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-13 13:05:11.405358 UTC - SSH Test output: 2026-07-13 13:05:11.405436 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-13 13:05:11.405458 UTC - Uptime: 13:05:11 up 0 min, 1 user, load average: 0.19, 0.06, 0.02 2026-07-13 13:05:11.592985 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-13 13:05:11.593067 UTC - lsb_release matches expected value 2026-07-13 13:05:11.593087 UTC - SSH Test passed. 2026-07-13 13:05:11.779151 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 13:04:46 +0000! 2026-07-13 13:05:11.787979 UTC - Destroying tst-cf3de42e tst 2026-07-13 13:05:11.788208 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-cf3de42e tst