Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.61s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 233ms 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 262ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-11 04:12:13.940949 UTC - Installing Slices CLI (latest stable version). 2026-07-11 04:12:13.941181 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 04:12:18.769386 UTC - Slices CLI installed. 2026-07-11 04:12:18.769726 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-11 04:12:18.771206 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-11 04:12:18.772366 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 04:12:18.772487 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-11 04:12:18.772514 UTC - Slices CLI exe: 'slices' 2026-07-11 04:12:18.772533 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 04:12:18.772648 UTC - Run: slices --version 2026-07-11 04:12:21.543418 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-11 04:12:21.543697 UTC - Run: slices pubkey list --format text 2026-07-11 04:12:22.359357 UTC - Pubkey already registered 2026-07-11 04:12:22.359617 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 04:12:22.924851 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 04:12:22.924960 UTC - Check List Flavors 2026-07-11 04:12:22.925121 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-11 04:12:24.191858 UTC - Check List DiskImages 2026-07-11 04:12:24.192078 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-11 04:12:24.807539 UTC - Requesting resources 2026-07-11 04:12:24.808064 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 04:12:24.808175 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-40bcdfa8 --user-data /tmp/tmp8prgahmv 2026-07-11 04:12:27.829264 UTC - Resource ID: r_be-gent1-bi-vm1_01kx7p0vmsevnvng6eb83z9vyk 2026-07-11 04:12:27.829460 UTC - Waiting until resource ready 2026-07-11 04:12:29.829827 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-40bcdfa8 tst 2026-07-11 04:12:30.598079 UTC - Status: IMAGING 2026-07-11 04:12:33.364358 UTC - Status: IMAGING 2026-07-11 04:12:36.080421 UTC - Status: IMAGING 2026-07-11 04:12:38.846422 UTC - Status: BOOTING 2026-07-11 04:12:41.621085 UTC - Status: BOOTING 2026-07-11 04:12:44.387835 UTC - Status: BOOTING 2026-07-11 04:12:47.104332 UTC - Status: BOOTING 2026-07-11 04:12:49.820877 UTC - Status: BOOTING 2026-07-11 04:12:52.538148 UTC - Status: INITIALIZING 2026-07-11 04:12:55.254469 UTC - Status: UP 2026-07-11 04:12:55.254566 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx7p0tqjevwtz1b0h0veb0a2 2026-07-11 04:12:55.254611 UTC - Validate resources 2026-07-11 04:12:55.970587 UTC - The fields of the created resource were validated. 2026-07-11 04:12:55.970687 UTC - Check if resources are registered in experiment 2026-07-11 04:12:55.970848 UTC - Run: slices experiment list-resources --format json tst-40bcdfa8 2026-07-11 04:12:56.586152 UTC - Status (on expauth): UP 2026-07-11 04:12:56.586303 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 04:12:56.586442 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-40bcdfa8 2026-07-11 04:12:59.307096 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-40bcdfa8 tst 2026-07-11 04:13:00.023160 UTC - Run: slices experiment list-resources --format json tst-40bcdfa8 2026-07-11 04:13:00.688676 UTC - expires_at (on expauth): 2026-07-11T07:12:00Z (correctly extended) 2026-07-11 04:13:00.688804 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 04:13:00.688972 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-40bcdfa8 2026-07-11 04:13:03.360029 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-40bcdfa8 tst 2026-07-11 04:13:04.126241 UTC - Run: slices experiment list-resources --format json tst-40bcdfa8 2026-07-11 04:13:04.792226 UTC - expires_at (on expauth): 2026-07-11T08:13:00Z (correctly extended) 2026-07-11 04:13:04.792408 UTC - Testing ssh login 2026-07-11 04:13:04.810394 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-40bcdfa8 tst 2026-07-11 04:13:05.626450 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-40bcdfa8 tst 2026-07-11 04:13:06.392544 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-40bcdfa8 tst 2026-07-11 04:13:07.158422 UTC - Logging in using 'slices bi ssh' 2026-07-11 04:13:07.158499 UTC - Forcing IPv4 only. 2026-07-11 04:13:07.158638 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-40bcdfa8 tst -- -4 uname -a 2026-07-11 04:13:08.576527 UTC - Forcing IPv4 only. 2026-07-11 04:13:08.576754 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-40bcdfa8 tst -- -4 uptime 2026-07-11 04:13:09.442780 UTC - CLI SSH Test passed. 2026-07-11 04:13:09.442862 UTC - Uname: Linux tst 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-11 04:13:09.442881 UTC - Uptime: 06:13:08 up 0 min, 1 user, load average: 0.24, 0.06, 0.02 2026-07-11 04:13:09.442915 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-11 04:13:09.443410 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:09.443580 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:09.443641 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:09.443827 UTC - Connecting to 10.10.216.0:22 2026-07-11 04:13:09.683220 UTC - SSH Test output: 2026-07-11 04:13:09.683293 UTC - Uname: Linux tst 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-11 04:13:09.683320 UTC - Uptime: 06:13:08 up 0 min, 1 user, load average: 0.24, 0.06, 0.02 2026-07-11 04:13:09.732661 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 04:13:09.732765 UTC - lsb_release matches expected value 2026-07-11 04:13:09.732795 UTC - SSH Test passed. 2026-07-11 04:13:09.777438 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 06:12:52 +0200! 2026-07-11 04:13:09.777582 UTC - Logging in using SSH over SSH proxy 2026-07-11 04:13:09.777732 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-40bcdfa8 tst 2026-07-11 04:13:10.543716 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:10.543880 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:10.543948 UTC - Added paramiko HostKeyEntry for 10.10.216.0 2026-07-11 04:13:10.544201 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 04:13:10.544253 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 04:13:10.544355 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 04:13:10.544375 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-11 04:13:10.838398 UTC - Connecting to 10.10.216.0:22 over proxy 2026-07-11 04:13:11.133163 UTC - SSH Test output: 2026-07-11 04:13:11.133258 UTC - Uname: Linux tst 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-11 04:13:11.133282 UTC - Uptime: 06:13:10 up 0 min, 2 users, load average: 0.24, 0.06, 0.02 2026-07-11 04:13:11.185666 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 04:13:11.185766 UTC - lsb_release matches expected value 2026-07-11 04:13:11.185793 UTC - SSH Test passed. 2026-07-11 04:13:11.231759 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 06:12:52 +0200! 2026-07-11 04:13:11.239223 UTC - Destroying tst-40bcdfa8 tst 2026-07-11 04:13:11.239428 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-40bcdfa8 tst