Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.96s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 241ms 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 558ms + 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-27 04:13:31.797449 UTC - Installing Slices CLI (latest stable version). 2026-07-27 04:13:31.797689 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 04:13:45.296712 UTC - Slices CLI installed. 2026-07-27 04:13:45.297192 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-27 04:13:45.299325 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-27 04:13:45.300309 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 04:13:45.300442 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-27 04:13:45.300471 UTC - Slices CLI exe: 'slices' 2026-07-27 04:13:45.300487 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 04:13:45.300620 UTC - Run: slices --version 2026-07-27 04:13:47.920967 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-27 04:13:47.921247 UTC - Run: slices pubkey list --format text 2026-07-27 04:13:48.690341 UTC - Pubkey already registered 2026-07-27 04:13:48.690966 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 04:13:49.258879 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 04:13:49.258970 UTC - Check List Flavors 2026-07-27 04:13:49.259117 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-27 04:13:49.874535 UTC - Check List DiskImages 2026-07-27 04:13:49.874797 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-27 04:13:50.490285 UTC - Requesting resources 2026-07-27 04:13:50.490893 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 04:13:50.491020 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-0217a533 --user-data /tmp/tmpalli2e08 2026-07-27 04:13:51.557681 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kygwexdaep086pzdahhxcfxv 2026-07-27 04:13:51.557902 UTC - Waiting until resource ready 2026-07-27 04:13:53.558409 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-0217a533 tst 2026-07-27 04:13:54.430071 UTC - Status: IMAGING 2026-07-27 04:13:57.407368 UTC - Status: IMAGING 2026-07-27 04:14:00.225036 UTC - Status: IMAGING 2026-07-27 04:14:05.746540 UTC - Status: IMAGING 2026-07-27 04:14:08.716955 UTC - Status: IMAGING 2026-07-27 04:14:11.650851 UTC - Status: IMAGING 2026-07-27 04:14:14.525697 UTC - Status: IMAGING 2026-07-27 04:14:17.491920 UTC - Status: IMAGING 2026-07-27 04:14:20.413313 UTC - Status: IMAGING 2026-07-27 04:14:23.243569 UTC - Status: IMAGING 2026-07-27 04:14:26.059893 UTC - Status: IMAGING 2026-07-27 04:14:28.876085 UTC - Status: IMAGING 2026-07-27 04:14:31.692079 UTC - Status: IMAGING 2026-07-27 04:14:34.508045 UTC - Status: IMAGING 2026-07-27 04:14:37.273998 UTC - Status: IMAGING 2026-07-27 04:14:40.190742 UTC - Status: IMAGING 2026-07-27 04:14:42.956682 UTC - Status: IMAGING 2026-07-27 04:14:45.781860 UTC - Status: IMAGING 2026-07-27 04:14:48.649762 UTC - Status: IMAGING 2026-07-27 04:14:51.465747 UTC - Status: IMAGING 2026-07-27 04:14:54.232042 UTC - Status: IMAGING 2026-07-27 04:14:57.098369 UTC - Status: IMAGING 2026-07-27 04:14:59.915583 UTC - Status: IMAGING 2026-07-27 04:15:02.891112 UTC - Status: IMAGING 2026-07-27 04:15:05.707767 UTC - Status: IMAGING 2026-07-27 04:15:08.530670 UTC - Status: IMAGING 2026-07-27 04:15:11.347696 UTC - Status: IMAGING 2026-07-27 04:15:14.163675 UTC - Status: IMAGING 2026-07-27 04:15:16.929622 UTC - Status: IMAGING 2026-07-27 04:15:19.695509 UTC - Status: IMAGING 2026-07-27 04:15:22.511328 UTC - Status: IMAGING 2026-07-27 04:15:25.802153 UTC - Status: IMAGING 2026-07-27 04:15:28.934431 UTC - Status: IMAGING 2026-07-27 04:15:31.901754 UTC - Status: IMAGING 2026-07-27 04:15:36.171899 UTC - Status: IMAGING 2026-07-27 04:15:39.623237 UTC - Status: IMAGING 2026-07-27 04:15:43.211228 UTC - Status: IMAGING 2026-07-27 04:15:46.179598 UTC - Status: IMAGING 2026-07-27 04:15:48.995891 UTC - Status: IMAGING 2026-07-27 04:15:51.862039 UTC - Status: IMAGING 2026-07-27 04:15:55.094929 UTC - Status: IMAGING 2026-07-27 04:15:58.663267 UTC - Status: IMAGING 2026-07-27 04:16:01.582725 UTC - Status: IMAGING 2026-07-27 04:16:04.627287 UTC - Status: IMAGING 2026-07-27 04:16:07.749768 UTC - Status: IMAGING 2026-07-27 04:16:10.892364 UTC - Status: IMAGING 2026-07-27 04:16:14.602999 UTC - Status: IMAGING 2026-07-27 04:16:18.589182 UTC - Status: IMAGING 2026-07-27 04:16:21.719272 UTC - Status: IMAGING 2026-07-27 04:16:24.845337 UTC - Status: IMAGING 2026-07-27 04:16:27.663216 UTC - Status: IMAGING 2026-07-27 04:16:30.430581 UTC - Status: IMAGING 2026-07-27 04:16:33.297215 UTC - Status: IMAGING 2026-07-27 04:16:36.113818 UTC - Status: IMAGING 2026-07-27 04:16:39.283753 UTC - Status: IMAGING 2026-07-27 04:16:42.251179 UTC - Status: IMAGING 2026-07-27 04:16:45.935171 UTC - Status: INITIALIZING 2026-07-27 04:16:49.070447 UTC - Status: UP 2026-07-27 04:16:49.070548 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kygwex6dewebb9nb4f2pa5xj 2026-07-27 04:16:49.070590 UTC - Validate resources 2026-07-27 04:16:50.141364 UTC - The fields of the created resource were validated. 2026-07-27 04:16:50.141453 UTC - Check if resources are registered in experiment 2026-07-27 04:16:50.141591 UTC - Run: slices experiment list-resources --format json tst-0217a533 2026-07-27 04:16:51.111137 UTC - Status (on expauth): UP 2026-07-27 04:16:51.111322 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 04:16:51.111563 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-0217a533 2026-07-27 04:16:54.519708 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-0217a533 tst 2026-07-27 04:16:55.592286 UTC - Run: slices experiment list-resources --format json tst-0217a533 2026-07-27 04:16:56.575117 UTC - expires_at (on expauth): 2026-07-27T07:16:00Z (correctly extended) 2026-07-27 04:16:56.575818 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 04:16:56.576002 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-0217a533 2026-07-27 04:16:59.849810 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-0217a533 tst 2026-07-27 04:17:00.615903 UTC - Run: slices experiment list-resources --format json tst-0217a533 2026-07-27 04:17:01.231099 UTC - expires_at (on expauth): 2026-07-27T08:16:00Z (correctly extended) 2026-07-27 04:17:01.231217 UTC - Testing ssh login 2026-07-27 04:17:01.254734 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0217a533 tst 2026-07-27 04:17:02.175704 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0217a533 tst 2026-07-27 04:17:03.150409 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-0217a533 tst 2026-07-27 04:17:04.021853 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:17:04.021930 UTC - Forcing IPv4 only. 2026-07-27 04:17:04.022067 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-0217a533 tst -- -4 uname -a 2026-07-27 04:17:05.106679 UTC - Forcing IPv4 only. 2026-07-27 04:17:05.106849 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-0217a533 tst -- -4 uptime 2026-07-27 04:17:06.286178 UTC - CLI SSH Test passed. 2026-07-27 04:17:06.286572 UTC - Uname: Linux tst.nuqnf8.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-27 04:17:06.286605 UTC - Uptime: 06:17:06 up 1 min, 0 users, load average: 0.36, 0.12, 0.04 2026-07-27 04:17:06.286655 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:17:06.286707 UTC - Forcing IPv6 only. 2026-07-27 04:17:06.286831 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-0217a533 tst -- -6 uname -a 2026-07-27 04:17:07.305051 UTC - Forcing IPv6 only. 2026-07-27 04:17:07.305238 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-0217a533 tst -- -6 uptime 2026-07-27 04:17:08.327063 UTC - CLI SSH Test passed. 2026-07-27 04:17:08.327136 UTC - Uname: Linux tst.nuqnf8.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-27 04:17:08.327155 UTC - Uptime: 06:17:08 up 1 min, 0 users, load average: 0.33, 0.11, 0.04 2026-07-27 04:17:08.327192 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-27 04:17:08.327714 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:08.328168 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:08.328244 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:08.328412 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 2026-07-27 04:17:08.656396 UTC - SSH Test output: 2026-07-27 04:17:08.656470 UTC - Uname: Linux tst.nuqnf8.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-27 04:17:08.656492 UTC - Uptime: 06:17:08 up 1 min, 0 users, load average: 0.33, 0.11, 0.04 2026-07-27 04:17:08.763179 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-27 04:17:08.763254 UTC - lsb_release matches expected value 2026-07-27 04:17:08.763274 UTC - SSH Test passed. 2026-07-27 04:17:08.815053 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:16:45 +0200! 2026-07-27 04:17:08.815239 UTC - Logging in using SSH over SSH proxy 2026-07-27 04:17:08.815405 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-0217a533 tst 2026-07-27 04:17:09.788341 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:09.788506 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:09.788564 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-27 04:17:09.788892 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:17:09.788948 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:17:09.789053 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:17:09.789075 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-27 04:17:10.070531 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 over proxy 2026-07-27 04:17:10.367768 UTC - SSH Test output: 2026-07-27 04:17:10.367844 UTC - Uname: Linux tst.nuqnf8.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-27 04:17:10.367863 UTC - Uptime: 06:17:10 up 1 min, 0 users, load average: 0.33, 0.11, 0.04 2026-07-27 04:17:10.477526 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-27 04:17:10.477599 UTC - lsb_release matches expected value 2026-07-27 04:17:10.477618 UTC - SSH Test passed. 2026-07-27 04:17:10.482021 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:16:45 +0200! 2026-07-27 04:17:10.496921 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-27 04:17:10.496982 UTC - Destroying tst-0217a533 tst 2026-07-27 04:17:10.497161 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-0217a533 tst