Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.18s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 187ms 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 243ms + annotated-types==0.8.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-24 04:29:30.860411 UTC - Installing Slices CLI (latest stable version). 2026-07-24 04:29:30.860912 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-24 04:29:35.486806 UTC - Slices CLI installed. 2026-07-24 04:29:35.487270 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-24 04:29:35.489749 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-24 04:29:35.490501 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-24 04:29:35.490640 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-24 04:29:35.490670 UTC - Slices CLI exe: 'slices' 2026-07-24 04:29:35.490687 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-24 04:29:35.490818 UTC - Run: slices --version 2026-07-24 04:29:38.060151 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-24 04:29:38.060426 UTC - Run: slices pubkey list --format text 2026-07-24 04:29:38.876260 UTC - Pubkey already registered 2026-07-24 04:29:38.876536 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-24 04:29:39.441764 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-24 04:29:39.441860 UTC - Check List Flavors 2026-07-24 04:29:39.442016 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-24 04:29:40.608615 UTC - Check List DiskImages 2026-07-24 04:29:40.608837 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-24 04:29:41.224287 UTC - Requesting resources 2026-07-24 04:29:41.225101 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-24 04:29:41.225220 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-72c5e3fa --user-data /tmp/tmp2y9gt7or 2026-07-24 04:29:44.246675 UTC - Resource ID: r_be-gent1-bi-vm1_01ky965tszehzb0ezbtbyd0eyn 2026-07-24 04:29:44.246844 UTC - Waiting until resource ready 2026-07-24 04:29:46.247233 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72c5e3fa tst 2026-07-24 04:29:46.912671 UTC - Status: IMAGING 2026-07-24 04:29:49.729661 UTC - Status: IMAGING 2026-07-24 04:29:52.495940 UTC - Status: IMAGING 2026-07-24 04:29:55.161806 UTC - Status: BOOTING 2026-07-24 04:29:57.827650 UTC - Status: BOOTING 2026-07-24 04:30:00.543747 UTC - Status: BOOTING 2026-07-24 04:30:03.309856 UTC - Status: BOOTING 2026-07-24 04:30:05.975664 UTC - Status: UP 2026-07-24 04:30:05.975768 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky965syce3tapgr491hhwrfx 2026-07-24 04:30:05.975814 UTC - Validate resources 2026-07-24 04:30:06.691710 UTC - The fields of the created resource were validated. 2026-07-24 04:30:06.691831 UTC - Check if resources are registered in experiment 2026-07-24 04:30:06.691994 UTC - Run: slices experiment list-resources --format json tst-72c5e3fa 2026-07-24 04:30:07.357443 UTC - Status (on expauth): BOOTING 2026-07-24 04:30:09.973186 UTC - Status (on expauth): BOOTING 2026-07-24 04:30:12.589075 UTC - Status (on expauth): BOOTING 2026-07-24 04:30:15.254908 UTC - Status (on expauth): BOOTING 2026-07-24 04:30:17.870497 UTC - Status (on expauth): UP 2026-07-24 04:30:17.870584 UTC - "slices experiment list-resources" was not ready immediately. 2026-07-24 04:30:17.870628 UTC - It was ready after: 11.178702 seconds. (Note: used wait steps of 2s) 2026-07-24 04:30:17.870704 UTC - Testing extend expires_at (all resources in experiment) 2026-07-24 04:30:17.870853 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-72c5e3fa 2026-07-24 04:30:21.142545 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72c5e3fa tst 2026-07-24 04:30:22.119565 UTC - Run: slices experiment list-resources --format json tst-72c5e3fa 2026-07-24 04:30:22.837675 UTC - expires_at (on expauth): 2026-07-24T06:29:00Z (not yet extended) 2026-07-24 04:30:25.504871 UTC - expires_at (on expauth): 2026-07-24T06:29:00Z (not yet extended) 2026-07-24 04:30:28.271942 UTC - expires_at (on expauth): 2026-07-24T07:30:00Z (correctly extended) 2026-07-24 04:30:28.272024 UTC - "slices experiment list-resources" was not updated immediately after extend expires_at. 2026-07-24 04:30:28.272048 UTC - It was updated after: 6.152571 seconds. (Note: used wait steps of 2s) 2026-07-24 04:30:28.272078 UTC - Testing extend expires_at (single resource in experiment) 2026-07-24 04:30:28.272207 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-72c5e3fa 2026-07-24 04:30:31.665388 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-72c5e3fa tst 2026-07-24 04:30:32.785440 UTC - Run: slices experiment list-resources --format json tst-72c5e3fa 2026-07-24 04:30:33.501133 UTC - expires_at (on expauth): 2026-07-24T08:30:00Z (correctly extended) 2026-07-24 04:30:33.501409 UTC - Testing ssh login 2026-07-24 04:30:33.520213 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-72c5e3fa tst 2026-07-24 04:30:34.386062 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-72c5e3fa tst 2026-07-24 04:30:35.203251 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-72c5e3fa tst 2026-07-24 04:30:35.920317 UTC - Logging in using 'slices bi ssh' 2026-07-24 04:30:35.920392 UTC - Forcing IPv4 only. 2026-07-24 04:30:35.920529 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-72c5e3fa tst -- -4 uname -a 2026-07-24 04:30:36.886582 UTC - Forcing IPv4 only. 2026-07-24 04:30:36.886789 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-72c5e3fa tst -- -4 uptime 2026-07-24 04:30:37.809665 UTC - CLI SSH Test passed. 2026-07-24 04:30:37.809759 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-24 04:30:37.809782 UTC - Uptime: 06:30:36 up 0 min, 1 user, load average: 0.04, 0.01, 0.00 2026-07-24 04:30:37.809815 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-24 04:30:37.810284 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:37.810463 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:37.810521 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:37.810781 UTC - Connecting to 10.10.216.23:22 2026-07-24 04:30:38.045123 UTC - SSH Test output: 2026-07-24 04:30:38.045323 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-24 04:30:38.045347 UTC - Uptime: 06:30:36 up 0 min, 1 user, load average: 0.04, 0.01, 0.00 2026-07-24 04:30:38.097103 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-24 04:30:38.144074 UTC - /etc/debian_version: 13.1 2026-07-24 04:30:38.144175 UTC - lsb_release matches expected value 2026-07-24 04:30:38.144203 UTC - /etc/debian_version matches expected value 2026-07-24 04:30:38.144232 UTC - SSH Test passed. 2026-07-24 04:30:38.196423 UTC - Cloud-init user-data: Hello World. The time is now Fri, 24 Jul 2026 06:30:03 +0200! 2026-07-24 04:30:38.197210 UTC - Logging in using SSH over SSH proxy 2026-07-24 04:30:38.197554 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-72c5e3fa tst 2026-07-24 04:30:39.123067 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:39.123217 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:39.123269 UTC - Added paramiko HostKeyEntry for 10.10.216.23 2026-07-24 04:30:39.123525 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:30:39.123583 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:30:39.123687 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:30:39.123722 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-24 04:30:39.434674 UTC - Connecting to 10.10.216.23:22 over proxy 2026-07-24 04:30:39.667825 UTC - SSH Test output: 2026-07-24 04:30:39.667894 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-24 04:30:39.667913 UTC - Uptime: 06:30:39 up 0 min, 2 users, load average: 0.04, 0.01, 0.00 2026-07-24 04:30:39.720921 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-24 04:30:39.771786 UTC - /etc/debian_version: 13.1 2026-07-24 04:30:39.771857 UTC - lsb_release matches expected value 2026-07-24 04:30:39.771876 UTC - /etc/debian_version matches expected value 2026-07-24 04:30:39.771892 UTC - SSH Test passed. 2026-07-24 04:30:39.819782 UTC - Cloud-init user-data: Hello World. The time is now Fri, 24 Jul 2026 06:30:03 +0200! 2026-07-24 04:30:39.830541 UTC - Destroying tst-72c5e3fa tst 2026-07-24 04:30:39.830919 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-72c5e3fa tst