Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.29s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 462ms 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 856ms + 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.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.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-20 13:03:57.257343 UTC - Installing Slices CLI (latest stable version). 2026-07-20 13:03:57.257969 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 13:04:04.328437 UTC - Slices CLI installed. 2026-07-20 13:04:04.328642 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-20 13:04:04.331014 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-20 13:04:04.332384 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 13:04:04.332663 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-20 13:04:04.332700 UTC - Slices CLI exe: 'slices' 2026-07-20 13:04:04.332844 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 13:04:04.333123 UTC - Run: slices --version 2026-07-20 13:04:09.851215 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-20 13:04:09.851742 UTC - Run: slices pubkey list --format text 2026-07-20 13:04:11.496942 UTC - Pubkey already registered 2026-07-20 13:04:11.497204 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 13:04:12.925688 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 13:04:12.925807 UTC - Check List Flavors 2026-07-20 13:04:12.925960 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-20 13:04:15.061327 UTC - Check List DiskImages 2026-07-20 13:04:15.061548 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-20 13:04:16.179840 UTC - Requesting resources 2026-07-20 13:04:16.180354 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 13:04:16.180468 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-df684299 --user-data /tmp/tmp5fz4jxyi 2026-07-20 13:04:20.258252 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxzt16xce18tp7d95y6hgmjb 2026-07-20 13:04:20.258408 UTC - Waiting until resource ready 2026-07-20 13:04:22.258750 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-df684299 tst 2026-07-20 13:04:23.426542 UTC - Status: IMAGING 2026-07-20 13:04:26.592920 UTC - Status: IMAGING 2026-07-20 13:04:29.515242 UTC - Status: IMAGING 2026-07-20 13:04:32.557733 UTC - Status: IMAGING 2026-07-20 13:04:36.275828 UTC - Status: IMAGING 2026-07-20 13:04:39.456803 UTC - Status: IMAGING 2026-07-20 13:04:42.845252 UTC - Status: IMAGING 2026-07-20 13:04:45.874126 UTC - Status: IMAGING 2026-07-20 13:04:49.642605 UTC - Status: IMAGING 2026-07-20 13:04:52.509502 UTC - Status: IMAGING 2026-07-20 13:04:55.938425 UTC - Status: IMAGING 2026-07-20 13:04:59.318510 UTC - Status: IMAGING 2026-07-20 13:05:02.134682 UTC - Status: IMAGING 2026-07-20 13:05:05.221453 UTC - Status: IMAGING 2026-07-20 13:05:08.137847 UTC - Status: IMAGING 2026-07-20 13:05:10.955794 UTC - Status: IMAGING 2026-07-20 13:05:13.929175 UTC - Status: IMAGING 2026-07-20 13:05:16.747463 UTC - Status: IMAGING 2026-07-20 13:05:19.513300 UTC - Status: IMAGING 2026-07-20 13:05:22.386859 UTC - Status: IMAGING 2026-07-20 13:05:25.457449 UTC - Status: IMAGING 2026-07-20 13:05:28.901649 UTC - Status: IMAGING 2026-07-20 13:05:31.931296 UTC - Status: IMAGING 2026-07-20 13:05:35.182349 UTC - Status: IMAGING 2026-07-20 13:05:38.365224 UTC - Status: IMAGING 2026-07-20 13:05:41.488588 UTC - Status: IMAGING 2026-07-20 13:05:44.993531 UTC - Status: IMAGING 2026-07-20 13:05:47.974185 UTC - Status: IMAGING 2026-07-20 13:05:50.893692 UTC - Status: IMAGING 2026-07-20 13:05:54.271340 UTC - Status: IMAGING 2026-07-20 13:05:57.190438 UTC - Status: IMAGING 2026-07-20 13:06:00.059485 UTC - Status: IMAGING 2026-07-20 13:06:02.926112 UTC - Status: IMAGING 2026-07-20 13:06:05.893400 UTC - Status: IMAGING 2026-07-20 13:06:08.659457 UTC - Status: IMAGING 2026-07-20 13:06:11.478089 UTC - Status: UP 2026-07-20 13:06:11.478181 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxzt15nqegs9y7h4zw4h016e 2026-07-20 13:06:11.478239 UTC - Validate resources 2026-07-20 13:06:12.245427 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-20 13:06:12.245493 UTC - The fields of the created resource were validated. 2026-07-20 13:06:12.245528 UTC - Check if resources are registered in experiment 2026-07-20 13:06:12.245660 UTC - Run: slices experiment list-resources --format json tst-df684299 2026-07-20 13:06:12.910868 UTC - Status (on expauth): UP 2026-07-20 13:06:12.911000 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 13:06:12.911121 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-df684299 2026-07-20 13:06:16.183747 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-df684299 tst 2026-07-20 13:06:17.202369 UTC - Run: slices experiment list-resources --format json tst-df684299 2026-07-20 13:06:18.175133 UTC - expires_at (on expauth): 2026-07-20T16:06:00Z (correctly extended) 2026-07-20 13:06:18.175814 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 13:06:18.176017 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-df684299 2026-07-20 13:06:21.582523 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-df684299 tst 2026-07-20 13:06:22.449325 UTC - Run: slices experiment list-resources --format json tst-df684299 2026-07-20 13:06:23.239181 UTC - expires_at (on expauth): 2026-07-20T17:06:00Z (correctly extended) 2026-07-20 13:06:23.239316 UTC - Testing ssh login 2026-07-20 13:06:23.253345 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-df684299 tst 2026-07-20 13:06:24.234170 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-df684299 tst 2026-07-20 13:06:25.157610 UTC - Logging in using 'slices bi ssh' 2026-07-20 13:06:25.157689 UTC - Forcing IPv4 only. 2026-07-20 13:06:25.157813 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-df684299 tst -- -4 uname -a 2026-07-20 13:06:28.306688 UTC - Forcing IPv4 only. 2026-07-20 13:06:28.306913 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-df684299 tst -- -4 uptime 2026-07-20 13:06:29.801618 UTC - CLI SSH Test passed. 2026-07-20 13:06:29.804668 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-20 13:06:29.804774 UTC - Uptime: 13:06:29 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-20 13:06:29.804837 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-20 13:06:29.805847 UTC - Added paramiko HostKeyEntry for 5.53.108.197 2026-07-20 13:06:29.805985 UTC - Added paramiko HostKeyEntry for 5.53.108.197 2026-07-20 13:06:29.806189 UTC - Added paramiko HostKeyEntry for 5.53.108.197 2026-07-20 13:06:29.806829 UTC - Connecting to 5.53.108.197:22 2026-07-20 13:06:30.353520 UTC - SSH Test output: 2026-07-20 13:06:30.353592 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-20 13:06:30.353613 UTC - Uptime: 13:06:30 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-20 13:06:30.451805 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 13:06:30.451885 UTC - lsb_release matches expected value 2026-07-20 13:06:30.451908 UTC - SSH Test passed. 2026-07-20 13:06:30.536216 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 13:05:52 +0000! 2026-07-20 13:06:30.536368 UTC - Skip SSH proxy test because no jump proxy 2026-07-20 13:06:30.546713 UTC - Destroying tst-df684299 tst 2026-07-20 13:06:30.546894 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-df684299 tst