Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.55s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 454ms 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 680ms + 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.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-26 20:15:28.897001 UTC - Installing Slices CLI (latest stable version). 2026-07-26 20:15:28.897548 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 20:15:36.930987 UTC - Slices CLI installed. 2026-07-26 20:15:36.931336 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-26 20:15:36.932560 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-26 20:15:36.933602 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 20:15:36.933723 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-26 20:15:36.933750 UTC - Slices CLI exe: 'slices' 2026-07-26 20:15:36.933768 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 20:15:36.933889 UTC - Run: slices --version 2026-07-26 20:15:41.444817 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-26 20:15:41.445097 UTC - Run: slices pubkey list --format text 2026-07-26 20:15:43.220285 UTC - Pubkey already registered 2026-07-26 20:15:43.220599 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 20:15:44.744376 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 20:15:44.744489 UTC - Check List Flavors 2026-07-26 20:15:44.744636 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-26 20:15:46.181377 UTC - Check List DiskImages 2026-07-26 20:15:46.181671 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-26 20:15:47.580164 UTC - Requesting resources 2026-07-26 20:15:47.581179 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 20:15:47.581299 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-02ceed2c --user-data /tmp/tmpcau1u64q 2026-07-26 20:15:49.719106 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kyg13khqegrskgdew604fb0r 2026-07-26 20:15:49.719349 UTC - Waiting until resource ready 2026-07-26 20:15:51.719746 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-02ceed2c tst 2026-07-26 20:15:52.698779 UTC - Status: IMAGING 2026-07-26 20:15:56.709303 UTC - Status: IMAGING 2026-07-26 20:16:00.621082 UTC - Status: IMAGING 2026-07-26 20:16:04.258875 UTC - Status: IMAGING 2026-07-26 20:16:08.096245 UTC - Status: IMAGING 2026-07-26 20:16:11.117319 UTC - Status: IMAGING 2026-07-26 20:16:14.109469 UTC - Status: IMAGING 2026-07-26 20:16:16.925813 UTC - Status: IMAGING 2026-07-26 20:16:19.803908 UTC - Status: IMAGING 2026-07-26 20:16:23.317576 UTC - Status: IMAGING 2026-07-26 20:16:27.230272 UTC - Status: IMAGING 2026-07-26 20:16:30.740285 UTC - Status: IMAGING 2026-07-26 20:16:34.178985 UTC - Status: IMAGING 2026-07-26 20:16:37.279361 UTC - Status: IMAGING 2026-07-26 20:16:40.095963 UTC - Status: IMAGING 2026-07-26 20:16:42.913553 UTC - Status: IMAGING 2026-07-26 20:16:45.882311 UTC - Status: IMAGING 2026-07-26 20:16:49.637273 UTC - Status: IMAGING 2026-07-26 20:16:53.095873 UTC - Status: IMAGING 2026-07-26 20:16:56.339222 UTC - Status: IMAGING 2026-07-26 20:16:59.357167 UTC - Status: IMAGING 2026-07-26 20:17:02.877080 UTC - Status: IMAGING 2026-07-26 20:17:05.793733 UTC - Status: IMAGING 2026-07-26 20:17:09.121393 UTC - Status: IMAGING 2026-07-26 20:17:12.197301 UTC - Status: IMAGING 2026-07-26 20:17:15.927599 UTC - Status: IMAGING 2026-07-26 20:17:18.796155 UTC - Status: IMAGING 2026-07-26 20:17:21.838180 UTC - Status: IMAGING 2026-07-26 20:17:24.816472 UTC - Status: IMAGING 2026-07-26 20:17:28.367770 UTC - Status: IMAGING 2026-07-26 20:17:31.299180 UTC - Status: IMAGING 2026-07-26 20:17:34.561246 UTC - Status: IMAGING 2026-07-26 20:17:37.634263 UTC - Status: IMAGING 2026-07-26 20:17:40.552333 UTC - Status: IMAGING 2026-07-26 20:17:43.318151 UTC - Status: IMAGING 2026-07-26 20:17:46.608885 UTC - Status: IMAGING 2026-07-26 20:17:49.633547 UTC - Status: IMAGING 2026-07-26 20:17:52.700909 UTC - Status: IMAGING 2026-07-26 20:17:55.517138 UTC - Status: IMAGING 2026-07-26 20:17:58.437030 UTC - Status: IMAGING 2026-07-26 20:18:01.302964 UTC - Status: IMAGING 2026-07-26 20:18:04.176153 UTC - Status: IMAGING 2026-07-26 20:18:07.306720 UTC - Status: IMAGING 2026-07-26 20:18:10.449580 UTC - Status: IMAGING 2026-07-26 20:18:13.797833 UTC - Status: IMAGING 2026-07-26 20:18:17.123695 UTC - Status: IMAGING 2026-07-26 20:18:20.394186 UTC - Status: IMAGING 2026-07-26 20:18:23.311723 UTC - Status: IMAGING 2026-07-26 20:18:26.235238 UTC - Status: IMAGING 2026-07-26 20:18:29.105234 UTC - Status: IMAGING 2026-07-26 20:18:32.021631 UTC - Status: UP 2026-07-26 20:18:32.021724 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyg13k90eeztz30w1tqmbjhp 2026-07-26 20:18:32.021766 UTC - Validate resources 2026-07-26 20:18:32.839253 UTC - The fields of the created resource were validated. 2026-07-26 20:18:32.839394 UTC - Check if resources are registered in experiment 2026-07-26 20:18:32.839647 UTC - Run: slices experiment list-resources --format json tst-02ceed2c 2026-07-26 20:18:33.557927 UTC - Status (on expauth): UP 2026-07-26 20:18:33.558086 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 20:18:33.558227 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-02ceed2c 2026-07-26 20:18:36.578458 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-02ceed2c tst 2026-07-26 20:18:37.344092 UTC - Run: slices experiment list-resources --format json tst-02ceed2c 2026-07-26 20:18:38.009520 UTC - expires_at (on expauth): 2026-07-26T23:18:00Z (correctly extended) 2026-07-26 20:18:38.009615 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 20:18:38.009758 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-02ceed2c 2026-07-26 20:18:40.981621 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-02ceed2c tst 2026-07-26 20:18:41.747684 UTC - Run: slices experiment list-resources --format json tst-02ceed2c 2026-07-26 20:18:42.413307 UTC - expires_at (on expauth): 2026-07-27T00:18:00Z (correctly extended) 2026-07-26 20:18:42.413429 UTC - Testing ssh login 2026-07-26 20:18:42.427076 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-02ceed2c tst 2026-07-26 20:18:43.192969 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-02ceed2c tst 2026-07-26 20:18:43.908537 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-02ceed2c tst 2026-07-26 20:18:44.674285 UTC - Logging in using 'slices bi ssh' 2026-07-26 20:18:44.674359 UTC - Forcing IPv4 only. 2026-07-26 20:18:44.674511 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-02ceed2c tst -- -4 uname -a 2026-07-26 20:18:45.640679 UTC - Forcing IPv4 only. 2026-07-26 20:18:45.640892 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-02ceed2c tst -- -4 uptime 2026-07-26 20:18:46.556732 UTC - CLI SSH Test passed. 2026-07-26 20:18:46.556803 UTC - Uname: Linux tst.bu6jqe.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 20:18:46.556823 UTC - Uptime: 22:18:46 up 0 min, 0 users, load average: 0.24, 0.07, 0.02 2026-07-26 20:18:46.556856 UTC - Logging in using 'slices bi ssh' 2026-07-26 20:18:46.556903 UTC - Forcing IPv6 only. 2026-07-26 20:18:46.557025 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-02ceed2c tst -- -6 uname -a 2026-07-26 20:18:47.472867 UTC - Forcing IPv6 only. 2026-07-26 20:18:47.473049 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-02ceed2c tst -- -6 uptime 2026-07-26 20:18:48.388877 UTC - CLI SSH Test passed. 2026-07-26 20:18:48.388949 UTC - Uname: Linux tst.bu6jqe.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 20:18:48.388967 UTC - Uptime: 22:18:48 up 0 min, 0 users, load average: 0.24, 0.07, 0.02 2026-07-26 20:18:48.389005 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-26 20:18:48.389463 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:48.389627 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:48.389684 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:48.389849 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 2026-07-26 20:18:48.736704 UTC - SSH Test output: 2026-07-26 20:18:48.736818 UTC - Uname: Linux tst.bu6jqe.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 20:18:48.736837 UTC - Uptime: 22:18:48 up 0 min, 0 users, load average: 0.24, 0.07, 0.02 2026-07-26 20:18:48.824115 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-26 20:18:48.824193 UTC - lsb_release matches expected value 2026-07-26 20:18:48.824226 UTC - SSH Test passed. 2026-07-26 20:18:48.871752 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 22:18:29 +0200! 2026-07-26 20:18:48.871922 UTC - Logging in using SSH over SSH proxy 2026-07-26 20:18:48.872096 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-02ceed2c tst 2026-07-26 20:18:49.587874 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:49.588038 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:49.588112 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 20:18:49.588365 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 20:18:49.588420 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 20:18:49.588540 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 20:18:49.588563 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-26 20:18:49.862023 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 over proxy 2026-07-26 20:18:50.161172 UTC - SSH Test output: 2026-07-26 20:18:50.161266 UTC - Uname: Linux tst.bu6jqe.fed4fire-eu.wall1.ilabt.imec.be 5.15.0-122-generic #132-Ubuntu SMP Thu Aug 29 13:45:52 UTC 2024 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 20:18:50.161286 UTC - Uptime: 22:18:50 up 0 min, 0 users, load average: 0.24, 0.07, 0.02 2026-07-26 20:18:50.255062 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-26 20:18:50.255169 UTC - lsb_release matches expected value 2026-07-26 20:18:50.255189 UTC - SSH Test passed. 2026-07-26 20:18:50.259709 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 22:18:29 +0200! 2026-07-26 20:18:50.267762 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-26 20:18:50.267816 UTC - Destroying tst-02ceed2c tst 2026-07-26 20:18:50.267991 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-02ceed2c tst