Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.54s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 194ms 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 234ms + 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-22 20:14:17.753056 UTC - Installing Slices CLI (latest stable version). 2026-07-22 20:14:17.753329 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 20:14:22.879003 UTC - Slices CLI installed. 2026-07-22 20:14:22.879220 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-22 20:14:22.881370 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-22 20:14:22.882257 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 20:14:22.882390 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-22 20:14:22.882419 UTC - Slices CLI exe: 'slices' 2026-07-22 20:14:22.882437 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 20:14:22.882579 UTC - Run: slices --version 2026-07-22 20:14:25.652819 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-22 20:14:25.653225 UTC - Run: slices pubkey list --format text 2026-07-22 20:14:26.469131 UTC - Pubkey already registered 2026-07-22 20:14:26.469396 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 20:14:27.135058 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 20:14:27.135161 UTC - Check List Flavors 2026-07-22 20:14:27.135333 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-22 20:14:28.051470 UTC - Check List DiskImages 2026-07-22 20:14:28.051676 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-22 20:14:28.667281 UTC - Requesting resources 2026-07-22 20:14:28.667771 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 20:14:28.667900 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-075ca864 --user-data /tmp/tmpx4jgez4t 2026-07-22 20:14:30.435897 UTC - Resource ID: r_be-gent1-bi-vm1_01ky5qeadefdfvmr1n1b21c5cv 2026-07-22 20:14:30.436126 UTC - Waiting until resource ready 2026-07-22 20:14:32.436696 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-075ca864 tst 2026-07-22 20:14:33.152542 UTC - Status: IMAGING 2026-07-22 20:14:35.919231 UTC - Status: IMAGING 2026-07-22 20:14:38.685961 UTC - Status: IMAGING 2026-07-22 20:14:41.401930 UTC - Status: BOOTING 2026-07-22 20:14:44.118076 UTC - Status: BOOTING 2026-07-22 20:14:46.884222 UTC - Status: BOOTING 2026-07-22 20:14:49.650527 UTC - Status: BOOTING 2026-07-22 20:14:52.417047 UTC - Status: BOOTING 2026-07-22 20:14:55.183553 UTC - Status: INITIALIZING 2026-07-22 20:14:57.950023 UTC - Status: UP 2026-07-22 20:14:57.950160 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky5qe9x9e6qa3g7b0mhfmy2b 2026-07-22 20:14:57.950214 UTC - Validate resources 2026-07-22 20:14:58.716559 UTC - The fields of the created resource were validated. 2026-07-22 20:14:58.716681 UTC - Check if resources are registered in experiment 2026-07-22 20:14:58.716920 UTC - Run: slices experiment list-resources --format json tst-075ca864 2026-07-22 20:14:59.382702 UTC - Status (on expauth): UP 2026-07-22 20:14:59.382968 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 20:14:59.383203 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-075ca864 2026-07-22 20:15:01.353243 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-075ca864 tst 2026-07-22 20:15:02.122772 UTC - Run: slices experiment list-resources --format json tst-075ca864 2026-07-22 20:15:02.892192 UTC - expires_at (on expauth): 2026-07-22T23:14:00Z (correctly extended) 2026-07-22 20:15:02.892317 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 20:15:02.892476 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-075ca864 2026-07-22 20:15:04.966384 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-075ca864 tst 2026-07-22 20:15:05.737842 UTC - Run: slices experiment list-resources --format json tst-075ca864 2026-07-22 20:15:06.456984 UTC - expires_at (on expauth): 2026-07-23T00:15:00Z (correctly extended) 2026-07-22 20:15:06.457754 UTC - Testing ssh login 2026-07-22 20:15:06.473120 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-075ca864 tst 2026-07-22 20:15:07.294875 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-075ca864 tst 2026-07-22 20:15:08.068101 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-075ca864 tst 2026-07-22 20:15:08.894400 UTC - Logging in using 'slices bi ssh' 2026-07-22 20:15:08.894513 UTC - Forcing IPv4 only. 2026-07-22 20:15:08.894691 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-075ca864 tst -- -4 uname -a 2026-07-22 20:15:09.965167 UTC - Forcing IPv4 only. 2026-07-22 20:15:09.965412 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-075ca864 tst -- -4 uptime 2026-07-22 20:15:10.831515 UTC - CLI SSH Test passed. 2026-07-22 20:15:10.831603 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-22 20:15:10.831623 UTC - Uptime: 22:15:10 up 0 min, 1 user, load average: 1.05, 0.27, 0.09 2026-07-22 20:15:10.831663 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-22 20:15:10.832177 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:10.832347 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:10.832408 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:10.832602 UTC - Connecting to 10.10.222.14:22 2026-07-22 20:15:11.071257 UTC - SSH Test output: 2026-07-22 20:15:11.071346 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-22 20:15:11.071424 UTC - Uptime: 22:15:10 up 0 min, 1 user, load average: 1.05, 0.27, 0.09 2026-07-22 20:15:11.120483 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 20:15:11.120573 UTC - lsb_release matches expected value 2026-07-22 20:15:11.120593 UTC - SSH Test passed. 2026-07-22 20:15:11.165108 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 22:14:54 +0200! 2026-07-22 20:15:11.165282 UTC - Logging in using SSH over SSH proxy 2026-07-22 20:15:11.165461 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-075ca864 tst 2026-07-22 20:15:11.982143 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:11.982394 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:11.982483 UTC - Added paramiko HostKeyEntry for 10.10.222.14 2026-07-22 20:15:11.982879 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 20:15:11.982981 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 20:15:11.983171 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 20:15:11.983218 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-22 20:15:12.251683 UTC - Connecting to 10.10.222.14:22 over proxy 2026-07-22 20:15:12.538112 UTC - SSH Test output: 2026-07-22 20:15:12.538197 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-22 20:15:12.538219 UTC - Uptime: 22:15:12 up 0 min, 2 users, load average: 1.05, 0.27, 0.09 2026-07-22 20:15:12.590889 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 20:15:12.590982 UTC - lsb_release matches expected value 2026-07-22 20:15:12.591003 UTC - SSH Test passed. 2026-07-22 20:15:12.637687 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 22:14:54 +0200! 2026-07-22 20:15:12.644944 UTC - Destroying tst-075ca864 tst 2026-07-22 20:15:12.645160 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-075ca864 tst