Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.39s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 460ms 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 623ms + 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:05:57.615889 UTC - Installing Slices CLI (latest stable version). 2026-07-25 04:05:57.616163 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 04:06:04.191631 UTC - Slices CLI installed. 2026-07-25 04:06:04.192696 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:06:04.195921 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:06:04.197222 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 04:06:04.197347 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:06:04.197374 UTC - Slices CLI exe: 'slices' 2026-07-25 04:06:04.197392 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 04:06:04.197513 UTC - Run: slices --version 2026-07-25 04:06:10.653708 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:06:10.654222 UTC - Run: slices pubkey list --format text 2026-07-25 04:06:14.014650 UTC - Pubkey already registered 2026-07-25 04:06:14.016375 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 04:06:16.135797 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 04:06:16.135890 UTC - Check List Flavors 2026-07-25 04:06:16.136045 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-25 04:06:18.086757 UTC - Check List DiskImages 2026-07-25 04:06:18.086949 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-25 04:06:19.590513 UTC - Requesting resources 2026-07-25 04:06:19.591061 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e8457966 2026-07-25 04:06:21.790784 UTC - Resource ID: r_ch-geneva-bi-vm1_01kybq7qxvffgs3xmhj8krj9fn 2026-07-25 04:06:21.790932 UTC - Waiting until resource ready 2026-07-25 04:06:23.791355 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e8457966 tst 2026-07-25 04:06:25.268145 UTC - Status: IMAGING 2026-07-25 04:06:28.190217 UTC - Status: IMAGING 2026-07-25 04:06:30.978129 UTC - Status: IMAGING 2026-07-25 04:06:33.851339 UTC - Status: IMAGING 2026-07-25 04:06:36.718760 UTC - Status: IMAGING 2026-07-25 04:06:39.803121 UTC - Status: IMAGING 2026-07-25 04:06:42.974871 UTC - Status: IMAGING 2026-07-25 04:06:46.117464 UTC - Status: IMAGING 2026-07-25 04:06:48.933583 UTC - Status: IMAGING 2026-07-25 04:06:51.813055 UTC - Status: IMAGING 2026-07-25 04:06:54.629142 UTC - Status: IMAGING 2026-07-25 04:06:57.445059 UTC - Status: IMAGING 2026-07-25 04:07:00.540614 UTC - Status: IMAGING 2026-07-25 04:07:03.829418 UTC - Status: IMAGING 2026-07-25 04:07:07.352575 UTC - Status: IMAGING 2026-07-25 04:07:10.705681 UTC - Status: IMAGING 2026-07-25 04:07:13.939897 UTC - Status: IMAGING 2026-07-25 04:07:17.064742 UTC - Status: IMAGING 2026-07-25 04:07:20.281860 UTC - Status: IMAGING 2026-07-25 04:07:23.152169 UTC - Status: IMAGING 2026-07-25 04:07:26.158467 UTC - Status: IMAGING 2026-07-25 04:07:29.596230 UTC - Status: IMAGING 2026-07-25 04:07:32.513949 UTC - Status: IMAGING 2026-07-25 04:07:35.497069 UTC - Status: IMAGING 2026-07-25 04:07:38.465041 UTC - Status: IMAGING 2026-07-25 04:07:41.360506 UTC - Status: IMAGING 2026-07-25 04:07:44.328640 UTC - Status: IMAGING 2026-07-25 04:07:47.250668 UTC - Status: IMAGING 2026-07-25 04:07:50.121970 UTC - Status: IMAGING 2026-07-25 04:07:53.095912 UTC - Status: IMAGING 2026-07-25 04:07:56.483240 UTC - Status: IMAGING 2026-07-25 04:07:59.513719 UTC - Status: IMAGING 2026-07-25 04:08:03.520321 UTC - Status: IMAGING 2026-07-25 04:08:06.541166 UTC - Status: IMAGING 2026-07-25 04:08:09.571151 UTC - Status: IMAGING 2026-07-25 04:08:12.593169 UTC - Status: UP 2026-07-25 04:08:12.593265 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kybq7qecfyz9g2ncxrmdt28q 2026-07-25 04:08:12.593308 UTC - Validate resources 2026-07-25 04:08:13.951235 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:08:13.951321 UTC - The fields of the created resource were validated. 2026-07-25 04:08:13.951362 UTC - Check if resources are registered in experiment 2026-07-25 04:08:13.951516 UTC - Run: slices experiment list-resources --format json tst-e8457966 2026-07-25 04:08:14.798418 UTC - Status (on expauth): UP 2026-07-25 04:08:14.798725 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 04:08:14.798907 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e8457966 2026-07-25 04:08:16.467829 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e8457966 tst 2026-07-25 04:08:17.283578 UTC - Run: slices experiment list-resources --format json tst-e8457966 2026-07-25 04:08:18.157858 UTC - expires_at (on expauth): 2026-07-25T07:08:00Z (correctly extended) 2026-07-25 04:08:18.157954 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 04:08:18.158109 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e8457966 2026-07-25 04:08:19.843514 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e8457966 tst 2026-07-25 04:08:21.087555 UTC - Run: slices experiment list-resources --format json tst-e8457966 2026-07-25 04:08:22.437793 UTC - expires_at (on expauth): 2026-07-25T08:08:00Z (correctly extended) 2026-07-25 04:08:22.437935 UTC - Testing ssh login 2026-07-25 04:08:22.466542 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e8457966 tst 2026-07-25 04:08:23.938591 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e8457966 tst 2026-07-25 04:08:25.128504 UTC - Logging in using 'slices bi ssh' 2026-07-25 04:08:25.128581 UTC - Forcing IPv4 only. 2026-07-25 04:08:25.128705 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e8457966 tst -- -4 uname -a 2026-07-25 04:08:27.920299 UTC - Forcing IPv4 only. 2026-07-25 04:08:27.920655 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e8457966 tst -- -4 uptime 2026-07-25 04:08:29.597344 UTC - CLI SSH Test passed. 2026-07-25 04:08:29.597416 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:08:29.597436 UTC - Uptime: 04:08:29 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-25 04:08:29.597471 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-25 04:08:29.598222 UTC - Added paramiko HostKeyEntry for 5.53.108.12 2026-07-25 04:08:29.598337 UTC - Added paramiko HostKeyEntry for 5.53.108.12 2026-07-25 04:08:29.598496 UTC - Added paramiko HostKeyEntry for 5.53.108.12 2026-07-25 04:08:29.598671 UTC - Connecting to 5.53.108.12:22 2026-07-25 04:08:30.150361 UTC - SSH Test output: 2026-07-25 04:08:30.150428 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:08:30.150449 UTC - Uptime: 04:08:30 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-25 04:08:30.247206 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 04:08:30.247282 UTC - lsb_release matches expected value 2026-07-25 04:08:30.247301 UTC - SSH Test passed. 2026-07-25 04:08:30.247377 UTC - Skip SSH proxy test because no jump proxy 2026-07-25 04:08:30.255862 UTC - Destroying tst-e8457966 tst 2026-07-25 04:08:30.256054 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e8457966 tst