Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.22s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 199ms 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 569ms + 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-23 20:29:28.824957 UTC - Installing Slices CLI (latest stable version). 2026-07-23 20:29:28.825161 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 20:29:34.452800 UTC - Slices CLI installed. 2026-07-23 20:29:34.453011 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 20:29:34.454221 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 20:29:34.454994 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 20:29:34.455106 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 20:29:34.455135 UTC - Slices CLI exe: 'slices' 2026-07-23 20:29:34.455151 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 20:29:34.455272 UTC - Run: slices --version 2026-07-23 20:29:36.974753 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 20:29:36.975008 UTC - Run: slices pubkey list --format text 2026-07-23 20:29:37.790644 UTC - Pubkey already registered 2026-07-23 20:29:37.790884 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 20:29:38.406122 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 20:29:38.406218 UTC - Check List Flavors 2026-07-23 20:29:38.406372 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-23 20:29:39.673358 UTC - Check List DiskImages 2026-07-23 20:29:39.673577 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-23 20:29:40.238800 UTC - Requesting resources 2026-07-23 20:29:40.239280 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 20:29:40.239392 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-2b00a0e1 --user-data /tmp/tmptm90_rj4 2026-07-23 20:29:43.260154 UTC - Resource ID: r_be-gent1-bi-vm1_01ky8apwtjeqere08ek7h8sgm6 2026-07-23 20:29:43.260315 UTC - Waiting until resource ready 2026-07-23 20:29:45.260748 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2b00a0e1 tst 2026-07-23 20:29:45.926170 UTC - Status: IMAGING 2026-07-23 20:29:48.642852 UTC - Status: IMAGING 2026-07-23 20:29:51.308819 UTC - Status: IMAGING 2026-07-23 20:29:54.024931 UTC - Status: BOOTING 2026-07-23 20:29:56.740977 UTC - Status: BOOTING 2026-07-23 20:29:59.406892 UTC - Status: BOOTING 2026-07-23 20:30:02.122894 UTC - Status: BOOTING 2026-07-23 20:30:04.789354 UTC - Status: UP 2026-07-23 20:30:04.789435 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky8apvv8e468qp9mg47amdem 2026-07-23 20:30:04.789483 UTC - Validate resources 2026-07-23 20:30:05.455247 UTC - The fields of the created resource were validated. 2026-07-23 20:30:05.455343 UTC - Check if resources are registered in experiment 2026-07-23 20:30:05.455498 UTC - Run: slices experiment list-resources --format json tst-2b00a0e1 2026-07-23 20:30:06.070822 UTC - Status (on expauth): BOOTING 2026-07-23 20:30:08.736758 UTC - Status (on expauth): BOOTING 2026-07-23 20:30:11.352975 UTC - Status (on expauth): BOOTING 2026-07-23 20:30:13.968601 UTC - Status (on expauth): BOOTING 2026-07-23 20:30:16.584199 UTC - Status (on expauth): UP 2026-07-23 20:30:16.584282 UTC - "slices experiment list-resources" was not ready immediately. 2026-07-23 20:30:16.584306 UTC - It was ready after: 11.12889 seconds. (Note: used wait steps of 2s) 2026-07-23 20:30:16.584392 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 20:30:16.584517 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-2b00a0e1 2026-07-23 20:30:19.805805 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2b00a0e1 tst 2026-07-23 20:30:20.521509 UTC - Run: slices experiment list-resources --format json tst-2b00a0e1 2026-07-23 20:30:21.136841 UTC - expires_at (on expauth): 2026-07-23T22:29:00Z (not yet extended) 2026-07-23 20:30:23.803039 UTC - expires_at (on expauth): 2026-07-23T22:29:00Z (not yet extended) 2026-07-23 20:30:26.585439 UTC - expires_at (on expauth): 2026-07-23T23:30:00Z (correctly extended) 2026-07-23 20:30:26.585548 UTC - "slices experiment list-resources" was not updated immediately after extend expires_at. 2026-07-23 20:30:26.585584 UTC - It was updated after: 6.064177 seconds. (Note: used wait steps of 2s) 2026-07-23 20:30:26.585614 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 20:30:26.585781 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-2b00a0e1 2026-07-23 20:30:30.199210 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2b00a0e1 tst 2026-07-23 20:30:31.069048 UTC - Run: slices experiment list-resources --format json tst-2b00a0e1 2026-07-23 20:30:31.734967 UTC - expires_at (on expauth): 2026-07-24T00:30:00Z (correctly extended) 2026-07-23 20:30:31.735268 UTC - Testing ssh login 2026-07-23 20:30:31.747059 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2b00a0e1 tst 2026-07-23 20:30:32.622072 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2b00a0e1 tst 2026-07-23 20:30:33.504846 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2b00a0e1 tst 2026-07-23 20:30:35.054907 UTC - Logging in using 'slices bi ssh' 2026-07-23 20:30:35.054984 UTC - Forcing IPv4 only. 2026-07-23 20:30:35.055113 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2b00a0e1 tst -- -4 uname -a 2026-07-23 20:30:37.033020 UTC - Forcing IPv4 only. 2026-07-23 20:30:37.033386 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2b00a0e1 tst -- -4 uptime 2026-07-23 20:30:38.164037 UTC - CLI SSH Test passed. 2026-07-23 20:30:38.164115 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-23 20:30:38.164135 UTC - Uptime: 22:30:38 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-23 20:30:38.164170 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-23 20:30:38.164650 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:38.164886 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:38.164948 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:38.165126 UTC - Connecting to 10.10.218.251:22 2026-07-23 20:30:38.395907 UTC - SSH Test output: 2026-07-23 20:30:38.395992 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-23 20:30:38.396019 UTC - Uptime: 22:30:38 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-23 20:30:38.449581 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-23 20:30:38.495799 UTC - /etc/debian_version: 13.1 2026-07-23 20:30:38.495875 UTC - lsb_release matches expected value 2026-07-23 20:30:38.495896 UTC - /etc/debian_version matches expected value 2026-07-23 20:30:38.495909 UTC - SSH Test passed. 2026-07-23 20:30:38.543013 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 22:30:02 +0200! 2026-07-23 20:30:38.543164 UTC - Logging in using SSH over SSH proxy 2026-07-23 20:30:38.543327 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2b00a0e1 tst 2026-07-23 20:30:39.312622 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:39.312781 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:39.312833 UTC - Added paramiko HostKeyEntry for 10.10.218.251 2026-07-23 20:30:39.313050 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:30:39.313095 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:30:39.313197 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:30:39.313217 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-23 20:30:39.583350 UTC - Connecting to 10.10.218.251:22 over proxy 2026-07-23 20:30:39.836789 UTC - SSH Test output: 2026-07-23 20:30:39.836863 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-23 20:30:39.836881 UTC - Uptime: 22:30:39 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-23 20:30:39.889111 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-23 20:30:39.936679 UTC - /etc/debian_version: 13.1 2026-07-23 20:30:39.936778 UTC - lsb_release matches expected value 2026-07-23 20:30:39.936799 UTC - /etc/debian_version matches expected value 2026-07-23 20:30:39.936814 UTC - SSH Test passed. 2026-07-23 20:30:39.984804 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 22:30:02 +0200! 2026-07-23 20:30:39.993945 UTC - Destroying tst-2b00a0e1 tst 2026-07-23 20:30:39.994155 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-2b00a0e1 tst