Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.24s 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 227ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-14 13:24:57.357207 UTC - Installing Slices CLI (latest stable version). 2026-07-14 13:24:57.357433 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 13:25:02.132250 UTC - Slices CLI installed. 2026-07-14 13:25:02.132451 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-14 13:25:02.133673 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-14 13:25:02.134410 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 13:25:02.134522 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-14 13:25:02.134547 UTC - Slices CLI exe: 'slices' 2026-07-14 13:25:02.134563 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 13:25:02.134696 UTC - Run: slices --version 2026-07-14 13:25:04.653716 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-14 13:25:04.654039 UTC - Run: slices pubkey list --format text 2026-07-14 13:25:05.519785 UTC - Pubkey already registered 2026-07-14 13:25:05.520043 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 13:25:06.135493 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 13:25:06.135611 UTC - Check List Flavors 2026-07-14 13:25:06.135783 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-14 13:25:07.603046 UTC - Check List DiskImages 2026-07-14 13:25:07.603236 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-14 13:25:08.218661 UTC - Requesting resources 2026-07-14 13:25:08.219147 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 13:25:08.219260 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-76531ae1 --user-data /tmp/tmp1guwte3u 2026-07-14 13:25:12.394742 UTC - Resource ID: r_be-gent1-bi-vm1_01kxgcv3x2edrrfrffb39qrn5e 2026-07-14 13:25:12.394945 UTC - Waiting until resource ready 2026-07-14 13:25:14.395401 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-76531ae1 tst 2026-07-14 13:25:15.111181 UTC - Status: IMAGING 2026-07-14 13:25:17.777091 UTC - Status: IMAGING 2026-07-14 13:25:20.493064 UTC - Status: IMAGING 2026-07-14 13:25:23.781532 UTC - Status: BOOTING 2026-07-14 13:25:26.600475 UTC - Status: BOOTING 2026-07-14 13:25:29.616988 UTC - Status: BOOTING 2026-07-14 13:25:32.440999 UTC - Status: BOOTING 2026-07-14 13:25:35.689474 UTC - Status: UP 2026-07-14 13:25:35.689583 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxgcv2mses1bm9e8b8qm59p3 2026-07-14 13:25:35.689645 UTC - Validate resources 2026-07-14 13:25:36.517726 UTC - The fields of the created resource were validated. 2026-07-14 13:25:36.517839 UTC - Check if resources are registered in experiment 2026-07-14 13:25:36.518009 UTC - Run: slices experiment list-resources --format json tst-76531ae1 2026-07-14 13:25:37.858649 UTC - Status (on expauth): UP 2026-07-14 13:25:37.858804 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 13:25:37.858934 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-76531ae1 2026-07-14 13:25:41.295111 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-76531ae1 tst 2026-07-14 13:25:42.390960 UTC - Run: slices experiment list-resources --format json tst-76531ae1 2026-07-14 13:25:43.320546 UTC - expires_at (on expauth): 2026-07-14T16:25:00Z (correctly extended) 2026-07-14 13:25:43.332949 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 13:25:43.333171 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-76531ae1 2026-07-14 13:25:47.348356 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-76531ae1 tst 2026-07-14 13:25:48.722992 UTC - Run: slices experiment list-resources --format json tst-76531ae1 2026-07-14 13:25:49.855284 UTC - expires_at (on expauth): 2026-07-14T17:25:00Z (correctly extended) 2026-07-14 13:25:49.855415 UTC - Testing ssh login 2026-07-14 13:25:49.883439 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-76531ae1 tst 2026-07-14 13:25:51.133394 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-76531ae1 tst 2026-07-14 13:25:51.952948 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-76531ae1 tst 2026-07-14 13:25:53.424791 UTC - Logging in using 'slices bi ssh' 2026-07-14 13:25:53.424863 UTC - Forcing IPv4 only. 2026-07-14 13:25:53.424989 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-76531ae1 tst -- -4 uname -a 2026-07-14 13:25:55.014742 UTC - Forcing IPv4 only. 2026-07-14 13:25:55.014917 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-76531ae1 tst -- -4 uptime 2026-07-14 13:25:56.086156 UTC - CLI SSH Test passed. 2026-07-14 13:25:56.086229 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-14 13:25:56.086249 UTC - Uptime: 15:25:54 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-14 13:25:56.086282 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-14 13:25:56.087387 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:56.087580 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:56.087641 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:56.087819 UTC - Connecting to 10.10.218.63:22 2026-07-14 13:25:56.332424 UTC - SSH Test output: 2026-07-14 13:25:56.332502 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-14 13:25:56.332522 UTC - Uptime: 15:25:54 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-14 13:25:56.384654 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-14 13:25:56.432147 UTC - /etc/debian_version: 13.1 2026-07-14 13:25:56.432221 UTC - lsb_release matches expected value 2026-07-14 13:25:56.432241 UTC - /etc/debian_version matches expected value 2026-07-14 13:25:56.432256 UTC - SSH Test passed. 2026-07-14 13:25:56.479675 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 15:25:31 +0200! 2026-07-14 13:25:56.479811 UTC - Logging in using SSH over SSH proxy 2026-07-14 13:25:56.479949 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-76531ae1 tst 2026-07-14 13:25:57.411393 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:57.411541 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:57.411594 UTC - Added paramiko HostKeyEntry for 10.10.218.63 2026-07-14 13:25:57.411814 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:25:57.411862 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:25:57.411959 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:25:57.411978 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-14 13:25:57.667086 UTC - Connecting to 10.10.218.63:22 over proxy 2026-07-14 13:25:57.909667 UTC - SSH Test output: 2026-07-14 13:25:57.909759 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-14 13:25:57.909796 UTC - Uptime: 15:25:56 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-14 13:25:57.961826 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-14 13:25:58.010383 UTC - /etc/debian_version: 13.1 2026-07-14 13:25:58.010483 UTC - lsb_release matches expected value 2026-07-14 13:25:58.010516 UTC - /etc/debian_version matches expected value 2026-07-14 13:25:58.010539 UTC - SSH Test passed. 2026-07-14 13:25:58.058478 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 15:25:31 +0200! 2026-07-14 13:25:58.076549 UTC - Destroying tst-76531ae1 tst 2026-07-14 13:25:58.076904 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-76531ae1 tst