Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 214ms 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 868ms + 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.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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-25 04:00:55.297768 UTC - Installing Slices CLI (latest stable version). 2026-07-25 04:00:55.298134 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 04:01:01.491340 UTC - Slices CLI installed. 2026-07-25 04:01:01.491625 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-25 04:01:01.494283 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-25 04:01:01.495336 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 04:01:01.495489 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-25 04:01:01.495519 UTC - Slices CLI exe: 'slices' 2026-07-25 04:01:01.495540 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 04:01:01.495691 UTC - Run: slices --version 2026-07-25 04:01:04.520165 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-25 04:01:04.520486 UTC - Run: slices pubkey list --format text 2026-07-25 04:01:05.586669 UTC - Pubkey already registered 2026-07-25 04:01:05.586924 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 04:01:06.253358 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 04:01:06.253454 UTC - Check List Flavors 2026-07-25 04:01:06.253613 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-25 04:01:07.069224 UTC - Check List DiskImages 2026-07-25 04:01:07.069425 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-25 04:01:07.735699 UTC - Requesting resources 2026-07-25 04:01:07.736441 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 04:01:07.736580 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-8a40c9e0 --user-data /tmp/tmp0qu3ua8o 2026-07-25 04:01:09.153246 UTC - Resource ID: r_ch-geneva-bi-vm1_01kybpy6vtekr8ywr926v7prc9 2026-07-25 04:01:09.153396 UTC - Waiting until resource ready 2026-07-25 04:01:11.153741 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-8a40c9e0 tst 2026-07-25 04:01:11.972522 UTC - Status: IMAGING 2026-07-25 04:01:14.899294 UTC - Status: IMAGING 2026-07-25 04:01:17.981982 UTC - Status: IMAGING 2026-07-25 04:01:21.111240 UTC - Status: IMAGING 2026-07-25 04:01:24.129971 UTC - Status: IMAGING 2026-07-25 04:01:26.895794 UTC - Status: IMAGING 2026-07-25 04:01:29.712133 UTC - Status: IMAGING 2026-07-25 04:01:32.585583 UTC - Status: IMAGING 2026-07-25 04:01:35.403530 UTC - Status: IMAGING 2026-07-25 04:01:38.319983 UTC - Status: IMAGING 2026-07-25 04:01:41.135934 UTC - Status: IMAGING 2026-07-25 04:01:43.902134 UTC - Status: IMAGING 2026-07-25 04:01:46.668055 UTC - Status: IMAGING 2026-07-25 04:01:49.433986 UTC - Status: IMAGING 2026-07-25 04:01:52.250141 UTC - Status: IMAGING 2026-07-25 04:01:55.015954 UTC - Status: IMAGING 2026-07-25 04:01:57.785216 UTC - Status: IMAGING 2026-07-25 04:02:00.601285 UTC - Status: IMAGING 2026-07-25 04:02:03.417369 UTC - Status: IMAGING 2026-07-25 04:02:06.292508 UTC - Status: IMAGING 2026-07-25 04:02:09.177253 UTC - Status: IMAGING 2026-07-25 04:02:12.093207 UTC - Status: IMAGING 2026-07-25 04:02:15.195941 UTC - Status: IMAGING 2026-07-25 04:02:18.063172 UTC - Status: IMAGING 2026-07-25 04:02:20.879429 UTC - Status: IMAGING 2026-07-25 04:02:23.645383 UTC - Status: IMAGING 2026-07-25 04:02:26.568304 UTC - Status: IMAGING 2026-07-25 04:02:29.534679 UTC - Status: IMAGING 2026-07-25 04:02:32.452158 UTC - Status: IMAGING 2026-07-25 04:02:35.218350 UTC - Status: IMAGING 2026-07-25 04:02:37.985835 UTC - Status: IMAGING 2026-07-25 04:02:40.856817 UTC - Status: IMAGING 2026-07-25 04:02:43.622743 UTC - Status: IMAGING 2026-07-25 04:02:46.544814 UTC - Status: IMAGING 2026-07-25 04:02:49.625054 UTC - Status: IMAGING 2026-07-25 04:02:52.445943 UTC - Status: IMAGING 2026-07-25 04:02:55.833438 UTC - Status: IMAGING 2026-07-25 04:02:58.651293 UTC - Status: IMAGING 2026-07-25 04:03:01.848337 UTC - Status: UP 2026-07-25 04:03:01.849071 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kybpy6cff06t1zfcj4y9hj70 2026-07-25 04:03:01.849151 UTC - Validate resources 2026-07-25 04:03:02.724037 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-25 04:03:02.724118 UTC - The fields of the created resource were validated. 2026-07-25 04:03:02.724163 UTC - Check if resources are registered in experiment 2026-07-25 04:03:02.724326 UTC - Run: slices experiment list-resources --format json tst-8a40c9e0 2026-07-25 04:03:03.704247 UTC - Status (on expauth): UP 2026-07-25 04:03:03.704383 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 04:03:03.704501 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-8a40c9e0 2026-07-25 04:03:05.889197 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-8a40c9e0 tst 2026-07-25 04:03:07.683254 UTC - Run: slices experiment list-resources --format json tst-8a40c9e0 2026-07-25 04:03:08.921677 UTC - expires_at (on expauth): 2026-07-25T07:03:00Z (correctly extended) 2026-07-25 04:03:08.921790 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 04:03:08.921924 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-8a40c9e0 2026-07-25 04:03:11.578315 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-8a40c9e0 tst 2026-07-25 04:03:12.817094 UTC - Run: slices experiment list-resources --format json tst-8a40c9e0 2026-07-25 04:03:14.163221 UTC - expires_at (on expauth): 2026-07-25T08:03:00Z (correctly extended) 2026-07-25 04:03:14.163348 UTC - Testing ssh login 2026-07-25 04:03:14.189641 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8a40c9e0 tst 2026-07-25 04:03:15.522441 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8a40c9e0 tst 2026-07-25 04:03:17.451250 UTC - Logging in using 'slices bi ssh' 2026-07-25 04:03:17.451505 UTC - Forcing IPv4 only. 2026-07-25 04:03:17.451656 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-8a40c9e0 tst -- -4 uname -a 2026-07-25 04:03:21.637654 UTC - Forcing IPv4 only. 2026-07-25 04:03:21.637852 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-8a40c9e0 tst -- -4 uptime 2026-07-25 04:03:23.917728 UTC - CLI SSH Test passed. 2026-07-25 04:03:23.917801 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-25 04:03:23.917820 UTC - Uptime: 04:03:23 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-25 04:03:23.917854 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-25 04:03:23.918606 UTC - Added paramiko HostKeyEntry for 5.53.108.165 2026-07-25 04:03:23.918719 UTC - Added paramiko HostKeyEntry for 5.53.108.165 2026-07-25 04:03:23.918878 UTC - Added paramiko HostKeyEntry for 5.53.108.165 2026-07-25 04:03:23.919084 UTC - Connecting to 5.53.108.165:22 2026-07-25 04:03:24.467020 UTC - SSH Test output: 2026-07-25 04:03:24.467115 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-25 04:03:24.467141 UTC - Uptime: 04:03:24 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-25 04:03:24.563925 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 04:03:24.564000 UTC - lsb_release matches expected value 2026-07-25 04:03:24.564035 UTC - SSH Test passed. 2026-07-25 04:03:24.649051 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 04:02:43 +0000! 2026-07-25 04:03:24.649183 UTC - Skip SSH proxy test because no jump proxy 2026-07-25 04:03:24.664451 UTC - Destroying tst-8a40c9e0 tst 2026-07-25 04:03:24.664623 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-8a40c9e0 tst