Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 213ms 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 298ms + annotated-types==0.7.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.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-23 04:18:03.400423 UTC - Installing Slices CLI (latest stable version). 2026-07-23 04:18:03.400665 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 04:18:08.529466 UTC - Slices CLI installed. 2026-07-23 04:18:08.529697 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-23 04:18:08.533110 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-23 04:18:08.534675 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 04:18:08.534881 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-23 04:18:08.534918 UTC - Slices CLI exe: 'slices' 2026-07-23 04:18:08.534938 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 04:18:08.535089 UTC - Run: slices --version 2026-07-23 04:18:11.616234 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-23 04:18:11.616668 UTC - Run: slices pubkey list --format text 2026-07-23 04:18:12.583678 UTC - Pubkey already registered 2026-07-23 04:18:12.584029 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 04:18:13.249731 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 04:18:13.249842 UTC - Check List Flavors 2026-07-23 04:18:13.250000 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-23 04:18:14.116080 UTC - Check List DiskImages 2026-07-23 04:18:14.116291 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-23 04:18:14.782020 UTC - Requesting resources 2026-07-23 04:18:14.782505 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 04:18:14.782638 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-aeeab013 --user-data /tmp/tmpgvasz8in 2026-07-23 04:18:16.651254 UTC - Resource ID: r_be-gent1-bi-vm1_01ky6k449wfm19581jf96ybjag 2026-07-23 04:18:16.651532 UTC - Waiting until resource ready 2026-07-23 04:18:18.652004 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aeeab013 tst 2026-07-23 04:18:19.418228 UTC - Status: IMAGING 2026-07-23 04:18:22.184750 UTC - Status: IMAGING 2026-07-23 04:18:25.002157 UTC - Status: IMAGING 2026-07-23 04:18:27.771064 UTC - Status: BOOTING 2026-07-23 04:18:30.587590 UTC - Status: BOOTING 2026-07-23 04:18:33.404455 UTC - Status: BOOTING 2026-07-23 04:18:36.221111 UTC - Status: BOOTING 2026-07-23 04:18:38.987713 UTC - Status: BOOTING 2026-07-23 04:18:41.757028 UTC - Status: UP 2026-07-23 04:18:41.757134 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky6k43qweq4bxc0rd75zpett 2026-07-23 04:18:41.757184 UTC - Validate resources 2026-07-23 04:18:42.523705 UTC - The fields of the created resource were validated. 2026-07-23 04:18:42.523821 UTC - Check if resources are registered in experiment 2026-07-23 04:18:42.523991 UTC - Run: slices experiment list-resources --format json tst-aeeab013 2026-07-23 04:18:43.239768 UTC - Status (on expauth): UP 2026-07-23 04:18:43.239924 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 04:18:43.240064 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-aeeab013 2026-07-23 04:18:45.313071 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aeeab013 tst 2026-07-23 04:18:46.029305 UTC - Run: slices experiment list-resources --format json tst-aeeab013 2026-07-23 04:18:46.745598 UTC - expires_at (on expauth): 2026-07-23T07:18:00Z (correctly extended) 2026-07-23 04:18:46.745723 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 04:18:46.745910 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-aeeab013 2026-07-23 04:18:48.766802 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-aeeab013 tst 2026-07-23 04:18:49.633951 UTC - Run: slices experiment list-resources --format json tst-aeeab013 2026-07-23 04:18:50.349871 UTC - expires_at (on expauth): 2026-07-23T08:18:00Z (correctly extended) 2026-07-23 04:18:50.350030 UTC - Testing ssh login 2026-07-23 04:18:50.364433 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-aeeab013 tst 2026-07-23 04:18:51.181013 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-aeeab013 tst 2026-07-23 04:18:52.047430 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-aeeab013 tst 2026-07-23 04:18:52.913938 UTC - Logging in using 'slices bi ssh' 2026-07-23 04:18:52.914013 UTC - Forcing IPv4 only. 2026-07-23 04:18:52.914155 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-aeeab013 tst -- -4 uname -a 2026-07-23 04:18:54.433208 UTC - Forcing IPv4 only. 2026-07-23 04:18:54.433451 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-aeeab013 tst -- -4 uptime 2026-07-23 04:18:55.349935 UTC - CLI SSH Test passed. 2026-07-23 04:18:55.350012 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-23 04:18:55.350032 UTC - Uptime: 06:18:54 up 0 min, 1 user, load average: 0.84, 0.21, 0.07 2026-07-23 04:18:55.350075 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-23 04:18:55.350524 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:55.350713 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:55.350791 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:55.351007 UTC - Connecting to 10.10.220.77:22 2026-07-23 04:18:55.593578 UTC - SSH Test output: 2026-07-23 04:18:55.593701 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-23 04:18:55.593742 UTC - Uptime: 06:18:55 up 0 min, 1 user, load average: 0.84, 0.21, 0.07 2026-07-23 04:18:55.644248 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 04:18:55.644395 UTC - lsb_release matches expected value 2026-07-23 04:18:55.644441 UTC - SSH Test passed. 2026-07-23 04:18:55.689919 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 06:18:40 +0200! 2026-07-23 04:18:55.690116 UTC - Logging in using SSH over SSH proxy 2026-07-23 04:18:55.690302 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-aeeab013 tst 2026-07-23 04:18:56.557137 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:56.557288 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:56.557339 UTC - Added paramiko HostKeyEntry for 10.10.220.77 2026-07-23 04:18:56.557618 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 04:18:56.557676 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 04:18:56.557803 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 04:18:56.557827 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-23 04:18:56.823344 UTC - Connecting to 10.10.220.77:22 over proxy 2026-07-23 04:18:57.125178 UTC - SSH Test output: 2026-07-23 04:18:57.125285 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-23 04:18:57.125308 UTC - Uptime: 06:18:56 up 0 min, 2 users, load average: 0.84, 0.21, 0.07 2026-07-23 04:18:57.181159 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 04:18:57.181266 UTC - lsb_release matches expected value 2026-07-23 04:18:57.181288 UTC - SSH Test passed. 2026-07-23 04:18:57.230870 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 06:18:40 +0200! 2026-07-23 04:18:57.240407 UTC - Destroying tst-aeeab013 tst 2026-07-23 04:18:57.240684 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-aeeab013 tst