Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.48s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 200ms 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 588ms + 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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev189+g506e4860f (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-28 20:35:25.174820 UTC - Installing Slices CLI (latest stable version). 2026-07-28 20:35:25.175264 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-28 20:35:30.703563 UTC - Slices CLI installed. 2026-07-28 20:35:30.703949 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-28 20:35:30.707707 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-07-28 20:35:30.707798 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-07-28 20:35:30.707886 UTC - which slices: /app/.venv/bin/slices 2026-07-28 20:35:30.707905 UTC - Slices CLI exe: 'slices' 2026-07-28 20:35:30.707922 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-28 20:35:30.708098 UTC - Run: slices --version 2026-07-28 20:35:33.530119 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-28 20:35:33.530435 UTC - Run: slices pubkey list --format text 2026-07-28 20:35:34.296251 UTC - Pubkey already registered 2026-07-28 20:35:34.296518 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-28 20:35:34.912048 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-28 20:35:34.912186 UTC - Check List Flavors 2026-07-28 20:35:34.912447 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-28 20:35:35.828820 UTC - Check List DiskImages 2026-07-28 20:35:35.829023 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-28 20:35:36.444469 UTC - Requesting resources 2026-07-28 20:35:36.445051 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-28 20:35:36.445187 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-2ec02de4 --user-data /tmp/tmpuhedr6go 2026-07-28 20:35:38.414446 UTC - Resource ID: r_be-gent1-bi-vm1_01kyn71anheqx8sa3xadp22qyw 2026-07-28 20:35:38.414680 UTC - Waiting until resource ready 2026-07-28 20:35:40.415200 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2ec02de4 tst 2026-07-28 20:35:41.131703 UTC - Status: IMAGING 2026-07-28 20:35:43.847732 UTC - Status: IMAGING 2026-07-28 20:35:46.765650 UTC - Status: IMAGING 2026-07-28 20:35:49.631952 UTC - Status: BOOTING 2026-07-28 20:35:52.449490 UTC - Status: BOOTING 2026-07-28 20:35:55.280440 UTC - Status: BOOTING 2026-07-28 20:35:58.197774 UTC - Status: BOOTING 2026-07-28 20:36:01.015970 UTC - Status: UP 2026-07-28 20:36:01.016052 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyn71a3cft8tz6v8bynnddx2 2026-07-28 20:36:01.016094 UTC - Validate resources 2026-07-28 20:36:02.047451 UTC - The fields of the created resource were validated. 2026-07-28 20:36:02.047548 UTC - Check if resources are registered in experiment 2026-07-28 20:36:02.047703 UTC - Run: slices experiment list-resources --format json tst-2ec02de4 2026-07-28 20:36:03.016606 UTC - Status (on expauth): UP 2026-07-28 20:36:03.016788 UTC - Testing extend expires_at (all resources in experiment) 2026-07-28 20:36:03.016961 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-2ec02de4 2026-07-28 20:36:05.358102 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2ec02de4 tst 2026-07-28 20:36:06.182138 UTC - Run: slices experiment list-resources --format json tst-2ec02de4 2026-07-28 20:36:07.154777 UTC - expires_at (on expauth): 2026-07-28T23:36:00Z (correctly extended) 2026-07-28 20:36:07.155053 UTC - Testing extend expires_at (single resource in experiment) 2026-07-28 20:36:07.155252 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-2ec02de4 2026-07-28 20:36:09.224308 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-2ec02de4 tst 2026-07-28 20:36:10.190847 UTC - Run: slices experiment list-resources --format json tst-2ec02de4 2026-07-28 20:36:10.856517 UTC - expires_at (on expauth): 2026-07-29T00:36:00Z (correctly extended) 2026-07-28 20:36:10.856633 UTC - Testing ssh login 2026-07-28 20:36:10.870737 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2ec02de4 tst 2026-07-28 20:36:11.688398 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2ec02de4 tst 2026-07-28 20:36:12.506359 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2ec02de4 tst 2026-07-28 20:36:13.474481 UTC - Logging in using 'slices bi ssh' 2026-07-28 20:36:13.474559 UTC - Forcing IPv4 only. 2026-07-28 20:36:13.474672 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2ec02de4 tst -- -4 uname -a 2026-07-28 20:36:14.591734 UTC - Forcing IPv4 only. 2026-07-28 20:36:14.591909 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-2ec02de4 tst -- -4 uptime 2026-07-28 20:36:15.890610 UTC - CLI SSH Test passed. 2026-07-28 20:36:15.890696 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-28 20:36:15.890716 UTC - Uptime: 22:36:15 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-28 20:36:15.890752 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-28 20:36:15.891249 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:15.891780 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:15.891913 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:15.892151 UTC - Connecting to 10.10.216.230:22 2026-07-28 20:36:16.125868 UTC - SSH Test output: 2026-07-28 20:36:16.125962 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-28 20:36:16.125989 UTC - Uptime: 22:36:15 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-28 20:36:16.180101 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-28 20:36:16.227209 UTC - /etc/debian_version: 13.1 2026-07-28 20:36:16.227292 UTC - lsb_release matches expected value 2026-07-28 20:36:16.227315 UTC - /etc/debian_version matches expected value 2026-07-28 20:36:16.227331 UTC - SSH Test passed. 2026-07-28 20:36:16.273329 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 22:35:59 +0200! 2026-07-28 20:36:16.273477 UTC - Logging in using SSH over SSH proxy 2026-07-28 20:36:16.273653 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2ec02de4 tst 2026-07-28 20:36:17.149307 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:17.149512 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:17.149577 UTC - Added paramiko HostKeyEntry for 10.10.216.230 2026-07-28 20:36:17.149855 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:36:17.149927 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:36:17.150063 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 20:36:17.150087 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-28 20:36:17.418342 UTC - Connecting to 10.10.216.230:22 over proxy 2026-07-28 20:36:17.661628 UTC - SSH Test output: 2026-07-28 20:36:17.661720 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-28 20:36:17.661744 UTC - Uptime: 22:36:17 up 0 min, 2 users, load average: 0.11, 0.03, 0.01 2026-07-28 20:36:17.721357 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-28 20:36:17.760882 UTC - /etc/debian_version: 13.1 2026-07-28 20:36:17.760982 UTC - lsb_release matches expected value 2026-07-28 20:36:17.761013 UTC - /etc/debian_version matches expected value 2026-07-28 20:36:17.761037 UTC - SSH Test passed. 2026-07-28 20:36:17.808680 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 22:35:59 +0200! 2026-07-28 20:36:17.821885 UTC - Destroying tst-2ec02de4 tst 2026-07-28 20:36:17.822384 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-2ec02de4 tst