Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.00s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 428ms 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.31s + 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.post1 + 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-26 13:04:23.863586 UTC - Installing Slices CLI (latest stable version). 2026-07-26 13:04:23.863823 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 13:04:29.815363 UTC - Slices CLI installed. 2026-07-26 13:04:29.815722 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-26 13:04:29.817942 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-26 13:04:29.819002 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 13:04:29.819131 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-26 13:04:29.819158 UTC - Slices CLI exe: 'slices' 2026-07-26 13:04:29.819175 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 13:04:29.819287 UTC - Run: slices --version 2026-07-26 13:04:33.731317 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-26 13:04:33.731606 UTC - Run: slices pubkey list --format text 2026-07-26 13:04:34.547408 UTC - Pubkey already registered 2026-07-26 13:04:34.547714 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 13:04:35.772263 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 13:04:35.774698 UTC - Check List Flavors 2026-07-26 13:04:35.775289 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-26 13:04:36.841372 UTC - Check List DiskImages 2026-07-26 13:04:36.841533 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-26 13:04:37.707147 UTC - Requesting resources 2026-07-26 13:04:37.707793 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 13:04:37.707924 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5b16de3e --user-data /tmp/tmpehcecsid 2026-07-26 13:04:40.487508 UTC - Resource ID: r_it-genoa1-bi-vm1_01kyf8e4waey9a8mgvrwyx7b7p 2026-07-26 13:04:40.487680 UTC - Waiting until resource ready 2026-07-26 13:04:42.488157 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5b16de3e tst 2026-07-26 13:04:43.304351 UTC - Status: IMAGING 2026-07-26 13:04:46.325159 UTC - Status: IMAGING 2026-07-26 13:04:49.244775 UTC - Status: BOOTING 2026-07-26 13:04:52.623851 UTC - Status: BOOTING 2026-07-26 13:04:55.545580 UTC - Status: BOOTING 2026-07-26 13:04:58.615619 UTC - Status: BOOTING 2026-07-26 13:05:01.582362 UTC - Status: BOOTING 2026-07-26 13:05:04.708115 UTC - Status: BOOTING 2026-07-26 13:05:07.856030 UTC - Status: BOOTING 2026-07-26 13:05:10.737343 UTC - Status: BOOTING 2026-07-26 13:05:13.711151 UTC - Status: BOOTING 2026-07-26 13:05:16.929709 UTC - Status: UP 2026-07-26 13:05:16.929800 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyf8e34hebst7my0kf4dqect 2026-07-26 13:05:16.929839 UTC - Validate resources 2026-07-26 13:05:17.847591 UTC - The fields of the created resource were validated. 2026-07-26 13:05:17.847726 UTC - Check if resources are registered in experiment 2026-07-26 13:05:17.847898 UTC - Run: slices experiment list-resources --format json tst-5b16de3e 2026-07-26 13:05:18.716613 UTC - Status (on expauth): UP 2026-07-26 13:05:18.716779 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 13:05:18.716913 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-5b16de3e 2026-07-26 13:05:20.665075 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5b16de3e tst 2026-07-26 13:05:22.141301 UTC - Run: slices experiment list-resources --format json tst-5b16de3e 2026-07-26 13:05:23.116951 UTC - expires_at (on expauth): 2026-07-26T16:05:00Z (correctly extended) 2026-07-26 13:05:23.117067 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 13:05:23.117219 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-5b16de3e 2026-07-26 13:05:24.693064 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-5b16de3e tst 2026-07-26 13:05:25.921807 UTC - Run: slices experiment list-resources --format json tst-5b16de3e 2026-07-26 13:05:26.587393 UTC - expires_at (on expauth): 2026-07-26T17:05:00Z (correctly extended) 2026-07-26 13:05:26.587514 UTC - Testing ssh login 2026-07-26 13:05:26.603845 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5b16de3e tst 2026-07-26 13:05:27.569844 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5b16de3e tst 2026-07-26 13:05:28.662047 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5b16de3e tst 2026-07-26 13:05:30.011126 UTC - Logging in using 'slices bi ssh' 2026-07-26 13:05:30.011214 UTC - Forcing IPv4 only. 2026-07-26 13:05:30.011340 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-5b16de3e tst -- -4 uname -a 2026-07-26 13:05:32.936867 UTC - Forcing IPv4 only. 2026-07-26 13:05:32.937638 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-5b16de3e tst -- -4 uptime 2026-07-26 13:05:34.967142 UTC - CLI SSH Test passed. 2026-07-26 13:05:34.967226 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-26 13:05:34.967246 UTC - Uptime: 13:05:34 up 0 min, 1 user, load average: 0.44, 0.12, 0.04 2026-07-26 13:05:34.967297 UTC - Logging in using SSH over SSH proxy 2026-07-26 13:05:34.967435 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5b16de3e tst 2026-07-26 13:05:36.385226 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 13:05:36.385344 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 13:05:36.385504 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 13:05:36.385716 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 13:05:36.385823 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 13:05:36.385909 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 13:05:36.385932 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-26 13:05:36.888389 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-26 13:05:37.775132 UTC - SSH Test output: 2026-07-26 13:05:37.775219 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-26 13:05:37.775242 UTC - Uptime: 13:05:37 up 0 min, 1 user, load average: 0.44, 0.12, 0.04 2026-07-26 13:05:37.967497 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 13:05:37.967576 UTC - lsb_release matches expected value 2026-07-26 13:05:37.967595 UTC - SSH Test passed. 2026-07-26 13:05:38.152696 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 13:05:15 +0000! 2026-07-26 13:05:38.159949 UTC - Destroying tst-5b16de3e tst 2026-07-26 13:05:38.160123 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-5b16de3e tst