Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.26s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 190ms 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 530ms + 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.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-07-30 05:00:42.725779 UTC - Installing Slices CLI (latest stable version). 2026-07-30 05:00:42.726233 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 05:00:48.306420 UTC - Slices CLI installed. 2026-07-30 05:00:48.307764 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-30 05:00:48.313046 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-30 05:00:48.313152 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-30 05:00:48.313254 UTC - which slices: /app/.venv/bin/slices 2026-07-30 05:00:48.313280 UTC - Slices CLI exe: 'slices' 2026-07-30 05:00:48.313309 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 05:00:48.313481 UTC - Run: slices --version 2026-07-30 05:00:51.154963 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-07-30 05:00:51.155275 UTC - Run: slices pubkey list --format text 2026-07-30 05:00:51.920942 UTC - Pubkey already registered 2026-07-30 05:00:51.921212 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 05:00:52.536440 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 05:00:52.536546 UTC - Check List Flavors 2026-07-30 05:00:52.536754 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-30 05:00:53.252537 UTC - Check List DiskImages 2026-07-30 05:00:53.252738 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-30 05:00:53.918215 UTC - Requesting resources 2026-07-30 05:00:53.918898 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 05:00:53.919027 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-52b95476 --user-data /tmp/tmp4dmf0c0y 2026-07-30 05:00:54.988850 UTC - Resource ID: r_be-gent1-bi-vm1_01kyrpb7p7efda2n8qrr5h9xp9 2026-07-30 05:00:54.989002 UTC - Waiting until resource ready 2026-07-30 05:00:56.990341 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-52b95476 tst 2026-07-30 05:00:57.756014 UTC - Status: IMAGING 2026-07-30 05:01:00.794391 UTC - Status: IMAGING 2026-07-30 05:01:03.561088 UTC - Status: IMAGING 2026-07-30 05:01:06.384443 UTC - Status: BOOTING 2026-07-30 05:01:09.239668 UTC - Status: BOOTING 2026-07-30 05:01:12.006281 UTC - Status: BOOTING 2026-07-30 05:01:14.872754 UTC - Status: BOOTING 2026-07-30 05:01:17.739460 UTC - Status: BOOTING 2026-07-30 05:01:20.560915 UTC - Status: UP 2026-07-30 05:01:20.561693 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyrpb7emfxtrt2k5f9mxj5x3 2026-07-30 05:01:20.561768 UTC - Validate resources 2026-07-30 05:01:21.329838 UTC - The fields of the created resource were validated. 2026-07-30 05:01:21.329959 UTC - Check if resources are registered in experiment 2026-07-30 05:01:21.330110 UTC - Run: slices experiment list-resources --format json tst-52b95476 2026-07-30 05:01:21.995463 UTC - Status (on expauth): UP 2026-07-30 05:01:21.995599 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 05:01:21.995712 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-52b95476 2026-07-30 05:01:23.570115 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-52b95476 tst 2026-07-30 05:01:24.338562 UTC - Run: slices experiment list-resources --format json tst-52b95476 2026-07-30 05:01:25.455617 UTC - expires_at (on expauth): 2026-07-30T08:01:00Z (correctly extended) 2026-07-30 05:01:25.455754 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 05:01:25.455983 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-52b95476 2026-07-30 05:01:27.226336 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-52b95476 tst 2026-07-30 05:01:27.996364 UTC - Run: slices experiment list-resources --format json tst-52b95476 2026-07-30 05:01:28.662762 UTC - expires_at (on expauth): 2026-07-30T09:01:00Z (correctly extended) 2026-07-30 05:01:28.662873 UTC - Testing ssh login 2026-07-30 05:01:28.682326 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-52b95476 tst 2026-07-30 05:01:29.548856 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-52b95476 tst 2026-07-30 05:01:30.340066 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-52b95476 tst 2026-07-30 05:01:31.112023 UTC - Logging in using 'slices bi ssh' 2026-07-30 05:01:31.112103 UTC - Forcing IPv4 only. 2026-07-30 05:01:31.112248 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-52b95476 tst -- -4 uname -a 2026-07-30 05:01:32.184058 UTC - Forcing IPv4 only. 2026-07-30 05:01:32.184250 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-52b95476 tst -- -4 uptime 2026-07-30 05:01:33.050149 UTC - CLI SSH Test passed. 2026-07-30 05:01:33.050274 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-30 05:01:33.050297 UTC - Uptime: 07:01:32 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-30 05:01:33.050331 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-30 05:01:33.050780 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:33.050934 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:33.050989 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:33.051164 UTC - Connecting to 10.10.216.135:22 2026-07-30 05:01:33.297863 UTC - SSH Test output: 2026-07-30 05:01:33.297952 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-30 05:01:33.297992 UTC - Uptime: 07:01:32 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-30 05:01:33.353023 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-30 05:01:33.401595 UTC - /etc/debian_version: 13.1 2026-07-30 05:01:33.401684 UTC - lsb_release matches expected value 2026-07-30 05:01:33.401706 UTC - /etc/debian_version matches expected value 2026-07-30 05:01:33.401721 UTC - SSH Test passed. 2026-07-30 05:01:33.447615 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 07:01:16 +0200! 2026-07-30 05:01:33.447759 UTC - Logging in using SSH over SSH proxy 2026-07-30 05:01:33.447906 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-52b95476 tst 2026-07-30 05:01:34.263957 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:34.264095 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:34.264144 UTC - Added paramiko HostKeyEntry for 10.10.216.135 2026-07-30 05:01:34.264429 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 05:01:34.264492 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 05:01:34.264603 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 05:01:34.264624 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-30 05:01:34.504466 UTC - Connecting to 10.10.216.135:22 over proxy 2026-07-30 05:01:34.736988 UTC - SSH Test output: 2026-07-30 05:01:34.737065 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-30 05:01:34.737094 UTC - Uptime: 07:01:33 up 0 min, 2 users, load average: 0.11, 0.03, 0.01 2026-07-30 05:01:34.788793 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-30 05:01:34.836205 UTC - /etc/debian_version: 13.1 2026-07-30 05:01:34.836281 UTC - lsb_release matches expected value 2026-07-30 05:01:34.836310 UTC - /etc/debian_version matches expected value 2026-07-30 05:01:34.836335 UTC - SSH Test passed. 2026-07-30 05:01:34.884565 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 07:01:16 +0200! 2026-07-30 05:01:34.892587 UTC - Destroying tst-52b95476 tst 2026-07-30 05:01:34.892842 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-52b95476 tst