Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.53s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 736ms 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 866ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-17 04:03:51.931300 UTC - Installing Slices CLI (latest stable version). 2026-07-17 04:03:51.931702 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 04:03:58.507164 UTC - Slices CLI installed. 2026-07-17 04:03:58.507563 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-17 04:03:58.509372 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-17 04:03:58.510696 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 04:03:58.510870 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-17 04:03:58.510905 UTC - Slices CLI exe: 'slices' 2026-07-17 04:03:58.510923 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 04:03:58.511048 UTC - Run: slices --version 2026-07-17 04:04:09.947290 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-17 04:04:09.947810 UTC - Run: slices pubkey list --format text 2026-07-17 04:04:13.091138 UTC - Pubkey already registered 2026-07-17 04:04:13.091405 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 04:04:15.890090 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 04:04:15.890335 UTC - Check List Flavors 2026-07-17 04:04:15.890511 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-17 04:04:18.799767 UTC - Check List DiskImages 2026-07-17 04:04:18.799980 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-17 04:04:20.320619 UTC - Requesting resources 2026-07-17 04:04:20.321746 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 04:04:20.321866 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9c588ef7 --user-data /tmp/tmp6np9abwf 2026-07-17 04:04:26.034026 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxq3yehaf6rs5ztneczbw6ey 2026-07-17 04:04:26.034206 UTC - Waiting until resource ready 2026-07-17 04:04:28.034546 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9c588ef7 tst 2026-07-17 04:04:31.035194 UTC - Status: IMAGING 2026-07-17 04:04:34.497996 UTC - Status: IMAGING 2026-07-17 04:04:37.971170 UTC - Status: IMAGING 2026-07-17 04:04:41.098198 UTC - Status: IMAGING 2026-07-17 04:04:43.920298 UTC - Status: IMAGING 2026-07-17 04:04:46.787604 UTC - Status: IMAGING 2026-07-17 04:04:49.756912 UTC - Status: IMAGING 2026-07-17 04:04:52.573707 UTC - Status: IMAGING 2026-07-17 04:04:55.861817 UTC - Status: IMAGING 2026-07-17 04:04:58.728412 UTC - Status: IMAGING 2026-07-17 04:05:01.930790 UTC - Status: IMAGING 2026-07-17 04:05:05.260645 UTC - Status: IMAGING 2026-07-17 04:05:09.257470 UTC - Status: IMAGING 2026-07-17 04:05:13.041781 UTC - Status: IMAGING 2026-07-17 04:05:16.327747 UTC - Status: IMAGING 2026-07-17 04:05:19.293980 UTC - Status: IMAGING 2026-07-17 04:05:22.535210 UTC - Status: IMAGING 2026-07-17 04:05:25.401694 UTC - Status: IMAGING 2026-07-17 04:05:28.422080 UTC - Status: IMAGING 2026-07-17 04:05:31.544134 UTC - Status: IMAGING 2026-07-17 04:05:34.933076 UTC - Status: IMAGING 2026-07-17 04:05:38.179321 UTC - Status: IMAGING 2026-07-17 04:05:41.684206 UTC - Status: IMAGING 2026-07-17 04:05:45.290825 UTC - Status: IMAGING 2026-07-17 04:05:49.004558 UTC - Status: IMAGING 2026-07-17 04:05:52.782563 UTC - Status: IMAGING 2026-07-17 04:05:55.548345 UTC - Status: IMAGING 2026-07-17 04:05:58.516560 UTC - Status: IMAGING 2026-07-17 04:06:01.439348 UTC - Status: IMAGING 2026-07-17 04:06:04.917651 UTC - Status: IMAGING 2026-07-17 04:06:08.248754 UTC - Status: IMAGING 2026-07-17 04:06:11.018577 UTC - Status: UP 2026-07-17 04:06:11.018665 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxq3yd1xewyra55n4h0ft5a1 2026-07-17 04:06:11.018705 UTC - Validate resources 2026-07-17 04:06:12.005834 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-17 04:06:12.005991 UTC - The fields of the created resource were validated. 2026-07-17 04:06:12.006080 UTC - Check if resources are registered in experiment 2026-07-17 04:06:12.006266 UTC - Run: slices experiment list-resources --format json tst-9c588ef7 2026-07-17 04:06:12.981096 UTC - Status (on expauth): UP 2026-07-17 04:06:12.981279 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 04:06:12.981405 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-9c588ef7 2026-07-17 04:06:16.221008 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9c588ef7 tst 2026-07-17 04:06:17.040631 UTC - Run: slices experiment list-resources --format json tst-9c588ef7 2026-07-17 04:06:17.871608 UTC - expires_at (on expauth): 2026-07-17T07:06:00Z (correctly extended) 2026-07-17 04:06:17.871742 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 04:06:17.871906 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-9c588ef7 2026-07-17 04:06:21.332044 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9c588ef7 tst 2026-07-17 04:06:22.659205 UTC - Run: slices experiment list-resources --format json tst-9c588ef7 2026-07-17 04:06:23.803626 UTC - expires_at (on expauth): 2026-07-17T08:06:00Z (correctly extended) 2026-07-17 04:06:23.803754 UTC - Testing ssh login 2026-07-17 04:06:23.828077 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9c588ef7 tst 2026-07-17 04:06:25.526910 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9c588ef7 tst 2026-07-17 04:06:26.449705 UTC - Logging in using 'slices bi ssh' 2026-07-17 04:06:26.449790 UTC - Forcing IPv4 only. 2026-07-17 04:06:26.449909 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9c588ef7 tst -- -4 uname -a 2026-07-17 04:06:29.605736 UTC - Forcing IPv4 only. 2026-07-17 04:06:29.606065 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9c588ef7 tst -- -4 uptime 2026-07-17 04:06:31.023411 UTC - CLI SSH Test passed. 2026-07-17 04:06:31.023481 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-17 04:06:31.023500 UTC - Uptime: 04:06:30 up 1 min, 1 user, load average: 0.46, 0.15, 0.05 2026-07-17 04:06:31.023537 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-17 04:06:31.024085 UTC - Added paramiko HostKeyEntry for 5.53.108.198 2026-07-17 04:06:31.024171 UTC - Added paramiko HostKeyEntry for 5.53.108.198 2026-07-17 04:06:31.024334 UTC - Added paramiko HostKeyEntry for 5.53.108.198 2026-07-17 04:06:31.024511 UTC - Connecting to 5.53.108.198:22 2026-07-17 04:06:31.587247 UTC - SSH Test output: 2026-07-17 04:06:31.587332 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-17 04:06:31.587356 UTC - Uptime: 04:06:31 up 1 min, 1 user, load average: 0.46, 0.15, 0.05 2026-07-17 04:06:31.683767 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 04:06:31.683863 UTC - lsb_release matches expected value 2026-07-17 04:06:31.683884 UTC - SSH Test passed. 2026-07-17 04:06:31.766678 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 04:05:54 +0000! 2026-07-17 04:06:31.766862 UTC - Skip SSH proxy test because no jump proxy 2026-07-17 04:06:31.792652 UTC - Destroying tst-9c588ef7 tst 2026-07-17 04:06:31.793030 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-9c588ef7 tst