Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.40s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 406ms 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 1.13s + 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.dev189+g506e4860f (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-28 20:18:23.457434 UTC - Installing Slices CLI (latest stable version). 2026-07-28 20:18:23.457974 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-28 20:18:30.946226 UTC - Slices CLI installed. 2026-07-28 20:18:30.946623 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-28 20:18:30.949580 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-28 20:18:30.949663 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-28 20:18:30.949747 UTC - which slices: /app/.venv/bin/slices 2026-07-28 20:18:30.949773 UTC - Slices CLI exe: 'slices' 2026-07-28 20:18:30.949795 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-28 20:18:30.949966 UTC - Run: slices --version 2026-07-28 20:18:33.922347 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-28 20:18:33.922649 UTC - Run: slices pubkey list --format text 2026-07-28 20:18:34.889041 UTC - Pubkey already registered 2026-07-28 20:18:34.889396 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-28 20:18:35.505287 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-28 20:18:35.505420 UTC - Check List Flavors 2026-07-28 20:18:35.505614 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-28 20:18:36.471778 UTC - Check List DiskImages 2026-07-28 20:18:36.471959 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-28 20:18:37.139013 UTC - Requesting resources 2026-07-28 20:18:37.139561 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-28 20:18:37.139681 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-8be08004 --user-data /tmp/tmpy021bdjx 2026-07-28 20:18:39.063639 UTC - Resource ID: r_be-gent1-bi-vm1_01kyn6275seyw8e0e0vgek9x5s 2026-07-28 20:18:39.063778 UTC - Waiting until resource ready 2026-07-28 20:18:41.064967 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8be08004 tst 2026-07-28 20:18:41.880878 UTC - Status: IMAGING 2026-07-28 20:18:44.600860 UTC - Status: IMAGING 2026-07-28 20:18:47.317335 UTC - Status: IMAGING 2026-07-28 20:18:50.033955 UTC - Status: BOOTING 2026-07-28 20:18:52.800242 UTC - Status: BOOTING 2026-07-28 20:18:55.570209 UTC - Status: BOOTING 2026-07-28 20:18:58.286396 UTC - Status: BOOTING 2026-07-28 20:19:01.052960 UTC - Status: BOOTING 2026-07-28 20:19:03.819389 UTC - Status: INITIALIZING 2026-07-28 20:19:06.637204 UTC - Status: UP 2026-07-28 20:19:06.637286 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyn626n2fpv8hkqp34w92edm 2026-07-28 20:19:06.637372 UTC - Validate resources 2026-07-28 20:19:07.403474 UTC - The fields of the created resource were validated. 2026-07-28 20:19:07.403572 UTC - Check if resources are registered in experiment 2026-07-28 20:19:07.403730 UTC - Run: slices experiment list-resources --format json tst-8be08004 2026-07-28 20:19:08.119458 UTC - Status (on expauth): UP 2026-07-28 20:19:08.119599 UTC - Testing extend expires_at (all resources in experiment) 2026-07-28 20:19:08.119728 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-8be08004 2026-07-28 20:19:10.389922 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8be08004 tst 2026-07-28 20:19:11.206073 UTC - Run: slices experiment list-resources --format json tst-8be08004 2026-07-28 20:19:11.871637 UTC - expires_at (on expauth): 2026-07-28T23:19:00Z (correctly extended) 2026-07-28 20:19:11.871730 UTC - Testing extend expires_at (single resource in experiment) 2026-07-28 20:19:11.871918 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-8be08004 2026-07-28 20:19:13.941123 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8be08004 tst 2026-07-28 20:19:14.710055 UTC - Run: slices experiment list-resources --format json tst-8be08004 2026-07-28 20:19:15.375929 UTC - expires_at (on expauth): 2026-07-29T00:19:00Z (correctly extended) 2026-07-28 20:19:15.376131 UTC - Testing ssh login 2026-07-28 20:19:15.390848 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8be08004 tst 2026-07-28 20:19:16.207345 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8be08004 tst 2026-07-28 20:19:16.973288 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8be08004 tst 2026-07-28 20:19:17.739342 UTC - Logging in using 'slices bi ssh' 2026-07-28 20:19:17.739421 UTC - Forcing IPv4 only. 2026-07-28 20:19:17.739568 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-8be08004 tst -- -4 uname -a 2026-07-28 20:19:19.156983 UTC - Forcing IPv4 only. 2026-07-28 20:19:19.157174 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-8be08004 tst -- -4 uptime 2026-07-28 20:19:20.073585 UTC - CLI SSH Test passed. 2026-07-28 20:19:20.073689 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-07-28 20:19:20.073709 UTC - Uptime: 22:19:18 up 0 min, 1 user, load average: 0.24, 0.06, 0.02 2026-07-28 20:19:20.073748 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-28 20:19:20.074365 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:20.074601 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:20.074671 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:20.074988 UTC - Connecting to 10.10.222.134:22 2026-07-28 20:19:20.312645 UTC - SSH Test output: 2026-07-28 20:19:20.312744 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-07-28 20:19:20.312774 UTC - Uptime: 22:19:19 up 0 min, 1 user, load average: 0.24, 0.06, 0.02 2026-07-28 20:19:20.363017 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-28 20:19:20.363097 UTC - lsb_release matches expected value 2026-07-28 20:19:20.363117 UTC - SSH Test passed. 2026-07-28 20:19:20.408314 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 22:19:02 +0200! 2026-07-28 20:19:20.408488 UTC - Logging in using SSH over SSH proxy 2026-07-28 20:19:20.408650 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8be08004 tst 2026-07-28 20:19:21.224875 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:21.225026 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:21.225079 UTC - Added paramiko HostKeyEntry for 10.10.222.134 2026-07-28 20:19:21.225319 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:19:21.225384 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:19:21.225491 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:19:21.225510 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-28 20:19:21.475022 UTC - Connecting to 10.10.222.134:22 over proxy 2026-07-28 20:19:21.720468 UTC - SSH Test output: 2026-07-28 20:19:21.720565 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-07-28 20:19:21.720594 UTC - Uptime: 22:19:20 up 0 min, 2 users, load average: 0.24, 0.06, 0.02 2026-07-28 20:19:21.773798 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-28 20:19:21.773882 UTC - lsb_release matches expected value 2026-07-28 20:19:21.773907 UTC - SSH Test passed. 2026-07-28 20:19:21.820448 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 22:19:02 +0200! 2026-07-28 20:19:21.827731 UTC - Destroying tst-8be08004 tst 2026-07-28 20:19:21.827929 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-8be08004 tst