Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 9.40s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 396ms 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.19s + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-14 20:03:13.695354 UTC - Installing Slices CLI (latest stable version). 2026-07-14 20:03:13.695585 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 20:03:20.783232 UTC - Slices CLI installed. 2026-07-14 20:03:20.783773 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-14 20:03:20.787520 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-14 20:03:20.788659 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 20:03:20.788990 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-14 20:03:20.789035 UTC - Slices CLI exe: 'slices' 2026-07-14 20:03:20.789054 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 20:03:20.789192 UTC - Run: slices --version 2026-07-14 20:03:24.695959 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-14 20:03:24.696265 UTC - Run: slices pubkey list --format text 2026-07-14 20:03:25.823396 UTC - Pubkey already registered 2026-07-14 20:03:25.823660 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 20:03:27.113799 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 20:03:27.113894 UTC - Check List Flavors 2026-07-14 20:03:27.114044 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-14 20:03:28.997497 UTC - Check List DiskImages 2026-07-14 20:03:28.997700 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-14 20:03:31.068452 UTC - Requesting resources 2026-07-14 20:03:31.069643 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 20:03:31.069771 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-bed0ff07 --user-data /tmp/tmpgqx6mw4i 2026-07-14 20:03:36.232562 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxh3mk1kf6rve6wtn1f3y75b 2026-07-14 20:03:36.232770 UTC - Waiting until resource ready 2026-07-14 20:03:38.233099 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bed0ff07 tst 2026-07-14 20:03:40.600365 UTC - Status: IMAGING 2026-07-14 20:03:45.491148 UTC - Status: IMAGING 2026-07-14 20:03:49.865676 UTC - Status: IMAGING 2026-07-14 20:03:53.099825 UTC - Status: IMAGING 2026-07-14 20:03:56.073979 UTC - Status: IMAGING 2026-07-14 20:03:58.892102 UTC - Status: IMAGING 2026-07-14 20:04:01.813391 UTC - Status: IMAGING 2026-07-14 20:04:05.066384 UTC - Status: IMAGING 2026-07-14 20:04:08.147184 UTC - Status: IMAGING 2026-07-14 20:04:11.421208 UTC - Status: IMAGING 2026-07-14 20:04:14.855400 UTC - Status: IMAGING 2026-07-14 20:04:18.157067 UTC - Status: IMAGING 2026-07-14 20:04:23.047984 UTC - Status: IMAGING 2026-07-14 20:04:27.827369 UTC - Status: IMAGING 2026-07-14 20:04:31.158415 UTC - Status: IMAGING 2026-07-14 20:04:34.700296 UTC - Status: IMAGING 2026-07-14 20:04:38.679521 UTC - Status: IMAGING 2026-07-14 20:04:42.269061 UTC - Status: IMAGING 2026-07-14 20:04:45.747355 UTC - Status: IMAGING 2026-07-14 20:04:48.867801 UTC - Status: IMAGING 2026-07-14 20:04:52.037612 UTC - Status: IMAGING 2026-07-14 20:04:54.855544 UTC - Status: IMAGING 2026-07-14 20:04:57.872527 UTC - Status: IMAGING 2026-07-14 20:05:00.692824 UTC - Status: IMAGING 2026-07-14 20:05:03.609149 UTC - Status: IMAGING 2026-07-14 20:05:06.375296 UTC - Status: IMAGING 2026-07-14 20:05:09.141221 UTC - Status: IMAGING 2026-07-14 20:05:11.907090 UTC - Status: IMAGING 2026-07-14 20:05:14.673993 UTC - Status: IMAGING 2026-07-14 20:05:17.440041 UTC - Status: IMAGING 2026-07-14 20:05:20.155796 UTC - Status: IMAGING 2026-07-14 20:05:22.994546 UTC - Status: UP 2026-07-14 20:05:22.994650 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxh3mhvpej0vdy4aa00n9dgp 2026-07-14 20:05:22.994701 UTC - Validate resources 2026-07-14 20:05:24.064673 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-14 20:05:24.064791 UTC - The fields of the created resource were validated. 2026-07-14 20:05:24.064831 UTC - Check if resources are registered in experiment 2026-07-14 20:05:24.064999 UTC - Run: slices experiment list-resources --format json tst-bed0ff07 2026-07-14 20:05:24.880988 UTC - Status (on expauth): UP 2026-07-14 20:05:24.881133 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 20:05:24.881274 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-bed0ff07 2026-07-14 20:05:28.609606 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bed0ff07 tst 2026-07-14 20:05:29.957426 UTC - Run: slices experiment list-resources --format json tst-bed0ff07 2026-07-14 20:05:30.672835 UTC - expires_at (on expauth): 2026-07-14T23:05:00Z (correctly extended) 2026-07-14 20:05:30.672957 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 20:05:30.673140 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-bed0ff07 2026-07-14 20:05:34.170206 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-bed0ff07 tst 2026-07-14 20:05:35.578605 UTC - Run: slices experiment list-resources --format json tst-bed0ff07 2026-07-14 20:05:36.770428 UTC - expires_at (on expauth): 2026-07-15T00:05:00Z (correctly extended) 2026-07-14 20:05:36.770571 UTC - Testing ssh login 2026-07-14 20:05:36.797458 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-bed0ff07 tst 2026-07-14 20:05:38.204154 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-bed0ff07 tst 2026-07-14 20:05:40.036839 UTC - Logging in using 'slices bi ssh' 2026-07-14 20:05:40.036943 UTC - Forcing IPv4 only. 2026-07-14 20:05:40.037079 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-bed0ff07 tst -- -4 uname -a 2026-07-14 20:05:44.168173 UTC - Forcing IPv4 only. 2026-07-14 20:05:44.168380 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-bed0ff07 tst -- -4 uptime 2026-07-14 20:05:46.397180 UTC - CLI SSH Test passed. 2026-07-14 20:05:46.397248 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-14 20:05:46.397268 UTC - Uptime: 20:05:46 up 1 min, 1 user, load average: 0.52, 0.18, 0.06 2026-07-14 20:05:46.397302 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-14 20:05:46.397966 UTC - Added paramiko HostKeyEntry for 5.53.108.171 2026-07-14 20:05:46.398086 UTC - Added paramiko HostKeyEntry for 5.53.108.171 2026-07-14 20:05:46.398235 UTC - Added paramiko HostKeyEntry for 5.53.108.171 2026-07-14 20:05:46.398400 UTC - Connecting to 5.53.108.171:22 2026-07-14 20:05:46.971443 UTC - SSH Test output: 2026-07-14 20:05:46.971531 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-14 20:05:46.971564 UTC - Uptime: 20:05:46 up 1 min, 1 user, load average: 0.52, 0.18, 0.06 2026-07-14 20:05:47.067361 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-14 20:05:47.067440 UTC - lsb_release matches expected value 2026-07-14 20:05:47.067460 UTC - SSH Test passed. 2026-07-14 20:05:47.156492 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 20:05:04 +0000! 2026-07-14 20:05:47.158100 UTC - Skip SSH proxy test because no jump proxy 2026-07-14 20:05:47.219152 UTC - Destroying tst-bed0ff07 tst 2026-07-14 20:05:47.219431 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-bed0ff07 tst