Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.04s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 261ms 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 265ms + 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-05 13:04:49.083822 UTC - Installing Slices CLI (latest stable version). 2026-08-05 13:04:49.084081 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-05 13:04:55.121326 UTC - Slices CLI installed. 2026-08-05 13:04:55.123654 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-05 13:04:55.129725 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-05 13:04:55.129827 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-05 13:04:55.129917 UTC - which slices: /app/.venv/bin/slices 2026-08-05 13:04:55.129939 UTC - Slices CLI exe: 'slices' 2026-08-05 13:04:55.129955 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-05 13:04:55.130091 UTC - Run: slices --version 2026-08-05 13:04:58.461154 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-05 13:04:58.461448 UTC - Run: slices pubkey list --format text 2026-08-05 13:04:59.534338 UTC - Pubkey already registered 2026-08-05 13:04:59.534583 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-05 13:05:00.250947 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-05 13:05:00.251047 UTC - Check List Flavors 2026-08-05 13:05:00.251190 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-08-05 13:05:01.071103 UTC - Check List DiskImages 2026-08-05 13:05:01.071552 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-08-05 13:05:01.737082 UTC - Requesting resources 2026-08-05 13:05:01.737777 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-05 13:05:01.737902 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-39b95ccf --user-data /tmp/tmpc21npetr 2026-08-05 13:05:03.204900 UTC - Resource ID: r_ch-geneva-bi-vm1_01kz90e124frgvse9mycze5ee5 2026-08-05 13:05:03.205091 UTC - Waiting until resource ready 2026-08-05 13:05:05.205477 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-39b95ccf tst 2026-08-05 13:05:06.021478 UTC - Status: IMAGING 2026-08-05 13:05:08.837410 UTC - Status: IMAGING 2026-08-05 13:05:11.653785 UTC - Status: IMAGING 2026-08-05 13:05:14.469962 UTC - Status: IMAGING 2026-08-05 13:05:17.492483 UTC - Status: IMAGING 2026-08-05 13:05:20.360851 UTC - Status: IMAGING 2026-08-05 13:05:23.697660 UTC - Status: IMAGING 2026-08-05 13:05:26.614277 UTC - Status: IMAGING 2026-08-05 13:05:29.539379 UTC - Status: IMAGING 2026-08-05 13:05:32.355498 UTC - Status: IMAGING 2026-08-05 13:05:35.122077 UTC - Status: IMAGING 2026-08-05 13:05:37.939296 UTC - Status: IMAGING 2026-08-05 13:05:40.755221 UTC - Status: IMAGING 2026-08-05 13:05:43.623030 UTC - Status: IMAGING 2026-08-05 13:05:46.389400 UTC - Status: IMAGING 2026-08-05 13:05:49.157086 UTC - Status: IMAGING 2026-08-05 13:05:51.973256 UTC - Status: IMAGING 2026-08-05 13:05:54.789597 UTC - Status: IMAGING 2026-08-05 13:05:57.706243 UTC - Status: IMAGING 2026-08-05 13:06:00.472137 UTC - Status: IMAGING 2026-08-05 13:06:04.592535 UTC - Status: IMAGING 2026-08-05 13:06:07.358725 UTC - Status: IMAGING 2026-08-05 13:06:10.125021 UTC - Status: IMAGING 2026-08-05 13:06:13.042170 UTC - Status: IMAGING 2026-08-05 13:06:15.809245 UTC - Status: IMAGING 2026-08-05 13:06:18.676114 UTC - Status: IMAGING 2026-08-05 13:06:21.442328 UTC - Status: IMAGING 2026-08-05 13:06:24.208543 UTC - Status: IMAGING 2026-08-05 13:06:26.975085 UTC - Status: IMAGING 2026-08-05 13:06:29.842298 UTC - Status: IMAGING 2026-08-05 13:06:32.709037 UTC - Status: IMAGING 2026-08-05 13:06:35.629297 UTC - Status: IMAGING 2026-08-05 13:06:38.495936 UTC - Status: IMAGING 2026-08-05 13:06:41.262040 UTC - Status: IMAGING 2026-08-05 13:06:44.027781 UTC - Status: IMAGING 2026-08-05 13:06:46.793848 UTC - Status: IMAGING 2026-08-05 13:06:49.660565 UTC - Status: UP 2026-08-05 13:06:49.660641 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz90e0hje20vs1jtff052vvq 2026-08-05 13:06:49.660684 UTC - Validate resources 2026-08-05 13:06:50.527052 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-05 13:06:50.527154 UTC - The fields of the created resource were validated. 2026-08-05 13:06:50.527199 UTC - Check if resources are registered in experiment 2026-08-05 13:06:50.527377 UTC - Run: slices experiment list-resources --format json tst-39b95ccf 2026-08-05 13:06:51.192932 UTC - Status (on expauth): UP 2026-08-05 13:06:51.193080 UTC - Testing extend expires_at (all resources in experiment) 2026-08-05 13:06:51.193208 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-39b95ccf 2026-08-05 13:06:52.309656 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-39b95ccf tst 2026-08-05 13:06:53.075350 UTC - Run: slices experiment list-resources --format json tst-39b95ccf 2026-08-05 13:06:53.690547 UTC - expires_at (on expauth): 2026-08-05T16:06:00Z (correctly extended) 2026-08-05 13:06:53.690644 UTC - Testing extend expires_at (single resource in experiment) 2026-08-05 13:06:53.690835 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-39b95ccf 2026-08-05 13:06:54.807271 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-39b95ccf tst 2026-08-05 13:06:55.623980 UTC - Run: slices experiment list-resources --format json tst-39b95ccf 2026-08-05 13:06:56.289844 UTC - expires_at (on expauth): 2026-08-05T17:06:00Z (correctly extended) 2026-08-05 13:06:56.289989 UTC - Testing ssh login 2026-08-05 13:06:56.301919 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-39b95ccf tst 2026-08-05 13:06:57.117650 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-39b95ccf tst 2026-08-05 13:06:57.933560 UTC - Logging in using 'slices bi ssh' 2026-08-05 13:06:57.933692 UTC - Forcing IPv4 only. 2026-08-05 13:06:57.933912 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-39b95ccf tst -- -4 uname -a 2026-08-05 13:07:00.854382 UTC - Forcing IPv4 only. 2026-08-05 13:07:00.854576 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-39b95ccf tst -- -4 uptime 2026-08-05 13:07:02.071352 UTC - CLI SSH Test passed. 2026-08-05 13:07:02.071425 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-05 13:07:02.071443 UTC - Uptime: 13:07:02 up 1 min, 1 user, load average: 0.22, 0.07, 0.02 2026-08-05 13:07:02.071477 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-08-05 13:07:02.071951 UTC - Added paramiko HostKeyEntry for 5.53.108.223 2026-08-05 13:07:02.072042 UTC - Added paramiko HostKeyEntry for 5.53.108.223 2026-08-05 13:07:02.072178 UTC - Added paramiko HostKeyEntry for 5.53.108.223 2026-08-05 13:07:02.072356 UTC - Connecting to 5.53.108.223:22 2026-08-05 13:07:02.633797 UTC - SSH Test output: 2026-08-05 13:07:02.633877 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-05 13:07:02.633900 UTC - Uptime: 13:07:02 up 1 min, 1 user, load average: 0.22, 0.07, 0.02 2026-08-05 13:07:02.732487 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-05 13:07:02.732599 UTC - lsb_release matches expected value 2026-08-05 13:07:02.732623 UTC - SSH Test passed. 2026-08-05 13:07:02.817321 UTC - Cloud-init user-data: Hello World. The time is now Wed, 05 Aug 2026 13:06:29 +0000! 2026-08-05 13:07:02.817487 UTC - Skip SSH proxy test because no jump proxy 2026-08-05 13:07:02.824511 UTC - Destroying tst-39b95ccf tst 2026-08-05 13:07:02.824693 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-39b95ccf tst