Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.01s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 186ms 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 275ms + 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.3 Run Test: slices-bi-singlenode-login 2026-08-10 20:59:17.637909 UTC - Installing Slices CLI (latest stable version). 2026-08-10 20:59:17.638175 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 20:59:24.367479 UTC - Slices CLI installed. 2026-08-10 20:59:24.367805 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-10 20:59:24.371026 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-10 20:59:24.371123 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-10 20:59:24.371237 UTC - which slices: /app/.venv/bin/slices 2026-08-10 20:59:24.371277 UTC - Slices CLI exe: 'slices' 2026-08-10 20:59:24.371295 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 20:59:24.371466 UTC - Run: slices --version 2026-08-10 20:59:27.091877 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-10 20:59:27.092144 UTC - Run: slices pubkey list --format text 2026-08-10 20:59:27.858019 UTC - Pubkey already registered 2026-08-10 20:59:27.858280 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 20:59:28.473659 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 20:59:28.473770 UTC - Check List Flavors 2026-08-10 20:59:28.473946 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-10 20:59:29.189734 UTC - Check List DiskImages 2026-08-10 20:59:29.189922 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-10 20:59:29.855562 UTC - Requesting resources 2026-08-10 20:59:29.856081 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 20:59:29.856193 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-9ce1b2a9 --user-data /tmp/tmpknwxjgoe 2026-08-10 20:59:30.872124 UTC - Resource ID: r_be-gent1-bi-vm1_01kzpqjchmfh6vd6nhzrktrhe3 2026-08-10 20:59:30.872296 UTC - Waiting until resource ready 2026-08-10 20:59:32.872761 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9ce1b2a9 tst 2026-08-10 20:59:33.639186 UTC - Status: IMAGING 2026-08-10 20:59:36.355544 UTC - Status: IMAGING 2026-08-10 20:59:39.121806 UTC - Status: IMAGING 2026-08-10 20:59:41.838185 UTC - Status: BOOTING 2026-08-10 20:59:44.604591 UTC - Status: BOOTING 2026-08-10 20:59:47.320661 UTC - Status: BOOTING 2026-08-10 20:59:50.036829 UTC - Status: BOOTING 2026-08-10 20:59:52.753034 UTC - Status: UP 2026-08-10 20:59:52.753143 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzpqjcavedv8t21z9bngk8g1 2026-08-10 20:59:52.753195 UTC - Validate resources 2026-08-10 20:59:53.469489 UTC - The fields of the created resource were validated. 2026-08-10 20:59:53.469616 UTC - Check if resources are registered in experiment 2026-08-10 20:59:53.469812 UTC - Run: slices experiment list-resources --format json tst-9ce1b2a9 2026-08-10 20:59:54.135539 UTC - Status (on expauth): UP 2026-08-10 20:59:54.135686 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 20:59:54.135848 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-9ce1b2a9 2026-08-10 20:59:55.502905 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9ce1b2a9 tst 2026-08-10 20:59:56.218743 UTC - Run: slices experiment list-resources --format json tst-9ce1b2a9 2026-08-10 20:59:56.884341 UTC - expires_at (on expauth): 2026-08-10T23:59:00Z (correctly extended) 2026-08-10 20:59:56.884438 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 20:59:56.884604 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-9ce1b2a9 2026-08-10 20:59:58.302000 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9ce1b2a9 tst 2026-08-10 20:59:59.018021 UTC - Run: slices experiment list-resources --format json tst-9ce1b2a9 2026-08-10 20:59:59.683517 UTC - expires_at (on expauth): 2026-08-11T00:59:00Z (correctly extended) 2026-08-10 20:59:59.683644 UTC - Testing ssh login 2026-08-10 20:59:59.697044 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9ce1b2a9 tst 2026-08-10 21:00:00.414262 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9ce1b2a9 tst 2026-08-10 21:00:01.130035 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9ce1b2a9 tst 2026-08-10 21:00:01.895844 UTC - Logging in using 'slices bi ssh' 2026-08-10 21:00:01.895916 UTC - Forcing IPv4 only. 2026-08-10 21:00:01.896044 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9ce1b2a9 tst -- -4 uname -a 2026-08-10 21:00:03.015502 UTC - Forcing IPv4 only. 2026-08-10 21:00:03.015683 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9ce1b2a9 tst -- -4 uptime 2026-08-10 21:00:03.881818 UTC - CLI SSH Test passed. 2026-08-10 21:00:03.881897 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-10 21:00:03.881916 UTC - Uptime: 23:00:02 up 0 min, 1 user, load average: 0.07, 0.02, 0.00 2026-08-10 21:00:03.881950 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-10 21:00:03.882442 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:03.882622 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:03.882678 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:03.882861 UTC - Connecting to 10.10.210.13:22 2026-08-10 21:00:04.142101 UTC - SSH Test output: 2026-08-10 21:00:04.142230 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-10 21:00:04.142276 UTC - Uptime: 23:00:02 up 0 min, 1 user, load average: 0.07, 0.02, 0.00 2026-08-10 21:00:04.197053 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 21:00:04.243302 UTC - /etc/debian_version: 13.1 2026-08-10 21:00:04.243409 UTC - lsb_release matches expected value 2026-08-10 21:00:04.243434 UTC - /etc/debian_version matches expected value 2026-08-10 21:00:04.243451 UTC - SSH Test passed. 2026-08-10 21:00:04.291884 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 22:59:51 +0200! 2026-08-10 21:00:04.292086 UTC - Logging in using SSH over SSH proxy 2026-08-10 21:00:04.292291 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9ce1b2a9 tst 2026-08-10 21:00:05.158785 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:05.158932 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:05.158982 UTC - Added paramiko HostKeyEntry for 10.10.210.13 2026-08-10 21:00:05.159280 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 21:00:05.159360 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 21:00:05.159479 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 21:00:05.159500 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-10 21:00:05.456182 UTC - Connecting to 10.10.210.13:22 over proxy 2026-08-10 21:00:05.801603 UTC - SSH Test output: 2026-08-10 21:00:05.801685 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-10 21:00:05.801704 UTC - Uptime: 23:00:04 up 0 min, 2 users, load average: 0.14, 0.03, 0.01 2026-08-10 21:00:05.853616 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-08-10 21:00:05.904391 UTC - /etc/debian_version: 13.1 2026-08-10 21:00:05.904465 UTC - lsb_release matches expected value 2026-08-10 21:00:05.904487 UTC - /etc/debian_version matches expected value 2026-08-10 21:00:05.904501 UTC - SSH Test passed. 2026-08-10 21:00:05.953082 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 22:59:51 +0200! 2026-08-10 21:00:05.961910 UTC - Destroying tst-9ce1b2a9 tst 2026-08-10 21:00:05.962112 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-9ce1b2a9 tst