Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.78s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 207ms 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 297ms + 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-08 13:49:20.541399 UTC - Installing Slices CLI (latest stable version). 2026-08-08 13:49:20.541640 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 13:49:26.124675 UTC - Slices CLI installed. 2026-08-08 13:49:26.125013 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-08 13:49:26.132707 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-08 13:49:26.132882 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-08 13:49:26.133003 UTC - which slices: /app/.venv/bin/slices 2026-08-08 13:49:26.133032 UTC - Slices CLI exe: 'slices' 2026-08-08 13:49:26.133056 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 13:49:26.133265 UTC - Run: slices --version 2026-08-08 13:49:29.003034 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-08 13:49:29.003332 UTC - Run: slices pubkey list --format text 2026-08-08 13:49:29.819412 UTC - Pubkey already registered 2026-08-08 13:49:29.819679 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 13:49:30.435219 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 13:49:30.435323 UTC - Check List Flavors 2026-08-08 13:49:30.435495 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-08 13:49:31.151120 UTC - Check List DiskImages 2026-08-08 13:49:31.151313 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-08 13:49:31.766658 UTC - Requesting resources 2026-08-08 13:49:31.767197 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 13:49:31.767371 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-4842645a --user-data /tmp/tmpejl47z7t 2026-08-08 13:49:32.833772 UTC - Resource ID: r_be-gent1-bi-vm1_01kzgt5n4jerpthk1tyv35axps 2026-08-08 13:49:32.833991 UTC - Waiting until resource ready 2026-08-08 13:49:34.834520 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4842645a tst 2026-08-08 13:49:35.550868 UTC - Status: IMAGING 2026-08-08 13:49:38.267219 UTC - Status: IMAGING 2026-08-08 13:49:40.985908 UTC - Status: IMAGING 2026-08-08 13:49:43.752280 UTC - Status: BOOTING 2026-08-08 13:49:46.468845 UTC - Status: BOOTING 2026-08-08 13:49:49.185250 UTC - Status: BOOTING 2026-08-08 13:49:51.902124 UTC - Status: BOOTING 2026-08-08 13:49:54.668486 UTC - Status: UP 2026-08-08 13:49:54.668567 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzgt5mxheyrbbz0bp09n1hrp 2026-08-08 13:49:54.668629 UTC - Validate resources 2026-08-08 13:49:55.434913 UTC - The fields of the created resource were validated. 2026-08-08 13:49:55.435005 UTC - Check if resources are registered in experiment 2026-08-08 13:49:55.435179 UTC - Run: slices experiment list-resources --format json tst-4842645a 2026-08-08 13:49:56.100762 UTC - Status (on expauth): UP 2026-08-08 13:49:56.100904 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 13:49:56.101039 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-4842645a 2026-08-08 13:49:57.519619 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4842645a tst 2026-08-08 13:49:58.235437 UTC - Run: slices experiment list-resources --format json tst-4842645a 2026-08-08 13:49:58.900903 UTC - expires_at (on expauth): 2026-08-08T16:49:00Z (correctly extended) 2026-08-08 13:49:58.900999 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 13:49:58.901170 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-4842645a 2026-08-08 13:50:00.318683 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-4842645a tst 2026-08-08 13:50:01.091742 UTC - Run: slices experiment list-resources --format json tst-4842645a 2026-08-08 13:50:01.758408 UTC - expires_at (on expauth): 2026-08-08T17:49:00Z (correctly extended) 2026-08-08 13:50:01.758545 UTC - Testing ssh login 2026-08-08 13:50:01.773226 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4842645a tst 2026-08-08 13:50:02.539399 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4842645a tst 2026-08-08 13:50:03.255278 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-4842645a tst 2026-08-08 13:50:04.021079 UTC - Logging in using 'slices bi ssh' 2026-08-08 13:50:04.021167 UTC - Forcing IPv4 only. 2026-08-08 13:50:04.021303 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-4842645a tst -- -4 uname -a 2026-08-08 13:50:04.987788 UTC - Forcing IPv4 only. 2026-08-08 13:50:04.987993 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-4842645a tst -- -4 uptime 2026-08-08 13:50:05.804170 UTC - CLI SSH Test passed. 2026-08-08 13:50:05.804250 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-08 13:50:05.804269 UTC - Uptime: 15:50:04 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-08-08 13:50:05.804304 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-08 13:50:05.804800 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:05.805010 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:05.805074 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:05.805263 UTC - Connecting to 10.10.212.238:22 2026-08-08 13:50:06.038789 UTC - SSH Test output: 2026-08-08 13:50:06.038864 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-08 13:50:06.038885 UTC - Uptime: 15:50:04 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-08-08 13:50:06.090887 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-08 13:50:06.138461 UTC - /etc/debian_version: 13.1 2026-08-08 13:50:06.138534 UTC - lsb_release matches expected value 2026-08-08 13:50:06.138554 UTC - /etc/debian_version matches expected value 2026-08-08 13:50:06.138569 UTC - SSH Test passed. 2026-08-08 13:50:06.186364 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 15:49:52 +0200! 2026-08-08 13:50:06.186503 UTC - Logging in using SSH over SSH proxy 2026-08-08 13:50:06.186654 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-4842645a tst 2026-08-08 13:50:06.952587 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:06.952776 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:06.952838 UTC - Added paramiko HostKeyEntry for 10.10.212.238 2026-08-08 13:50:06.953103 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-08 13:50:06.953159 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-08 13:50:06.953258 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-08 13:50:06.953278 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-08 13:50:07.279339 UTC - Connecting to 10.10.212.238:22 over proxy 2026-08-08 13:50:07.509286 UTC - SSH Test output: 2026-08-08 13:50:07.509363 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-08 13:50:07.509383 UTC - Uptime: 15:50:06 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-08-08 13:50:07.561412 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-08 13:50:07.608638 UTC - /etc/debian_version: 13.1 2026-08-08 13:50:07.608733 UTC - lsb_release matches expected value 2026-08-08 13:50:07.608758 UTC - /etc/debian_version matches expected value 2026-08-08 13:50:07.608773 UTC - SSH Test passed. 2026-08-08 13:50:07.657092 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 15:49:52 +0200! 2026-08-08 13:50:07.664514 UTC - Destroying tst-4842645a tst 2026-08-08 13:50:07.664703 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-4842645a tst