Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.80s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 383ms 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 998ms + 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.3 + 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-07 20:04:55.264759 UTC - Installing Slices CLI (latest stable version). 2026-08-07 20:04:55.268368 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-07 20:05:02.061947 UTC - Slices CLI installed. 2026-08-07 20:05:02.062243 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-07 20:05:02.065797 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-07 20:05:02.065876 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-07 20:05:02.065945 UTC - which slices: /app/.venv/bin/slices 2026-08-07 20:05:02.065964 UTC - Slices CLI exe: 'slices' 2026-08-07 20:05:02.065982 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-07 20:05:02.066144 UTC - Run: slices --version 2026-08-07 20:05:07.233248 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-07 20:05:07.234255 UTC - Run: slices pubkey list --format text 2026-08-07 20:05:08.511670 UTC - Pubkey already registered 2026-08-07 20:05:08.512006 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-07 20:05:09.178061 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-07 20:05:09.178155 UTC - Check List Flavors 2026-08-07 20:05:09.178307 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-07 20:05:10.953207 UTC - Check List DiskImages 2026-08-07 20:05:10.953870 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-07 20:05:11.771803 UTC - Requesting resources 2026-08-07 20:05:11.772261 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-07 20:05:11.772369 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-db160dd7 --user-data /tmp/tmpgp8swbve 2026-08-07 20:05:13.699516 UTC - Resource ID: r_ch-geneva-bi-vm1_01kzex8tm0e1rrwfq3hqc0zdbh 2026-08-07 20:05:13.699668 UTC - Waiting until resource ready 2026-08-07 20:05:15.699975 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-db160dd7 tst 2026-08-07 20:05:16.935256 UTC - Status: IMAGING 2026-08-07 20:05:19.903023 UTC - Status: IMAGING 2026-08-07 20:05:22.820006 UTC - Status: IMAGING 2026-08-07 20:05:25.786989 UTC - Status: IMAGING 2026-08-07 20:05:28.754122 UTC - Status: IMAGING 2026-08-07 20:05:32.084209 UTC - Status: IMAGING 2026-08-07 20:05:35.108687 UTC - Status: IMAGING 2026-08-07 20:05:37.999807 UTC - Status: IMAGING 2026-08-07 20:05:40.815957 UTC - Status: IMAGING 2026-08-07 20:05:43.682297 UTC - Status: IMAGING 2026-08-07 20:05:46.650616 UTC - Status: IMAGING 2026-08-07 20:05:49.630653 UTC - Status: IMAGING 2026-08-07 20:05:52.553408 UTC - Status: IMAGING 2026-08-07 20:05:55.421166 UTC - Status: IMAGING 2026-08-07 20:05:58.388815 UTC - Status: IMAGING 2026-08-07 20:06:01.558410 UTC - Status: IMAGING 2026-08-07 20:06:04.626222 UTC - Status: IMAGING 2026-08-07 20:06:08.156054 UTC - Status: IMAGING 2026-08-07 20:06:11.174965 UTC - Status: IMAGING 2026-08-07 20:06:14.722450 UTC - Status: IMAGING 2026-08-07 20:06:17.843361 UTC - Status: IMAGING 2026-08-07 20:06:20.714235 UTC - Status: IMAGING 2026-08-07 20:06:23.630974 UTC - Status: IMAGING 2026-08-07 20:06:26.597838 UTC - Status: IMAGING 2026-08-07 20:06:29.716512 UTC - Status: IMAGING 2026-08-07 20:06:33.084847 UTC - Status: IMAGING 2026-08-07 20:06:36.176739 UTC - Status: IMAGING 2026-08-07 20:06:39.003668 UTC - Status: IMAGING 2026-08-07 20:06:41.870385 UTC - Status: IMAGING 2026-08-07 20:06:44.686596 UTC - Status: IMAGING 2026-08-07 20:06:47.610591 UTC - Status: IMAGING 2026-08-07 20:06:50.587717 UTC - Status: IMAGING 2026-08-07 20:06:53.665938 UTC - Status: IMAGING 2026-08-07 20:06:57.034477 UTC - Status: IMAGING 2026-08-07 20:07:00.104276 UTC - Status: IMAGING 2026-08-07 20:07:02.970793 UTC - Status: IMAGING 2026-08-07 20:07:05.893393 UTC - Status: IMAGING 2026-08-07 20:07:08.883409 UTC - Status: IMAGING 2026-08-07 20:07:12.014912 UTC - Status: UP 2026-08-07 20:07:12.015008 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzex8t48f978jj2n4hjhntad 2026-08-07 20:07:12.015067 UTC - Validate resources 2026-08-07 20:07:13.395105 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-08-07 20:07:13.395208 UTC - The fields of the created resource were validated. 2026-08-07 20:07:13.395267 UTC - Check if resources are registered in experiment 2026-08-07 20:07:13.395456 UTC - Run: slices experiment list-resources --format json tst-db160dd7 2026-08-07 20:07:15.077682 UTC - Status (on expauth): UP 2026-08-07 20:07:15.077843 UTC - Testing extend expires_at (all resources in experiment) 2026-08-07 20:07:15.077981 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-db160dd7 2026-08-07 20:07:16.809788 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-db160dd7 tst 2026-08-07 20:07:17.949476 UTC - Run: slices experiment list-resources --format json tst-db160dd7 2026-08-07 20:07:18.787953 UTC - expires_at (on expauth): 2026-08-07T23:07:00Z (correctly extended) 2026-08-07 20:07:18.788047 UTC - Testing extend expires_at (single resource in experiment) 2026-08-07 20:07:18.788202 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-db160dd7 2026-08-07 20:07:20.726151 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-db160dd7 tst 2026-08-07 20:07:21.958704 UTC - Run: slices experiment list-resources --format json tst-db160dd7 2026-08-07 20:07:22.942449 UTC - expires_at (on expauth): 2026-08-08T00:07:00Z (correctly extended) 2026-08-07 20:07:22.942737 UTC - Testing ssh login 2026-08-07 20:07:22.963314 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-db160dd7 tst 2026-08-07 20:07:24.132536 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-db160dd7 tst 2026-08-07 20:07:25.100106 UTC - Logging in using 'slices bi ssh' 2026-08-07 20:07:25.100231 UTC - Forcing IPv4 only. 2026-08-07 20:07:25.100367 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-db160dd7 tst -- -4 uname -a 2026-08-07 20:07:27.927045 UTC - Forcing IPv4 only. 2026-08-07 20:07:27.927348 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-db160dd7 tst -- -4 uptime 2026-08-07 20:07:29.254800 UTC - CLI SSH Test passed. 2026-08-07 20:07:29.254885 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-08-07 20:07:29.254908 UTC - Uptime: 20:07:29 up 1 min, 1 user, load average: 0.10, 0.04, 0.01 2026-08-07 20:07:29.254944 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-07 20:07:29.255459 UTC - Added paramiko HostKeyEntry for 5.53.108.69 2026-08-07 20:07:29.255580 UTC - Added paramiko HostKeyEntry for 5.53.108.69 2026-08-07 20:07:29.255753 UTC - Added paramiko HostKeyEntry for 5.53.108.69 2026-08-07 20:07:29.255985 UTC - Connecting to 5.53.108.69:22 2026-08-07 20:07:29.805524 UTC - SSH Test output: 2026-08-07 20:07:29.805640 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-08-07 20:07:29.805668 UTC - Uptime: 20:07:29 up 1 min, 1 user, load average: 0.10, 0.04, 0.01 2026-08-07 20:07:29.903262 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 20:07:29.903348 UTC - lsb_release matches expected value 2026-08-07 20:07:29.903367 UTC - SSH Test passed. 2026-08-07 20:07:29.988201 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 20:06:49 +0000! 2026-08-07 20:07:29.988399 UTC - Skip SSH proxy test because no jump proxy 2026-08-07 20:07:29.997225 UTC - Destroying tst-db160dd7 tst 2026-08-07 20:07:29.997448 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-db160dd7 tst