Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.25s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 178ms 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 591ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-17 04:00:27.733811 UTC - Installing Slices CLI (latest stable version). 2026-07-17 04:00:27.734204 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 04:00:33.933924 UTC - Slices CLI installed. 2026-07-17 04:00:33.934195 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-17 04:00:33.940994 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-17 04:00:33.943055 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 04:00:33.943208 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-17 04:00:33.943235 UTC - Slices CLI exe: 'slices' 2026-07-17 04:00:33.943253 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 04:00:33.943396 UTC - Run: slices --version 2026-07-17 04:00:36.770297 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-17 04:00:36.770627 UTC - Run: slices pubkey list --format text 2026-07-17 04:00:37.736642 UTC - Pubkey already registered 2026-07-17 04:00:37.736910 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 04:00:38.301772 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 04:00:38.301867 UTC - Check List Flavors 2026-07-17 04:00:38.302016 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-17 04:00:40.070019 UTC - Check List DiskImages 2026-07-17 04:00:40.070280 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-17 04:00:40.686067 UTC - Requesting resources 2026-07-17 04:00:40.686613 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 04:00:40.686750 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-471d49c3 --user-data /tmp/tmp1zr138iv 2026-07-17 04:00:45.511623 UTC - Resource ID: r_be-gent1-bi-vm1_01kxq3qqsjf6895019x5b3yxy5 2026-07-17 04:00:45.511820 UTC - Waiting until resource ready 2026-07-17 04:00:47.512236 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-471d49c3 tst 2026-07-17 04:00:48.227941 UTC - Status: IMAGING 2026-07-17 04:00:51.067540 UTC - Status: IMAGING 2026-07-17 04:00:54.123041 UTC - Status: IMAGING 2026-07-17 04:00:57.044495 UTC - Status: BOOTING 2026-07-17 04:01:00.262407 UTC - Status: BOOTING 2026-07-17 04:01:03.032000 UTC - Status: BOOTING 2026-07-17 04:01:05.798216 UTC - Status: BOOTING 2026-07-17 04:01:08.572521 UTC - Status: INITIALIZING 2026-07-17 04:01:11.598207 UTC - Status: UP 2026-07-17 04:01:11.598283 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxq3qpn8fgb9n1zfyprj3jce 2026-07-17 04:01:11.598325 UTC - Validate resources 2026-07-17 04:01:12.535307 UTC - The fields of the created resource were validated. 2026-07-17 04:01:12.535414 UTC - Check if resources are registered in experiment 2026-07-17 04:01:12.535570 UTC - Run: slices experiment list-resources --format json tst-471d49c3 2026-07-17 04:01:13.322483 UTC - Status (on expauth): UP 2026-07-17 04:01:13.322649 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 04:01:13.322790 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-471d49c3 2026-07-17 04:01:16.451373 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-471d49c3 tst 2026-07-17 04:01:17.166909 UTC - Run: slices experiment list-resources --format json tst-471d49c3 2026-07-17 04:01:17.782143 UTC - expires_at (on expauth): 2026-07-17T07:01:00Z (correctly extended) 2026-07-17 04:01:17.782250 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 04:01:17.782408 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-471d49c3 2026-07-17 04:01:21.258033 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-471d49c3 tst 2026-07-17 04:01:22.181953 UTC - Run: slices experiment list-resources --format json tst-471d49c3 2026-07-17 04:01:22.847490 UTC - expires_at (on expauth): 2026-07-17T08:01:00Z (correctly extended) 2026-07-17 04:01:22.847838 UTC - Testing ssh login 2026-07-17 04:01:22.861727 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-471d49c3 tst 2026-07-17 04:01:23.577641 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-471d49c3 tst 2026-07-17 04:01:24.343456 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-471d49c3 tst 2026-07-17 04:01:25.109374 UTC - Logging in using 'slices bi ssh' 2026-07-17 04:01:25.109463 UTC - Forcing IPv4 only. 2026-07-17 04:01:25.109614 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-471d49c3 tst -- -4 uname -a 2026-07-17 04:01:26.582150 UTC - Forcing IPv4 only. 2026-07-17 04:01:26.582360 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-471d49c3 tst -- -4 uptime 2026-07-17 04:01:27.498277 UTC - CLI SSH Test passed. 2026-07-17 04:01:27.498354 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-17 04:01:27.498372 UTC - Uptime: 06:01:27 up 0 min, 2 users, load average: 0.06, 0.01, 0.00 2026-07-17 04:01:27.498406 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-17 04:01:27.498909 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:27.499536 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:27.499631 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:27.499845 UTC - Connecting to 10.10.218.133:22 2026-07-17 04:01:27.734084 UTC - SSH Test output: 2026-07-17 04:01:27.734183 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-17 04:01:27.734215 UTC - Uptime: 06:01:27 up 0 min, 2 users, load average: 0.06, 0.01, 0.00 2026-07-17 04:01:27.783240 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 04:01:27.783336 UTC - lsb_release matches expected value 2026-07-17 04:01:27.783356 UTC - SSH Test passed. 2026-07-17 04:01:27.827390 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 06:01:08 +0200! 2026-07-17 04:01:27.827560 UTC - Logging in using SSH over SSH proxy 2026-07-17 04:01:27.827735 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-471d49c3 tst 2026-07-17 04:01:28.644313 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:28.644508 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:28.644572 UTC - Added paramiko HostKeyEntry for 10.10.218.133 2026-07-17 04:01:28.644914 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 04:01:28.644967 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 04:01:28.645083 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 04:01:28.645104 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-17 04:01:28.913879 UTC - Connecting to 10.10.218.133:22 over proxy 2026-07-17 04:01:29.205701 UTC - SSH Test output: 2026-07-17 04:01:29.205839 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-17 04:01:29.205875 UTC - Uptime: 06:01:28 up 0 min, 2 users, load average: 0.06, 0.01, 0.00 2026-07-17 04:01:29.257509 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 04:01:29.257606 UTC - lsb_release matches expected value 2026-07-17 04:01:29.257626 UTC - SSH Test passed. 2026-07-17 04:01:29.304763 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 06:01:08 +0200! 2026-07-17 04:01:29.312661 UTC - Destroying tst-471d49c3 tst 2026-07-17 04:01:29.312952 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-471d49c3 tst