Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.27s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 190ms 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 294ms + 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-16 13:22:18.938801 UTC - Installing Slices CLI (latest stable version). 2026-07-16 13:22:18.939029 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 13:22:24.368980 UTC - Slices CLI installed. 2026-07-16 13:22:24.369165 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-16 13:22:24.371713 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-16 13:22:24.372807 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 13:22:24.372946 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-16 13:22:24.372976 UTC - Slices CLI exe: 'slices' 2026-07-16 13:22:24.372994 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 13:22:24.373129 UTC - Run: slices --version 2026-07-16 13:22:26.942343 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-16 13:22:26.942634 UTC - Run: slices pubkey list --format text 2026-07-16 13:22:27.708156 UTC - Pubkey already registered 2026-07-16 13:22:27.708459 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 13:22:28.273513 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 13:22:28.273644 UTC - Check List Flavors 2026-07-16 13:22:28.273822 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-16 13:22:29.642062 UTC - Check List DiskImages 2026-07-16 13:22:29.642332 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-16 13:22:30.258224 UTC - Requesting resources 2026-07-16 13:22:30.258722 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 13:22:30.258842 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-5eee3356 --user-data /tmp/tmp2zys0aj2 2026-07-16 13:22:33.532800 UTC - Resource ID: r_be-gent1-bi-vm1_01kxnhfpsne8na6vd8pvh6623g 2026-07-16 13:22:33.532973 UTC - Waiting until resource ready 2026-07-16 13:22:35.533359 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5eee3356 tst 2026-07-16 13:22:36.248936 UTC - Status: IMAGING 2026-07-16 13:22:38.914674 UTC - Status: IMAGING 2026-07-16 13:22:41.631354 UTC - Status: IMAGING 2026-07-16 13:22:44.347904 UTC - Status: BOOTING 2026-07-16 13:22:47.014492 UTC - Status: BOOTING 2026-07-16 13:22:49.680349 UTC - Status: BOOTING 2026-07-16 13:22:52.396433 UTC - Status: BOOTING 2026-07-16 13:22:55.112474 UTC - Status: UP 2026-07-16 13:22:55.112597 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxnhfnwbe26vh6jwnz740qmy 2026-07-16 13:22:55.112661 UTC - Validate resources 2026-07-16 13:22:55.828537 UTC - The fields of the created resource were validated. 2026-07-16 13:22:55.828626 UTC - Check if resources are registered in experiment 2026-07-16 13:22:55.828809 UTC - Run: slices experiment list-resources --format json tst-5eee3356 2026-07-16 13:22:56.544506 UTC - Status (on expauth): UP 2026-07-16 13:22:56.544663 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 13:22:56.544842 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-5eee3356 2026-07-16 13:22:59.666311 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5eee3356 tst 2026-07-16 13:23:00.331896 UTC - Run: slices experiment list-resources --format json tst-5eee3356 2026-07-16 13:23:00.947198 UTC - expires_at (on expauth): 2026-07-16T16:22:00Z (correctly extended) 2026-07-16 13:23:00.947304 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 13:23:00.947449 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-5eee3356 2026-07-16 13:23:03.968148 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5eee3356 tst 2026-07-16 13:23:04.683936 UTC - Run: slices experiment list-resources --format json tst-5eee3356 2026-07-16 13:23:05.299158 UTC - expires_at (on expauth): 2026-07-16T17:23:00Z (correctly extended) 2026-07-16 13:23:05.299275 UTC - Testing ssh login 2026-07-16 13:23:05.312091 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5eee3356 tst 2026-07-16 13:23:06.027665 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5eee3356 tst 2026-07-16 13:23:06.743471 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5eee3356 tst 2026-07-16 13:23:07.458951 UTC - Logging in using 'slices bi ssh' 2026-07-16 13:23:07.459028 UTC - Forcing IPv4 only. 2026-07-16 13:23:07.459161 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5eee3356 tst -- -4 uname -a 2026-07-16 13:23:08.425173 UTC - Forcing IPv4 only. 2026-07-16 13:23:08.425363 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5eee3356 tst -- -4 uptime 2026-07-16 13:23:09.241104 UTC - CLI SSH Test passed. 2026-07-16 13:23:09.241177 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-16 13:23:09.241197 UTC - Uptime: 15:23:08 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-16 13:23:09.241232 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-16 13:23:09.241689 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:09.241897 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:09.241962 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:09.242136 UTC - Connecting to 10.10.223.1:22 2026-07-16 13:23:09.465158 UTC - SSH Test output: 2026-07-16 13:23:09.465240 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-16 13:23:09.465267 UTC - Uptime: 15:23:08 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-16 13:23:09.513089 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-16 13:23:09.556988 UTC - /etc/debian_version: 13.1 2026-07-16 13:23:09.557064 UTC - lsb_release matches expected value 2026-07-16 13:23:09.557084 UTC - /etc/debian_version matches expected value 2026-07-16 13:23:09.557098 UTC - SSH Test passed. 2026-07-16 13:23:09.600676 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 15:22:53 +0200! 2026-07-16 13:23:09.600841 UTC - Logging in using SSH over SSH proxy 2026-07-16 13:23:09.600987 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5eee3356 tst 2026-07-16 13:23:10.316847 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:10.317035 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:10.317102 UTC - Added paramiko HostKeyEntry for 10.10.223.1 2026-07-16 13:23:10.317432 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 13:23:10.317492 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 13:23:10.317594 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 13:23:10.317615 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-16 13:23:10.440781 UTC - Connecting to 10.10.223.1:22 over proxy 2026-07-16 13:23:10.673108 UTC - SSH Test output: 2026-07-16 13:23:10.673181 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-16 13:23:10.673200 UTC - Uptime: 15:23:09 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-16 13:23:10.725516 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-16 13:23:10.771963 UTC - /etc/debian_version: 13.1 2026-07-16 13:23:10.772041 UTC - lsb_release matches expected value 2026-07-16 13:23:10.772061 UTC - /etc/debian_version matches expected value 2026-07-16 13:23:10.772076 UTC - SSH Test passed. 2026-07-16 13:23:10.819861 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 15:22:53 +0200! 2026-07-16 13:23:10.826433 UTC - Destroying tst-5eee3356 tst 2026-07-16 13:23:10.826601 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-5eee3356 tst