Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 20.03s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 204ms 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 388ms + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-10 20:03:04.955959 UTC - Installing Slices CLI (latest stable version). 2026-07-10 20:03:04.956205 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 20:03:17.407183 UTC - Slices CLI installed. 2026-07-10 20:03:17.408235 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-10 20:03:17.409705 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-10 20:03:17.411661 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 20:03:17.411833 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-10 20:03:17.411868 UTC - Slices CLI exe: 'slices' 2026-07-10 20:03:17.411885 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 20:03:17.412010 UTC - Run: slices --version 2026-07-10 20:03:23.712147 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-10 20:03:23.713627 UTC - Run: slices pubkey list --format text 2026-07-10 20:03:26.061392 UTC - Pubkey already registered 2026-07-10 20:03:26.061645 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 20:03:27.837129 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 20:03:27.837221 UTC - Check List Flavors 2026-07-10 20:03:27.837357 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-10 20:03:30.779777 UTC - Check List DiskImages 2026-07-10 20:03:30.779965 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-10 20:03:32.877504 UTC - Requesting resources 2026-07-10 20:03:32.878676 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 20:03:32.878846 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-378d9f10 --user-data /tmp/tmp4u2rq30u 2026-07-10 20:03:36.555850 UTC - Resource ID: r_ch-geneva-bi-vm1_01kx6t1qh7fk8r18r6jnzs8j07 2026-07-10 20:03:36.556027 UTC - Waiting until resource ready 2026-07-10 20:03:38.556335 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-378d9f10 tst 2026-07-10 20:03:40.878827 UTC - Status: IMAGING 2026-07-10 20:03:44.665940 UTC - Status: IMAGING 2026-07-10 20:03:49.251162 UTC - Status: IMAGING 2026-07-10 20:03:52.429593 UTC - Status: IMAGING 2026-07-10 20:03:55.446581 UTC - Status: IMAGING 2026-07-10 20:04:00.203220 UTC - Status: IMAGING 2026-07-10 20:04:04.141113 UTC - Status: IMAGING 2026-07-10 20:04:07.977122 UTC - Status: IMAGING 2026-07-10 20:04:12.242490 UTC - Status: IMAGING 2026-07-10 20:04:15.347234 UTC - Status: IMAGING 2026-07-10 20:04:18.520900 UTC - Status: IMAGING 2026-07-10 20:04:21.979626 UTC - Status: IMAGING 2026-07-10 20:04:24.808702 UTC - Status: IMAGING 2026-07-10 20:04:28.582114 UTC - Status: IMAGING 2026-07-10 20:04:31.411458 UTC - Status: IMAGING 2026-07-10 20:04:34.277814 UTC - Status: IMAGING 2026-07-10 20:04:37.044065 UTC - Status: IMAGING 2026-07-10 20:04:39.860667 UTC - Status: IMAGING 2026-07-10 20:04:42.677108 UTC - Status: IMAGING 2026-07-10 20:04:45.493563 UTC - Status: IMAGING 2026-07-10 20:04:48.259722 UTC - Status: IMAGING 2026-07-10 20:04:51.025747 UTC - Status: IMAGING 2026-07-10 20:04:53.858115 UTC - Status: IMAGING 2026-07-10 20:04:56.633993 UTC - Status: IMAGING 2026-07-10 20:04:59.400537 UTC - Status: IMAGING 2026-07-10 20:05:02.216709 UTC - Status: IMAGING 2026-07-10 20:05:05.033076 UTC - Status: IMAGING 2026-07-10 20:05:07.849126 UTC - Status: IMAGING 2026-07-10 20:05:10.665774 UTC - Status: IMAGING 2026-07-10 20:05:13.431635 UTC - Status: IMAGING 2026-07-10 20:05:16.197937 UTC - Status: IMAGING 2026-07-10 20:05:19.115495 UTC - Status: IMAGING 2026-07-10 20:05:22.140350 UTC - Status: IMAGING 2026-07-10 20:05:25.012043 UTC - Status: IMAGING 2026-07-10 20:05:28.095186 UTC - Status: IMAGING 2026-07-10 20:05:31.125170 UTC - Status: IMAGING 2026-07-10 20:05:34.508261 UTC - Status: UP 2026-07-10 20:05:34.508874 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx6t1pfqes7tbmk296nh15rg 2026-07-10 20:05:34.508942 UTC - Validate resources 2026-07-10 20:05:35.848497 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-10 20:05:35.849316 UTC - The fields of the created resource were validated. 2026-07-10 20:05:35.849379 UTC - Check if resources are registered in experiment 2026-07-10 20:05:35.849548 UTC - Run: slices experiment list-resources --format json tst-378d9f10 2026-07-10 20:05:37.377146 UTC - Status (on expauth): UP 2026-07-10 20:05:37.380173 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 20:05:37.380355 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-378d9f10 2026-07-10 20:05:41.020043 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-378d9f10 tst 2026-07-10 20:05:43.163336 UTC - Run: slices experiment list-resources --format json tst-378d9f10 2026-07-10 20:05:44.706288 UTC - expires_at (on expauth): 2026-07-10T23:05:00Z (correctly extended) 2026-07-10 20:05:44.706403 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 20:05:44.706598 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-378d9f10 2026-07-10 20:05:48.011781 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-378d9f10 tst 2026-07-10 20:05:50.044472 UTC - Run: slices experiment list-resources --format json tst-378d9f10 2026-07-10 20:05:51.745969 UTC - expires_at (on expauth): 2026-07-11T00:05:00Z (correctly extended) 2026-07-10 20:05:51.746088 UTC - Testing ssh login 2026-07-10 20:05:51.764493 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-378d9f10 tst 2026-07-10 20:05:53.403421 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-378d9f10 tst 2026-07-10 20:05:55.046402 UTC - Logging in using 'slices bi ssh' 2026-07-10 20:05:55.046514 UTC - Forcing IPv4 only. 2026-07-10 20:05:55.046666 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-378d9f10 tst -- -4 uname -a 2026-07-10 20:05:58.131381 UTC - Forcing IPv4 only. 2026-07-10 20:05:58.131567 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-378d9f10 tst -- -4 uptime 2026-07-10 20:05:59.817235 UTC - CLI SSH Test passed. 2026-07-10 20:05:59.817315 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-10 20:05:59.817334 UTC - Uptime: 20:05:59 up 1 min, 1 user, load average: 0.28, 0.09, 0.03 2026-07-10 20:05:59.817368 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-10 20:05:59.817882 UTC - Added paramiko HostKeyEntry for 5.53.108.56 2026-07-10 20:05:59.817987 UTC - Added paramiko HostKeyEntry for 5.53.108.56 2026-07-10 20:05:59.818131 UTC - Added paramiko HostKeyEntry for 5.53.108.56 2026-07-10 20:05:59.818491 UTC - Connecting to 5.53.108.56:22 2026-07-10 20:06:00.364459 UTC - SSH Test output: 2026-07-10 20:06:00.364542 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-10 20:06:00.364563 UTC - Uptime: 20:06:00 up 1 min, 1 user, load average: 0.28, 0.09, 0.03 2026-07-10 20:06:00.462580 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 20:06:00.462662 UTC - lsb_release matches expected value 2026-07-10 20:06:00.462683 UTC - SSH Test passed. 2026-07-10 20:06:00.546230 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 20:05:11 +0000! 2026-07-10 20:06:00.546387 UTC - Skip SSH proxy test because no jump proxy 2026-07-10 20:06:00.554421 UTC - Destroying tst-378d9f10 tst 2026-07-10 20:06:00.554643 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-378d9f10 tst