Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.59s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 200ms 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 293ms + 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.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.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-20 04:13:03.151183 UTC - Installing Slices CLI (latest stable version). 2026-07-20 04:13:03.151417 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 04:13:11.333413 UTC - Slices CLI installed. 2026-07-20 04:13:11.333596 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-20 04:13:11.334726 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-20 04:13:11.335529 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 04:13:11.335639 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-20 04:13:11.335674 UTC - Slices CLI exe: 'slices' 2026-07-20 04:13:11.335698 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 04:13:11.335863 UTC - Run: slices --version 2026-07-20 04:13:14.161361 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-20 04:13:14.161699 UTC - Run: slices pubkey list --format text 2026-07-20 04:13:14.977448 UTC - Pubkey already registered 2026-07-20 04:13:14.977761 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 04:13:15.643311 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 04:13:15.643443 UTC - Check List Flavors 2026-07-20 04:13:15.643672 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-20 04:13:17.211423 UTC - Check List DiskImages 2026-07-20 04:13:17.211625 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-20 04:13:17.829932 UTC - Requesting resources 2026-07-20 04:13:17.830427 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 04:13:17.830559 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-34dd2f6b --user-data /tmp/tmpob9psikw 2026-07-20 04:13:21.353274 UTC - Resource ID: r_be-gent1-bi-vm1_01kxyvmyxdfx8v6ymw6b17ca55 2026-07-20 04:13:21.353456 UTC - Waiting until resource ready 2026-07-20 04:13:23.353768 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-34dd2f6b tst 2026-07-20 04:13:24.119374 UTC - Status: IMAGING 2026-07-20 04:13:26.835374 UTC - Status: IMAGING 2026-07-20 04:13:29.551565 UTC - Status: IMAGING 2026-07-20 04:13:32.267697 UTC - Status: BOOTING 2026-07-20 04:13:34.983741 UTC - Status: BOOTING 2026-07-20 04:13:37.750532 UTC - Status: BOOTING 2026-07-20 04:13:40.466444 UTC - Status: BOOTING 2026-07-20 04:13:43.182446 UTC - Status: BOOTING 2026-07-20 04:13:45.948410 UTC - Status: INITIALIZING 2026-07-20 04:13:48.765869 UTC - Status: UP 2026-07-20 04:13:48.765964 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxyvmxxcexc8a0xtwfc2b8wy 2026-07-20 04:13:48.766006 UTC - Validate resources 2026-07-20 04:13:49.481933 UTC - The fields of the created resource were validated. 2026-07-20 04:13:49.482025 UTC - Check if resources are registered in experiment 2026-07-20 04:13:49.482172 UTC - Run: slices experiment list-resources --format json tst-34dd2f6b 2026-07-20 04:13:50.097451 UTC - Status (on expauth): UP 2026-07-20 04:13:50.097600 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 04:13:50.097739 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-34dd2f6b 2026-07-20 04:13:53.068902 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-34dd2f6b tst 2026-07-20 04:13:53.784860 UTC - Run: slices experiment list-resources --format json tst-34dd2f6b 2026-07-20 04:13:54.450464 UTC - expires_at (on expauth): 2026-07-20T07:13:00Z (correctly extended) 2026-07-20 04:13:54.450572 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 04:13:54.450752 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-34dd2f6b 2026-07-20 04:13:57.071176 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-34dd2f6b tst 2026-07-20 04:13:57.837355 UTC - Run: slices experiment list-resources --format json tst-34dd2f6b 2026-07-20 04:13:58.553086 UTC - expires_at (on expauth): 2026-07-20T08:13:00Z (correctly extended) 2026-07-20 04:13:58.553227 UTC - Testing ssh login 2026-07-20 04:13:58.569631 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-34dd2f6b tst 2026-07-20 04:13:59.385958 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-34dd2f6b tst 2026-07-20 04:14:00.102289 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-34dd2f6b tst 2026-07-20 04:14:00.818236 UTC - Logging in using 'slices bi ssh' 2026-07-20 04:14:00.818346 UTC - Forcing IPv4 only. 2026-07-20 04:14:00.818517 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-34dd2f6b tst -- -4 uname -a 2026-07-20 04:14:02.236406 UTC - Forcing IPv4 only. 2026-07-20 04:14:02.236645 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-34dd2f6b tst -- -4 uptime 2026-07-20 04:14:03.152606 UTC - CLI SSH Test passed. 2026-07-20 04:14:03.152679 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-20 04:14:03.152701 UTC - Uptime: 06:14:02 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-20 04:14:03.152751 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-20 04:14:03.153179 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:03.153347 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:03.153408 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:03.153583 UTC - Connecting to 10.10.220.158:22 2026-07-20 04:14:03.390880 UTC - SSH Test output: 2026-07-20 04:14:03.390949 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-20 04:14:03.390973 UTC - Uptime: 06:14:02 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-20 04:14:03.441223 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 04:14:03.441306 UTC - lsb_release matches expected value 2026-07-20 04:14:03.441327 UTC - SSH Test passed. 2026-07-20 04:14:03.486729 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 06:13:45 +0200! 2026-07-20 04:14:03.486863 UTC - Logging in using SSH over SSH proxy 2026-07-20 04:14:03.487006 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-34dd2f6b tst 2026-07-20 04:14:04.303078 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:04.303255 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:04.303337 UTC - Added paramiko HostKeyEntry for 10.10.220.158 2026-07-20 04:14:04.303617 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:14:04.303686 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:14:04.303824 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-20 04:14:04.303852 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-20 04:14:04.588076 UTC - Connecting to 10.10.220.158:22 over proxy 2026-07-20 04:14:04.831929 UTC - SSH Test output: 2026-07-20 04:14:04.832005 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-20 04:14:04.832026 UTC - Uptime: 06:14:04 up 0 min, 2 users, load average: 0.18, 0.05, 0.01 2026-07-20 04:14:04.884027 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 04:14:04.884101 UTC - lsb_release matches expected value 2026-07-20 04:14:04.884121 UTC - SSH Test passed. 2026-07-20 04:14:04.935636 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 06:13:45 +0200! 2026-07-20 04:14:04.942353 UTC - Destroying tst-34dd2f6b tst 2026-07-20 04:14:04.942527 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-34dd2f6b tst