Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.75s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 911ms 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.06s + 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 20:03:39.233850 UTC - Installing Slices CLI (latest stable version). 2026-07-17 20:03:39.236812 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 20:03:46.010237 UTC - Slices CLI installed. 2026-07-17 20:03:46.011510 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 20:03:46.013143 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 20:03:46.013973 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 20:03:46.014088 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 20:03:46.014116 UTC - Slices CLI exe: 'slices' 2026-07-17 20:03:46.014132 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 20:03:46.015037 UTC - Run: slices --version 2026-07-17 20:03:56.026478 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 20:03:56.029156 UTC - Run: slices pubkey list --format text 2026-07-17 20:03:58.372266 UTC - Pubkey already registered 2026-07-17 20:03:58.372531 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 20:04:00.023141 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 20:04:00.023235 UTC - Check List Flavors 2026-07-17 20:04:00.023374 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-17 20:04:03.035483 UTC - Check List DiskImages 2026-07-17 20:04:03.035692 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-17 20:04:04.872450 UTC - Requesting resources 2026-07-17 20:04:04.873211 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 20:04:04.873326 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-cfb1ce74 --user-data /tmp/tmph_4p3_iu 2026-07-17 20:04:11.711295 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxrtvts0fmrar85mgchcdk8s 2026-07-17 20:04:11.711451 UTC - Waiting until resource ready 2026-07-17 20:04:13.711825 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-cfb1ce74 tst 2026-07-17 20:04:15.003390 UTC - Status: IMAGING 2026-07-17 20:04:18.181970 UTC - Status: IMAGING 2026-07-17 20:04:21.349765 UTC - Status: IMAGING 2026-07-17 20:04:24.397793 UTC - Status: IMAGING 2026-07-17 20:04:27.973496 UTC - Status: IMAGING 2026-07-17 20:04:31.097297 UTC - Status: IMAGING 2026-07-17 20:04:34.780005 UTC - Status: IMAGING 2026-07-17 20:04:38.215116 UTC - Status: IMAGING 2026-07-17 20:04:41.897499 UTC - Status: IMAGING 2026-07-17 20:04:45.436918 UTC - Status: IMAGING 2026-07-17 20:04:48.756773 UTC - Status: IMAGING 2026-07-17 20:04:51.623523 UTC - Status: IMAGING 2026-07-17 20:04:54.692949 UTC - Status: IMAGING 2026-07-17 20:04:57.711697 UTC - Status: IMAGING 2026-07-17 20:05:00.632412 UTC - Status: IMAGING 2026-07-17 20:05:03.451035 UTC - Status: IMAGING 2026-07-17 20:05:06.217060 UTC - Status: IMAGING 2026-07-17 20:05:09.032958 UTC - Status: IMAGING 2026-07-17 20:05:11.848646 UTC - Status: IMAGING 2026-07-17 20:05:14.874879 UTC - Status: IMAGING 2026-07-17 20:05:17.691145 UTC - Status: IMAGING 2026-07-17 20:05:20.456918 UTC - Status: IMAGING 2026-07-17 20:05:23.273197 UTC - Status: IMAGING 2026-07-17 20:05:26.089071 UTC - Status: IMAGING 2026-07-17 20:05:29.166072 UTC - Status: IMAGING 2026-07-17 20:05:32.141546 UTC - Status: IMAGING 2026-07-17 20:05:35.689145 UTC - Status: IMAGING 2026-07-17 20:05:38.764491 UTC - Status: IMAGING 2026-07-17 20:05:42.734268 UTC - Status: IMAGING 2026-07-17 20:05:46.027722 UTC - Status: IMAGING 2026-07-17 20:05:50.143034 UTC - Status: IMAGING 2026-07-17 20:05:53.071044 UTC - Status: IMAGING 2026-07-17 20:05:56.139743 UTC - Status: UP 2026-07-17 20:05:56.139839 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxrtvs7hetx95f7ea399rjt7 2026-07-17 20:05:56.139883 UTC - Validate resources 2026-07-17 20:05:57.219332 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 20:05:57.219483 UTC - The fields of the created resource were validated. 2026-07-17 20:05:57.219528 UTC - Check if resources are registered in experiment 2026-07-17 20:05:57.219714 UTC - Run: slices experiment list-resources --format json tst-cfb1ce74 2026-07-17 20:05:57.885655 UTC - Status (on expauth): UP 2026-07-17 20:05:57.885793 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 20:05:57.885907 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-cfb1ce74 2026-07-17 20:06:01.658982 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-cfb1ce74 tst 2026-07-17 20:06:02.482185 UTC - Run: slices experiment list-resources --format json tst-cfb1ce74 2026-07-17 20:06:03.147475 UTC - expires_at (on expauth): 2026-07-17T23:05:00Z (correctly extended) 2026-07-17 20:06:03.147568 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 20:06:03.147705 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-cfb1ce74 2026-07-17 20:06:07.501613 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-cfb1ce74 tst 2026-07-17 20:06:09.053169 UTC - Run: slices experiment list-resources --format json tst-cfb1ce74 2026-07-17 20:06:10.255693 UTC - expires_at (on expauth): 2026-07-18T00:06:00Z (correctly extended) 2026-07-17 20:06:10.255811 UTC - Testing ssh login 2026-07-17 20:06:10.280907 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cfb1ce74 tst 2026-07-17 20:06:11.310881 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cfb1ce74 tst 2026-07-17 20:06:12.363808 UTC - Logging in using 'slices bi ssh' 2026-07-17 20:06:12.363916 UTC - Forcing IPv4 only. 2026-07-17 20:06:12.364056 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-cfb1ce74 tst -- -4 uname -a 2026-07-17 20:06:16.318720 UTC - Forcing IPv4 only. 2026-07-17 20:06:16.319070 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-cfb1ce74 tst -- -4 uptime 2026-07-17 20:06:18.318251 UTC - CLI SSH Test passed. 2026-07-17 20:06:18.318335 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 20:06:18.318364 UTC - Uptime: 20:06:18 up 1 min, 1 user, load average: 0.25, 0.08, 0.03 2026-07-17 20:06:18.318401 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-17 20:06:18.319223 UTC - Added paramiko HostKeyEntry for 5.53.108.153 2026-07-17 20:06:18.319317 UTC - Added paramiko HostKeyEntry for 5.53.108.153 2026-07-17 20:06:18.319482 UTC - Added paramiko HostKeyEntry for 5.53.108.153 2026-07-17 20:06:18.319896 UTC - Connecting to 5.53.108.153:22 2026-07-17 20:06:18.878820 UTC - SSH Test output: 2026-07-17 20:06:18.878912 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 20:06:18.878943 UTC - Uptime: 20:06:18 up 1 min, 1 user, load average: 0.25, 0.08, 0.03 2026-07-17 20:06:18.977135 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 20:06:18.977213 UTC - lsb_release matches expected value 2026-07-17 20:06:18.977234 UTC - SSH Test passed. 2026-07-17 20:06:19.061326 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 20:05:41 +0000! 2026-07-17 20:06:19.061477 UTC - Skip SSH proxy test because no jump proxy 2026-07-17 20:06:19.070065 UTC - Destroying tst-cfb1ce74 tst 2026-07-17 20:06:19.070235 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-cfb1ce74 tst