Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.92s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 313ms 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 694ms + 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 20:05:24.951482 UTC - Installing Slices CLI (latest stable version). 2026-07-26 20:05:24.951731 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 20:05:31.454559 UTC - Slices CLI installed. 2026-07-26 20:05:31.455734 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 20:05:31.458322 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 20:05:31.460179 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 20:05:31.464881 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 20:05:31.464958 UTC - Slices CLI exe: 'slices' 2026-07-26 20:05:31.464979 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 20:05:31.465134 UTC - Run: slices --version 2026-07-26 20:05:35.080714 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 20:05:35.081040 UTC - Run: slices pubkey list --format text 2026-07-26 20:05:35.951497 UTC - Pubkey already registered 2026-07-26 20:05:35.951823 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 20:05:37.285574 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 20:05:37.285865 UTC - Check List Flavors 2026-07-26 20:05:37.286030 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-26 20:05:38.878397 UTC - Check List DiskImages 2026-07-26 20:05:38.878623 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-26 20:05:39.802085 UTC - Requesting resources 2026-07-26 20:05:39.802613 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 20:05:39.802728 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ccd0a501 --user-data /tmp/tmph8xx4q9h 2026-07-26 20:05:42.075053 UTC - Resource ID: r_it-genoa1-bi-vm1_01kyg0h2c1fc1rtavc8gc3m4j3 2026-07-26 20:05:42.075198 UTC - Waiting until resource ready 2026-07-26 20:05:44.075502 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-ccd0a501 tst 2026-07-26 20:05:45.158722 UTC - Status: IMAGING 2026-07-26 20:05:48.242330 UTC - Status: IMAGING 2026-07-26 20:05:51.813571 UTC - Status: BOOTING 2026-07-26 20:05:55.441610 UTC - Status: BOOTING 2026-07-26 20:05:58.257581 UTC - Status: BOOTING 2026-07-26 20:06:01.224903 UTC - Status: BOOTING 2026-07-26 20:06:04.180536 UTC - Status: BOOTING 2026-07-26 20:06:07.499345 UTC - Status: BOOTING 2026-07-26 20:06:10.528061 UTC - Status: BOOTING 2026-07-26 20:06:13.344380 UTC - Status: BOOTING 2026-07-26 20:06:16.386360 UTC - Status: BOOTING 2026-07-26 20:06:19.302706 UTC - Status: UP 2026-07-26 20:06:19.303525 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyg0h14gedkvsd3tkrdfn0ck 2026-07-26 20:06:19.303598 UTC - Validate resources 2026-07-26 20:06:20.172198 UTC - The fields of the created resource were validated. 2026-07-26 20:06:20.172289 UTC - Check if resources are registered in experiment 2026-07-26 20:06:20.172441 UTC - Run: slices experiment list-resources --format json tst-ccd0a501 2026-07-26 20:06:21.313049 UTC - Status (on expauth): UP 2026-07-26 20:06:21.313228 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 20:06:21.313361 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-ccd0a501 2026-07-26 20:06:22.991121 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-ccd0a501 tst 2026-07-26 20:06:24.185719 UTC - Run: slices experiment list-resources --format json tst-ccd0a501 2026-07-26 20:06:25.270048 UTC - expires_at (on expauth): 2026-07-26T23:06:00Z (correctly extended) 2026-07-26 20:06:25.270176 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 20:06:25.270309 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-ccd0a501 2026-07-26 20:06:26.842200 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-ccd0a501 tst 2026-07-26 20:06:27.860922 UTC - Run: slices experiment list-resources --format json tst-ccd0a501 2026-07-26 20:06:28.676942 UTC - expires_at (on expauth): 2026-07-27T00:06:00Z (correctly extended) 2026-07-26 20:06:28.677050 UTC - Testing ssh login 2026-07-26 20:06:28.691794 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ccd0a501 tst 2026-07-26 20:06:29.708710 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ccd0a501 tst 2026-07-26 20:06:30.725711 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ccd0a501 tst 2026-07-26 20:06:31.750690 UTC - Logging in using 'slices bi ssh' 2026-07-26 20:06:31.750773 UTC - Forcing IPv4 only. 2026-07-26 20:06:31.750895 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-ccd0a501 tst -- -4 uname -a 2026-07-26 20:06:34.963999 UTC - Forcing IPv4 only. 2026-07-26 20:06:34.964214 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-ccd0a501 tst -- -4 uptime 2026-07-26 20:06:37.140804 UTC - CLI SSH Test passed. 2026-07-26 20:06:37.140886 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 20:06:37.140905 UTC - Uptime: 20:06:37 up 0 min, 1 user, load average: 0.46, 0.11, 0.04 2026-07-26 20:06:37.140938 UTC - Logging in using SSH over SSH proxy 2026-07-26 20:06:37.141470 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ccd0a501 tst 2026-07-26 20:06:38.262406 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 20:06:38.262547 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 20:06:38.262732 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-26 20:06:38.262947 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 20:06:38.263057 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 20:06:38.263146 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-26 20:06:38.263167 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-26 20:06:38.792961 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-26 20:06:39.738054 UTC - SSH Test output: 2026-07-26 20:06:39.738144 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 20:06:39.738165 UTC - Uptime: 20:06:39 up 0 min, 1 user, load average: 0.42, 0.11, 0.04 2026-07-26 20:06:39.941933 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 20:06:39.942031 UTC - lsb_release matches expected value 2026-07-26 20:06:39.942064 UTC - SSH Test passed. 2026-07-26 20:06:40.153283 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 20:06:18 +0000! 2026-07-26 20:06:40.169952 UTC - Destroying tst-ccd0a501 tst 2026-07-26 20:06:40.170149 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-ccd0a501 tst