Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.42s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 428ms 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 965ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev192+gcac2d3fe6 (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-08-09 14:59:43.611180 UTC - Installing Slices CLI (latest stable version). 2026-08-09 14:59:43.611404 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 14:59:49.560536 UTC - Slices CLI installed. 2026-08-09 14:59:49.560873 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-08-09 14:59:49.563421 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-09 14:59:49.563522 UTC - env: {'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-08-09 14:59:49.563637 UTC - which slices: /app/.venv/bin/slices 2026-08-09 14:59:49.563674 UTC - Slices CLI exe: 'slices' 2026-08-09 14:59:49.563702 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 14:59:49.563857 UTC - Run: slices --version 2026-08-09 14:59:53.097532 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-09 14:59:53.097801 UTC - Run: slices pubkey list --format text 2026-08-09 14:59:53.864138 UTC - Pubkey already registered 2026-08-09 14:59:53.864361 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 14:59:54.479698 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 14:59:54.479788 UTC - Check List Flavors 2026-08-09 14:59:54.479933 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-09 14:59:55.499827 UTC - Check List DiskImages 2026-08-09 14:59:55.500021 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-09 14:59:56.484995 UTC - Requesting resources 2026-08-09 14:59:56.485510 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-09 14:59:56.485657 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-acbde2d2 --user-data /tmp/tmph5tbabg1 2026-08-09 14:59:57.902284 UTC - Resource ID: r_ch-geneva-bi-vm1_01kzkgka3sfsr8036kd2rebh62 2026-08-09 14:59:57.902428 UTC - Waiting until resource ready 2026-08-09 14:59:59.902731 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-acbde2d2 tst 2026-08-09 15:00:01.119656 UTC - Status: IMAGING 2026-08-09 15:00:03.991075 UTC - Status: IMAGING 2026-08-09 15:00:06.860993 UTC - Status: IMAGING 2026-08-09 15:00:10.024044 UTC - Status: IMAGING 2026-08-09 15:00:13.322284 UTC - Status: IMAGING 2026-08-09 15:00:16.446494 UTC - Status: IMAGING 2026-08-09 15:00:19.721298 UTC - Status: IMAGING 2026-08-09 15:00:23.044021 UTC - Status: IMAGING 2026-08-09 15:00:26.073799 UTC - Status: IMAGING 2026-08-09 15:00:29.190671 UTC - Status: IMAGING 2026-08-09 15:00:32.664309 UTC - Status: IMAGING 2026-08-09 15:00:36.041056 UTC - Status: IMAGING 2026-08-09 15:00:39.537669 UTC - Status: IMAGING 2026-08-09 15:00:42.458354 UTC - Status: IMAGING 2026-08-09 15:00:45.480436 UTC - Status: IMAGING 2026-08-09 15:00:48.442198 UTC - Status: IMAGING 2026-08-09 15:00:51.259333 UTC - Status: IMAGING 2026-08-09 15:00:54.279414 UTC - Status: IMAGING 2026-08-09 15:00:57.297311 UTC - Status: IMAGING 2026-08-09 15:01:00.695239 UTC - Status: IMAGING 2026-08-09 15:01:03.775432 UTC - Status: IMAGING 2026-08-09 15:01:06.699171 UTC - Status: IMAGING 2026-08-09 15:01:09.515026 UTC - Status: IMAGING 2026-08-09 15:01:12.331042 UTC - Status: IMAGING 2026-08-09 15:01:15.738508 UTC - Status: IMAGING 2026-08-09 15:01:18.864321 UTC - Status: IMAGING 2026-08-09 15:01:22.202692 UTC - Status: IMAGING 2026-08-09 15:01:25.119480 UTC - Status: IMAGING 2026-08-09 15:01:28.151593 UTC - Status: IMAGING 2026-08-09 15:01:31.119294 UTC - Status: IMAGING 2026-08-09 15:01:34.536286 UTC - Status: IMAGING 2026-08-09 15:01:37.462224 UTC - Status: IMAGING 2026-08-09 15:01:40.436279 UTC - Status: IMAGING 2026-08-09 15:01:43.252319 UTC - Status: IMAGING 2026-08-09 15:01:46.018269 UTC - Status: UP 2026-08-09 15:01:46.018410 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzkgk9mpfajr0a3sf4mk7yh6 2026-08-09 15:01:46.018477 UTC - Validate resources 2026-08-09 15:01:46.838632 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-08-09 15:01:46.838724 UTC - The fields of the created resource were validated. 2026-08-09 15:01:46.838771 UTC - Check if resources are registered in experiment 2026-08-09 15:01:46.838930 UTC - Run: slices experiment list-resources --format json tst-acbde2d2 2026-08-09 15:01:47.504921 UTC - Status (on expauth): UP 2026-08-09 15:01:47.505058 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 15:01:47.505183 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-acbde2d2 2026-08-09 15:01:48.721983 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-acbde2d2 tst 2026-08-09 15:01:49.538075 UTC - Run: slices experiment list-resources --format json tst-acbde2d2 2026-08-09 15:01:50.203920 UTC - expires_at (on expauth): 2026-08-09T18:01:00Z (correctly extended) 2026-08-09 15:01:50.204017 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 15:01:50.204173 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-acbde2d2 2026-08-09 15:01:51.524129 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-acbde2d2 tst 2026-08-09 15:01:52.440320 UTC - Run: slices experiment list-resources --format json tst-acbde2d2 2026-08-09 15:01:53.631038 UTC - expires_at (on expauth): 2026-08-09T19:01:00Z (correctly extended) 2026-08-09 15:01:53.631176 UTC - Testing ssh login 2026-08-09 15:01:53.647464 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-acbde2d2 tst 2026-08-09 15:01:54.513535 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-acbde2d2 tst 2026-08-09 15:01:55.432441 UTC - Logging in using 'slices bi ssh' 2026-08-09 15:01:55.432522 UTC - Forcing IPv4 only. 2026-08-09 15:01:55.432646 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-acbde2d2 tst -- -4 uname -a 2026-08-09 15:01:58.614192 UTC - Forcing IPv4 only. 2026-08-09 15:01:58.614371 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-acbde2d2 tst -- -4 uptime 2026-08-09 15:02:00.081676 UTC - CLI SSH Test passed. 2026-08-09 15:02:00.081846 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-08-09 15:02:00.081896 UTC - Uptime: 15:02:00 up 1 min, 1 user, load average: 0.42, 0.13, 0.04 2026-08-09 15:02:00.082015 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-09 15:02:00.083469 UTC - Added paramiko HostKeyEntry for 5.53.108.119 2026-08-09 15:02:00.083625 UTC - Added paramiko HostKeyEntry for 5.53.108.119 2026-08-09 15:02:00.084018 UTC - Added paramiko HostKeyEntry for 5.53.108.119 2026-08-09 15:02:00.084267 UTC - Connecting to 5.53.108.119:22 2026-08-09 15:02:00.643588 UTC - SSH Test output: 2026-08-09 15:02:00.643675 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-08-09 15:02:00.643697 UTC - Uptime: 15:02:00 up 1 min, 1 user, load average: 0.38, 0.13, 0.04 2026-08-09 15:02:00.742145 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 15:02:00.742223 UTC - lsb_release matches expected value 2026-08-09 15:02:00.742242 UTC - SSH Test passed. 2026-08-09 15:02:00.826920 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 15:01:27 +0000! 2026-08-09 15:02:00.827104 UTC - Skip SSH proxy test because no jump proxy 2026-08-09 15:02:00.862683 UTC - Destroying tst-acbde2d2 tst 2026-08-09 15:02:00.864491 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-acbde2d2 tst