Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.01s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 318ms 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 958ms + 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-25 20:03:59.060667 UTC - Installing Slices CLI (latest stable version). 2026-07-25 20:03:59.064781 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 20:04:06.415154 UTC - Slices CLI installed. 2026-07-25 20:04:06.416747 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-25 20:04:06.419708 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-25 20:04:06.420800 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 20:04:06.420951 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-25 20:04:06.420983 UTC - Slices CLI exe: 'slices' 2026-07-25 20:04:06.421001 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 20:04:06.421411 UTC - Run: slices --version 2026-07-25 20:04:10.214313 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-25 20:04:10.214746 UTC - Run: slices pubkey list --format text 2026-07-25 20:04:11.234398 UTC - Pubkey already registered 2026-07-25 20:04:11.234652 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 20:04:11.953581 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 20:04:11.953679 UTC - Check List Flavors 2026-07-25 20:04:11.953813 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-25 20:04:13.982720 UTC - Check List DiskImages 2026-07-25 20:04:13.983082 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-25 20:04:15.261075 UTC - Requesting resources 2026-07-25 20:04:15.261618 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 20:04:15.261737 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5ccc6c45 --user-data /tmp/tmpm_tvz7g4 2026-07-25 20:04:16.790632 UTC - Resource ID: r_ch-geneva-bi-vm1_01kyde1qzre6h8xvn7r9ewdhfc 2026-07-25 20:04:16.790921 UTC - Waiting until resource ready 2026-07-25 20:04:18.791454 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5ccc6c45 tst 2026-07-25 20:04:19.858340 UTC - Status: IMAGING 2026-07-25 20:04:23.247804 UTC - Status: IMAGING 2026-07-25 20:04:26.951134 UTC - Status: IMAGING 2026-07-25 20:04:30.841794 UTC - Status: IMAGING 2026-07-25 20:04:33.783329 UTC - Status: IMAGING 2026-07-25 20:04:37.014134 UTC - Status: IMAGING 2026-07-25 20:04:40.188317 UTC - Status: IMAGING 2026-07-25 20:04:43.123028 UTC - Status: IMAGING 2026-07-25 20:04:46.147016 UTC - Status: IMAGING 2026-07-25 20:04:49.023876 UTC - Status: IMAGING 2026-07-25 20:04:52.200820 UTC - Status: IMAGING 2026-07-25 20:04:55.047537 UTC - Status: IMAGING 2026-07-25 20:04:58.438092 UTC - Status: IMAGING 2026-07-25 20:05:01.383634 UTC - Status: IMAGING 2026-07-25 20:05:04.911091 UTC - Status: IMAGING 2026-07-25 20:05:08.001413 UTC - Status: IMAGING 2026-07-25 20:05:10.817338 UTC - Status: IMAGING 2026-07-25 20:05:14.096148 UTC - Status: IMAGING 2026-07-25 20:05:17.015805 UTC - Status: IMAGING 2026-07-25 20:05:20.093346 UTC - Status: IMAGING 2026-07-25 20:05:23.063443 UTC - Status: IMAGING 2026-07-25 20:05:25.882785 UTC - Status: IMAGING 2026-07-25 20:05:29.008949 UTC - Status: IMAGING 2026-07-25 20:05:31.928201 UTC - Status: IMAGING 2026-07-25 20:05:35.097260 UTC - Status: IMAGING 2026-07-25 20:05:38.574394 UTC - Status: IMAGING 2026-07-25 20:05:42.003720 UTC - Status: IMAGING 2026-07-25 20:05:45.225368 UTC - Status: IMAGING 2026-07-25 20:05:48.355616 UTC - Status: IMAGING 2026-07-25 20:05:51.174601 UTC - Status: IMAGING 2026-07-25 20:05:53.990698 UTC - Status: IMAGING 2026-07-25 20:05:56.908326 UTC - Status: IMAGING 2026-07-25 20:06:00.085996 UTC - Status: IMAGING 2026-07-25 20:06:03.179235 UTC - Status: UP 2026-07-25 20:06:03.179344 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyde1qfqfcar1dtc85yw44eh 2026-07-25 20:06:03.179388 UTC - Validate resources 2026-07-25 20:06:04.337680 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-25 20:06:04.337752 UTC - The fields of the created resource were validated. 2026-07-25 20:06:04.337789 UTC - Check if resources are registered in experiment 2026-07-25 20:06:04.337933 UTC - Run: slices experiment list-resources --format json tst-5ccc6c45 2026-07-25 20:06:05.306010 UTC - Status (on expauth): UP 2026-07-25 20:06:05.306165 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 20:06:05.306278 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-5ccc6c45 2026-07-25 20:06:06.787338 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5ccc6c45 tst 2026-07-25 20:06:08.012898 UTC - Run: slices experiment list-resources --format json tst-5ccc6c45 2026-07-25 20:06:08.982323 UTC - expires_at (on expauth): 2026-07-25T23:06:00Z (correctly extended) 2026-07-25 20:06:08.982415 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 20:06:08.982554 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-5ccc6c45 2026-07-25 20:06:10.401937 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-5ccc6c45 tst 2026-07-25 20:06:11.271494 UTC - Run: slices experiment list-resources --format json tst-5ccc6c45 2026-07-25 20:06:12.356517 UTC - expires_at (on expauth): 2026-07-26T00:06:00Z (correctly extended) 2026-07-25 20:06:12.356642 UTC - Testing ssh login 2026-07-25 20:06:12.378107 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5ccc6c45 tst 2026-07-25 20:06:13.442419 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5ccc6c45 tst 2026-07-25 20:06:14.911171 UTC - Logging in using 'slices bi ssh' 2026-07-25 20:06:14.911251 UTC - Forcing IPv4 only. 2026-07-25 20:06:14.911373 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-5ccc6c45 tst -- -4 uname -a 2026-07-25 20:06:18.114149 UTC - Forcing IPv4 only. 2026-07-25 20:06:18.114509 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-5ccc6c45 tst -- -4 uptime 2026-07-25 20:06:19.751283 UTC - CLI SSH Test passed. 2026-07-25 20:06:19.751354 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-25 20:06:19.751374 UTC - Uptime: 20:06:19 up 1 min, 1 user, load average: 0.47, 0.13, 0.04 2026-07-25 20:06:19.751406 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-25 20:06:19.752106 UTC - Added paramiko HostKeyEntry for 5.53.108.225 2026-07-25 20:06:19.752216 UTC - Added paramiko HostKeyEntry for 5.53.108.225 2026-07-25 20:06:19.752373 UTC - Added paramiko HostKeyEntry for 5.53.108.225 2026-07-25 20:06:19.752549 UTC - Connecting to 5.53.108.225:22 2026-07-25 20:06:20.303886 UTC - SSH Test output: 2026-07-25 20:06:20.303954 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-25 20:06:20.303974 UTC - Uptime: 20:06:20 up 1 min, 1 user, load average: 0.47, 0.13, 0.04 2026-07-25 20:06:20.400932 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 20:06:20.401001 UTC - lsb_release matches expected value 2026-07-25 20:06:20.401020 UTC - SSH Test passed. 2026-07-25 20:06:20.485126 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 20:05:45 +0000! 2026-07-25 20:06:20.485259 UTC - Skip SSH proxy test because no jump proxy 2026-07-25 20:06:20.492626 UTC - Destroying tst-5ccc6c45 tst 2026-07-25 20:06:20.500902 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-5ccc6c45 tst