Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.09s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 384ms 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 641ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.dev192+gcac2d3fe6 (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-08-04 04:12:33.749519 UTC - Installing Slices CLI (latest stable version). 2026-08-04 04:12:33.749908 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-04 04:12:39.125693 UTC - Slices CLI installed. 2026-08-04 04:12:39.126579 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-08-04 04:12:39.131880 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-04 04:12:39.131982 UTC - env: {'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-08-04 04:12:39.132064 UTC - which slices: /app/.venv/bin/slices 2026-08-04 04:12:39.132085 UTC - Slices CLI exe: 'slices' 2026-08-04 04:12:39.132101 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-04 04:12:39.132242 UTC - Run: slices --version 2026-08-04 04:12:42.373278 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-04 04:12:42.373764 UTC - Run: slices pubkey list --format text 2026-08-04 04:12:43.297769 UTC - Pubkey already registered 2026-08-04 04:12:43.298065 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-04 04:12:44.272530 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-04 04:12:44.272658 UTC - Check List Flavors 2026-08-04 04:12:44.272870 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-08-04 04:12:45.343113 UTC - Check List DiskImages 2026-08-04 04:12:45.343298 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-08-04 04:12:46.460864 UTC - Requesting resources 2026-08-04 04:12:46.461775 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-04 04:12:46.461899 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-d5989b62 --user-data /tmp/tmp4bvybntd 2026-08-04 04:12:48.287800 UTC - Resource ID: r_it-genoa1-bi-vm1_01kz5fjqjrev0876dxt9df7b83 2026-08-04 04:12:48.288154 UTC - Waiting until resource ready 2026-08-04 04:12:50.288607 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-d5989b62 tst 2026-08-04 04:12:51.359986 UTC - Status: IMAGING 2026-08-04 04:12:54.479798 UTC - Status: IMAGING 2026-08-04 04:12:57.752487 UTC - Status: BOOTING 2026-08-04 04:13:00.621724 UTC - Status: BOOTING 2026-08-04 04:13:03.541572 UTC - Status: BOOTING 2026-08-04 04:13:06.709721 UTC - Status: BOOTING 2026-08-04 04:13:09.788762 UTC - Status: BOOTING 2026-08-04 04:13:13.159636 UTC - Status: BOOTING 2026-08-04 04:13:16.076233 UTC - Status: BOOTING 2026-08-04 04:13:19.092992 UTC - Status: BOOTING 2026-08-04 04:13:22.042342 UTC - Status: BOOTING 2026-08-04 04:13:25.040163 UTC - Status: UP 2026-08-04 04:13:25.040395 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz5fjpv7e8ftx3xw7rzx99h9 2026-08-04 04:13:25.040470 UTC - Validate resources 2026-08-04 04:13:26.219675 UTC - The fields of the created resource were validated. 2026-08-04 04:13:26.219794 UTC - Check if resources are registered in experiment 2026-08-04 04:13:26.219966 UTC - Run: slices experiment list-resources --format json tst-d5989b62 2026-08-04 04:13:26.985872 UTC - Status (on expauth): UP 2026-08-04 04:13:26.986037 UTC - Testing extend expires_at (all resources in experiment) 2026-08-04 04:13:26.986178 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-d5989b62 2026-08-04 04:13:28.455741 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-d5989b62 tst 2026-08-04 04:13:29.372438 UTC - Run: slices experiment list-resources --format json tst-d5989b62 2026-08-04 04:13:30.339112 UTC - expires_at (on expauth): 2026-08-04T07:13:00Z (correctly extended) 2026-08-04 04:13:30.339435 UTC - Testing extend expires_at (single resource in experiment) 2026-08-04 04:13:30.339643 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-d5989b62 2026-08-04 04:13:31.809266 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-d5989b62 tst 2026-08-04 04:13:33.388738 UTC - Run: slices experiment list-resources --format json tst-d5989b62 2026-08-04 04:13:34.166479 UTC - expires_at (on expauth): 2026-08-04T08:13:00Z (correctly extended) 2026-08-04 04:13:34.171530 UTC - Testing ssh login 2026-08-04 04:13:34.211867 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d5989b62 tst 2026-08-04 04:13:35.479189 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d5989b62 tst 2026-08-04 04:13:36.547405 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-d5989b62 tst 2026-08-04 04:13:37.771003 UTC - Logging in using 'slices bi ssh' 2026-08-04 04:13:37.771074 UTC - Forcing IPv4 only. 2026-08-04 04:13:37.771206 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-d5989b62 tst -- -4 uname -a 2026-08-04 04:13:40.643446 UTC - Forcing IPv4 only. 2026-08-04 04:13:40.643676 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-d5989b62 tst -- -4 uptime 2026-08-04 04:13:42.762264 UTC - CLI SSH Test passed. 2026-08-04 04:13:42.762351 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-08-04 04:13:42.762369 UTC - Uptime: 04:13:42 up 0 min, 1 user, load average: 0.84, 0.22, 0.07 2026-08-04 04:13:42.762409 UTC - Logging in using SSH over SSH proxy 2026-08-04 04:13:42.762552 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-d5989b62 tst 2026-08-04 04:13:43.881313 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 04:13:43.881481 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 04:13:43.881714 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-04 04:13:43.881948 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 04:13:43.882066 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 04:13:43.882146 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-04 04:13:43.882167 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-08-04 04:13:44.380608 UTC - Connecting to 172.30.44.3:22 over proxy 2026-08-04 04:13:45.259186 UTC - SSH Test output: 2026-08-04 04:13:45.259271 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-08-04 04:13:45.259301 UTC - Uptime: 04:13:45 up 0 min, 1 user, load average: 0.84, 0.22, 0.07 2026-08-04 04:13:45.446322 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-04 04:13:45.446412 UTC - lsb_release matches expected value 2026-08-04 04:13:45.446440 UTC - SSH Test passed. 2026-08-04 04:13:45.632752 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 04:13:24 +0000! 2026-08-04 04:13:45.642922 UTC - Destroying tst-d5989b62 tst 2026-08-04 04:13:45.643132 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-d5989b62 tst