Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.12s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 182ms 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 428ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev189+g506e4860f (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-28 04:50:38.788243 UTC - Installing Slices CLI (latest stable version). 2026-07-28 04:50:38.788464 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-28 04:50:43.514468 UTC - Slices CLI installed. 2026-07-28 04:50:43.514750 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-28 04:50:43.516020 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-07-28 04:50:43.516101 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-07-28 04:50:43.516174 UTC - which slices: /app/.venv/bin/slices 2026-07-28 04:50:43.516196 UTC - Slices CLI exe: 'slices' 2026-07-28 04:50:43.516213 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-28 04:50:43.516344 UTC - Run: slices --version 2026-07-28 04:50:46.741915 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-28 04:50:46.742392 UTC - Run: slices pubkey list --format text 2026-07-28 04:50:47.558908 UTC - Pubkey already registered 2026-07-28 04:50:47.559337 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-28 04:50:48.224736 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-28 04:50:48.225127 UTC - Check List Flavors 2026-07-28 04:50:48.225288 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-28 04:50:49.294220 UTC - Check List DiskImages 2026-07-28 04:50:49.294418 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-28 04:50:49.913782 UTC - Requesting resources 2026-07-28 04:50:49.914269 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-28 04:50:49.914376 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-95bb992f --user-data /tmp/tmpug6ggaoa 2026-07-28 04:50:52.202311 UTC - Resource ID: r_be-gent1-bi-vm1_01kykgzcwrfc196krf9mettryc 2026-07-28 04:50:52.203284 UTC - Waiting until resource ready 2026-07-28 04:50:54.203642 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-95bb992f tst 2026-07-28 04:50:55.021890 UTC - Status: IMAGING 2026-07-28 04:50:57.947507 UTC - Status: IMAGING 2026-07-28 04:51:01.530980 UTC - Status: BOOTING 2026-07-28 04:51:04.349396 UTC - Status: BOOTING 2026-07-28 04:51:07.748047 UTC - Status: BOOTING 2026-07-28 04:51:10.564668 UTC - Status: BOOTING 2026-07-28 04:51:13.280725 UTC - Status: UP 2026-07-28 04:51:13.280799 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kykgzcbqfb5baaafavw8nmez 2026-07-28 04:51:13.280837 UTC - Validate resources 2026-07-28 04:51:13.998931 UTC - The fields of the created resource were validated. 2026-07-28 04:51:13.999051 UTC - Check if resources are registered in experiment 2026-07-28 04:51:13.999196 UTC - Run: slices experiment list-resources --format json tst-95bb992f 2026-07-28 04:51:14.664998 UTC - Status (on expauth): UP 2026-07-28 04:51:14.665141 UTC - Testing extend expires_at (all resources in experiment) 2026-07-28 04:51:14.665273 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-95bb992f 2026-07-28 04:51:16.834497 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-95bb992f tst 2026-07-28 04:51:17.723045 UTC - Run: slices experiment list-resources --format json tst-95bb992f 2026-07-28 04:51:18.642048 UTC - expires_at (on expauth): 2026-07-28T07:51:00Z (correctly extended) 2026-07-28 04:51:18.642198 UTC - Testing extend expires_at (single resource in experiment) 2026-07-28 04:51:18.642381 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-95bb992f 2026-07-28 04:51:20.668245 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-95bb992f tst 2026-07-28 04:51:21.433910 UTC - Run: slices experiment list-resources --format json tst-95bb992f 2026-07-28 04:51:22.373893 UTC - expires_at (on expauth): 2026-07-28T08:51:00Z (correctly extended) 2026-07-28 04:51:22.374014 UTC - Testing ssh login 2026-07-28 04:51:22.422874 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-95bb992f tst 2026-07-28 04:51:23.745318 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-95bb992f tst 2026-07-28 04:51:24.871954 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-95bb992f tst 2026-07-28 04:51:25.791608 UTC - Logging in using 'slices bi ssh' 2026-07-28 04:51:25.791686 UTC - Forcing IPv4 only. 2026-07-28 04:51:25.791818 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-95bb992f tst -- -4 uname -a 2026-07-28 04:51:27.120663 UTC - Forcing IPv4 only. 2026-07-28 04:51:27.120898 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-95bb992f tst -- -4 uptime 2026-07-28 04:51:28.191238 UTC - CLI SSH Test passed. 2026-07-28 04:51:28.191311 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-28 04:51:28.191329 UTC - Uptime: 06:51:26 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-28 04:51:28.191360 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-28 04:51:28.191828 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:28.192006 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:28.192068 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:28.192238 UTC - Connecting to 10.10.216.184:22 2026-07-28 04:51:28.499833 UTC - SSH Test output: 2026-07-28 04:51:28.499900 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-28 04:51:28.499919 UTC - Uptime: 06:51:26 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-28 04:51:28.552103 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-28 04:51:28.599727 UTC - /etc/debian_version: 13.1 2026-07-28 04:51:28.599799 UTC - lsb_release matches expected value 2026-07-28 04:51:28.599819 UTC - /etc/debian_version matches expected value 2026-07-28 04:51:28.599834 UTC - SSH Test passed. 2026-07-28 04:51:28.643552 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 06:51:11 +0200! 2026-07-28 04:51:28.643706 UTC - Logging in using SSH over SSH proxy 2026-07-28 04:51:28.643860 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-95bb992f tst 2026-07-28 04:51:29.519129 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:29.519277 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:29.519325 UTC - Added paramiko HostKeyEntry for 10.10.216.184 2026-07-28 04:51:29.519565 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:51:29.519616 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:51:29.519717 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:51:29.519736 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-28 04:51:29.813156 UTC - Connecting to 10.10.216.184:22 over proxy 2026-07-28 04:51:30.042125 UTC - SSH Test output: 2026-07-28 04:51:30.042220 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-28 04:51:30.042253 UTC - Uptime: 06:51:28 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-28 04:51:30.097719 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-28 04:51:30.144892 UTC - /etc/debian_version: 13.1 2026-07-28 04:51:30.147986 UTC - lsb_release matches expected value 2026-07-28 04:51:30.148033 UTC - /etc/debian_version matches expected value 2026-07-28 04:51:30.148054 UTC - SSH Test passed. 2026-07-28 04:51:30.239700 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 06:51:11 +0200! 2026-07-28 04:51:30.305838 UTC - Destroying tst-95bb992f tst 2026-07-28 04:51:30.306028 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-95bb992f tst