Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.35s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 214ms 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 730ms + 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.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-07-29 14:05:45.432204 UTC - Installing Slices CLI (latest stable version). 2026-07-29 14:05:45.432464 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-29 14:05:50.961254 UTC - Slices CLI installed. 2026-07-29 14:05:50.961761 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-29 14:05:50.967156 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-29 14:05:50.967262 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-29 14:05:50.967346 UTC - which slices: /app/.venv/bin/slices 2026-07-29 14:05:50.967380 UTC - Slices CLI exe: 'slices' 2026-07-29 14:05:50.967407 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-29 14:05:50.967563 UTC - Run: slices --version 2026-07-29 14:05:53.813841 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-07-29 14:05:53.814160 UTC - Run: slices pubkey list --format text 2026-07-29 14:05:54.662252 UTC - Pubkey already registered 2026-07-29 14:05:54.662489 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-29 14:05:55.277750 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-29 14:05:55.277840 UTC - Check List Flavors 2026-07-29 14:05:55.277993 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-29 14:05:56.303576 UTC - Check List DiskImages 2026-07-29 14:05:56.304171 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-29 14:05:57.043924 UTC - Requesting resources 2026-07-29 14:05:57.044409 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-29 14:05:57.044522 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-75c17d21 --user-data /tmp/tmp9oqc59z4 2026-07-29 14:05:58.279241 UTC - Resource ID: r_be-gent1-bi-vm1_01kyq34hg2eb7r74121vsfrmxa 2026-07-29 14:05:58.279395 UTC - Waiting until resource ready 2026-07-29 14:06:00.280182 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-75c17d21 tst 2026-07-29 14:06:01.149375 UTC - Status: IMAGING 2026-07-29 14:06:03.965821 UTC - Status: IMAGING 2026-07-29 14:06:06.855562 UTC - Status: IMAGING 2026-07-29 14:06:09.681574 UTC - Status: BOOTING 2026-07-29 14:06:12.397306 UTC - Status: BOOTING 2026-07-29 14:06:15.214501 UTC - Status: BOOTING 2026-07-29 14:06:17.990559 UTC - Status: BOOTING 2026-07-29 14:06:20.857151 UTC - Status: UP 2026-07-29 14:06:20.857237 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyq34h79fvhaa0dn8n3my6v5 2026-07-29 14:06:20.857279 UTC - Validate resources 2026-07-29 14:06:21.726148 UTC - The fields of the created resource were validated. 2026-07-29 14:06:21.726243 UTC - Check if resources are registered in experiment 2026-07-29 14:06:21.726399 UTC - Run: slices experiment list-resources --format json tst-75c17d21 2026-07-29 14:06:22.542467 UTC - Status (on expauth): UP 2026-07-29 14:06:22.542599 UTC - Testing extend expires_at (all resources in experiment) 2026-07-29 14:06:22.542707 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-75c17d21 2026-07-29 14:06:24.155029 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-75c17d21 tst 2026-07-29 14:06:24.924858 UTC - Run: slices experiment list-resources --format json tst-75c17d21 2026-07-29 14:06:25.691690 UTC - expires_at (on expauth): 2026-07-29T17:06:00Z (correctly extended) 2026-07-29 14:06:25.691793 UTC - Testing extend expires_at (single resource in experiment) 2026-07-29 14:06:25.691956 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-75c17d21 2026-07-29 14:06:27.209173 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-75c17d21 tst 2026-07-29 14:06:27.925003 UTC - Run: slices experiment list-resources --format json tst-75c17d21 2026-07-29 14:06:28.590419 UTC - expires_at (on expauth): 2026-07-29T18:06:00Z (correctly extended) 2026-07-29 14:06:28.590526 UTC - Testing ssh login 2026-07-29 14:06:28.605510 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-75c17d21 tst 2026-07-29 14:06:29.371064 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-75c17d21 tst 2026-07-29 14:06:30.086626 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-75c17d21 tst 2026-07-29 14:06:30.852157 UTC - Logging in using 'slices bi ssh' 2026-07-29 14:06:30.852230 UTC - Forcing IPv4 only. 2026-07-29 14:06:30.852381 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-75c17d21 tst -- -4 uname -a 2026-07-29 14:06:31.818294 UTC - Forcing IPv4 only. 2026-07-29 14:06:31.818479 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-75c17d21 tst -- -4 uptime 2026-07-29 14:06:32.734206 UTC - CLI SSH Test passed. 2026-07-29 14:06:32.734279 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-29 14:06:32.734297 UTC - Uptime: 16:06:31 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-29 14:06:32.734331 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-29 14:06:32.734834 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:32.735048 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:32.735108 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:32.735265 UTC - Connecting to 10.10.218.92:22 2026-07-29 14:06:32.965027 UTC - SSH Test output: 2026-07-29 14:06:32.965098 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-29 14:06:32.965117 UTC - Uptime: 16:06:31 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-29 14:06:33.016336 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-29 14:06:33.064171 UTC - /etc/debian_version: 13.1 2026-07-29 14:06:33.064244 UTC - lsb_release matches expected value 2026-07-29 14:06:33.064264 UTC - /etc/debian_version matches expected value 2026-07-29 14:06:33.064280 UTC - SSH Test passed. 2026-07-29 14:06:33.107748 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 16:06:17 +0200! 2026-07-29 14:06:33.107904 UTC - Logging in using SSH over SSH proxy 2026-07-29 14:06:33.108067 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-75c17d21 tst 2026-07-29 14:06:34.180461 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:34.182269 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:34.182335 UTC - Added paramiko HostKeyEntry for 10.10.218.92 2026-07-29 14:06:34.182562 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 14:06:34.182611 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 14:06:34.182710 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-29 14:06:34.182729 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-29 14:06:34.473814 UTC - Connecting to 10.10.218.92:22 over proxy 2026-07-29 14:06:34.703612 UTC - SSH Test output: 2026-07-29 14:06:34.703683 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-29 14:06:34.703702 UTC - Uptime: 16:06:33 up 0 min, 2 users, load average: 0.00, 0.00, 0.00 2026-07-29 14:06:34.755998 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-29 14:06:34.803752 UTC - /etc/debian_version: 13.1 2026-07-29 14:06:34.803826 UTC - lsb_release matches expected value 2026-07-29 14:06:34.803846 UTC - /etc/debian_version matches expected value 2026-07-29 14:06:34.803860 UTC - SSH Test passed. 2026-07-29 14:06:34.851814 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 16:06:17 +0200! 2026-07-29 14:06:34.858553 UTC - Destroying tst-75c17d21 tst 2026-07-29 14:06:34.858725 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-75c17d21 tst