Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.97s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 912ms 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 1.14s + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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-20 20:03:48.551566 UTC - Installing Slices CLI (latest stable version). 2026-07-20 20:03:48.552491 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 20:03:54.983809 UTC - Slices CLI installed. 2026-07-20 20:03:54.984166 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-20 20:03:54.990598 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-20 20:03:54.992041 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 20:03:54.992204 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-20 20:03:54.992237 UTC - Slices CLI exe: 'slices' 2026-07-20 20:03:54.992255 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 20:03:54.992397 UTC - Run: slices --version 2026-07-20 20:04:02.473974 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-20 20:04:02.474281 UTC - Run: slices pubkey list --format text 2026-07-20 20:04:03.963071 UTC - Pubkey already registered 2026-07-20 20:04:03.963328 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 20:04:05.098441 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 20:04:05.098556 UTC - Check List Flavors 2026-07-20 20:04:05.098709 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-20 20:04:07.283810 UTC - Check List DiskImages 2026-07-20 20:04:07.284002 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-20 20:04:08.151083 UTC - Requesting resources 2026-07-20 20:04:08.151705 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 20:04:08.151825 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-1c50f288 --user-data /tmp/tmpde5u6f85 2026-07-20 20:04:12.395134 UTC - Resource ID: r_ch-geneva-bi-vm1_01ky0j20j9eegbmqvr9aqa6nvj 2026-07-20 20:04:12.395343 UTC - Waiting until resource ready 2026-07-20 20:04:14.395737 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-1c50f288 tst 2026-07-20 20:04:16.418711 UTC - Status: IMAGING 2026-07-20 20:04:19.926300 UTC - Status: IMAGING 2026-07-20 20:04:23.514042 UTC - Status: IMAGING 2026-07-20 20:04:26.432241 UTC - Status: IMAGING 2026-07-20 20:04:29.299104 UTC - Status: IMAGING 2026-07-20 20:04:32.117843 UTC - Status: IMAGING 2026-07-20 20:04:35.773776 UTC - Status: IMAGING 2026-07-20 20:04:38.986482 UTC - Status: IMAGING 2026-07-20 20:04:41.953446 UTC - Status: IMAGING 2026-07-20 20:04:45.565382 UTC - Status: IMAGING 2026-07-20 20:04:49.062105 UTC - Status: IMAGING 2026-07-20 20:04:52.733113 UTC - Status: IMAGING 2026-07-20 20:04:56.679286 UTC - Status: IMAGING 2026-07-20 20:05:00.679666 UTC - Status: IMAGING 2026-07-20 20:05:04.099563 UTC - Status: IMAGING 2026-07-20 20:05:07.268264 UTC - Status: IMAGING 2026-07-20 20:05:10.089240 UTC - Status: IMAGING 2026-07-20 20:05:13.361283 UTC - Status: IMAGING 2026-07-20 20:05:16.177349 UTC - Status: IMAGING 2026-07-20 20:05:19.043624 UTC - Status: IMAGING 2026-07-20 20:05:21.860038 UTC - Status: IMAGING 2026-07-20 20:05:24.677260 UTC - Status: IMAGING 2026-07-20 20:05:27.501878 UTC - Status: IMAGING 2026-07-20 20:05:30.625495 UTC - Status: IMAGING 2026-07-20 20:05:34.156334 UTC - Status: IMAGING 2026-07-20 20:05:37.817115 UTC - Status: IMAGING 2026-07-20 20:05:40.835967 UTC - Status: IMAGING 2026-07-20 20:05:44.666124 UTC - Status: IMAGING 2026-07-20 20:05:47.840997 UTC - Status: IMAGING 2026-07-20 20:05:51.459843 UTC - Status: IMAGING 2026-07-20 20:05:54.275903 UTC - Status: IMAGING 2026-07-20 20:05:57.242841 UTC - Status: IMAGING 2026-07-20 20:06:00.422931 UTC - Status: IMAGING 2026-07-20 20:06:03.463180 UTC - Status: UP 2026-07-20 20:06:03.463273 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky0j1z71ez2ab0w28vamw5eg 2026-07-20 20:06:03.463323 UTC - Validate resources 2026-07-20 20:06:04.987522 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-20 20:06:04.987611 UTC - The fields of the created resource were validated. 2026-07-20 20:06:04.987648 UTC - Check if resources are registered in experiment 2026-07-20 20:06:04.987792 UTC - Run: slices experiment list-resources --format json tst-1c50f288 2026-07-20 20:06:05.922745 UTC - Status (on expauth): UP 2026-07-20 20:06:05.927305 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 20:06:05.927553 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-1c50f288 2026-07-20 20:06:09.028647 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-1c50f288 tst 2026-07-20 20:06:09.794490 UTC - Run: slices experiment list-resources --format json tst-1c50f288 2026-07-20 20:06:10.511310 UTC - expires_at (on expauth): 2026-07-20T23:06:00Z (correctly extended) 2026-07-20 20:06:10.511402 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 20:06:10.511544 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-1c50f288 2026-07-20 20:06:13.933143 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-1c50f288 tst 2026-07-20 20:06:15.362269 UTC - Run: slices experiment list-resources --format json tst-1c50f288 2026-07-20 20:06:16.394642 UTC - expires_at (on expauth): 2026-07-21T00:06:00Z (correctly extended) 2026-07-20 20:06:16.394826 UTC - Testing ssh login 2026-07-20 20:06:16.412083 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-1c50f288 tst 2026-07-20 20:06:17.831690 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-1c50f288 tst 2026-07-20 20:06:18.802395 UTC - Logging in using 'slices bi ssh' 2026-07-20 20:06:18.802480 UTC - Forcing IPv4 only. 2026-07-20 20:06:18.802605 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-1c50f288 tst -- -4 uname -a 2026-07-20 20:06:22.675496 UTC - Forcing IPv4 only. 2026-07-20 20:06:22.675689 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-1c50f288 tst -- -4 uptime 2026-07-20 20:06:24.385739 UTC - CLI SSH Test passed. 2026-07-20 20:06:24.386004 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-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-20 20:06:24.386064 UTC - Uptime: 20:06:24 up 1 min, 1 user, load average: 0.25, 0.08, 0.03 2026-07-20 20:06:24.386104 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-20 20:06:24.386957 UTC - Added paramiko HostKeyEntry for 5.53.108.158 2026-07-20 20:06:24.387078 UTC - Added paramiko HostKeyEntry for 5.53.108.158 2026-07-20 20:06:24.387234 UTC - Added paramiko HostKeyEntry for 5.53.108.158 2026-07-20 20:06:24.387430 UTC - Connecting to 5.53.108.158:22 2026-07-20 20:06:24.957719 UTC - SSH Test output: 2026-07-20 20:06:24.957795 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-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-20 20:06:24.957816 UTC - Uptime: 20:06:24 up 1 min, 1 user, load average: 0.25, 0.08, 0.03 2026-07-20 20:06:25.056346 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 20:06:25.056448 UTC - lsb_release matches expected value 2026-07-20 20:06:25.056479 UTC - SSH Test passed. 2026-07-20 20:06:25.140696 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 20:05:44 +0000! 2026-07-20 20:06:25.140932 UTC - Skip SSH proxy test because no jump proxy 2026-07-20 20:06:25.154768 UTC - Destroying tst-1c50f288 tst 2026-07-20 20:06:25.154967 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-1c50f288 tst