Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.53s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 214ms 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 238ms + 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.3 Run Test: slices-bi-singlenode-login 2026-08-10 14:05:02.597490 UTC - Installing Slices CLI (latest stable version). 2026-08-10 14:05:02.597718 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 14:05:07.824560 UTC - Slices CLI installed. 2026-08-10 14:05:07.824870 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-10 14:05:07.828120 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-10 14:05:07.828221 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-10 14:05:07.828298 UTC - which slices: /app/.venv/bin/slices 2026-08-10 14:05:07.828316 UTC - Slices CLI exe: 'slices' 2026-08-10 14:05:07.828331 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 14:05:07.828494 UTC - Run: slices --version 2026-08-10 14:05:10.598926 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-10 14:05:10.599260 UTC - Run: slices pubkey list --format text 2026-08-10 14:05:11.365179 UTC - Pubkey already registered 2026-08-10 14:05:11.365451 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 14:05:11.980940 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 14:05:11.981043 UTC - Check List Flavors 2026-08-10 14:05:11.981238 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-10 14:05:12.646754 UTC - Check List DiskImages 2026-08-10 14:05:12.647006 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-10 14:05:13.312809 UTC - Requesting resources 2026-08-10 14:05:13.313368 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 14:05:13.313484 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-cdb9af7f --user-data /tmp/tmpaulib__d 2026-08-10 14:05:14.379916 UTC - Resource ID: r_be-gent1-bi-vm1_01kznzvtmcfk38vef7gqw5vj97 2026-08-10 14:05:14.380093 UTC - Waiting until resource ready 2026-08-10 14:05:16.380648 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cdb9af7f tst 2026-08-10 14:05:17.096652 UTC - Status: IMAGING 2026-08-10 14:05:19.913880 UTC - Status: IMAGING 2026-08-10 14:05:22.680551 UTC - Status: IMAGING 2026-08-10 14:05:25.497507 UTC - Status: BOOTING 2026-08-10 14:05:28.264052 UTC - Status: BOOTING 2026-08-10 14:05:30.980257 UTC - Status: BOOTING 2026-08-10 14:05:33.747535 UTC - Status: BOOTING 2026-08-10 14:05:36.513837 UTC - Status: UP 2026-08-10 14:05:36.513933 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kznzvtcbfjcsa0jmrt9y3q8z 2026-08-10 14:05:36.513978 UTC - Validate resources 2026-08-10 14:05:37.230134 UTC - The fields of the created resource were validated. 2026-08-10 14:05:37.230248 UTC - Check if resources are registered in experiment 2026-08-10 14:05:37.230411 UTC - Run: slices experiment list-resources --format json tst-cdb9af7f 2026-08-10 14:05:37.896205 UTC - Status (on expauth): UP 2026-08-10 14:05:37.896353 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 14:05:37.896507 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-cdb9af7f 2026-08-10 14:05:39.364280 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cdb9af7f tst 2026-08-10 14:05:40.080279 UTC - Run: slices experiment list-resources --format json tst-cdb9af7f 2026-08-10 14:05:40.745840 UTC - expires_at (on expauth): 2026-08-10T17:05:00Z (correctly extended) 2026-08-10 14:05:40.745943 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 14:05:40.746113 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-cdb9af7f 2026-08-10 14:05:42.165331 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-cdb9af7f tst 2026-08-10 14:05:42.988202 UTC - Run: slices experiment list-resources --format json tst-cdb9af7f 2026-08-10 14:05:43.971086 UTC - expires_at (on expauth): 2026-08-10T18:05:00Z (correctly extended) 2026-08-10 14:05:43.971213 UTC - Testing ssh login 2026-08-10 14:05:43.986344 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-cdb9af7f tst 2026-08-10 14:05:45.082723 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-cdb9af7f tst 2026-08-10 14:05:46.028169 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-cdb9af7f tst 2026-08-10 14:05:46.898309 UTC - Logging in using 'slices bi ssh' 2026-08-10 14:05:46.898397 UTC - Forcing IPv4 only. 2026-08-10 14:05:46.898598 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cdb9af7f tst -- -4 uname -a 2026-08-10 14:05:48.093740 UTC - Forcing IPv4 only. 2026-08-10 14:05:48.093936 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-cdb9af7f tst -- -4 uptime 2026-08-10 14:05:49.349985 UTC - CLI SSH Test passed. 2026-08-10 14:05:49.350061 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-10 14:05:49.350080 UTC - Uptime: 16:05:47 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-08-10 14:05:49.350114 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-10 14:05:49.350573 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:49.350729 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:49.350820 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:49.351517 UTC - Connecting to 10.10.214.241:22 2026-08-10 14:05:49.585023 UTC - SSH Test output: 2026-08-10 14:05:49.585160 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-10 14:05:49.585232 UTC - Uptime: 16:05:47 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-08-10 14:05:49.635151 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 14:05:49.682462 UTC - /etc/debian_version: 13.1 2026-08-10 14:05:49.682539 UTC - lsb_release matches expected value 2026-08-10 14:05:49.682561 UTC - /etc/debian_version matches expected value 2026-08-10 14:05:49.682582 UTC - SSH Test passed. 2026-08-10 14:05:49.726549 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 16:05:33 +0200! 2026-08-10 14:05:49.726708 UTC - Logging in using SSH over SSH proxy 2026-08-10 14:05:49.726859 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-cdb9af7f tst 2026-08-10 14:05:51.019764 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:51.019915 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:51.019968 UTC - Added paramiko HostKeyEntry for 10.10.214.241 2026-08-10 14:05:51.020462 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 14:05:51.020549 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 14:05:51.020656 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 14:05:51.020676 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-10 14:05:51.153888 UTC - Connecting to 10.10.214.241:22 over proxy 2026-08-10 14:05:51.393274 UTC - SSH Test output: 2026-08-10 14:05:51.393344 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-10 14:05:51.393363 UTC - Uptime: 16:05:49 up 0 min, 2 users, load average: 0.12, 0.03, 0.01 2026-08-10 14:05:51.445840 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 14:05:51.493165 UTC - /etc/debian_version: 13.1 2026-08-10 14:05:51.493246 UTC - lsb_release matches expected value 2026-08-10 14:05:51.493266 UTC - /etc/debian_version matches expected value 2026-08-10 14:05:51.493289 UTC - SSH Test passed. 2026-08-10 14:05:51.540876 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 16:05:33 +0200! 2026-08-10 14:05:51.549465 UTC - Destroying tst-cdb9af7f tst 2026-08-10 14:05:51.549663 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-cdb9af7f tst