Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.22s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 368ms 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 399ms + 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-15 04:14:43.474883 UTC - Installing Slices CLI (latest stable version). 2026-07-15 04:14:43.475103 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 04:14:51.708260 UTC - Slices CLI installed. 2026-07-15 04:14:51.708475 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-15 04:14:51.711013 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-15 04:14:51.711923 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 04:14:51.712043 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-15 04:14:51.712071 UTC - Slices CLI exe: 'slices' 2026-07-15 04:14:51.712087 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 04:14:51.712218 UTC - Run: slices --version 2026-07-15 04:14:54.230877 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-15 04:14:54.231141 UTC - Run: slices pubkey list --format text 2026-07-15 04:14:54.996589 UTC - Pubkey already registered 2026-07-15 04:14:54.997000 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 04:14:55.564273 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 04:14:55.564365 UTC - Check List Flavors 2026-07-15 04:14:55.564505 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-15 04:14:56.782264 UTC - Check List DiskImages 2026-07-15 04:14:56.782470 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-15 04:14:57.397588 UTC - Requesting resources 2026-07-15 04:14:57.398068 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 04:14:57.398178 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-105516c0 --user-data /tmp/tmptvh2p4mj 2026-07-15 04:15:00.568002 UTC - Resource ID: r_be-gent1-bi-vm1_01kxhzrcswe2cvshwzrfpzqaks 2026-07-15 04:15:00.568153 UTC - Waiting until resource ready 2026-07-15 04:15:02.568520 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-105516c0 tst 2026-07-15 04:15:03.283986 UTC - Status: IMAGING 2026-07-15 04:15:05.999937 UTC - Status: IMAGING 2026-07-15 04:15:08.715833 UTC - Status: IMAGING 2026-07-15 04:15:11.483005 UTC - Status: BOOTING 2026-07-15 04:15:14.198759 UTC - Status: BOOTING 2026-07-15 04:15:16.914508 UTC - Status: BOOTING 2026-07-15 04:15:19.680790 UTC - Status: BOOTING 2026-07-15 04:15:22.549993 UTC - Status: BOOTING 2026-07-15 04:15:26.173117 UTC - Status: STARTING 2026-07-15 04:15:29.745533 UTC - Status: UP 2026-07-15 04:15:29.746128 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxhzrbr9fhe8ek7d7wvqq2ex 2026-07-15 04:15:29.746191 UTC - Validate resources 2026-07-15 04:15:30.901939 UTC - The fields of the created resource were validated. 2026-07-15 04:15:30.902038 UTC - Check if resources are registered in experiment 2026-07-15 04:15:30.902193 UTC - Run: slices experiment list-resources --format json tst-105516c0 2026-07-15 04:15:32.266325 UTC - Status (on expauth): UP 2026-07-15 04:15:32.266472 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 04:15:32.266587 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-105516c0 2026-07-15 04:15:37.557230 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-105516c0 tst 2026-07-15 04:15:39.393941 UTC - Run: slices experiment list-resources --format json tst-105516c0 2026-07-15 04:15:40.982102 UTC - expires_at (on expauth): 2026-07-15T07:15:00Z (correctly extended) 2026-07-15 04:15:40.982863 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 04:15:40.983039 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-105516c0 2026-07-15 04:15:45.719801 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-105516c0 tst 2026-07-15 04:15:46.895223 UTC - Run: slices experiment list-resources --format json tst-105516c0 2026-07-15 04:15:48.329514 UTC - expires_at (on expauth): 2026-07-15T08:15:00Z (correctly extended) 2026-07-15 04:15:48.329661 UTC - Testing ssh login 2026-07-15 04:15:48.365710 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-105516c0 tst 2026-07-15 04:15:50.430083 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-105516c0 tst 2026-07-15 04:15:51.568656 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-105516c0 tst 2026-07-15 04:15:52.589187 UTC - Logging in using 'slices bi ssh' 2026-07-15 04:15:52.589253 UTC - Forcing IPv4 only. 2026-07-15 04:15:52.589368 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-105516c0 tst -- -4 uname -a 2026-07-15 04:15:54.524944 UTC - Forcing IPv4 only. 2026-07-15 04:15:54.525153 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-105516c0 tst -- -4 uptime 2026-07-15 04:15:55.552150 UTC - CLI SSH Test passed. 2026-07-15 04:15:55.552228 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-15 04:15:55.552257 UTC - Uptime: 06:15:54 up 0 min, 1 user, load average: 0.56, 0.17, 0.06 2026-07-15 04:15:55.552306 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-15 04:15:55.552812 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:55.552989 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:55.553049 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:55.553233 UTC - Connecting to 10.10.217.184:22 2026-07-15 04:15:55.791395 UTC - SSH Test output: 2026-07-15 04:15:55.791489 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-15 04:15:55.791518 UTC - Uptime: 06:15:54 up 0 min, 1 user, load average: 0.56, 0.17, 0.06 2026-07-15 04:15:55.840881 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 04:15:55.840959 UTC - lsb_release matches expected value 2026-07-15 04:15:55.840981 UTC - SSH Test passed. 2026-07-15 04:15:55.885678 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 06:15:24 +0200! 2026-07-15 04:15:55.885807 UTC - Logging in using SSH over SSH proxy 2026-07-15 04:15:55.885951 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-105516c0 tst 2026-07-15 04:15:57.200772 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:57.200930 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:57.200985 UTC - Added paramiko HostKeyEntry for 10.10.217.184 2026-07-15 04:15:57.201225 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:15:57.201282 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:15:57.201390 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:15:57.201411 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-15 04:15:57.476536 UTC - Connecting to 10.10.217.184:22 over proxy 2026-07-15 04:15:57.724276 UTC - SSH Test output: 2026-07-15 04:15:57.724360 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-15 04:15:57.724381 UTC - Uptime: 06:15:57 up 0 min, 2 users, load average: 0.56, 0.17, 0.06 2026-07-15 04:15:57.776888 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 04:15:57.776964 UTC - lsb_release matches expected value 2026-07-15 04:15:57.776982 UTC - SSH Test passed. 2026-07-15 04:15:57.825144 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 06:15:24 +0200! 2026-07-15 04:15:57.834842 UTC - Destroying tst-105516c0 tst 2026-07-15 04:15:57.835374 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-105516c0 tst