Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.02s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 238ms 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.03s + 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-08 13:04:58.546662 UTC - Installing Slices CLI (latest stable version). 2026-08-08 13:04:58.547261 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 13:05:06.910355 UTC - Slices CLI installed. 2026-08-08 13:05:06.910694 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-08 13:05:06.913458 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-08 13:05:06.913540 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-08 13:05:06.913611 UTC - which slices: /app/.venv/bin/slices 2026-08-08 13:05:06.913630 UTC - Slices CLI exe: 'slices' 2026-08-08 13:05:06.913647 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 13:05:06.913894 UTC - Run: slices --version 2026-08-08 13:05:11.887944 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-08 13:05:11.888433 UTC - Run: slices pubkey list --format text 2026-08-08 13:05:16.125719 UTC - Pubkey already registered 2026-08-08 13:05:16.126059 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 13:05:16.948936 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 13:05:16.949215 UTC - Check List Flavors 2026-08-08 13:05:16.949571 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-08 13:05:18.244201 UTC - Check List DiskImages 2026-08-08 13:05:18.244418 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-08 13:05:19.235617 UTC - Requesting resources 2026-08-08 13:05:19.270558 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 13:05:19.270715 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-07417b3c --user-data /tmp/tmp1felp2e7 2026-08-08 13:05:21.048938 UTC - Resource ID: r_ch-geneva-bi-vm1_01kzgqmqaneas9v4je6m4fb0hh 2026-08-08 13:05:21.049086 UTC - Waiting until resource ready 2026-08-08 13:05:23.049402 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-07417b3c tst 2026-08-08 13:05:24.125241 UTC - Status: IMAGING 2026-08-08 13:05:27.148564 UTC - Status: IMAGING 2026-08-08 13:05:30.069228 UTC - Status: IMAGING 2026-08-08 13:05:33.157065 UTC - Status: IMAGING 2026-08-08 13:05:36.494668 UTC - Status: IMAGING 2026-08-08 13:05:39.470206 UTC - Status: IMAGING 2026-08-08 13:05:42.336652 UTC - Status: IMAGING 2026-08-08 13:05:45.561856 UTC - Status: IMAGING 2026-08-08 13:05:48.629854 UTC - Status: IMAGING 2026-08-08 13:05:52.145775 UTC - Status: IMAGING 2026-08-08 13:05:56.148794 UTC - Status: IMAGING 2026-08-08 13:05:59.281892 UTC - Status: IMAGING 2026-08-08 13:06:02.634808 UTC - Status: IMAGING 2026-08-08 13:06:05.852842 UTC - Status: IMAGING 2026-08-08 13:06:09.170804 UTC - Status: IMAGING 2026-08-08 13:06:12.196530 UTC - Status: IMAGING 2026-08-08 13:06:15.468018 UTC - Status: IMAGING 2026-08-08 13:06:19.786975 UTC - Status: IMAGING 2026-08-08 13:06:22.708779 UTC - Status: IMAGING 2026-08-08 13:06:25.727218 UTC - Status: IMAGING 2026-08-08 13:06:28.593369 UTC - Status: IMAGING 2026-08-08 13:06:31.549641 UTC - Status: IMAGING 2026-08-08 13:06:34.416109 UTC - Status: IMAGING 2026-08-08 13:06:37.283209 UTC - Status: IMAGING 2026-08-08 13:06:40.303814 UTC - Status: IMAGING 2026-08-08 13:06:43.371817 UTC - Status: IMAGING 2026-08-08 13:06:46.557742 UTC - Status: IMAGING 2026-08-08 13:06:49.851234 UTC - Status: IMAGING 2026-08-08 13:06:52.870748 UTC - Status: IMAGING 2026-08-08 13:06:56.193716 UTC - Status: IMAGING 2026-08-08 13:06:59.163998 UTC - Status: IMAGING 2026-08-08 13:07:02.149721 UTC - Status: IMAGING 2026-08-08 13:07:04.971746 UTC - Status: IMAGING 2026-08-08 13:07:07.837955 UTC - Status: IMAGING 2026-08-08 13:07:10.654336 UTC - Status: IMAGING 2026-08-08 13:07:13.572490 UTC - Status: UP 2026-08-08 13:07:13.572592 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzgqmptqferbrmsxgv5fvn4y 2026-08-08 13:07:13.572642 UTC - Validate resources 2026-08-08 13:07:14.470179 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-08 13:07:14.470448 UTC - The fields of the created resource were validated. 2026-08-08 13:07:14.470576 UTC - Check if resources are registered in experiment 2026-08-08 13:07:14.470874 UTC - Run: slices experiment list-resources --format json tst-07417b3c 2026-08-08 13:07:15.287171 UTC - Status (on expauth): UP 2026-08-08 13:07:15.287310 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 13:07:15.287448 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-07417b3c 2026-08-08 13:07:16.561810 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-07417b3c tst 2026-08-08 13:07:17.632439 UTC - Run: slices experiment list-resources --format json tst-07417b3c 2026-08-08 13:07:18.548415 UTC - expires_at (on expauth): 2026-08-08T16:07:00Z (correctly extended) 2026-08-08 13:07:18.548531 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 13:07:18.548703 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-07417b3c 2026-08-08 13:07:19.938587 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-07417b3c tst 2026-08-08 13:07:20.809663 UTC - Run: slices experiment list-resources --format json tst-07417b3c 2026-08-08 13:07:21.989531 UTC - expires_at (on expauth): 2026-08-08T17:07:00Z (correctly extended) 2026-08-08 13:07:21.989654 UTC - Testing ssh login 2026-08-08 13:07:22.009788 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-07417b3c tst 2026-08-08 13:07:23.082377 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-07417b3c tst 2026-08-08 13:07:24.324371 UTC - Logging in using 'slices bi ssh' 2026-08-08 13:07:24.324485 UTC - Forcing IPv4 only. 2026-08-08 13:07:24.324620 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-07417b3c tst -- -4 uname -a 2026-08-08 13:07:26.859507 UTC - Forcing IPv4 only. 2026-08-08 13:07:26.859726 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-07417b3c tst -- -4 uptime 2026-08-08 13:07:28.384673 UTC - CLI SSH Test passed. 2026-08-08 13:07:28.384778 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-08 13:07:28.384800 UTC - Uptime: 13:07:28 up 1 min, 1 user, load average: 0.18, 0.06, 0.02 2026-08-08 13:07:28.384844 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-08 13:07:28.385435 UTC - Added paramiko HostKeyEntry for 5.53.108.234 2026-08-08 13:07:28.385564 UTC - Added paramiko HostKeyEntry for 5.53.108.234 2026-08-08 13:07:28.385745 UTC - Added paramiko HostKeyEntry for 5.53.108.234 2026-08-08 13:07:28.385953 UTC - Connecting to 5.53.108.234:22 2026-08-08 13:07:28.968956 UTC - SSH Test output: 2026-08-08 13:07:28.969030 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-08 13:07:28.969073 UTC - Uptime: 13:07:28 up 1 min, 1 user, load average: 0.16, 0.06, 0.02 2026-08-08 13:07:29.066834 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 13:07:29.066911 UTC - lsb_release matches expected value 2026-08-08 13:07:29.066931 UTC - SSH Test passed. 2026-08-08 13:07:29.150426 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 13:06:54 +0000! 2026-08-08 13:07:29.150580 UTC - Skip SSH proxy test because no jump proxy 2026-08-08 13:07:29.158074 UTC - Destroying tst-07417b3c tst 2026-08-08 13:07:29.158255 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-07417b3c tst