Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.72s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 263ms 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 802ms + 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.3 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-15 20:07:38.383330 UTC - Installing Slices CLI (latest stable version). 2026-07-15 20:07:38.383631 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 20:08:00.244857 UTC - Slices CLI installed. 2026-07-15 20:08:00.248922 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-15 20:08:00.251945 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-15 20:08:00.253372 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 20:08:00.253511 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-15 20:08:00.253540 UTC - Slices CLI exe: 'slices' 2026-07-15 20:08:00.253557 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 20:08:00.253832 UTC - Run: slices --version 2026-07-15 20:08:03.590500 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-15 20:08:03.590782 UTC - Run: slices pubkey list --format text 2026-07-15 20:08:05.439189 UTC - Pubkey already registered 2026-07-15 20:08:05.439434 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 20:08:07.815128 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 20:08:07.815237 UTC - Check List Flavors 2026-07-15 20:08:07.815384 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-15 20:08:10.599217 UTC - Check List DiskImages 2026-07-15 20:08:10.599417 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-15 20:08:12.301301 UTC - Requesting resources 2026-07-15 20:08:12.301801 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-342132e3 2026-07-15 20:08:15.774418 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxkp9vfqewr8zdadyyp22v2b 2026-07-15 20:08:15.774576 UTC - Waiting until resource ready 2026-07-15 20:08:17.774975 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-342132e3 tst 2026-07-15 20:08:18.540772 UTC - Status: IMAGING 2026-07-15 20:08:21.307451 UTC - Status: IMAGING 2026-07-15 20:08:24.073428 UTC - Status: IMAGING 2026-07-15 20:08:26.839651 UTC - Status: IMAGING 2026-07-15 20:08:29.605390 UTC - Status: IMAGING 2026-07-15 20:08:32.321196 UTC - Status: IMAGING 2026-07-15 20:08:35.037082 UTC - Status: IMAGING 2026-07-15 20:08:37.752844 UTC - Status: IMAGING 2026-07-15 20:08:40.518658 UTC - Status: IMAGING 2026-07-15 20:08:43.284518 UTC - Status: IMAGING 2026-07-15 20:08:46.100378 UTC - Status: IMAGING 2026-07-15 20:08:49.223453 UTC - Status: IMAGING 2026-07-15 20:08:53.498178 UTC - Status: IMAGING 2026-07-15 20:08:56.837793 UTC - Status: IMAGING 2026-07-15 20:08:59.923549 UTC - Status: IMAGING 2026-07-15 20:09:02.845953 UTC - Status: IMAGING 2026-07-15 20:09:05.768600 UTC - Status: IMAGING 2026-07-15 20:09:09.115322 UTC - Status: IMAGING 2026-07-15 20:09:12.589667 UTC - Status: IMAGING 2026-07-15 20:09:16.105286 UTC - Status: IMAGING 2026-07-15 20:09:19.126084 UTC - Status: IMAGING 2026-07-15 20:09:21.943322 UTC - Status: IMAGING 2026-07-15 20:09:25.245113 UTC - Status: IMAGING 2026-07-15 20:09:28.115171 UTC - Status: IMAGING 2026-07-15 20:09:30.937084 UTC - Status: IMAGING 2026-07-15 20:09:34.425398 UTC - Status: IMAGING 2026-07-15 20:09:37.241235 UTC - Status: IMAGING 2026-07-15 20:09:40.602325 UTC - Status: IMAGING 2026-07-15 20:09:43.584259 UTC - Status: IMAGING 2026-07-15 20:09:46.781695 UTC - Status: IMAGING 2026-07-15 20:09:49.998885 UTC - Status: IMAGING 2026-07-15 20:09:53.846290 UTC - Status: IMAGING 2026-07-15 20:09:56.871483 UTC - Status: IMAGING 2026-07-15 20:09:59.691971 UTC - Status: IMAGING 2026-07-15 20:10:02.765921 UTC - Status: IMAGING 2026-07-15 20:10:05.535232 UTC - Status: IMAGING 2026-07-15 20:10:08.511972 UTC - Status: UP 2026-07-15 20:10:08.512070 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxkp9tcpe068bm3y6hrxyagt 2026-07-15 20:10:08.512118 UTC - Validate resources 2026-07-15 20:10:10.043692 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-15 20:10:10.043764 UTC - The fields of the created resource were validated. 2026-07-15 20:10:10.043802 UTC - Check if resources are registered in experiment 2026-07-15 20:10:10.043962 UTC - Run: slices experiment list-resources --format json tst-342132e3 2026-07-15 20:10:11.447971 UTC - Status (on expauth): UP 2026-07-15 20:10:11.448129 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 20:10:11.448248 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-342132e3 2026-07-15 20:10:15.445405 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-342132e3 tst 2026-07-15 20:10:16.541407 UTC - Run: slices experiment list-resources --format json tst-342132e3 2026-07-15 20:10:17.256656 UTC - expires_at (on expauth): 2026-07-15T23:10:00Z (correctly extended) 2026-07-15 20:10:17.257667 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 20:10:17.257856 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-342132e3 2026-07-15 20:10:20.345173 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-342132e3 tst 2026-07-15 20:10:21.375530 UTC - Run: slices experiment list-resources --format json tst-342132e3 2026-07-15 20:10:22.198434 UTC - expires_at (on expauth): 2026-07-16T00:10:00Z (correctly extended) 2026-07-15 20:10:22.198579 UTC - Testing ssh login 2026-07-15 20:10:22.212248 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-342132e3 tst 2026-07-15 20:10:23.028226 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-342132e3 tst 2026-07-15 20:10:23.795040 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:10:23.795124 UTC - Forcing IPv4 only. 2026-07-15 20:10:23.795256 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-342132e3 tst -- -4 uname -a 2026-07-15 20:10:26.931360 UTC - Forcing IPv4 only. 2026-07-15 20:10:26.931727 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-342132e3 tst -- -4 uptime 2026-07-15 20:10:28.665828 UTC - CLI SSH Test passed. 2026-07-15 20:10:28.665922 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-15 20:10:28.665941 UTC - Uptime: 20:10:28 up 1 min, 1 user, load average: 0.39, 0.14, 0.05 2026-07-15 20:10:28.665973 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-15 20:10:28.666747 UTC - Added paramiko HostKeyEntry for 5.53.108.65 2026-07-15 20:10:28.666882 UTC - Added paramiko HostKeyEntry for 5.53.108.65 2026-07-15 20:10:28.667042 UTC - Added paramiko HostKeyEntry for 5.53.108.65 2026-07-15 20:10:28.667206 UTC - Connecting to 5.53.108.65:22 2026-07-15 20:10:29.230221 UTC - SSH Test output: 2026-07-15 20:10:29.230490 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-15 20:10:29.230524 UTC - Uptime: 20:10:29 up 1 min, 1 user, load average: 0.39, 0.14, 0.05 2026-07-15 20:10:29.328903 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 20:10:29.328994 UTC - lsb_release matches expected value 2026-07-15 20:10:29.329025 UTC - SSH Test passed. 2026-07-15 20:10:29.329143 UTC - Skip SSH proxy test because no jump proxy 2026-07-15 20:10:29.344683 UTC - Destroying tst-342132e3 tst 2026-07-15 20:10:29.344919 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-342132e3 tst