Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.00s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 226ms 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.03s + 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.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-07-30 13:04:09.305439 UTC - Installing Slices CLI (latest stable version). 2026-07-30 13:04:09.305814 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 13:04:15.855551 UTC - Slices CLI installed. 2026-07-30 13:04:15.855860 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-30 13:04:15.871369 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-07-30 13:04:15.871539 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-07-30 13:04:15.871655 UTC - which slices: /app/.venv/bin/slices 2026-07-30 13:04:15.871687 UTC - Slices CLI exe: 'slices' 2026-07-30 13:04:15.871706 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 13:04:15.871904 UTC - Run: slices --version 2026-07-30 13:04:20.068925 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-07-30 13:04:20.069370 UTC - Run: slices pubkey list --format text 2026-07-30 13:04:21.140504 UTC - Pubkey already registered 2026-07-30 13:04:21.140824 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 13:04:21.820268 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 13:04:21.820378 UTC - Check List Flavors 2026-07-30 13:04:21.820567 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-30 13:04:25.743693 UTC - Check List DiskImages 2026-07-30 13:04:25.743904 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-30 13:04:26.511131 UTC - Requesting resources 2026-07-30 13:04:26.511669 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 13:04:26.511789 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e64bf4b8 --user-data /tmp/tmp33iwwy4q 2026-07-30 13:04:27.879834 UTC - Resource ID: r_ch-geneva-bi-vm1_01kysj0mgxfehvqgtgp49j9ane 2026-07-30 13:04:27.879990 UTC - Waiting until resource ready 2026-07-30 13:04:29.936670 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e64bf4b8 tst 2026-07-30 13:04:31.023935 UTC - Status: IMAGING 2026-07-30 13:04:34.398413 UTC - Status: IMAGING 2026-07-30 13:04:38.367186 UTC - Status: IMAGING 2026-07-30 13:04:41.656373 UTC - Status: IMAGING 2026-07-30 13:04:44.861206 UTC - Status: IMAGING 2026-07-30 13:04:48.083788 UTC - Status: IMAGING 2026-07-30 13:04:51.241291 UTC - Status: IMAGING 2026-07-30 13:04:54.309402 UTC - Status: IMAGING 2026-07-30 13:04:57.432195 UTC - Status: IMAGING 2026-07-30 13:05:00.250316 UTC - Status: IMAGING 2026-07-30 13:05:03.432337 UTC - Status: IMAGING 2026-07-30 13:05:06.530292 UTC - Status: IMAGING 2026-07-30 13:05:10.006513 UTC - Status: IMAGING 2026-07-30 13:05:12.915371 UTC - Status: IMAGING 2026-07-30 13:05:15.790285 UTC - Status: IMAGING 2026-07-30 13:05:18.556163 UTC - Status: IMAGING 2026-07-30 13:05:21.322545 UTC - Status: IMAGING 2026-07-30 13:05:24.455812 UTC - Status: IMAGING 2026-07-30 13:05:27.519686 UTC - Status: IMAGING 2026-07-30 13:05:30.738841 UTC - Status: IMAGING 2026-07-30 13:05:33.554851 UTC - Status: IMAGING 2026-07-30 13:05:36.370679 UTC - Status: IMAGING 2026-07-30 13:05:39.186583 UTC - Status: IMAGING 2026-07-30 13:05:41.952297 UTC - Status: IMAGING 2026-07-30 13:05:44.771743 UTC - Status: IMAGING 2026-07-30 13:05:47.641639 UTC - Status: IMAGING 2026-07-30 13:05:50.508020 UTC - Status: IMAGING 2026-07-30 13:05:53.383868 UTC - Status: IMAGING 2026-07-30 13:05:56.625094 UTC - Status: IMAGING 2026-07-30 13:05:59.702306 UTC - Status: IMAGING 2026-07-30 13:06:02.738451 UTC - Status: IMAGING 2026-07-30 13:06:06.259673 UTC - Status: IMAGING 2026-07-30 13:06:10.015502 UTC - Status: IMAGING 2026-07-30 13:06:12.883286 UTC - Status: IMAGING 2026-07-30 13:06:16.040076 UTC - Status: IMAGING 2026-07-30 13:06:18.906654 UTC - Status: UP 2026-07-30 13:06:18.906843 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kysj0m2af5t9qy0t0c9whwzs 2026-07-30 13:06:18.906901 UTC - Validate resources 2026-07-30 13:06:19.776357 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-30 13:06:19.776431 UTC - The fields of the created resource were validated. 2026-07-30 13:06:19.776467 UTC - Check if resources are registered in experiment 2026-07-30 13:06:19.776615 UTC - Run: slices experiment list-resources --format json tst-e64bf4b8 2026-07-30 13:06:20.441918 UTC - Status (on expauth): UP 2026-07-30 13:06:20.442049 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 13:06:20.442167 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e64bf4b8 2026-07-30 13:06:21.715845 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e64bf4b8 tst 2026-07-30 13:06:22.531653 UTC - Run: slices experiment list-resources --format json tst-e64bf4b8 2026-07-30 13:06:23.196971 UTC - expires_at (on expauth): 2026-07-30T16:06:00Z (correctly extended) 2026-07-30 13:06:23.197058 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 13:06:23.197208 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e64bf4b8 2026-07-30 13:06:24.314385 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e64bf4b8 tst 2026-07-30 13:06:25.130443 UTC - Run: slices experiment list-resources --format json tst-e64bf4b8 2026-07-30 13:06:25.805981 UTC - expires_at (on expauth): 2026-07-30T17:06:00Z (correctly extended) 2026-07-30 13:06:25.806088 UTC - Testing ssh login 2026-07-30 13:06:25.827743 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e64bf4b8 tst 2026-07-30 13:06:26.645340 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e64bf4b8 tst 2026-07-30 13:06:27.562422 UTC - Logging in using 'slices bi ssh' 2026-07-30 13:06:27.562523 UTC - Forcing IPv4 only. 2026-07-30 13:06:27.562675 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e64bf4b8 tst -- -4 uname -a 2026-07-30 13:06:30.404415 UTC - Forcing IPv4 only. 2026-07-30 13:06:30.404593 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e64bf4b8 tst -- -4 uptime 2026-07-30 13:06:31.621140 UTC - CLI SSH Test passed. 2026-07-30 13:06:31.621213 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-30 13:06:31.621243 UTC - Uptime: 13:06:31 up 1 min, 1 user, load average: 0.66, 0.21, 0.07 2026-07-30 13:06:31.621275 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-30 13:06:31.621753 UTC - Added paramiko HostKeyEntry for 5.53.108.220 2026-07-30 13:06:31.621846 UTC - Added paramiko HostKeyEntry for 5.53.108.220 2026-07-30 13:06:31.621988 UTC - Added paramiko HostKeyEntry for 5.53.108.220 2026-07-30 13:06:31.622176 UTC - Connecting to 5.53.108.220:22 2026-07-30 13:06:32.173213 UTC - SSH Test output: 2026-07-30 13:06:32.173304 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-30 13:06:32.173325 UTC - Uptime: 13:06:32 up 1 min, 1 user, load average: 0.66, 0.21, 0.07 2026-07-30 13:06:32.270265 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 13:06:32.270341 UTC - lsb_release matches expected value 2026-07-30 13:06:32.270362 UTC - SSH Test passed. 2026-07-30 13:06:32.354557 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 13:05:59 +0000! 2026-07-30 13:06:32.354727 UTC - Skip SSH proxy test because no jump proxy 2026-07-30 13:06:32.365850 UTC - Destroying tst-e64bf4b8 tst 2026-07-30 13:06:32.366106 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e64bf4b8 tst