Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.22s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 503ms 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 864ms + 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-09 13:03:21.140996 UTC - Installing Slices CLI (latest stable version). 2026-07-09 13:03:21.141282 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 13:03:27.015145 UTC - Slices CLI installed. 2026-07-09 13:03:27.015448 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-09 13:03:27.022357 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-09 13:03:27.024960 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-09 13:03:27.025130 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-09 13:03:27.025160 UTC - Slices CLI exe: 'slices' 2026-07-09 13:03:27.025178 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 13:03:27.025317 UTC - Run: slices --version 2026-07-09 13:03:32.922374 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-09 13:03:32.922649 UTC - Run: slices pubkey list --format text 2026-07-09 13:03:34.207585 UTC - Pubkey already registered 2026-07-09 13:03:34.207823 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 13:03:36.249665 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 13:03:36.249760 UTC - Check List Flavors 2026-07-09 13:03:36.249956 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-09 13:03:39.195188 UTC - Check List DiskImages 2026-07-09 13:03:39.195410 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-09 13:03:41.254383 UTC - Requesting resources 2026-07-09 13:03:41.254937 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 13:03:41.255060 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-009ecc1b --user-data /tmp/tmpgfwx0b1u 2026-07-09 13:03:45.795646 UTC - Resource ID: r_it-genoa1-bi-vm1_01kx3fm888e61r0p5xd16hg61b 2026-07-09 13:03:45.796196 UTC - Waiting until resource ready 2026-07-09 13:03:47.796519 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-009ecc1b tst 2026-07-09 13:03:48.932550 UTC - Status: IMAGING 2026-07-09 13:03:53.281110 UTC - Status: BOOTING 2026-07-09 13:03:56.501894 UTC - Status: BOOTING 2026-07-09 13:04:00.183142 UTC - Status: BOOTING 2026-07-09 13:04:03.375632 UTC - Status: BOOTING 2026-07-09 13:04:06.400523 UTC - Status: BOOTING 2026-07-09 13:04:09.217367 UTC - Status: BOOTING 2026-07-09 13:04:12.033349 UTC - Status: BOOTING 2026-07-09 13:04:14.849269 UTC - Status: BOOTING 2026-07-09 13:04:17.665624 UTC - Status: BOOTING 2026-07-09 13:04:20.481869 UTC - Status: BOOTING 2026-07-09 13:04:23.348954 UTC - Status: UP 2026-07-09 13:04:23.349037 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx3fm6r5e4ssc2x2ss3k7qey 2026-07-09 13:04:23.349088 UTC - Validate resources 2026-07-09 13:04:24.215417 UTC - The fields of the created resource were validated. 2026-07-09 13:04:24.215572 UTC - Check if resources are registered in experiment 2026-07-09 13:04:24.215769 UTC - Run: slices experiment list-resources --format json tst-009ecc1b 2026-07-09 13:04:24.881417 UTC - Status (on expauth): UP 2026-07-09 13:04:24.881563 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 13:04:24.881686 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-009ecc1b 2026-07-09 13:04:27.451372 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-009ecc1b tst 2026-07-09 13:04:28.267387 UTC - Run: slices experiment list-resources --format json tst-009ecc1b 2026-07-09 13:04:28.883772 UTC - expires_at (on expauth): 2026-07-09T16:04:00Z (correctly extended) 2026-07-09 13:04:28.883862 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 13:04:28.883999 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-009ecc1b 2026-07-09 13:04:31.252990 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-009ecc1b tst 2026-07-09 13:04:32.119838 UTC - Run: slices experiment list-resources --format json tst-009ecc1b 2026-07-09 13:04:32.835600 UTC - expires_at (on expauth): 2026-07-09T17:04:00Z (correctly extended) 2026-07-09 13:04:32.835740 UTC - Testing ssh login 2026-07-09 13:04:32.849704 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-009ecc1b tst 2026-07-09 13:04:33.866080 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-009ecc1b tst 2026-07-09 13:04:34.784293 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-009ecc1b tst 2026-07-09 13:04:35.700142 UTC - Logging in using 'slices bi ssh' 2026-07-09 13:04:35.700242 UTC - Forcing IPv4 only. 2026-07-09 13:04:35.700391 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-009ecc1b tst -- -4 uname -a 2026-07-09 13:04:38.420187 UTC - Forcing IPv4 only. 2026-07-09 13:04:38.420383 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-009ecc1b tst -- -4 uptime 2026-07-09 13:04:40.338718 UTC - CLI SSH Test passed. 2026-07-09 13:04:40.338788 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-09 13:04:40.338809 UTC - Uptime: 13:04:40 up 0 min, 1 user, load average: 0.36, 0.09, 0.03 2026-07-09 13:04:40.338842 UTC - Logging in using SSH over SSH proxy 2026-07-09 13:04:40.338978 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-009ecc1b tst 2026-07-09 13:04:41.255346 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-09 13:04:41.255477 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-09 13:04:41.255643 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-09 13:04:41.255858 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-09 13:04:41.255974 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-09 13:04:41.256049 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-09 13:04:41.256070 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-09 13:04:41.756492 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-09 13:04:42.629478 UTC - SSH Test output: 2026-07-09 13:04:42.629553 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-09 13:04:42.629574 UTC - Uptime: 13:04:42 up 0 min, 1 user, load average: 0.36, 0.09, 0.03 2026-07-09 13:04:42.816619 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-09 13:04:42.816738 UTC - lsb_release matches expected value 2026-07-09 13:04:42.816773 UTC - SSH Test passed. 2026-07-09 13:04:43.001944 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 13:04:20 +0000! 2026-07-09 13:04:43.009526 UTC - Destroying tst-009ecc1b tst 2026-07-09 13:04:43.009708 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-009ecc1b tst