Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.24s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 188ms 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 345ms + 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 + 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-25 13:14:45.854823 UTC - Installing Slices CLI (latest stable version). 2026-07-25 13:14:45.855069 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 13:14:51.280920 UTC - Slices CLI installed. 2026-07-25 13:14:51.281448 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-25 13:14:51.283348 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-25 13:14:51.284268 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 13:14:51.284398 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-25 13:14:51.284425 UTC - Slices CLI exe: 'slices' 2026-07-25 13:14:51.284442 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 13:14:51.284644 UTC - Run: slices --version 2026-07-25 13:14:54.157665 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-25 13:14:54.158012 UTC - Run: slices pubkey list --format text 2026-07-25 13:14:54.873379 UTC - Pubkey already registered 2026-07-25 13:14:54.873639 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 13:14:55.438749 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 13:14:55.438841 UTC - Check List Flavors 2026-07-25 13:14:55.439013 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-25 13:14:56.054243 UTC - Check List DiskImages 2026-07-25 13:14:56.054430 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-25 13:14:56.669793 UTC - Requesting resources 2026-07-25 13:14:56.670430 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 13:14:56.670590 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-398b2710 --user-data /tmp/tmphjm1hhm0 2026-07-25 13:14:57.736777 UTC - Resource ID: r_be-gent1-bi-vm1_01kycpm8nfexer2707rbhebs0m 2026-07-25 13:14:57.736929 UTC - Waiting until resource ready 2026-07-25 13:14:59.737286 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-398b2710 tst 2026-07-25 13:15:00.502771 UTC - Status: IMAGING 2026-07-25 13:15:03.218510 UTC - Status: IMAGING 2026-07-25 13:15:05.934255 UTC - Status: IMAGING 2026-07-25 13:15:08.649835 UTC - Status: BOOTING 2026-07-25 13:15:11.418643 UTC - Status: BOOTING 2026-07-25 13:15:14.134572 UTC - Status: BOOTING 2026-07-25 13:15:16.850667 UTC - Status: BOOTING 2026-07-25 13:15:19.566811 UTC - Status: BOOTING 2026-07-25 13:15:22.333743 UTC - Status: STARTING 2026-07-25 13:15:25.156629 UTC - Status: UP 2026-07-25 13:15:25.156712 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kycpm8erftwrb0mgv99ag9r7 2026-07-25 13:15:25.156767 UTC - Validate resources 2026-07-25 13:15:26.158728 UTC - The fields of the created resource were validated. 2026-07-25 13:15:26.158815 UTC - Check if resources are registered in experiment 2026-07-25 13:15:26.158954 UTC - Run: slices experiment list-resources --format json tst-398b2710 2026-07-25 13:15:26.876272 UTC - Status (on expauth): UP 2026-07-25 13:15:26.876564 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 13:15:26.877538 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-398b2710 2026-07-25 13:15:28.619965 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-398b2710 tst 2026-07-25 13:15:29.543651 UTC - Run: slices experiment list-resources --format json tst-398b2710 2026-07-25 13:15:30.259455 UTC - expires_at (on expauth): 2026-07-25T16:15:00Z (correctly extended) 2026-07-25 13:15:30.259555 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 13:15:30.259690 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-398b2710 2026-07-25 13:15:31.784322 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-398b2710 tst 2026-07-25 13:15:32.802295 UTC - Run: slices experiment list-resources --format json tst-398b2710 2026-07-25 13:15:33.769898 UTC - expires_at (on expauth): 2026-07-25T17:15:00Z (correctly extended) 2026-07-25 13:15:33.770152 UTC - Testing ssh login 2026-07-25 13:15:33.783734 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-398b2710 tst 2026-07-25 13:15:34.909577 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-398b2710 tst 2026-07-25 13:15:36.347360 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-398b2710 tst 2026-07-25 13:15:38.030930 UTC - Logging in using 'slices bi ssh' 2026-07-25 13:15:38.031021 UTC - Forcing IPv4 only. 2026-07-25 13:15:38.031180 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-398b2710 tst -- -4 uname -a 2026-07-25 13:15:39.829689 UTC - Forcing IPv4 only. 2026-07-25 13:15:39.829874 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-398b2710 tst -- -4 uptime 2026-07-25 13:15:41.375167 UTC - CLI SSH Test passed. 2026-07-25 13:15:41.375262 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-25 13:15:41.375285 UTC - Uptime: 15:15:40 up 0 min, 1 user, load average: 0.24, 0.06, 0.02 2026-07-25 13:15:41.375318 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-25 13:15:41.375806 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:41.376013 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:41.376090 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:41.376259 UTC - Connecting to 10.10.217.192:22 2026-07-25 13:15:41.609570 UTC - SSH Test output: 2026-07-25 13:15:41.609640 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-25 13:15:41.609665 UTC - Uptime: 15:15:40 up 0 min, 1 user, load average: 0.30, 0.08, 0.03 2026-07-25 13:15:41.660032 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 13:15:41.660114 UTC - lsb_release matches expected value 2026-07-25 13:15:41.660140 UTC - SSH Test passed. 2026-07-25 13:15:41.704740 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 15:15:21 +0200! 2026-07-25 13:15:41.704885 UTC - Logging in using SSH over SSH proxy 2026-07-25 13:15:41.705092 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-398b2710 tst 2026-07-25 13:15:42.939068 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:42.939239 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:42.939291 UTC - Added paramiko HostKeyEntry for 10.10.217.192 2026-07-25 13:15:42.939535 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 13:15:42.939586 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 13:15:42.939720 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 13:15:42.939746 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-25 13:15:43.066953 UTC - Connecting to 10.10.217.192:22 over proxy 2026-07-25 13:15:43.320162 UTC - SSH Test output: 2026-07-25 13:15:43.320230 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-25 13:15:43.320249 UTC - Uptime: 15:15:42 up 0 min, 2 users, load average: 0.30, 0.08, 0.03 2026-07-25 13:15:43.373435 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 13:15:43.373510 UTC - lsb_release matches expected value 2026-07-25 13:15:43.373530 UTC - SSH Test passed. 2026-07-25 13:15:43.425671 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 15:15:21 +0200! 2026-07-25 13:15:43.435728 UTC - Destroying tst-398b2710 tst 2026-07-25 13:15:43.435932 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-398b2710 tst