Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 197ms 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 229ms + annotated-types==0.7.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.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-22 13:29:19.882638 UTC - Installing Slices CLI (latest stable version). 2026-07-22 13:29:19.882851 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 13:29:25.110123 UTC - Slices CLI installed. 2026-07-22 13:29:25.110396 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-22 13:29:25.111975 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-22 13:29:25.112839 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 13:29:25.113002 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-22 13:29:25.113034 UTC - Slices CLI exe: 'slices' 2026-07-22 13:29:25.113053 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 13:29:25.113218 UTC - Run: slices --version 2026-07-22 13:29:27.784652 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-22 13:29:27.785020 UTC - Run: slices pubkey list --format text 2026-07-22 13:29:28.600956 UTC - Pubkey already registered 2026-07-22 13:29:28.601274 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 13:29:29.166459 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 13:29:29.166583 UTC - Check List Flavors 2026-07-22 13:29:29.166805 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-22 13:29:30.033075 UTC - Check List DiskImages 2026-07-22 13:29:30.033268 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-22 13:29:30.648744 UTC - Requesting resources 2026-07-22 13:29:30.649226 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 13:29:30.649332 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-840bea31 --user-data /tmp/tmprtks6xnk 2026-07-22 13:29:32.517374 UTC - Resource ID: r_be-gent1-bi-vm1_01ky508sz0etqb1sqw8vp7c3rk 2026-07-22 13:29:32.517590 UTC - Waiting until resource ready 2026-07-22 13:29:34.517982 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-840bea31 tst 2026-07-22 13:29:35.233720 UTC - Status: IMAGING 2026-07-22 13:29:37.949782 UTC - Status: IMAGING 2026-07-22 13:29:40.665814 UTC - Status: IMAGING 2026-07-22 13:29:43.382044 UTC - Status: BOOTING 2026-07-22 13:29:46.048035 UTC - Status: BOOTING 2026-07-22 13:29:48.814445 UTC - Status: BOOTING 2026-07-22 13:29:51.580690 UTC - Status: BOOTING 2026-07-22 13:29:54.296807 UTC - Status: UP 2026-07-22 13:29:54.296899 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky508sdme6j9ajy0j1372ca8 2026-07-22 13:29:54.296944 UTC - Validate resources 2026-07-22 13:29:54.962784 UTC - The fields of the created resource were validated. 2026-07-22 13:29:54.962880 UTC - Check if resources are registered in experiment 2026-07-22 13:29:54.963038 UTC - Run: slices experiment list-resources --format json tst-840bea31 2026-07-22 13:29:55.628668 UTC - Status (on expauth): UP 2026-07-22 13:29:55.628868 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 13:29:55.629031 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-840bea31 2026-07-22 13:29:57.597725 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-840bea31 tst 2026-07-22 13:29:58.313442 UTC - Run: slices experiment list-resources --format json tst-840bea31 2026-07-22 13:29:58.928834 UTC - expires_at (on expauth): 2026-07-22T16:29:00Z (correctly extended) 2026-07-22 13:29:58.928930 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 13:29:58.929088 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-840bea31 2026-07-22 13:30:00.897847 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-840bea31 tst 2026-07-22 13:30:01.613704 UTC - Run: slices experiment list-resources --format json tst-840bea31 2026-07-22 13:30:02.329635 UTC - expires_at (on expauth): 2026-07-22T17:29:00Z (correctly extended) 2026-07-22 13:30:02.329754 UTC - Testing ssh login 2026-07-22 13:30:02.342035 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-840bea31 tst 2026-07-22 13:30:03.108151 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-840bea31 tst 2026-07-22 13:30:03.824089 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-840bea31 tst 2026-07-22 13:30:04.589898 UTC - Logging in using 'slices bi ssh' 2026-07-22 13:30:04.589980 UTC - Forcing IPv4 only. 2026-07-22 13:30:04.590140 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-840bea31 tst -- -4 uname -a 2026-07-22 13:30:05.556344 UTC - Forcing IPv4 only. 2026-07-22 13:30:05.556554 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-840bea31 tst -- -4 uptime 2026-07-22 13:30:06.422613 UTC - CLI SSH Test passed. 2026-07-22 13:30:06.422687 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-22 13:30:06.422706 UTC - Uptime: 15:30:05 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-22 13:30:06.422739 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-22 13:30:06.423166 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:06.423322 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:06.423380 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:06.423573 UTC - Connecting to 10.10.217.244:22 2026-07-22 13:30:06.661180 UTC - SSH Test output: 2026-07-22 13:30:06.661254 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-22 13:30:06.661279 UTC - Uptime: 15:30:05 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-22 13:30:06.713111 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-22 13:30:06.760949 UTC - /etc/debian_version: 13.1 2026-07-22 13:30:06.761029 UTC - lsb_release matches expected value 2026-07-22 13:30:06.761050 UTC - /etc/debian_version matches expected value 2026-07-22 13:30:06.761065 UTC - SSH Test passed. 2026-07-22 13:30:06.808528 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 15:29:51 +0200! 2026-07-22 13:30:06.808675 UTC - Logging in using SSH over SSH proxy 2026-07-22 13:30:06.808867 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-840bea31 tst 2026-07-22 13:30:07.574944 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:07.575115 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:07.575189 UTC - Added paramiko HostKeyEntry for 10.10.217.244 2026-07-22 13:30:07.575419 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 13:30:07.575474 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 13:30:07.575592 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 13:30:07.575612 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-22 13:30:07.710217 UTC - Connecting to 10.10.217.244:22 over proxy 2026-07-22 13:30:07.950840 UTC - SSH Test output: 2026-07-22 13:30:07.950967 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-22 13:30:07.951008 UTC - Uptime: 15:30:07 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-22 13:30:08.007721 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-22 13:30:08.054108 UTC - /etc/debian_version: 13.1 2026-07-22 13:30:08.054216 UTC - lsb_release matches expected value 2026-07-22 13:30:08.054252 UTC - /etc/debian_version matches expected value 2026-07-22 13:30:08.054277 UTC - SSH Test passed. 2026-07-22 13:30:08.102106 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 15:29:51 +0200! 2026-07-22 13:30:08.113692 UTC - Destroying tst-840bea31 tst 2026-07-22 13:30:08.114003 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-840bea31 tst