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 297ms 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 244ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.2 + 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-17 13:14:14.680678 UTC - Installing Slices CLI (latest stable version). 2026-07-17 13:14:14.680909 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 13:14:21.359821 UTC - Slices CLI installed. 2026-07-17 13:14:21.360231 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-17 13:14:21.361652 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-17 13:14:21.362578 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 13:14:21.362790 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-17 13:14:21.362836 UTC - Slices CLI exe: 'slices' 2026-07-17 13:14:21.362868 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 13:14:21.363090 UTC - Run: slices --version 2026-07-17 13:14:24.134264 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-17 13:14:24.134651 UTC - Run: slices pubkey list --format text 2026-07-17 13:14:24.901693 UTC - Pubkey already registered 2026-07-17 13:14:24.902014 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 13:14:25.517476 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 13:14:25.517574 UTC - Check List Flavors 2026-07-17 13:14:25.517728 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-17 13:14:26.834659 UTC - Check List DiskImages 2026-07-17 13:14:26.834861 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-17 13:14:27.400137 UTC - Requesting resources 2026-07-17 13:14:27.400798 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 13:14:27.400934 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b031688d --user-data /tmp/tmp6xbck3p2 2026-07-17 13:14:30.773032 UTC - Resource ID: r_be-gent1-bi-vm1_01kxr3dpcafgp9dw5a61x04xqq 2026-07-17 13:14:30.773191 UTC - Waiting until resource ready 2026-07-17 13:14:32.773602 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b031688d tst 2026-07-17 13:14:33.489290 UTC - Status: IMAGING 2026-07-17 13:14:36.205876 UTC - Status: IMAGING 2026-07-17 13:14:38.922551 UTC - Status: IMAGING 2026-07-17 13:14:41.588946 UTC - Status: BOOTING 2026-07-17 13:14:44.355727 UTC - Status: BOOTING 2026-07-17 13:14:47.122258 UTC - Status: BOOTING 2026-07-17 13:14:49.838494 UTC - Status: BOOTING 2026-07-17 13:14:52.555008 UTC - Status: BOOTING 2026-07-17 13:14:55.220671 UTC - Status: INITIALIZING 2026-07-17 13:14:57.987313 UTC - Status: UP 2026-07-17 13:14:57.987420 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxr3dnd3eb6abrtj7j66gmz2 2026-07-17 13:14:57.987471 UTC - Validate resources 2026-07-17 13:14:58.753966 UTC - The fields of the created resource were validated. 2026-07-17 13:14:58.754108 UTC - Check if resources are registered in experiment 2026-07-17 13:14:58.754367 UTC - Run: slices experiment list-resources --format json tst-b031688d 2026-07-17 13:14:59.470128 UTC - Status (on expauth): UP 2026-07-17 13:14:59.470331 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 13:14:59.470487 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-b031688d 2026-07-17 13:15:02.242142 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b031688d tst 2026-07-17 13:15:02.958364 UTC - Run: slices experiment list-resources --format json tst-b031688d 2026-07-17 13:15:03.623678 UTC - expires_at (on expauth): 2026-07-17T16:14:00Z (correctly extended) 2026-07-17 13:15:03.623775 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 13:15:03.623918 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-b031688d 2026-07-17 13:15:08.449237 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-b031688d tst 2026-07-17 13:15:09.164820 UTC - Run: slices experiment list-resources --format json tst-b031688d 2026-07-17 13:15:09.780436 UTC - expires_at (on expauth): 2026-07-17T17:15:00Z (correctly extended) 2026-07-17 13:15:09.780608 UTC - Testing ssh login 2026-07-17 13:15:09.794257 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b031688d tst 2026-07-17 13:15:10.560561 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b031688d tst 2026-07-17 13:15:11.276814 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b031688d tst 2026-07-17 13:15:11.993594 UTC - Logging in using 'slices bi ssh' 2026-07-17 13:15:11.993690 UTC - Forcing IPv4 only. 2026-07-17 13:15:11.993849 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-b031688d tst -- -4 uname -a 2026-07-17 13:15:13.411403 UTC - Forcing IPv4 only. 2026-07-17 13:15:13.411589 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-b031688d tst -- -4 uptime 2026-07-17 13:15:14.277451 UTC - CLI SSH Test passed. 2026-07-17 13:15:14.277522 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-17 13:15:14.277542 UTC - Uptime: 15:15:13 up 0 min, 1 user, load average: 0.74, 0.19, 0.06 2026-07-17 13:15:14.277576 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-17 13:15:14.278004 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:14.278180 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:14.278243 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:14.278419 UTC - Connecting to 10.10.220.51:22 2026-07-17 13:15:14.544992 UTC - SSH Test output: 2026-07-17 13:15:14.545063 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-17 13:15:14.545089 UTC - Uptime: 15:15:13 up 0 min, 1 user, load average: 0.68, 0.19, 0.06 2026-07-17 13:15:14.598027 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 13:15:14.598111 UTC - lsb_release matches expected value 2026-07-17 13:15:14.598133 UTC - SSH Test passed. 2026-07-17 13:15:14.642328 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 15:14:55 +0200! 2026-07-17 13:15:14.642471 UTC - Logging in using SSH over SSH proxy 2026-07-17 13:15:14.642623 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b031688d tst 2026-07-17 13:15:15.358312 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:15.358465 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:15.358516 UTC - Added paramiko HostKeyEntry for 10.10.220.51 2026-07-17 13:15:15.358745 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 13:15:15.358801 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 13:15:15.358905 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 13:15:15.358925 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-17 13:15:15.480261 UTC - Connecting to 10.10.220.51:22 over proxy 2026-07-17 13:15:15.728804 UTC - SSH Test output: 2026-07-17 13:15:15.728871 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-17 13:15:15.728890 UTC - Uptime: 15:15:15 up 0 min, 2 users, load average: 0.68, 0.19, 0.06 2026-07-17 13:15:15.781097 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 13:15:15.781191 UTC - lsb_release matches expected value 2026-07-17 13:15:15.781212 UTC - SSH Test passed. 2026-07-17 13:15:15.828345 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 15:14:55 +0200! 2026-07-17 13:15:15.835403 UTC - Destroying tst-b031688d tst 2026-07-17 13:15:15.835579 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-b031688d tst