Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.63s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 223ms 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 279ms + 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 04:43:56.488981 UTC - Installing Slices CLI (latest stable version). 2026-08-05 04:43:56.489221 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-05 04:44:02.018777 UTC - Slices CLI installed. 2026-08-05 04:44:02.019143 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 04:44:02.021999 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 04:44:02.022129 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 04:44:02.022224 UTC - which slices: /app/.venv/bin/slices 2026-08-05 04:44:02.022245 UTC - Slices CLI exe: 'slices' 2026-08-05 04:44:02.022261 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-05 04:44:02.022454 UTC - Run: slices --version 2026-08-05 04:44:04.893901 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 04:44:04.894211 UTC - Run: slices pubkey list --format text 2026-08-05 04:44:05.659938 UTC - Pubkey already registered 2026-08-05 04:44:05.660205 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-05 04:44:06.275663 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-05 04:44:06.275766 UTC - Check List Flavors 2026-08-05 04:44:06.275944 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-05 04:44:06.991809 UTC - Check List DiskImages 2026-08-05 04:44:06.991995 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-05 04:44:07.607587 UTC - Requesting resources 2026-08-05 04:44:07.608122 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-05 04:44:07.608242 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-2829a57b --user-data /tmp/tmp8fc2hqj2 2026-08-05 04:44:08.674752 UTC - Resource ID: r_be-gent1-bi-vm1_01kz83rtycfbvrvp95b9mpbrmm 2026-08-05 04:44:08.674917 UTC - Waiting until resource ready 2026-08-05 04:44:10.675413 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2829a57b tst 2026-08-05 04:44:11.441918 UTC - Status: IMAGING 2026-08-05 04:44:14.158197 UTC - Status: IMAGING 2026-08-05 04:44:17.025017 UTC - Status: IMAGING 2026-08-05 04:44:19.791543 UTC - Status: BOOTING 2026-08-05 04:44:22.508042 UTC - Status: BOOTING 2026-08-05 04:44:25.224446 UTC - Status: BOOTING 2026-08-05 04:44:27.940813 UTC - Status: BOOTING 2026-08-05 04:44:30.657131 UTC - Status: BOOTING 2026-08-05 04:44:33.423437 UTC - Status: UP 2026-08-05 04:44:33.423518 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz83rtq6egrav8wkth389ak8 2026-08-05 04:44:33.423561 UTC - Validate resources 2026-08-05 04:44:34.139983 UTC - The fields of the created resource were validated. 2026-08-05 04:44:34.140102 UTC - Check if resources are registered in experiment 2026-08-05 04:44:34.140290 UTC - Run: slices experiment list-resources --format json tst-2829a57b 2026-08-05 04:44:34.806040 UTC - Status (on expauth): UP 2026-08-05 04:44:34.806199 UTC - Testing extend expires_at (all resources in experiment) 2026-08-05 04:44:34.806341 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-2829a57b 2026-08-05 04:44:36.224358 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2829a57b tst 2026-08-05 04:44:36.940802 UTC - Run: slices experiment list-resources --format json tst-2829a57b 2026-08-05 04:44:37.606469 UTC - expires_at (on expauth): 2026-08-05T07:44:00Z (correctly extended) 2026-08-05 04:44:37.606572 UTC - Testing extend expires_at (single resource in experiment) 2026-08-05 04:44:37.606737 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-2829a57b 2026-08-05 04:44:39.024280 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2829a57b tst 2026-08-05 04:44:39.790516 UTC - Run: slices experiment list-resources --format json tst-2829a57b 2026-08-05 04:44:40.456240 UTC - expires_at (on expauth): 2026-08-05T08:44:00Z (correctly extended) 2026-08-05 04:44:40.456351 UTC - Testing ssh login 2026-08-05 04:44:40.470707 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2829a57b tst 2026-08-05 04:44:41.236901 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2829a57b tst 2026-08-05 04:44:42.053392 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2829a57b tst 2026-08-05 04:44:42.819693 UTC - Logging in using 'slices bi ssh' 2026-08-05 04:44:42.819792 UTC - Forcing IPv4 only. 2026-08-05 04:44:42.819984 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2829a57b tst -- -4 uname -a 2026-08-05 04:44:43.937337 UTC - Forcing IPv4 only. 2026-08-05 04:44:43.937559 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2829a57b tst -- -4 uptime 2026-08-05 04:44:44.853802 UTC - CLI SSH Test passed. 2026-08-05 04:44:44.853880 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-08-05 04:44:44.853901 UTC - Uptime: 06:44:44 up 0 min, 1 user, load average: 0.44, 0.11, 0.04 2026-08-05 04:44:44.853936 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-05 04:44:44.854376 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:44.854530 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:44.854584 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:44.854776 UTC - Connecting to 10.10.215.149:22 2026-08-05 04:44:45.116648 UTC - SSH Test output: 2026-08-05 04:44:45.116764 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-08-05 04:44:45.116805 UTC - Uptime: 06:44:44 up 0 min, 1 user, load average: 0.44, 0.11, 0.04 2026-08-05 04:44:45.173560 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-05 04:44:45.220580 UTC - /etc/debian_version: 13.1 2026-08-05 04:44:45.220686 UTC - lsb_release matches expected value 2026-08-05 04:44:45.220741 UTC - /etc/debian_version matches expected value 2026-08-05 04:44:45.220771 UTC - SSH Test passed. 2026-08-05 04:44:45.267720 UTC - Cloud-init user-data: Hello World. The time is now Wed, 05 Aug 2026 06:44:32 +0200! 2026-08-05 04:44:45.267857 UTC - Logging in using SSH over SSH proxy 2026-08-05 04:44:45.268034 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2829a57b tst 2026-08-05 04:44:46.034379 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:46.034590 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:46.034654 UTC - Added paramiko HostKeyEntry for 10.10.215.149 2026-08-05 04:44:46.034938 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-05 04:44:46.034987 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-05 04:44:46.035106 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-05 04:44:46.035136 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-05 04:44:46.338321 UTC - Connecting to 10.10.215.149:22 over proxy 2026-08-05 04:44:46.605652 UTC - SSH Test output: 2026-08-05 04:44:46.605724 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-08-05 04:44:46.605743 UTC - Uptime: 06:44:46 up 0 min, 2 users, load average: 0.44, 0.11, 0.04 2026-08-05 04:44:46.661484 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-05 04:44:46.708897 UTC - /etc/debian_version: 13.1 2026-08-05 04:44:46.708978 UTC - lsb_release matches expected value 2026-08-05 04:44:46.709003 UTC - /etc/debian_version matches expected value 2026-08-05 04:44:46.709019 UTC - SSH Test passed. 2026-08-05 04:44:46.756564 UTC - Cloud-init user-data: Hello World. The time is now Wed, 05 Aug 2026 06:44:32 +0200! 2026-08-05 04:44:46.763604 UTC - Destroying tst-2829a57b tst 2026-08-05 04:44:46.763789 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-2829a57b tst