Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.47s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 748ms 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 994ms + 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.dev187+g005dd054e (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-27 04:03:26.935795 UTC - Installing Slices CLI (latest stable version). 2026-07-27 04:03:26.936974 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 04:03:35.418624 UTC - Slices CLI installed. 2026-07-27 04:03:35.419693 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-27 04:03:35.421228 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-27 04:03:35.422265 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 04:03:35.422391 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-27 04:03:35.422418 UTC - Slices CLI exe: 'slices' 2026-07-27 04:03:35.422437 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 04:03:35.422554 UTC - Run: slices --version 2026-07-27 04:03:41.932385 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-27 04:03:41.932666 UTC - Run: slices pubkey list --format text 2026-07-27 04:03:42.698362 UTC - Pubkey already registered 2026-07-27 04:03:42.698667 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 04:03:43.620251 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 04:03:43.620357 UTC - Check List Flavors 2026-07-27 04:03:43.620511 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-27 04:03:44.486305 UTC - Check List DiskImages 2026-07-27 04:03:44.486492 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-27 04:03:45.556489 UTC - Requesting resources 2026-07-27 04:03:45.557056 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 04:03:45.557192 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-bf1cc3bd --user-data /tmp/tmpgvi0ds60 2026-07-27 04:03:47.647771 UTC - Resource ID: r_ch-geneva-bi-vm1_01kygvwfm1fcsrn9jpe612cc3r 2026-07-27 04:03:47.647962 UTC - Waiting until resource ready 2026-07-27 04:03:49.648336 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bf1cc3bd tst 2026-07-27 04:03:50.769903 UTC - Status: IMAGING 2026-07-27 04:03:53.747764 UTC - Status: IMAGING 2026-07-27 04:03:56.664905 UTC - Status: IMAGING 2026-07-27 04:03:59.959419 UTC - Status: IMAGING 2026-07-27 04:04:02.983546 UTC - Status: IMAGING 2026-07-27 04:04:06.007492 UTC - Status: IMAGING 2026-07-27 04:04:08.968999 UTC - Status: IMAGING 2026-07-27 04:04:11.835365 UTC - Status: IMAGING 2026-07-27 04:04:14.706987 UTC - Status: IMAGING 2026-07-27 04:04:17.541832 UTC - Status: IMAGING 2026-07-27 04:04:20.820072 UTC - Status: IMAGING 2026-07-27 04:04:23.859140 UTC - Status: IMAGING 2026-07-27 04:04:26.920859 UTC - Status: IMAGING 2026-07-27 04:04:29.738018 UTC - Status: IMAGING 2026-07-27 04:04:32.902022 UTC - Status: IMAGING 2026-07-27 04:04:36.021559 UTC - Status: IMAGING 2026-07-27 04:04:39.717548 UTC - Status: IMAGING 2026-07-27 04:04:43.347125 UTC - Status: IMAGING 2026-07-27 04:04:46.554517 UTC - Status: IMAGING 2026-07-27 04:04:49.371416 UTC - Status: IMAGING 2026-07-27 04:04:52.195398 UTC - Status: IMAGING 2026-07-27 04:04:55.061651 UTC - Status: IMAGING 2026-07-27 04:04:58.341625 UTC - Status: IMAGING 2026-07-27 04:05:01.366049 UTC - Status: IMAGING 2026-07-27 04:05:04.132856 UTC - Status: IMAGING 2026-07-27 04:05:07.201851 UTC - Status: IMAGING 2026-07-27 04:05:10.018509 UTC - Status: IMAGING 2026-07-27 04:05:12.837001 UTC - Status: IMAGING 2026-07-27 04:05:15.602979 UTC - Status: IMAGING 2026-07-27 04:05:18.369078 UTC - Status: IMAGING 2026-07-27 04:05:21.235571 UTC - Status: IMAGING 2026-07-27 04:05:24.408282 UTC - Status: IMAGING 2026-07-27 04:05:27.920271 UTC - Status: IMAGING 2026-07-27 04:05:30.856351 UTC - Status: IMAGING 2026-07-27 04:05:34.285092 UTC - Status: IMAGING 2026-07-27 04:05:37.588614 UTC - Status: IMAGING 2026-07-27 04:05:40.729058 UTC - Status: UP 2026-07-27 04:05:40.729147 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kygvwf4pf9mvwg32a2fqr88r 2026-07-27 04:05:40.729188 UTC - Validate resources 2026-07-27 04:05:41.857775 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-27 04:05:41.857845 UTC - The fields of the created resource were validated. 2026-07-27 04:05:41.857883 UTC - Check if resources are registered in experiment 2026-07-27 04:05:41.858029 UTC - Run: slices experiment list-resources --format json tst-bf1cc3bd 2026-07-27 04:05:42.783458 UTC - Status (on expauth): UP 2026-07-27 04:05:42.783590 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 04:05:42.783701 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-bf1cc3bd 2026-07-27 04:05:44.104361 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bf1cc3bd tst 2026-07-27 04:05:45.021886 UTC - Run: slices experiment list-resources --format json tst-bf1cc3bd 2026-07-27 04:05:45.737597 UTC - expires_at (on expauth): 2026-07-27T07:05:00Z (correctly extended) 2026-07-27 04:05:45.737724 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 04:05:45.737894 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-bf1cc3bd 2026-07-27 04:05:47.159698 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bf1cc3bd tst 2026-07-27 04:05:47.976409 UTC - Run: slices experiment list-resources --format json tst-bf1cc3bd 2026-07-27 04:05:48.642426 UTC - expires_at (on expauth): 2026-07-27T08:05:00Z (correctly extended) 2026-07-27 04:05:48.642536 UTC - Testing ssh login 2026-07-27 04:05:48.656361 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-bf1cc3bd tst 2026-07-27 04:05:49.623092 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-bf1cc3bd tst 2026-07-27 04:05:50.823173 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:05:50.823272 UTC - Forcing IPv4 only. 2026-07-27 04:05:50.823422 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-bf1cc3bd tst -- -4 uname -a 2026-07-27 04:05:54.048152 UTC - Forcing IPv4 only. 2026-07-27 04:05:54.048363 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-bf1cc3bd tst -- -4 uptime 2026-07-27 04:05:55.417756 UTC - CLI SSH Test passed. 2026-07-27 04:05:55.417831 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-27 04:05:55.417851 UTC - Uptime: 04:05:55 up 1 min, 1 user, load average: 0.51, 0.16, 0.05 2026-07-27 04:05:55.417884 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-27 04:05:55.418416 UTC - Added paramiko HostKeyEntry for 5.53.108.37 2026-07-27 04:05:55.418528 UTC - Added paramiko HostKeyEntry for 5.53.108.37 2026-07-27 04:05:55.418678 UTC - Added paramiko HostKeyEntry for 5.53.108.37 2026-07-27 04:05:55.418859 UTC - Connecting to 5.53.108.37:22 2026-07-27 04:05:55.969965 UTC - SSH Test output: 2026-07-27 04:05:55.970041 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-27 04:05:55.970062 UTC - Uptime: 04:05:55 up 1 min, 1 user, load average: 0.51, 0.16, 0.05 2026-07-27 04:05:56.067316 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-27 04:05:56.067399 UTC - lsb_release matches expected value 2026-07-27 04:05:56.067418 UTC - SSH Test passed. 2026-07-27 04:05:56.151532 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 04:05:21 +0000! 2026-07-27 04:05:56.151834 UTC - Skip SSH proxy test because no jump proxy 2026-07-27 04:05:56.161073 UTC - Destroying tst-bf1cc3bd tst 2026-07-27 04:05:56.161324 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-bf1cc3bd tst