Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.68s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 225ms 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 298ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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-08-04 20:04:42.860213 UTC - Installing Slices CLI (latest stable version). 2026-08-04 20:04:42.860457 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-04 20:04:49.359309 UTC - Slices CLI installed. 2026-08-04 20:04:49.359918 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-08-04 20:04:49.364939 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-08-04 20:04:49.365037 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-08-04 20:04:49.365133 UTC - which slices: /app/.venv/bin/slices 2026-08-04 20:04:49.365157 UTC - Slices CLI exe: 'slices' 2026-08-04 20:04:49.365179 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-04 20:04:49.365324 UTC - Run: slices --version 2026-08-04 20:04:52.712573 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-08-04 20:04:52.712959 UTC - Run: slices pubkey list --format text 2026-08-04 20:04:53.636169 UTC - Pubkey already registered 2026-08-04 20:04:53.636432 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-04 20:04:54.615741 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-04 20:04:54.615837 UTC - Check List Flavors 2026-08-04 20:04:54.615975 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-04 20:04:55.750002 UTC - Check List DiskImages 2026-08-04 20:04:55.750244 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-04 20:04:56.716981 UTC - Requesting resources 2026-08-04 20:04:56.717687 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-04 20:04:56.717830 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5f88f81b --user-data /tmp/tmpl4592jqk 2026-08-04 20:04:58.235121 UTC - Resource ID: r_ch-geneva-bi-vm1_01kz7626hdeegs4h7exwga888k 2026-08-04 20:04:58.235304 UTC - Waiting until resource ready 2026-08-04 20:05:00.235662 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5f88f81b tst 2026-08-04 20:05:01.414490 UTC - Status: IMAGING 2026-08-04 20:05:04.610274 UTC - Status: IMAGING 2026-08-04 20:05:07.580922 UTC - Status: IMAGING 2026-08-04 20:05:10.553498 UTC - Status: IMAGING 2026-08-04 20:05:14.103745 UTC - Status: IMAGING 2026-08-04 20:05:17.859953 UTC - Status: IMAGING 2026-08-04 20:05:21.537637 UTC - Status: IMAGING 2026-08-04 20:05:24.766813 UTC - Status: IMAGING 2026-08-04 20:05:27.994434 UTC - Status: IMAGING 2026-08-04 20:05:31.069412 UTC - Status: IMAGING 2026-08-04 20:05:34.036563 UTC - Status: IMAGING 2026-08-04 20:05:37.053551 UTC - Status: IMAGING 2026-08-04 20:05:40.649705 UTC - Status: IMAGING 2026-08-04 20:05:43.780932 UTC - Status: IMAGING 2026-08-04 20:05:47.326855 UTC - Status: IMAGING 2026-08-04 20:05:50.396702 UTC - Status: IMAGING 2026-08-04 20:05:53.363468 UTC - Status: IMAGING 2026-08-04 20:05:56.333124 UTC - Status: IMAGING 2026-08-04 20:05:59.550423 UTC - Status: IMAGING 2026-08-04 20:06:02.937926 UTC - Status: IMAGING 2026-08-04 20:06:06.356985 UTC - Status: IMAGING 2026-08-04 20:06:09.547649 UTC - Status: IMAGING 2026-08-04 20:06:12.616195 UTC - Status: IMAGING 2026-08-04 20:06:15.562300 UTC - Status: IMAGING 2026-08-04 20:06:18.632576 UTC - Status: IMAGING 2026-08-04 20:06:22.055258 UTC - Status: IMAGING 2026-08-04 20:06:25.082031 UTC - Status: IMAGING 2026-08-04 20:06:27.952029 UTC - Status: IMAGING 2026-08-04 20:06:30.768185 UTC - Status: IMAGING 2026-08-04 20:06:33.584447 UTC - Status: IMAGING 2026-08-04 20:06:36.400875 UTC - Status: IMAGING 2026-08-04 20:06:39.327258 UTC - Status: IMAGING 2026-08-04 20:06:42.364500 UTC - Status: IMAGING 2026-08-04 20:06:45.564304 UTC - Status: IMAGING 2026-08-04 20:06:48.772104 UTC - Status: UP 2026-08-04 20:06:48.772222 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz76261tedwv973fjxm8ncw5 2026-08-04 20:06:48.772265 UTC - Validate resources 2026-08-04 20:06:50.058500 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-08-04 20:06:50.058575 UTC - The fields of the created resource were validated. 2026-08-04 20:06:50.058612 UTC - Check if resources are registered in experiment 2026-08-04 20:06:50.058777 UTC - Run: slices experiment list-resources --format json tst-5f88f81b 2026-08-04 20:06:51.075867 UTC - Status (on expauth): UP 2026-08-04 20:06:51.076057 UTC - Testing extend expires_at (all resources in experiment) 2026-08-04 20:06:51.076204 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-5f88f81b 2026-08-04 20:06:52.676248 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5f88f81b tst 2026-08-04 20:06:53.702177 UTC - Run: slices experiment list-resources --format json tst-5f88f81b 2026-08-04 20:06:54.628502 UTC - expires_at (on expauth): 2026-08-04T23:06:00Z (correctly extended) 2026-08-04 20:06:54.628643 UTC - Testing extend expires_at (single resource in experiment) 2026-08-04 20:06:54.628879 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-5f88f81b 2026-08-04 20:06:56.013428 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5f88f81b tst 2026-08-04 20:06:57.237276 UTC - Run: slices experiment list-resources --format json tst-5f88f81b 2026-08-04 20:06:58.162948 UTC - expires_at (on expauth): 2026-08-05T00:06:00Z (correctly extended) 2026-08-04 20:06:58.163616 UTC - Testing ssh login 2026-08-04 20:06:58.191359 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5f88f81b tst 2026-08-04 20:06:59.308793 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5f88f81b tst 2026-08-04 20:07:00.427357 UTC - Logging in using 'slices bi ssh' 2026-08-04 20:07:00.427439 UTC - Forcing IPv4 only. 2026-08-04 20:07:00.427554 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-5f88f81b tst -- -4 uname -a 2026-08-04 20:07:03.861702 UTC - Forcing IPv4 only. 2026-08-04 20:07:03.861876 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-5f88f81b tst -- -4 uptime 2026-08-04 20:07:05.489312 UTC - CLI SSH Test passed. 2026-08-04 20:07:05.489396 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-08-04 20:07:05.489416 UTC - Uptime: 20:07:05 up 1 min, 1 user, load average: 0.47, 0.15, 0.05 2026-08-04 20:07:05.489451 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-04 20:07:05.489923 UTC - Added paramiko HostKeyEntry for 5.53.108.161 2026-08-04 20:07:05.490008 UTC - Added paramiko HostKeyEntry for 5.53.108.161 2026-08-04 20:07:05.490164 UTC - Added paramiko HostKeyEntry for 5.53.108.161 2026-08-04 20:07:05.490376 UTC - Connecting to 5.53.108.161:22 2026-08-04 20:07:06.058028 UTC - SSH Test output: 2026-08-04 20:07:06.058102 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-08-04 20:07:06.058124 UTC - Uptime: 20:07:05 up 1 min, 1 user, load average: 0.47, 0.15, 0.05 2026-08-04 20:07:06.157958 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-04 20:07:06.158036 UTC - lsb_release matches expected value 2026-08-04 20:07:06.158056 UTC - SSH Test passed. 2026-08-04 20:07:06.263779 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 20:06:32 +0000! 2026-08-04 20:07:06.263969 UTC - Skip SSH proxy test because no jump proxy 2026-08-04 20:07:06.273748 UTC - Destroying tst-5f88f81b tst 2026-08-04 20:07:06.273933 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-5f88f81b tst