Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.23s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 215ms 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 370ms + 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-09 15:26:14.946786 UTC - Installing Slices CLI (latest stable version). 2026-08-09 15:26:14.947184 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-09 15:26:20.172785 UTC - Slices CLI installed. 2026-08-09 15:26:20.173428 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-09 15:26:20.177012 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-09 15:26:20.177121 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-09 15:26:20.177199 UTC - which slices: /app/.venv/bin/slices 2026-08-09 15:26:20.177221 UTC - Slices CLI exe: 'slices' 2026-08-09 15:26:20.177238 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-09 15:26:20.177392 UTC - Run: slices --version 2026-08-09 15:26:23.003219 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-09 15:26:23.003479 UTC - Run: slices pubkey list --format text 2026-08-09 15:26:23.770712 UTC - Pubkey already registered 2026-08-09 15:26:23.770956 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-09 15:26:24.386108 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-09 15:26:24.386210 UTC - Check List Flavors 2026-08-09 15:26:24.386369 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-09 15:26:25.152148 UTC - Check List DiskImages 2026-08-09 15:26:25.152345 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-09 15:26:25.930717 UTC - Requesting resources 2026-08-09 15:26:25.932098 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-09 15:26:25.932426 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-fb74373a --user-data /tmp/tmpqyj8ggya 2026-08-09 15:26:27.101920 UTC - Resource ID: r_be-gent1-bi-vm1_01kzkj3t3afb4atdfpqfyv25qq 2026-08-09 15:26:27.102094 UTC - Waiting until resource ready 2026-08-09 15:26:29.102470 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fb74373a tst 2026-08-09 15:26:29.969074 UTC - Status: IMAGING 2026-08-09 15:26:32.885963 UTC - Status: IMAGING 2026-08-09 15:26:35.756641 UTC - Status: IMAGING 2026-08-09 15:26:38.644522 UTC - Status: BOOTING 2026-08-09 15:26:41.411554 UTC - Status: BOOTING 2026-08-09 15:26:44.338735 UTC - Status: BOOTING 2026-08-09 15:26:47.155516 UTC - Status: BOOTING 2026-08-09 15:26:49.926003 UTC - Status: INITIALIZING 2026-08-09 15:26:53.157090 UTC - Status: UP 2026-08-09 15:26:53.157224 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzkj3swgezh861xq5bcfq4cn 2026-08-09 15:26:53.157416 UTC - Validate resources 2026-08-09 15:26:54.239712 UTC - The fields of the created resource were validated. 2026-08-09 15:26:54.239815 UTC - Check if resources are registered in experiment 2026-08-09 15:26:54.240027 UTC - Run: slices experiment list-resources --format json tst-fb74373a 2026-08-09 15:26:55.063261 UTC - Status (on expauth): UP 2026-08-09 15:26:55.063393 UTC - Testing extend expires_at (all resources in experiment) 2026-08-09 15:26:55.063509 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-fb74373a 2026-08-09 15:26:56.681569 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fb74373a tst 2026-08-09 15:26:57.497763 UTC - Run: slices experiment list-resources --format json tst-fb74373a 2026-08-09 15:26:58.213233 UTC - expires_at (on expauth): 2026-08-09T18:26:00Z (correctly extended) 2026-08-09 15:26:58.213363 UTC - Testing extend expires_at (single resource in experiment) 2026-08-09 15:26:58.213524 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-fb74373a 2026-08-09 15:26:59.627670 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fb74373a tst 2026-08-09 15:27:00.394091 UTC - Run: slices experiment list-resources --format json tst-fb74373a 2026-08-09 15:27:01.097822 UTC - expires_at (on expauth): 2026-08-09T19:26:00Z (correctly extended) 2026-08-09 15:27:01.098184 UTC - Testing ssh login 2026-08-09 15:27:01.121446 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-fb74373a tst 2026-08-09 15:27:02.038596 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-fb74373a tst 2026-08-09 15:27:02.909001 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-fb74373a tst 2026-08-09 15:27:03.674585 UTC - Logging in using 'slices bi ssh' 2026-08-09 15:27:03.674656 UTC - Forcing IPv4 only. 2026-08-09 15:27:03.674773 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-fb74373a tst -- -4 uname -a 2026-08-09 15:27:05.094475 UTC - Forcing IPv4 only. 2026-08-09 15:27:05.094652 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-fb74373a tst -- -4 uptime 2026-08-09 15:27:06.114747 UTC - CLI SSH Test passed. 2026-08-09 15:27:06.114819 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-09 15:27:06.114841 UTC - Uptime: 17:27:05 up 0 min, 1 user, load average: 1.10, 0.28, 0.09 2026-08-09 15:27:06.114883 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-09 15:27:06.115346 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:06.115527 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:06.115590 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:06.115778 UTC - Connecting to 10.10.209.1:22 2026-08-09 15:27:06.366547 UTC - SSH Test output: 2026-08-09 15:27:06.366621 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-09 15:27:06.366645 UTC - Uptime: 17:27:05 up 0 min, 1 user, load average: 1.10, 0.28, 0.09 2026-08-09 15:27:06.416502 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 15:27:06.416581 UTC - lsb_release matches expected value 2026-08-09 15:27:06.416601 UTC - SSH Test passed. 2026-08-09 15:27:06.465083 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 17:26:51 +0200! 2026-08-09 15:27:06.465228 UTC - Logging in using SSH over SSH proxy 2026-08-09 15:27:06.465381 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-fb74373a tst 2026-08-09 15:27:07.382518 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:07.382655 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:07.382706 UTC - Added paramiko HostKeyEntry for 10.10.209.1 2026-08-09 15:27:07.382930 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-09 15:27:07.382994 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-09 15:27:07.383096 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-09 15:27:07.383115 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-09 15:27:07.701447 UTC - Connecting to 10.10.209.1:22 over proxy 2026-08-09 15:27:07.999989 UTC - SSH Test output: 2026-08-09 15:27:08.000062 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-08-09 15:27:08.000082 UTC - Uptime: 17:27:07 up 0 min, 2 users, load average: 1.10, 0.28, 0.09 2026-08-09 15:27:08.051713 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-09 15:27:08.051788 UTC - lsb_release matches expected value 2026-08-09 15:27:08.051808 UTC - SSH Test passed. 2026-08-09 15:27:08.103306 UTC - Cloud-init user-data: Hello World. The time is now Sun, 09 Aug 2026 17:26:51 +0200! 2026-08-09 15:27:08.110966 UTC - Destroying tst-fb74373a tst 2026-08-09 15:27:08.111163 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-fb74373a tst