Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.20s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 542ms 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.82s + annotated-types==0.7.0 + anyio==4.14.1 + 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-10 04:07:09.701946 UTC - Installing Slices CLI (latest stable version). 2026-07-10 04:07:09.702380 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 04:07:54.176236 UTC - Slices CLI installed. 2026-07-10 04:07:54.190146 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-10 04:07:54.194638 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-10 04:07:54.196285 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 04:07:54.196459 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-10 04:07:54.196501 UTC - Slices CLI exe: 'slices' 2026-07-10 04:07:54.196529 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 04:07:54.196702 UTC - Run: slices --version 2026-07-10 04:07:57.714996 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-10 04:07:57.716857 UTC - Run: slices pubkey list --format text 2026-07-10 04:07:59.891382 UTC - Pubkey already registered 2026-07-10 04:07:59.891644 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 04:08:01.655095 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 04:08:01.655192 UTC - Check List Flavors 2026-07-10 04:08:01.655360 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-10 04:08:05.428885 UTC - Check List DiskImages 2026-07-10 04:08:05.429087 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-10 04:08:07.331909 UTC - Requesting resources 2026-07-10 04:08:07.333100 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-534ed365 2026-07-10 04:08:11.723041 UTC - Resource ID: r_ch-geneva-bi-vm1_01kx53caayfz1ajhh4hjwgvz2e 2026-07-10 04:08:11.723193 UTC - Waiting until resource ready 2026-07-10 04:08:13.723489 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-534ed365 tst 2026-07-10 04:08:15.101241 UTC - Status: IMAGING 2026-07-10 04:08:18.084949 UTC - Status: IMAGING 2026-07-10 04:08:21.701520 UTC - Status: IMAGING 2026-07-10 04:08:25.019870 UTC - Status: IMAGING 2026-07-10 04:08:27.836328 UTC - Status: IMAGING 2026-07-10 04:08:30.602172 UTC - Status: IMAGING 2026-07-10 04:08:33.475309 UTC - Status: IMAGING 2026-07-10 04:08:36.451844 UTC - Status: IMAGING 2026-07-10 04:08:39.370586 UTC - Status: IMAGING 2026-07-10 04:08:42.449734 UTC - Status: IMAGING 2026-07-10 04:08:45.265842 UTC - Status: IMAGING 2026-07-10 04:08:48.403463 UTC - Status: IMAGING 2026-07-10 04:08:51.335630 UTC - Status: IMAGING 2026-07-10 04:08:54.512414 UTC - Status: IMAGING 2026-07-10 04:08:57.744317 UTC - Status: IMAGING 2026-07-10 04:09:00.561650 UTC - Status: IMAGING 2026-07-10 04:09:03.377961 UTC - Status: IMAGING 2026-07-10 04:09:06.244259 UTC - Status: IMAGING 2026-07-10 04:09:09.114640 UTC - Status: IMAGING 2026-07-10 04:09:12.987225 UTC - Status: IMAGING 2026-07-10 04:09:15.803306 UTC - Status: IMAGING 2026-07-10 04:09:18.619678 UTC - Status: IMAGING 2026-07-10 04:09:21.755621 UTC - Status: IMAGING 2026-07-10 04:09:24.635162 UTC - Status: IMAGING 2026-07-10 04:09:27.401063 UTC - Status: IMAGING 2026-07-10 04:09:30.216962 UTC - Status: IMAGING 2026-07-10 04:09:33.032799 UTC - Status: IMAGING 2026-07-10 04:09:35.798572 UTC - Status: IMAGING 2026-07-10 04:09:38.564588 UTC - Status: IMAGING 2026-07-10 04:09:41.380647 UTC - Status: IMAGING 2026-07-10 04:09:44.146957 UTC - Status: IMAGING 2026-07-10 04:09:47.215003 UTC - Status: IMAGING 2026-07-10 04:09:50.035611 UTC - Status: IMAGING 2026-07-10 04:09:52.909231 UTC - Status: IMAGING 2026-07-10 04:09:55.680046 UTC - Status: IMAGING 2026-07-10 04:09:58.447476 UTC - Status: IMAGING 2026-07-10 04:10:01.761291 UTC - Status: IMAGING 2026-07-10 04:10:04.845369 UTC - Status: UP 2026-07-10 04:10:04.845486 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx53c987eaw8qre3p4r9hbkc 2026-07-10 04:10:04.845535 UTC - Validate resources 2026-07-10 04:10:05.662336 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-10 04:10:05.662407 UTC - The fields of the created resource were validated. 2026-07-10 04:10:05.662441 UTC - Check if resources are registered in experiment 2026-07-10 04:10:05.662593 UTC - Run: slices experiment list-resources --format json tst-534ed365 2026-07-10 04:10:06.698018 UTC - Status (on expauth): UP 2026-07-10 04:10:06.698218 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 04:10:06.698397 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-534ed365 2026-07-10 04:10:09.624579 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-534ed365 tst 2026-07-10 04:10:10.490460 UTC - Run: slices experiment list-resources --format json tst-534ed365 2026-07-10 04:10:11.160849 UTC - expires_at (on expauth): 2026-07-10T07:10:00Z (correctly extended) 2026-07-10 04:10:11.160948 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 04:10:11.161107 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-534ed365 2026-07-10 04:10:14.281512 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-534ed365 tst 2026-07-10 04:10:15.097499 UTC - Run: slices experiment list-resources --format json tst-534ed365 2026-07-10 04:10:15.814070 UTC - expires_at (on expauth): 2026-07-10T08:10:00Z (correctly extended) 2026-07-10 04:10:15.814183 UTC - Testing ssh login 2026-07-10 04:10:15.825916 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-534ed365 tst 2026-07-10 04:10:16.641996 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-534ed365 tst 2026-07-10 04:10:17.507924 UTC - Logging in using 'slices bi ssh' 2026-07-10 04:10:17.508011 UTC - Forcing IPv4 only. 2026-07-10 04:10:17.508158 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-534ed365 tst -- -4 uname -a 2026-07-10 04:10:20.782899 UTC - Forcing IPv4 only. 2026-07-10 04:10:20.783135 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-534ed365 tst -- -4 uptime 2026-07-10 04:10:22.099881 UTC - CLI SSH Test passed. 2026-07-10 04:10:22.099965 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-10 04:10:22.099986 UTC - Uptime: 04:10:22 up 1 min, 2 users, load average: 0.77, 0.35, 0.13 2026-07-10 04:10:22.100023 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-10 04:10:22.100586 UTC - Added paramiko HostKeyEntry for 5.53.108.237 2026-07-10 04:10:22.100691 UTC - Added paramiko HostKeyEntry for 5.53.108.237 2026-07-10 04:10:22.100910 UTC - Added paramiko HostKeyEntry for 5.53.108.237 2026-07-10 04:10:22.101135 UTC - Connecting to 5.53.108.237:22 2026-07-10 04:10:22.654783 UTC - SSH Test output: 2026-07-10 04:10:22.654856 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-10 04:10:22.654878 UTC - Uptime: 04:10:22 up 1 min, 1 user, load average: 0.71, 0.35, 0.13 2026-07-10 04:10:22.752531 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 04:10:22.752626 UTC - lsb_release matches expected value 2026-07-10 04:10:22.752649 UTC - SSH Test passed. 2026-07-10 04:10:22.752764 UTC - Skip SSH proxy test because no jump proxy 2026-07-10 04:10:22.759234 UTC - Destroying tst-534ed365 tst 2026-07-10 04:10:22.759408 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-534ed365 tst