Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.13s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 186ms 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 524ms + annotated-types==0.7.0 + anyio==4.14.2 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-18 04:29:08.568986 UTC - Installing Slices CLI (latest stable version). 2026-07-18 04:29:08.569225 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 04:29:13.945823 UTC - Slices CLI installed. 2026-07-18 04:29:13.946105 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-18 04:29:13.947544 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-18 04:29:13.948643 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 04:29:13.948808 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-18 04:29:13.948840 UTC - Slices CLI exe: 'slices' 2026-07-18 04:29:13.948859 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 04:29:13.949006 UTC - Run: slices --version 2026-07-18 04:29:16.568911 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-18 04:29:16.569206 UTC - Run: slices pubkey list --format text 2026-07-18 04:29:17.334542 UTC - Pubkey already registered 2026-07-18 04:29:17.334799 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 04:29:17.899828 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 04:29:17.899928 UTC - Check List Flavors 2026-07-18 04:29:17.900093 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-18 04:29:19.116775 UTC - Check List DiskImages 2026-07-18 04:29:19.116966 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-18 04:29:19.732250 UTC - Requesting resources 2026-07-18 04:29:19.732767 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 04:29:19.732883 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-f5414be2 --user-data /tmp/tmp7ay6gnjb 2026-07-18 04:29:22.653263 UTC - Resource ID: r_be-gent1-bi-vm1_01kxsqrvp8ej1tjvyfh1bkqxp9 2026-07-18 04:29:22.653430 UTC - Waiting until resource ready 2026-07-18 04:29:24.653797 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-f5414be2 tst 2026-07-18 04:29:25.369596 UTC - Status: IMAGING 2026-07-18 04:29:28.035433 UTC - Status: IMAGING 2026-07-18 04:29:30.751577 UTC - Status: IMAGING 2026-07-18 04:29:33.417871 UTC - Status: BOOTING 2026-07-18 04:29:36.134444 UTC - Status: BOOTING 2026-07-18 04:29:38.900893 UTC - Status: BOOTING 2026-07-18 04:29:41.617047 UTC - Status: BOOTING 2026-07-18 04:29:44.333391 UTC - Status: INITIALIZING 2026-07-18 04:29:46.999168 UTC - Status: UP 2026-07-18 04:29:46.999277 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxsqrtr0e3japyjvwzrzexwd 2026-07-18 04:29:46.999328 UTC - Validate resources 2026-07-18 04:29:47.665370 UTC - The fields of the created resource were validated. 2026-07-18 04:29:47.665495 UTC - Check if resources are registered in experiment 2026-07-18 04:29:47.665683 UTC - Run: slices experiment list-resources --format json tst-f5414be2 2026-07-18 04:29:48.281339 UTC - Status (on expauth): UP 2026-07-18 04:29:48.281518 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 04:29:48.281677 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-f5414be2 2026-07-18 04:29:50.801471 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-f5414be2 tst 2026-07-18 04:29:51.517339 UTC - Run: slices experiment list-resources --format json tst-f5414be2 2026-07-18 04:29:52.133065 UTC - expires_at (on expauth): 2026-07-18T07:29:00Z (correctly extended) 2026-07-18 04:29:52.133195 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 04:29:52.133371 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-f5414be2 2026-07-18 04:29:54.655351 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-f5414be2 tst 2026-07-18 04:29:55.371245 UTC - Run: slices experiment list-resources --format json tst-f5414be2 2026-07-18 04:29:55.986638 UTC - expires_at (on expauth): 2026-07-18T08:29:00Z (correctly extended) 2026-07-18 04:29:55.986770 UTC - Testing ssh login 2026-07-18 04:29:55.997782 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f5414be2 tst 2026-07-18 04:29:56.713402 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f5414be2 tst 2026-07-18 04:29:57.429255 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-f5414be2 tst 2026-07-18 04:29:58.145280 UTC - Logging in using 'slices bi ssh' 2026-07-18 04:29:58.145372 UTC - Forcing IPv4 only. 2026-07-18 04:29:58.145527 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-f5414be2 tst -- -4 uname -a 2026-07-18 04:29:59.111923 UTC - Forcing IPv4 only. 2026-07-18 04:29:59.112122 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-f5414be2 tst -- -4 uptime 2026-07-18 04:29:59.927710 UTC - CLI SSH Test passed. 2026-07-18 04:29:59.927787 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-18 04:29:59.927807 UTC - Uptime: 06:29:59 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-18 04:29:59.927844 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-18 04:29:59.928293 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:29:59.928464 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:29:59.928521 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:29:59.928711 UTC - Connecting to 10.10.220.133:22 2026-07-18 04:30:00.163697 UTC - SSH Test output: 2026-07-18 04:30:00.163769 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-18 04:30:00.163793 UTC - Uptime: 06:29:59 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-18 04:30:00.217495 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-18 04:30:00.263966 UTC - /etc/debian_version: 13.1 2026-07-18 04:30:00.264055 UTC - lsb_release matches expected value 2026-07-18 04:30:00.264078 UTC - /etc/debian_version matches expected value 2026-07-18 04:30:00.264095 UTC - SSH Test passed. 2026-07-18 04:30:00.311348 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 06:29:44 +0200! 2026-07-18 04:30:00.311494 UTC - Logging in using SSH over SSH proxy 2026-07-18 04:30:00.311637 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-f5414be2 tst 2026-07-18 04:30:01.077818 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:30:01.078002 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:30:01.078073 UTC - Added paramiko HostKeyEntry for 10.10.220.133 2026-07-18 04:30:01.078387 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 04:30:01.078444 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 04:30:01.078560 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-18 04:30:01.078582 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-18 04:30:01.418898 UTC - Connecting to 10.10.220.133:22 over proxy 2026-07-18 04:30:01.682541 UTC - SSH Test output: 2026-07-18 04:30:01.682632 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-18 04:30:01.682654 UTC - Uptime: 06:30:01 up 0 min, 2 users, load average: 0.11, 0.03, 0.01 2026-07-18 04:30:01.739073 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-18 04:30:01.787003 UTC - /etc/debian_version: 13.1 2026-07-18 04:30:01.787128 UTC - lsb_release matches expected value 2026-07-18 04:30:01.787154 UTC - /etc/debian_version matches expected value 2026-07-18 04:30:01.787170 UTC - SSH Test passed. 2026-07-18 04:30:01.835106 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 06:29:44 +0200! 2026-07-18 04:30:01.844230 UTC - Destroying tst-f5414be2 tst 2026-07-18 04:30:01.844429 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-f5414be2 tst