Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.73s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 515ms 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 886ms + 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 + 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 13:03:22.560558 UTC - Installing Slices CLI (latest stable version). 2026-07-25 13:03:22.561201 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 13:03:28.758818 UTC - Slices CLI installed. 2026-07-25 13:03:28.759034 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 13:03:28.761027 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 13:03:28.762167 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 13:03:28.762290 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 13:03:28.762318 UTC - Slices CLI exe: 'slices' 2026-07-25 13:03:28.762335 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 13:03:28.762455 UTC - Run: slices --version 2026-07-25 13:03:32.189690 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 13:03:32.189981 UTC - Run: slices pubkey list --format text 2026-07-25 13:03:33.660624 UTC - Pubkey already registered 2026-07-25 13:03:33.660929 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 13:03:35.010051 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 13:03:35.010153 UTC - Check List Flavors 2026-07-25 13:03:35.010317 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-25 13:03:36.485494 UTC - Check List DiskImages 2026-07-25 13:03:36.485674 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-25 13:03:37.730907 UTC - Requesting resources 2026-07-25 13:03:37.738372 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 13:03:37.738513 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-46c727db --user-data /tmp/tmpxdiv8wmd 2026-07-25 13:03:39.518165 UTC - Resource ID: r_ch-geneva-bi-vm1_01kycnzj4rfhrvdn6932htgmp0 2026-07-25 13:03:39.518320 UTC - Waiting until resource ready 2026-07-25 13:03:41.518696 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-46c727db tst 2026-07-25 13:03:42.757133 UTC - Status: IMAGING 2026-07-25 13:03:45.784360 UTC - Status: IMAGING 2026-07-25 13:03:48.804259 UTC - Status: IMAGING 2026-07-25 13:03:52.334386 UTC - Status: IMAGING 2026-07-25 13:03:55.768301 UTC - Status: IMAGING 2026-07-25 13:03:59.068019 UTC - Status: IMAGING 2026-07-25 13:04:01.985121 UTC - Status: IMAGING 2026-07-25 13:04:05.166451 UTC - Status: IMAGING 2026-07-25 13:04:08.675461 UTC - Status: IMAGING 2026-07-25 13:04:11.958745 UTC - Status: IMAGING 2026-07-25 13:04:15.253295 UTC - Status: IMAGING 2026-07-25 13:04:18.220300 UTC - Status: IMAGING 2026-07-25 13:04:22.189994 UTC - Status: IMAGING 2026-07-25 13:04:25.702888 UTC - Status: IMAGING 2026-07-25 13:04:29.675173 UTC - Status: IMAGING 2026-07-25 13:04:33.129550 UTC - Status: IMAGING 2026-07-25 13:04:36.753240 UTC - Status: IMAGING 2026-07-25 13:04:39.994466 UTC - Status: IMAGING 2026-07-25 13:04:42.810566 UTC - Status: IMAGING 2026-07-25 13:04:45.954883 UTC - Status: IMAGING 2026-07-25 13:04:49.062027 UTC - Status: IMAGING 2026-07-25 13:04:52.249285 UTC - Status: IMAGING 2026-07-25 13:04:55.373380 UTC - Status: IMAGING 2026-07-25 13:04:58.089170 UTC - Status: IMAGING 2026-07-25 13:05:00.855104 UTC - Status: IMAGING 2026-07-25 13:05:03.621105 UTC - Status: IMAGING 2026-07-25 13:05:06.387002 UTC - Status: IMAGING 2026-07-25 13:05:09.202985 UTC - Status: IMAGING 2026-07-25 13:05:11.969502 UTC - Status: IMAGING 2026-07-25 13:05:14.735587 UTC - Status: IMAGING 2026-07-25 13:05:17.652627 UTC - Status: IMAGING 2026-07-25 13:05:20.420790 UTC - Status: IMAGING 2026-07-25 13:05:23.187701 UTC - Status: IMAGING 2026-07-25 13:05:26.054342 UTC - Status: UP 2026-07-25 13:05:26.054445 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kycnzhkbfcn8jhep6zp69htc 2026-07-25 13:05:26.054492 UTC - Validate resources 2026-07-25 13:05:26.872530 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 13:05:26.872630 UTC - The fields of the created resource were validated. 2026-07-25 13:05:26.872671 UTC - Check if resources are registered in experiment 2026-07-25 13:05:26.872902 UTC - Run: slices experiment list-resources --format json tst-46c727db 2026-07-25 13:05:27.689469 UTC - Status (on expauth): UP 2026-07-25 13:05:27.689654 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 13:05:27.689799 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-46c727db 2026-07-25 13:05:29.319236 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-46c727db tst 2026-07-25 13:05:30.287929 UTC - Run: slices experiment list-resources --format json tst-46c727db 2026-07-25 13:05:31.628019 UTC - expires_at (on expauth): 2026-07-25T16:05:00Z (correctly extended) 2026-07-25 13:05:31.628132 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 13:05:31.628272 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-46c727db 2026-07-25 13:05:33.360640 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-46c727db tst 2026-07-25 13:05:34.384501 UTC - Run: slices experiment list-resources --format json tst-46c727db 2026-07-25 13:05:35.157970 UTC - expires_at (on expauth): 2026-07-25T17:05:00Z (correctly extended) 2026-07-25 13:05:35.158089 UTC - Testing ssh login 2026-07-25 13:05:35.170469 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-46c727db tst 2026-07-25 13:05:36.140563 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-46c727db tst 2026-07-25 13:05:38.231159 UTC - Logging in using 'slices bi ssh' 2026-07-25 13:05:38.231263 UTC - Forcing IPv4 only. 2026-07-25 13:05:38.231395 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-46c727db tst -- -4 uname -a 2026-07-25 13:05:42.187300 UTC - Forcing IPv4 only. 2026-07-25 13:05:42.187548 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-46c727db tst -- -4 uptime 2026-07-25 13:05:43.924192 UTC - CLI SSH Test passed. 2026-07-25 13:05:43.924266 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 13:05:43.924286 UTC - Uptime: 13:05:43 up 1 min, 1 user, load average: 0.24, 0.07, 0.02 2026-07-25 13:05:43.924321 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-25 13:05:43.924796 UTC - Added paramiko HostKeyEntry for 5.53.108.25 2026-07-25 13:05:43.924887 UTC - Added paramiko HostKeyEntry for 5.53.108.25 2026-07-25 13:05:43.925058 UTC - Added paramiko HostKeyEntry for 5.53.108.25 2026-07-25 13:05:43.925263 UTC - Connecting to 5.53.108.25:22 2026-07-25 13:05:44.506868 UTC - SSH Test output: 2026-07-25 13:05:44.506943 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 13:05:44.506974 UTC - Uptime: 13:05:44 up 1 min, 1 user, load average: 0.24, 0.07, 0.02 2026-07-25 13:05:44.604494 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 13:05:44.604569 UTC - lsb_release matches expected value 2026-07-25 13:05:44.604588 UTC - SSH Test passed. 2026-07-25 13:05:44.690421 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 13:05:07 +0000! 2026-07-25 13:05:44.690556 UTC - Skip SSH proxy test because no jump proxy 2026-07-25 13:05:44.709977 UTC - Destroying tst-46c727db tst 2026-07-25 13:05:44.710213 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-46c727db tst