Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.39s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 268ms 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 790ms + 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-08 20:07:20.325721 UTC - Installing Slices CLI (latest stable version). 2026-08-08 20:07:20.326199 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 20:07:27.322694 UTC - Slices CLI installed. 2026-08-08 20:07:27.325507 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-08 20:07:27.331719 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-08 20:07:27.331855 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-08 20:07:27.331961 UTC - which slices: /app/.venv/bin/slices 2026-08-08 20:07:27.331993 UTC - Slices CLI exe: 'slices' 2026-08-08 20:07:27.332019 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 20:07:27.332250 UTC - Run: slices --version 2026-08-08 20:07:30.595575 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-08 20:07:30.596043 UTC - Run: slices pubkey list --format text 2026-08-08 20:07:31.462238 UTC - Pubkey already registered 2026-08-08 20:07:31.462582 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 20:07:32.382644 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 20:07:32.382775 UTC - Check List Flavors 2026-08-08 20:07:32.382981 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-08 20:07:34.253667 UTC - Check List DiskImages 2026-08-08 20:07:34.253908 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-08 20:07:35.120551 UTC - Requesting resources 2026-08-08 20:07:35.121231 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 20:07:35.121420 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-55c0bbac --user-data /tmp/tmpa1h_2i_x 2026-08-08 20:07:36.740271 UTC - Resource ID: r_ch-geneva-bi-vm1_01kzhfsx96e61vakt24crbw37r 2026-08-08 20:07:36.740481 UTC - Waiting until resource ready 2026-08-08 20:07:38.755363 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-55c0bbac tst 2026-08-08 20:07:39.874838 UTC - Status: IMAGING 2026-08-08 20:07:43.043527 UTC - Status: IMAGING 2026-08-08 20:07:45.965525 UTC - Status: IMAGING 2026-08-08 20:07:48.983775 UTC - Status: IMAGING 2026-08-08 20:07:52.152559 UTC - Status: IMAGING 2026-08-08 20:07:54.968961 UTC - Status: IMAGING 2026-08-08 20:07:57.890257 UTC - Status: IMAGING 2026-08-08 20:08:00.807619 UTC - Status: IMAGING 2026-08-08 20:08:03.876387 UTC - Status: IMAGING 2026-08-08 20:08:06.975293 UTC - Status: IMAGING 2026-08-08 20:08:09.848419 UTC - Status: IMAGING 2026-08-08 20:08:12.815775 UTC - Status: IMAGING 2026-08-08 20:08:15.682719 UTC - Status: IMAGING 2026-08-08 20:08:18.549322 UTC - Status: IMAGING 2026-08-08 20:08:21.415513 UTC - Status: IMAGING 2026-08-08 20:08:24.281893 UTC - Status: IMAGING 2026-08-08 20:08:27.402091 UTC - Status: IMAGING 2026-08-08 20:08:30.726634 UTC - Status: IMAGING 2026-08-08 20:08:33.593810 UTC - Status: IMAGING 2026-08-08 20:08:36.614893 UTC - Status: IMAGING 2026-08-08 20:08:39.430925 UTC - Status: IMAGING 2026-08-08 20:08:42.350821 UTC - Status: IMAGING 2026-08-08 20:08:45.467635 UTC - Status: IMAGING 2026-08-08 20:08:48.488959 UTC - Status: IMAGING 2026-08-08 20:08:51.507784 UTC - Status: IMAGING 2026-08-08 20:08:54.324154 UTC - Status: IMAGING 2026-08-08 20:08:57.192468 UTC - Status: IMAGING 2026-08-08 20:09:00.313246 UTC - Status: IMAGING 2026-08-08 20:09:03.129259 UTC - Status: IMAGING 2026-08-08 20:09:06.095549 UTC - Status: IMAGING 2026-08-08 20:09:09.119698 UTC - Status: IMAGING 2026-08-08 20:09:12.087095 UTC - Status: IMAGING 2026-08-08 20:09:15.005241 UTC - Status: IMAGING 2026-08-08 20:09:17.949129 UTC - Status: IMAGING 2026-08-08 20:09:20.817038 UTC - Status: IMAGING 2026-08-08 20:09:23.683238 UTC - Status: IMAGING 2026-08-08 20:09:26.502414 UTC - Status: IMAGING 2026-08-08 20:09:29.484864 UTC - Status: UP 2026-08-08 20:09:29.484964 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzhfswskendsgyp0k7t755a9 2026-08-08 20:09:29.485021 UTC - Validate resources 2026-08-08 20:09:30.304808 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-08 20:09:30.304896 UTC - The fields of the created resource were validated. 2026-08-08 20:09:30.304934 UTC - Check if resources are registered in experiment 2026-08-08 20:09:30.305079 UTC - Run: slices experiment list-resources --format json tst-55c0bbac 2026-08-08 20:09:31.120962 UTC - Status (on expauth): UP 2026-08-08 20:09:31.121156 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 20:09:31.121363 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-55c0bbac 2026-08-08 20:09:32.289324 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-55c0bbac tst 2026-08-08 20:09:33.259002 UTC - Run: slices experiment list-resources --format json tst-55c0bbac 2026-08-08 20:09:34.024457 UTC - expires_at (on expauth): 2026-08-08T23:09:00Z (correctly extended) 2026-08-08 20:09:34.025286 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 20:09:34.025504 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-55c0bbac 2026-08-08 20:09:35.546536 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-55c0bbac tst 2026-08-08 20:09:36.512653 UTC - Run: slices experiment list-resources --format json tst-55c0bbac 2026-08-08 20:09:37.329486 UTC - expires_at (on expauth): 2026-08-09T00:09:00Z (correctly extended) 2026-08-08 20:09:37.329618 UTC - Testing ssh login 2026-08-08 20:09:37.345565 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-55c0bbac tst 2026-08-08 20:09:38.261717 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-55c0bbac tst 2026-08-08 20:09:39.181446 UTC - Logging in using 'slices bi ssh' 2026-08-08 20:09:39.181592 UTC - Forcing IPv4 only. 2026-08-08 20:09:39.181775 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-55c0bbac tst -- -4 uname -a 2026-08-08 20:09:41.701849 UTC - Forcing IPv4 only. 2026-08-08 20:09:41.702040 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-55c0bbac tst -- -4 uptime 2026-08-08 20:09:42.918602 UTC - CLI SSH Test passed. 2026-08-08 20:09:42.918674 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-08 20:09:42.918693 UTC - Uptime: 20:09:42 up 1 min, 1 user, load average: 0.03, 0.01, 0.00 2026-08-08 20:09:42.918727 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-08 20:09:42.919220 UTC - Added paramiko HostKeyEntry for 5.53.108.58 2026-08-08 20:09:42.919317 UTC - Added paramiko HostKeyEntry for 5.53.108.58 2026-08-08 20:09:42.919457 UTC - Added paramiko HostKeyEntry for 5.53.108.58 2026-08-08 20:09:42.919634 UTC - Connecting to 5.53.108.58:22 2026-08-08 20:09:43.475987 UTC - SSH Test output: 2026-08-08 20:09:43.476069 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-08 20:09:43.476094 UTC - Uptime: 20:09:43 up 1 min, 1 user, load average: 0.19, 0.04, 0.01 2026-08-08 20:09:43.573269 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 20:09:43.573346 UTC - lsb_release matches expected value 2026-08-08 20:09:43.573366 UTC - SSH Test passed. 2026-08-08 20:09:43.658426 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 20:09:08 +0000! 2026-08-08 20:09:43.658585 UTC - Skip SSH proxy test because no jump proxy 2026-08-08 20:09:43.668302 UTC - Destroying tst-55c0bbac tst 2026-08-08 20:09:43.668489 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-55c0bbac tst