Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.74s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 205ms 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 919ms + 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.3 Run Test: slices-bi-singlenode-login 2026-08-10 13:05:02.168493 UTC - Installing Slices CLI (latest stable version). 2026-08-10 13:05:02.169112 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 13:05:08.741028 UTC - Slices CLI installed. 2026-08-10 13:05:08.741466 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-10 13:05:08.744148 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-10 13:05:08.744220 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-10 13:05:08.744301 UTC - which slices: /app/.venv/bin/slices 2026-08-10 13:05:08.744322 UTC - Slices CLI exe: 'slices' 2026-08-10 13:05:08.744339 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 13:05:08.744473 UTC - Run: slices --version 2026-08-10 13:05:12.522763 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-10 13:05:12.523057 UTC - Run: slices pubkey list --format text 2026-08-10 13:05:13.707735 UTC - Pubkey already registered 2026-08-10 13:05:13.707953 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 13:05:15.008953 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 13:05:15.009061 UTC - Check List Flavors 2026-08-10 13:05:15.009219 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-10 13:05:16.444479 UTC - Check List DiskImages 2026-08-10 13:05:16.444682 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-10 13:05:17.300670 UTC - Requesting resources 2026-08-10 13:05:17.301227 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 13:05:17.301367 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-7704d9ce --user-data /tmp/tmp8cticlnc 2026-08-10 13:05:18.726830 UTC - Resource ID: r_ch-geneva-bi-vm1_01kznwe35tebs86tkdfwh3cv93 2026-08-10 13:05:18.727031 UTC - Waiting until resource ready 2026-08-10 13:05:20.727411 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-7704d9ce tst 2026-08-10 13:05:21.601301 UTC - Status: IMAGING 2026-08-10 13:05:24.747808 UTC - Status: IMAGING 2026-08-10 13:05:28.509468 UTC - Status: IMAGING 2026-08-10 13:05:31.801354 UTC - Status: IMAGING 2026-08-10 13:05:34.932265 UTC - Status: IMAGING 2026-08-10 13:05:38.152926 UTC - Status: IMAGING 2026-08-10 13:05:41.424611 UTC - Status: IMAGING 2026-08-10 13:05:44.657059 UTC - Status: IMAGING 2026-08-10 13:05:47.574157 UTC - Status: IMAGING 2026-08-10 13:05:50.440497 UTC - Status: IMAGING 2026-08-10 13:05:53.515663 UTC - Status: IMAGING 2026-08-10 13:05:56.649164 UTC - Status: IMAGING 2026-08-10 13:06:00.165678 UTC - Status: IMAGING 2026-08-10 13:06:03.188806 UTC - Status: IMAGING 2026-08-10 13:06:06.257700 UTC - Status: IMAGING 2026-08-10 13:06:09.275931 UTC - Status: IMAGING 2026-08-10 13:06:12.343726 UTC - Status: IMAGING 2026-08-10 13:06:15.367476 UTC - Status: IMAGING 2026-08-10 13:06:18.811380 UTC - Status: IMAGING 2026-08-10 13:06:22.482856 UTC - Status: IMAGING 2026-08-10 13:06:25.407790 UTC - Status: IMAGING 2026-08-10 13:06:28.656565 UTC - Status: IMAGING 2026-08-10 13:06:31.724533 UTC - Status: IMAGING 2026-08-10 13:06:34.645671 UTC - Status: IMAGING 2026-08-10 13:06:37.514563 UTC - Status: IMAGING 2026-08-10 13:06:40.591767 UTC - Status: IMAGING 2026-08-10 13:06:43.511985 UTC - Status: IMAGING 2026-08-10 13:06:46.531742 UTC - Status: IMAGING 2026-08-10 13:06:49.360427 UTC - Status: IMAGING 2026-08-10 13:06:52.176575 UTC - Status: IMAGING 2026-08-10 13:06:55.108357 UTC - Status: IMAGING 2026-08-10 13:06:58.280591 UTC - Status: IMAGING 2026-08-10 13:07:01.357904 UTC - Status: IMAGING 2026-08-10 13:07:04.550609 UTC - Status: IMAGING 2026-08-10 13:07:07.718888 UTC - Status: UP 2026-08-10 13:07:07.718965 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kznwe2p1exya7rdg6vhyw7sn 2026-08-10 13:07:07.719008 UTC - Validate resources 2026-08-10 13:07:08.641766 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-10 13:07:08.641845 UTC - The fields of the created resource were validated. 2026-08-10 13:07:08.641884 UTC - Check if resources are registered in experiment 2026-08-10 13:07:08.642087 UTC - Run: slices experiment list-resources --format json tst-7704d9ce 2026-08-10 13:07:09.458132 UTC - Status (on expauth): UP 2026-08-10 13:07:09.458301 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 13:07:09.458462 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-7704d9ce 2026-08-10 13:07:10.626685 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-7704d9ce tst 2026-08-10 13:07:11.492662 UTC - Run: slices experiment list-resources --format json tst-7704d9ce 2026-08-10 13:07:12.208587 UTC - expires_at (on expauth): 2026-08-10T16:07:00Z (correctly extended) 2026-08-10 13:07:12.208685 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 13:07:12.208870 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-7704d9ce 2026-08-10 13:07:13.375341 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-7704d9ce tst 2026-08-10 13:07:14.346211 UTC - Run: slices experiment list-resources --format json tst-7704d9ce 2026-08-10 13:07:15.267154 UTC - expires_at (on expauth): 2026-08-10T17:07:00Z (correctly extended) 2026-08-10 13:07:15.267472 UTC - Testing ssh login 2026-08-10 13:07:15.286216 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-7704d9ce tst 2026-08-10 13:07:16.527188 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-7704d9ce tst 2026-08-10 13:07:17.554562 UTC - Logging in using 'slices bi ssh' 2026-08-10 13:07:17.554650 UTC - Forcing IPv4 only. 2026-08-10 13:07:17.554780 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-7704d9ce tst -- -4 uname -a 2026-08-10 13:07:20.481477 UTC - Forcing IPv4 only. 2026-08-10 13:07:20.481639 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-7704d9ce tst -- -4 uptime 2026-08-10 13:07:21.959439 UTC - CLI SSH Test passed. 2026-08-10 13:07:21.959512 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-10 13:07:21.959531 UTC - Uptime: 13:07:21 up 1 min, 1 user, load average: 0.51, 0.16, 0.05 2026-08-10 13:07:21.959564 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-10 13:07:21.960010 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-08-10 13:07:21.960100 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-08-10 13:07:21.960255 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-08-10 13:07:21.960431 UTC - Connecting to 5.53.108.24:22 2026-08-10 13:07:22.521894 UTC - SSH Test output: 2026-08-10 13:07:22.521972 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-10 13:07:22.521993 UTC - Uptime: 13:07:22 up 1 min, 1 user, load average: 0.51, 0.16, 0.05 2026-08-10 13:07:22.618700 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-10 13:07:22.618771 UTC - lsb_release matches expected value 2026-08-10 13:07:22.618791 UTC - SSH Test passed. 2026-08-10 13:07:22.715288 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 13:06:47 +0000! 2026-08-10 13:07:22.715450 UTC - Skip SSH proxy test because no jump proxy 2026-08-10 13:07:22.761266 UTC - Destroying tst-7704d9ce tst 2026-08-10 13:07:22.761483 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-7704d9ce tst