Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.92s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 829ms 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.20s + annotated-types==0.7.0 + anyio==4.14.2 + 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-13 13:03:09.559044 UTC - Installing Slices CLI (latest stable version). 2026-07-13 13:03:09.559326 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 13:03:15.966288 UTC - Slices CLI installed. 2026-07-13 13:03:15.966720 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-13 13:03:15.970596 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-13 13:03:15.972484 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 13:03:15.972640 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-13 13:03:15.972671 UTC - Slices CLI exe: 'slices' 2026-07-13 13:03:15.972689 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 13:03:15.973028 UTC - Run: slices --version 2026-07-13 13:03:23.001875 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-13 13:03:23.002327 UTC - Run: slices pubkey list --format text 2026-07-13 13:03:25.227789 UTC - Pubkey already registered 2026-07-13 13:03:25.229536 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 13:03:27.540668 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 13:03:27.541518 UTC - Check List Flavors 2026-07-13 13:03:27.541749 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-13 13:03:30.594069 UTC - Check List DiskImages 2026-07-13 13:03:30.597845 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-13 13:03:33.074616 UTC - Requesting resources 2026-07-13 13:03:33.075495 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 13:03:33.075626 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-187844cd --user-data /tmp/tmpx78bj770 2026-07-13 13:03:38.191314 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxds6wrxfd082pedf5ej27c1 2026-07-13 13:03:38.191503 UTC - Waiting until resource ready 2026-07-13 13:03:40.191828 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-187844cd tst 2026-07-13 13:03:41.870950 UTC - Status: IMAGING 2026-07-13 13:03:46.726381 UTC - Status: IMAGING 2026-07-13 13:03:51.815212 UTC - Status: IMAGING 2026-07-13 13:03:57.172921 UTC - Status: IMAGING 2026-07-13 13:04:01.530908 UTC - Status: IMAGING 2026-07-13 13:04:05.035139 UTC - Status: IMAGING 2026-07-13 13:04:08.484759 UTC - Status: IMAGING 2026-07-13 13:04:12.491414 UTC - Status: IMAGING 2026-07-13 13:04:16.739026 UTC - Status: IMAGING 2026-07-13 13:04:21.168535 UTC - Status: IMAGING 2026-07-13 13:04:24.336742 UTC - Status: IMAGING 2026-07-13 13:04:28.297949 UTC - Status: IMAGING 2026-07-13 13:04:32.017257 UTC - Status: IMAGING 2026-07-13 13:04:35.614782 UTC - Status: IMAGING 2026-07-13 13:04:38.481243 UTC - Status: IMAGING 2026-07-13 13:04:41.397620 UTC - Status: IMAGING 2026-07-13 13:04:44.616021 UTC - Status: IMAGING 2026-07-13 13:04:47.482394 UTC - Status: IMAGING 2026-07-13 13:04:50.689268 UTC - Status: IMAGING 2026-07-13 13:04:53.811207 UTC - Status: IMAGING 2026-07-13 13:04:56.968287 UTC - Status: IMAGING 2026-07-13 13:04:59.986200 UTC - Status: IMAGING 2026-07-13 13:05:02.902875 UTC - Status: IMAGING 2026-07-13 13:05:05.670044 UTC - Status: IMAGING 2026-07-13 13:05:08.536980 UTC - Status: IMAGING 2026-07-13 13:05:11.353331 UTC - Status: IMAGING 2026-07-13 13:05:14.220331 UTC - Status: IMAGING 2026-07-13 13:05:17.036661 UTC - Status: IMAGING 2026-07-13 13:05:19.903150 UTC - Status: IMAGING 2026-07-13 13:05:22.719632 UTC - Status: UP 2026-07-13 13:05:22.719732 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxds6vgdf958c1ceht3n5qv7 2026-07-13 13:05:22.719775 UTC - Validate resources 2026-07-13 13:05:23.638740 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-13 13:05:23.638931 UTC - The fields of the created resource were validated. 2026-07-13 13:05:23.639026 UTC - Check if resources are registered in experiment 2026-07-13 13:05:23.639379 UTC - Run: slices experiment list-resources --format json tst-187844cd 2026-07-13 13:05:24.305648 UTC - Status (on expauth): UP 2026-07-13 13:05:24.305800 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 13:05:24.305929 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-187844cd 2026-07-13 13:05:27.336261 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-187844cd tst 2026-07-13 13:05:28.588524 UTC - Run: slices experiment list-resources --format json tst-187844cd 2026-07-13 13:05:29.930573 UTC - expires_at (on expauth): 2026-07-13T16:05:00Z (correctly extended) 2026-07-13 13:05:29.930767 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 13:05:29.931030 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-187844cd 2026-07-13 13:05:34.051396 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-187844cd tst 2026-07-13 13:05:35.651844 UTC - Run: slices experiment list-resources --format json tst-187844cd 2026-07-13 13:05:36.933093 UTC - expires_at (on expauth): 2026-07-13T17:05:00Z (correctly extended) 2026-07-13 13:05:36.933442 UTC - Testing ssh login 2026-07-13 13:05:36.961522 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-187844cd tst 2026-07-13 13:05:38.638768 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-187844cd tst 2026-07-13 13:05:40.210295 UTC - Logging in using 'slices bi ssh' 2026-07-13 13:05:40.210374 UTC - Forcing IPv4 only. 2026-07-13 13:05:40.210513 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-187844cd tst -- -4 uname -a 2026-07-13 13:05:44.632442 UTC - Forcing IPv4 only. 2026-07-13 13:05:44.632759 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-187844cd tst -- -4 uptime 2026-07-13 13:05:46.915728 UTC - CLI SSH Test passed. 2026-07-13 13:05:46.915831 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-13 13:05:46.915860 UTC - Uptime: 13:05:46 up 1 min, 1 user, load average: 0.55, 0.19, 0.06 2026-07-13 13:05:46.915903 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-13 13:05:46.916488 UTC - Added paramiko HostKeyEntry for 5.53.108.155 2026-07-13 13:05:46.916593 UTC - Added paramiko HostKeyEntry for 5.53.108.155 2026-07-13 13:05:46.916886 UTC - Added paramiko HostKeyEntry for 5.53.108.155 2026-07-13 13:05:46.917093 UTC - Connecting to 5.53.108.155:22 2026-07-13 13:05:47.465515 UTC - SSH Test output: 2026-07-13 13:05:47.465590 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-13 13:05:47.465612 UTC - Uptime: 13:05:47 up 1 min, 1 user, load average: 0.55, 0.19, 0.06 2026-07-13 13:05:47.562300 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-13 13:05:47.562384 UTC - lsb_release matches expected value 2026-07-13 13:05:47.562404 UTC - SSH Test passed. 2026-07-13 13:05:47.645552 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 13:05:06 +0000! 2026-07-13 13:05:47.645714 UTC - Skip SSH proxy test because no jump proxy 2026-07-13 13:05:47.661096 UTC - Destroying tst-187844cd tst 2026-07-13 13:05:47.661336 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-187844cd tst