Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.57s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 216ms 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 939ms + 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.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-07-30 20:05:12.609208 UTC - Installing Slices CLI (latest stable version). 2026-07-30 20:05:12.609462 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 20:05:19.959580 UTC - Slices CLI installed. 2026-07-30 20:05:19.961212 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-30 20:05:19.963633 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-07-30 20:05:19.963726 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-07-30 20:05:19.963800 UTC - which slices: /app/.venv/bin/slices 2026-07-30 20:05:19.963819 UTC - Slices CLI exe: 'slices' 2026-07-30 20:05:19.963835 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 20:05:19.963969 UTC - Run: slices --version 2026-07-30 20:05:23.834072 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-07-30 20:05:23.834352 UTC - Run: slices pubkey list --format text 2026-07-30 20:05:24.700985 UTC - Pubkey already registered 2026-07-30 20:05:24.701262 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 20:05:25.417239 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 20:05:25.417326 UTC - Check List Flavors 2026-07-30 20:05:25.417462 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-30 20:05:27.643162 UTC - Check List DiskImages 2026-07-30 20:05:27.643489 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-30 20:05:28.360082 UTC - Requesting resources 2026-07-30 20:05:28.360577 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 20:05:28.360688 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-061f56a3 --user-data /tmp/tmpyeeh2bem 2026-07-30 20:05:29.728019 UTC - Resource ID: r_ch-geneva-bi-vm1_01kyta3ja7fs9bzt945hkz116y 2026-07-30 20:05:29.728172 UTC - Waiting until resource ready 2026-07-30 20:05:31.731698 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-061f56a3 tst 2026-07-30 20:05:32.608235 UTC - Status: IMAGING 2026-07-30 20:05:35.832090 UTC - Status: IMAGING 2026-07-30 20:05:39.202274 UTC - Status: IMAGING 2026-07-30 20:05:42.537578 UTC - Status: IMAGING 2026-07-30 20:05:45.504899 UTC - Status: IMAGING 2026-07-30 20:05:48.271060 UTC - Status: IMAGING 2026-07-30 20:05:51.413208 UTC - Status: IMAGING 2026-07-30 20:05:54.585652 UTC - Status: IMAGING 2026-07-30 20:05:57.502267 UTC - Status: IMAGING 2026-07-30 20:06:00.637759 UTC - Status: IMAGING 2026-07-30 20:06:03.622317 UTC - Status: IMAGING 2026-07-30 20:06:06.388219 UTC - Status: IMAGING 2026-07-30 20:06:09.209304 UTC - Status: IMAGING 2026-07-30 20:06:12.250243 UTC - Status: IMAGING 2026-07-30 20:06:15.270122 UTC - Status: IMAGING 2026-07-30 20:06:18.725413 UTC - Status: IMAGING 2026-07-30 20:06:22.003466 UTC - Status: IMAGING 2026-07-30 20:06:25.598695 UTC - Status: IMAGING 2026-07-30 20:06:29.227952 UTC - Status: IMAGING 2026-07-30 20:06:32.734275 UTC - Status: IMAGING 2026-07-30 20:06:35.758633 UTC - Status: IMAGING 2026-07-30 20:06:38.834138 UTC - Status: IMAGING 2026-07-30 20:06:41.750772 UTC - Status: IMAGING 2026-07-30 20:06:44.792606 UTC - Status: IMAGING 2026-07-30 20:06:47.915526 UTC - Status: IMAGING 2026-07-30 20:06:51.487602 UTC - Status: IMAGING 2026-07-30 20:06:54.658358 UTC - Status: IMAGING 2026-07-30 20:06:57.845473 UTC - Status: IMAGING 2026-07-30 20:07:00.614384 UTC - Status: IMAGING 2026-07-30 20:07:03.481383 UTC - Status: IMAGING 2026-07-30 20:07:06.398514 UTC - Status: IMAGING 2026-07-30 20:07:09.321686 UTC - Status: IMAGING 2026-07-30 20:07:12.189521 UTC - Status: IMAGING 2026-07-30 20:07:15.161140 UTC - Status: UP 2026-07-30 20:07:15.161223 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyta3htweas9ge2g726gmfdb 2026-07-30 20:07:15.161265 UTC - Validate resources 2026-07-30 20:07:16.130930 UTC - "slices bi list-resources" returned resource without private IPv4 address, but with a public IPv4 address. As test_allow_public_ipv4_instead_of_private is set, that is acceptable. 2026-07-30 20:07:16.131013 UTC - The fields of the created resource were validated. 2026-07-30 20:07:16.131068 UTC - Check if resources are registered in experiment 2026-07-30 20:07:16.131217 UTC - Run: slices experiment list-resources --format json tst-061f56a3 2026-07-30 20:07:17.049961 UTC - Status (on expauth): UP 2026-07-30 20:07:17.050110 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 20:07:17.050232 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-061f56a3 2026-07-30 20:07:18.323724 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-061f56a3 tst 2026-07-30 20:07:19.298626 UTC - Run: slices experiment list-resources --format json tst-061f56a3 2026-07-30 20:07:20.118155 UTC - expires_at (on expauth): 2026-07-30T23:07:00Z (correctly extended) 2026-07-30 20:07:20.118273 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 20:07:20.118456 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-061f56a3 2026-07-30 20:07:21.289400 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-061f56a3 tst 2026-07-30 20:07:22.178732 UTC - Run: slices experiment list-resources --format json tst-061f56a3 2026-07-30 20:07:22.844819 UTC - expires_at (on expauth): 2026-07-31T00:07:00Z (correctly extended) 2026-07-30 20:07:22.844940 UTC - Testing ssh login 2026-07-30 20:07:22.859828 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-061f56a3 tst 2026-07-30 20:07:23.725720 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-061f56a3 tst 2026-07-30 20:07:24.541512 UTC - Logging in using 'slices bi ssh' 2026-07-30 20:07:24.541603 UTC - Forcing IPv4 only. 2026-07-30 20:07:24.541760 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-061f56a3 tst -- -4 uname -a 2026-07-30 20:07:26.209536 UTC - Forcing IPv4 only. 2026-07-30 20:07:26.209777 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-061f56a3 tst -- -4 uptime 2026-07-30 20:07:27.426999 UTC - CLI SSH Test passed. 2026-07-30 20:07:27.427140 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-30 20:07:27.427175 UTC - Uptime: 20:07:27 up 1 min, 1 user, load average: 1.08, 0.33, 0.11 2026-07-30 20:07:27.427226 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-30 20:07:27.427849 UTC - Added paramiko HostKeyEntry for 5.53.108.222 2026-07-30 20:07:27.428006 UTC - Added paramiko HostKeyEntry for 5.53.108.222 2026-07-30 20:07:27.428243 UTC - Added paramiko HostKeyEntry for 5.53.108.222 2026-07-30 20:07:27.428504 UTC - Connecting to 5.53.108.222:22 2026-07-30 20:07:27.985260 UTC - SSH Test output: 2026-07-30 20:07:27.985333 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-30 20:07:27.985353 UTC - Uptime: 20:07:27 up 1 min, 1 user, load average: 1.08, 0.33, 0.11 2026-07-30 20:07:28.082375 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 20:07:28.082450 UTC - lsb_release matches expected value 2026-07-30 20:07:28.082471 UTC - SSH Test passed. 2026-07-30 20:07:28.167031 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 20:06:58 +0000! 2026-07-30 20:07:28.167193 UTC - Skip SSH proxy test because no jump proxy 2026-07-30 20:07:28.180315 UTC - Destroying tst-061f56a3 tst 2026-07-30 20:07:28.180504 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-061f56a3 tst