Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.73s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 203ms 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 315ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev192+gcac2d3fe6 (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-08-06 19:28:55.605423 UTC - Installing Slices CLI (latest stable version). 2026-08-06 19:28:55.605740 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-06 19:29:00.732740 UTC - Slices CLI installed. 2026-08-06 19:29:00.733034 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-08-06 19:29:00.735458 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-06 19:29:00.735531 UTC - env: {'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-08-06 19:29:00.735599 UTC - which slices: /app/.venv/bin/slices 2026-08-06 19:29:00.735626 UTC - Slices CLI exe: 'slices' 2026-08-06 19:29:00.735650 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-06 19:29:00.735811 UTC - Run: slices --version 2026-08-06 19:29:03.456561 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-06 19:29:03.456927 UTC - Run: slices pubkey list --format text 2026-08-06 19:29:04.222538 UTC - Pubkey already registered 2026-08-06 19:29:04.222802 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-06 19:29:04.838185 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-06 19:29:04.838284 UTC - Check List Flavors 2026-08-06 19:29:04.838436 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-06 19:29:05.504075 UTC - Check List DiskImages 2026-08-06 19:29:05.504261 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-06 19:29:06.119954 UTC - Requesting resources 2026-08-06 19:29:06.120571 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-06 19:29:06.120795 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-a973fe30 --user-data /tmp/tmpcyi6k73x 2026-08-06 19:29:07.187896 UTC - Resource ID: r_be-gent1-bi-vm1_01kzc8szyjfv4vcs80178btr87 2026-08-06 19:29:07.188072 UTC - Waiting until resource ready 2026-08-06 19:29:09.188466 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a973fe30 tst 2026-08-06 19:29:09.954378 UTC - Status: IMAGING 2026-08-06 19:29:12.670571 UTC - Status: IMAGING 2026-08-06 19:29:15.387255 UTC - Status: IMAGING 2026-08-06 19:29:18.153627 UTC - Status: BOOTING 2026-08-06 19:29:20.869571 UTC - Status: BOOTING 2026-08-06 19:29:23.585830 UTC - Status: BOOTING 2026-08-06 19:29:26.301821 UTC - Status: BOOTING 2026-08-06 19:29:29.068227 UTC - Status: UP 2026-08-06 19:29:29.068306 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzc8szqre878d2fcq7gyz7r3 2026-08-06 19:29:29.068350 UTC - Validate resources 2026-08-06 19:29:29.784594 UTC - The fields of the created resource were validated. 2026-08-06 19:29:29.784689 UTC - Check if resources are registered in experiment 2026-08-06 19:29:29.784875 UTC - Run: slices experiment list-resources --format json tst-a973fe30 2026-08-06 19:29:30.450498 UTC - Status (on expauth): UP 2026-08-06 19:29:30.450639 UTC - Testing extend expires_at (all resources in experiment) 2026-08-06 19:29:30.450777 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-a973fe30 2026-08-06 19:29:31.868554 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a973fe30 tst 2026-08-06 19:29:32.634800 UTC - Run: slices experiment list-resources --format json tst-a973fe30 2026-08-06 19:29:33.300395 UTC - expires_at (on expauth): 2026-08-06T22:29:00Z (correctly extended) 2026-08-06 19:29:33.300508 UTC - Testing extend expires_at (single resource in experiment) 2026-08-06 19:29:33.300681 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-a973fe30 2026-08-06 19:29:34.718429 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a973fe30 tst 2026-08-06 19:29:35.434366 UTC - Run: slices experiment list-resources --format json tst-a973fe30 2026-08-06 19:29:36.100032 UTC - expires_at (on expauth): 2026-08-06T23:29:00Z (correctly extended) 2026-08-06 19:29:36.100195 UTC - Testing ssh login 2026-08-06 19:29:36.117767 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a973fe30 tst 2026-08-06 19:29:36.883773 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a973fe30 tst 2026-08-06 19:29:37.649613 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-a973fe30 tst 2026-08-06 19:29:38.415607 UTC - Logging in using 'slices bi ssh' 2026-08-06 19:29:38.415707 UTC - Forcing IPv4 only. 2026-08-06 19:29:38.415881 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-a973fe30 tst -- -4 uname -a 2026-08-06 19:29:39.432404 UTC - Forcing IPv4 only. 2026-08-06 19:29:39.432592 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-a973fe30 tst -- -4 uptime 2026-08-06 19:29:40.248634 UTC - CLI SSH Test passed. 2026-08-06 19:29:40.248732 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-08-06 19:29:40.248766 UTC - Uptime: 21:29:38 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-08-06 19:29:40.248815 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-06 19:29:40.249329 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:40.249541 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:40.249606 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:40.249850 UTC - Connecting to 10.10.215.244:22 2026-08-06 19:29:40.476763 UTC - SSH Test output: 2026-08-06 19:29:40.476861 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-08-06 19:29:40.476885 UTC - Uptime: 21:29:38 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-08-06 19:29:40.528712 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-06 19:29:40.576241 UTC - /etc/debian_version: 13.1 2026-08-06 19:29:40.576318 UTC - lsb_release matches expected value 2026-08-06 19:29:40.576341 UTC - /etc/debian_version matches expected value 2026-08-06 19:29:40.576358 UTC - SSH Test passed. 2026-08-06 19:29:40.619766 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 21:29:26 +0200! 2026-08-06 19:29:40.619949 UTC - Logging in using SSH over SSH proxy 2026-08-06 19:29:40.620121 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-a973fe30 tst 2026-08-06 19:29:41.437358 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:41.437576 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:41.437636 UTC - Added paramiko HostKeyEntry for 10.10.215.244 2026-08-06 19:29:41.438645 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:29:41.438732 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:29:41.438883 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-06 19:29:41.438910 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-06 19:29:41.687495 UTC - Connecting to 10.10.215.244:22 over proxy 2026-08-06 19:29:41.920521 UTC - SSH Test output: 2026-08-06 19:29:41.920601 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-08-06 19:29:41.920623 UTC - Uptime: 21:29:40 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-08-06 19:29:41.973549 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-06 19:29:42.020784 UTC - /etc/debian_version: 13.1 2026-08-06 19:29:42.020899 UTC - lsb_release matches expected value 2026-08-06 19:29:42.020922 UTC - /etc/debian_version matches expected value 2026-08-06 19:29:42.020939 UTC - SSH Test passed. 2026-08-06 19:29:42.068003 UTC - Cloud-init user-data: Hello World. The time is now Thu, 06 Aug 2026 21:29:26 +0200! 2026-08-06 19:29:42.078335 UTC - Destroying tst-a973fe30 tst 2026-08-06 19:29:42.078596 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-a973fe30 tst