Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.08s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 379ms 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 709ms + 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 13:14:37.309254 UTC - Installing Slices CLI (latest stable version). 2026-07-26 13:14:37.309480 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 13:14:48.067103 UTC - Slices CLI installed. 2026-07-26 13:14:48.067492 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 13:14:48.070825 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 13:14:48.072222 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 13:14:48.072373 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 13:14:48.072403 UTC - Slices CLI exe: 'slices' 2026-07-26 13:14:48.072422 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 13:14:48.072541 UTC - Run: slices --version 2026-07-26 13:14:51.816383 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 13:14:51.816646 UTC - Run: slices pubkey list --format text 2026-07-26 13:14:52.951159 UTC - Pubkey already registered 2026-07-26 13:14:52.951407 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 13:14:53.577440 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 13:14:53.577540 UTC - Check List Flavors 2026-07-26 13:14:53.577703 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-26 13:14:54.514696 UTC - Check List DiskImages 2026-07-26 13:14:54.514871 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-26 13:14:55.455365 UTC - Requesting resources 2026-07-26 13:14:55.455861 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 13:14:55.455982 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-acdab52c --user-data /tmp/tmpb0tiaaxw 2026-07-26 13:14:56.697243 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kyf90yn7e19bvgz8hhwyeqqt 2026-07-26 13:14:56.697395 UTC - Waiting until resource ready 2026-07-26 13:14:58.697770 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-acdab52c tst 2026-07-26 13:14:59.569131 UTC - Status: IMAGING 2026-07-26 13:15:02.435905 UTC - Status: IMAGING 2026-07-26 13:15:05.151942 UTC - Status: IMAGING 2026-07-26 13:15:07.968088 UTC - Status: IMAGING 2026-07-26 13:15:10.784153 UTC - Status: IMAGING 2026-07-26 13:15:13.600433 UTC - Status: IMAGING 2026-07-26 13:15:16.416884 UTC - Status: IMAGING 2026-07-26 13:15:19.283537 UTC - Status: IMAGING 2026-07-26 13:15:22.475247 UTC - Status: IMAGING 2026-07-26 13:15:25.871326 UTC - Status: IMAGING 2026-07-26 13:15:29.060440 UTC - Status: IMAGING 2026-07-26 13:15:32.301141 UTC - Status: IMAGING 2026-07-26 13:15:36.733573 UTC - Status: IMAGING 2026-07-26 13:15:40.421668 UTC - Status: IMAGING 2026-07-26 13:15:43.695717 UTC - Status: IMAGING 2026-07-26 13:15:47.081729 UTC - Status: IMAGING 2026-07-26 13:15:49.949806 UTC - Status: IMAGING 2026-07-26 13:15:52.816107 UTC - Status: IMAGING 2026-07-26 13:15:55.836698 UTC - Status: IMAGING 2026-07-26 13:15:58.861600 UTC - Status: IMAGING 2026-07-26 13:16:01.933614 UTC - Status: IMAGING 2026-07-26 13:16:04.751705 UTC - Status: IMAGING 2026-07-26 13:16:07.773206 UTC - Status: IMAGING 2026-07-26 13:16:10.743327 UTC - Status: IMAGING 2026-07-26 13:16:14.409729 UTC - Status: IMAGING 2026-07-26 13:16:17.955870 UTC - Status: IMAGING 2026-07-26 13:16:21.081228 UTC - Status: IMAGING 2026-07-26 13:16:23.847360 UTC - Status: IMAGING 2026-07-26 13:16:26.763688 UTC - Status: IMAGING 2026-07-26 13:16:29.632826 UTC - Status: IMAGING 2026-07-26 13:16:32.870076 UTC - Status: IMAGING 2026-07-26 13:16:36.027321 UTC - Status: IMAGING 2026-07-26 13:16:38.843430 UTC - Status: IMAGING 2026-07-26 13:16:42.125445 UTC - Status: IMAGING 2026-07-26 13:16:45.513122 UTC - Status: IMAGING 2026-07-26 13:16:48.737397 UTC - Status: IMAGING 2026-07-26 13:16:52.079477 UTC - Status: IMAGING 2026-07-26 13:16:55.253350 UTC - Status: IMAGING 2026-07-26 13:16:58.517159 UTC - Status: IMAGING 2026-07-26 13:17:01.637472 UTC - Status: IMAGING 2026-07-26 13:17:04.603955 UTC - Status: IMAGING 2026-07-26 13:17:07.578339 UTC - Status: IMAGING 2026-07-26 13:17:11.018350 UTC - Status: IMAGING 2026-07-26 13:17:14.099685 UTC - Status: IMAGING 2026-07-26 13:17:16.980360 UTC - Status: IMAGING 2026-07-26 13:17:19.947229 UTC - Status: IMAGING 2026-07-26 13:17:22.713233 UTC - Status: IMAGING 2026-07-26 13:17:25.540821 UTC - Status: IMAGING 2026-07-26 13:17:28.306628 UTC - Status: IMAGING 2026-07-26 13:17:31.088692 UTC - Status: IMAGING 2026-07-26 13:17:33.855685 UTC - Status: IMAGING 2026-07-26 13:17:37.045052 UTC - Status: IMAGING 2026-07-26 13:17:40.183208 UTC - Status: IMAGING 2026-07-26 13:17:43.476307 UTC - Status: IMAGING 2026-07-26 13:17:47.169067 UTC - Status: IMAGING 2026-07-26 13:17:50.318777 UTC - Status: IMAGING 2026-07-26 13:17:53.313685 UTC - Status: IMAGING 2026-07-26 13:17:56.132228 UTC - Status: STARTING 2026-07-26 13:17:58.948278 UTC - Status: STARTING 2026-07-26 13:18:01.765056 UTC - Status: STARTING 2026-07-26 13:18:04.531083 UTC - Status: UP 2026-07-26 13:18:04.531168 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyf90yd3edxtbgdnjh0rkspb 2026-07-26 13:18:04.531212 UTC - Validate resources 2026-07-26 13:18:05.298402 UTC - The fields of the created resource were validated. 2026-07-26 13:18:05.298494 UTC - Check if resources are registered in experiment 2026-07-26 13:18:05.298641 UTC - Run: slices experiment list-resources --format json tst-acdab52c 2026-07-26 13:18:05.964099 UTC - Status (on expauth): UP 2026-07-26 13:18:05.964253 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 13:18:05.964409 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-acdab52c 2026-07-26 13:18:08.935884 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-acdab52c tst 2026-07-26 13:18:09.702061 UTC - Run: slices experiment list-resources --format json tst-acdab52c 2026-07-26 13:18:10.317768 UTC - expires_at (on expauth): 2026-07-26T16:18:00Z (correctly extended) 2026-07-26 13:18:10.317888 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 13:18:10.318066 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-acdab52c 2026-07-26 13:18:13.238644 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-acdab52c tst 2026-07-26 13:18:14.004758 UTC - Run: slices experiment list-resources --format json tst-acdab52c 2026-07-26 13:18:14.620897 UTC - expires_at (on expauth): 2026-07-26T17:18:00Z (correctly extended) 2026-07-26 13:18:14.621083 UTC - Testing ssh login 2026-07-26 13:18:14.634693 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-acdab52c tst 2026-07-26 13:18:15.400689 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-acdab52c tst 2026-07-26 13:18:16.216864 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-acdab52c tst 2026-07-26 13:18:17.033003 UTC - Logging in using 'slices bi ssh' 2026-07-26 13:18:17.033085 UTC - Forcing IPv4 only. 2026-07-26 13:18:17.033220 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-acdab52c tst -- -4 uname -a 2026-07-26 13:18:18.049455 UTC - Forcing IPv4 only. 2026-07-26 13:18:18.049692 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-acdab52c tst -- -4 uptime 2026-07-26 13:18:19.015882 UTC - CLI SSH Test passed. 2026-07-26 13:18:19.015968 UTC - Uname: Linux tst.y6oqy9.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 13:18:19.015988 UTC - Uptime: 15:18:19 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-26 13:18:19.016023 UTC - Logging in using 'slices bi ssh' 2026-07-26 13:18:19.016070 UTC - Forcing IPv6 only. 2026-07-26 13:18:19.016193 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-acdab52c tst -- -6 uname -a 2026-07-26 13:18:19.932199 UTC - Forcing IPv6 only. 2026-07-26 13:18:19.932398 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-acdab52c tst -- -6 uptime 2026-07-26 13:18:20.848521 UTC - CLI SSH Test passed. 2026-07-26 13:18:20.848601 UTC - Uname: Linux tst.y6oqy9.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 13:18:20.848619 UTC - Uptime: 15:18:20 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-26 13:18:20.848656 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-26 13:18:20.849197 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:20.849394 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:20.849453 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:20.849632 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 2026-07-26 13:18:21.175103 UTC - SSH Test output: 2026-07-26 13:18:21.175210 UTC - Uname: Linux tst.y6oqy9.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 13:18:21.175235 UTC - Uptime: 15:18:21 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-26 13:18:21.282495 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-26 13:18:21.282605 UTC - lsb_release matches expected value 2026-07-26 13:18:21.282627 UTC - SSH Test passed. 2026-07-26 13:18:21.286605 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 15:17:53 +0200! 2026-07-26 13:18:21.286820 UTC - Logging in using SSH over SSH proxy 2026-07-26 13:18:21.286986 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-acdab52c tst 2026-07-26 13:18:22.053166 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:22.053369 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:22.053431 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-26 13:18:22.053755 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 13:18:22.053815 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 13:18:22.053943 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 13:18:22.053967 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-26 13:18:22.306974 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 over proxy 2026-07-26 13:18:22.608928 UTC - SSH Test output: 2026-07-26 13:18:22.609031 UTC - Uname: Linux tst.y6oqy9.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 13:18:22.609058 UTC - Uptime: 15:18:22 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-26 13:18:22.682128 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-26 13:18:22.682205 UTC - lsb_release matches expected value 2026-07-26 13:18:22.682226 UTC - SSH Test passed. 2026-07-26 13:18:22.729858 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 15:17:53 +0200! 2026-07-26 13:18:22.737851 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-26 13:18:22.737918 UTC - Destroying tst-acdab52c tst 2026-07-26 13:18:22.738096 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-acdab52c tst