Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.61s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 197ms 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 659ms + 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.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-07-29 20:04:28.780053 UTC - Installing Slices CLI (latest stable version). 2026-07-29 20:04:28.780511 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-29 20:04:35.220369 UTC - Slices CLI installed. 2026-07-29 20:04:35.222410 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-29 20:04:35.229536 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-07-29 20:04:35.229658 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-07-29 20:04:35.229919 UTC - which slices: /app/.venv/bin/slices 2026-07-29 20:04:35.229948 UTC - Slices CLI exe: 'slices' 2026-07-29 20:04:35.229964 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-29 20:04:35.230091 UTC - Run: slices --version 2026-07-29 20:04:37.958894 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-07-29 20:04:37.959162 UTC - Run: slices pubkey list --format text 2026-07-29 20:04:38.674513 UTC - Pubkey already registered 2026-07-29 20:04:38.674743 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-29 20:04:39.290030 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-29 20:04:39.290128 UTC - Check List Flavors 2026-07-29 20:04:39.290268 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-29 20:04:40.216668 UTC - Check List DiskImages 2026-07-29 20:04:40.216871 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-29 20:04:41.138988 UTC - Requesting resources 2026-07-29 20:04:41.139846 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-29 20:04:41.139976 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-d9aec0ca --user-data /tmp/tmp3flj9q1u 2026-07-29 20:04:42.713202 UTC - Resource ID: r_ch-geneva-bi-vm1_01kyqqndaze81ty7mye9nrpk4t 2026-07-29 20:04:42.713378 UTC - Waiting until resource ready 2026-07-29 20:04:44.713716 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d9aec0ca tst 2026-07-29 20:04:45.529456 UTC - Status: IMAGING 2026-07-29 20:04:48.516249 UTC - Status: IMAGING 2026-07-29 20:04:52.310833 UTC - Status: IMAGING 2026-07-29 20:04:55.578726 UTC - Status: IMAGING 2026-07-29 20:04:59.105175 UTC - Status: IMAGING 2026-07-29 20:05:02.604190 UTC - Status: IMAGING 2026-07-29 20:05:05.420457 UTC - Status: IMAGING 2026-07-29 20:05:08.236243 UTC - Status: IMAGING 2026-07-29 20:05:11.312199 UTC - Status: IMAGING 2026-07-29 20:05:14.493154 UTC - Status: IMAGING 2026-07-29 20:05:17.942339 UTC - Status: IMAGING 2026-07-29 20:05:21.111079 UTC - Status: IMAGING 2026-07-29 20:05:23.929720 UTC - Status: IMAGING 2026-07-29 20:05:27.071432 UTC - Status: IMAGING 2026-07-29 20:05:30.454432 UTC - Status: IMAGING 2026-07-29 20:05:34.100225 UTC - Status: IMAGING 2026-07-29 20:05:37.241590 UTC - Status: IMAGING 2026-07-29 20:05:41.311408 UTC - Status: IMAGING 2026-07-29 20:05:44.537855 UTC - Status: IMAGING 2026-07-29 20:05:47.409314 UTC - Status: IMAGING 2026-07-29 20:05:50.734294 UTC - Status: IMAGING 2026-07-29 20:05:53.734534 UTC - Status: IMAGING 2026-07-29 20:05:56.600826 UTC - Status: IMAGING 2026-07-29 20:05:59.517080 UTC - Status: IMAGING 2026-07-29 20:06:02.544933 UTC - Status: IMAGING 2026-07-29 20:06:05.561275 UTC - Status: IMAGING 2026-07-29 20:06:08.644552 UTC - Status: IMAGING 2026-07-29 20:06:11.574866 UTC - Status: IMAGING 2026-07-29 20:06:14.627826 UTC - Status: IMAGING 2026-07-29 20:06:17.444667 UTC - Status: IMAGING 2026-07-29 20:06:20.211642 UTC - Status: IMAGING 2026-07-29 20:06:23.128083 UTC - Status: IMAGING 2026-07-29 20:06:26.164225 UTC - Status: IMAGING 2026-07-29 20:06:29.231330 UTC - Status: IMAGING 2026-07-29 20:06:32.205743 UTC - Status: UP 2026-07-29 20:06:32.205843 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyqqncvcep68vz5bk7k3txpd 2026-07-29 20:06:32.205883 UTC - Validate resources 2026-07-29 20:06:32.972837 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-29 20:06:32.972908 UTC - The fields of the created resource were validated. 2026-07-29 20:06:32.972943 UTC - Check if resources are registered in experiment 2026-07-29 20:06:32.973087 UTC - Run: slices experiment list-resources --format json tst-d9aec0ca 2026-07-29 20:06:33.638763 UTC - Status (on expauth): UP 2026-07-29 20:06:33.638892 UTC - Testing extend expires_at (all resources in experiment) 2026-07-29 20:06:33.639007 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-d9aec0ca 2026-07-29 20:06:34.805711 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d9aec0ca tst 2026-07-29 20:06:35.571476 UTC - Run: slices experiment list-resources --format json tst-d9aec0ca 2026-07-29 20:06:36.186741 UTC - expires_at (on expauth): 2026-07-29T23:06:00Z (correctly extended) 2026-07-29 20:06:36.186836 UTC - Testing extend expires_at (single resource in experiment) 2026-07-29 20:06:36.187035 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-d9aec0ca 2026-07-29 20:06:37.303413 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d9aec0ca tst 2026-07-29 20:06:38.069128 UTC - Run: slices experiment list-resources --format json tst-d9aec0ca 2026-07-29 20:06:38.848037 UTC - expires_at (on expauth): 2026-07-30T00:06:00Z (correctly extended) 2026-07-29 20:06:38.848147 UTC - Testing ssh login 2026-07-29 20:06:38.875729 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d9aec0ca tst 2026-07-29 20:06:39.794911 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d9aec0ca tst 2026-07-29 20:06:40.675318 UTC - Logging in using 'slices bi ssh' 2026-07-29 20:06:40.675426 UTC - Forcing IPv4 only. 2026-07-29 20:06:40.675599 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-d9aec0ca tst -- -4 uname -a 2026-07-29 20:06:43.578047 UTC - Forcing IPv4 only. 2026-07-29 20:06:43.604684 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-d9aec0ca tst -- -4 uptime 2026-07-29 20:06:44.922656 UTC - CLI SSH Test passed. 2026-07-29 20:06:44.923583 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-29 20:06:44.923627 UTC - Uptime: 20:06:44 up 1 min, 1 user, load average: 0.51, 0.16, 0.05 2026-07-29 20:06:44.923689 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-29 20:06:44.925165 UTC - Added paramiko HostKeyEntry for 5.53.108.27 2026-07-29 20:06:44.925288 UTC - Added paramiko HostKeyEntry for 5.53.108.27 2026-07-29 20:06:44.925679 UTC - Added paramiko HostKeyEntry for 5.53.108.27 2026-07-29 20:06:44.925863 UTC - Connecting to 5.53.108.27:22 2026-07-29 20:06:45.472215 UTC - SSH Test output: 2026-07-29 20:06:45.472302 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-29 20:06:45.472329 UTC - Uptime: 20:06:45 up 1 min, 1 user, load average: 0.47, 0.16, 0.05 2026-07-29 20:06:45.570849 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-29 20:06:45.570921 UTC - lsb_release matches expected value 2026-07-29 20:06:45.570941 UTC - SSH Test passed. 2026-07-29 20:06:45.655185 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 20:06:13 +0000! 2026-07-29 20:06:45.655331 UTC - Skip SSH proxy test because no jump proxy 2026-07-29 20:06:45.697533 UTC - Destroying tst-d9aec0ca tst 2026-07-29 20:06:45.697760 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-d9aec0ca tst