Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.20s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 486ms 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 866ms + annotated-types==0.7.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.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-23 20:13:19.961871 UTC - Installing Slices CLI (latest stable version). 2026-07-23 20:13:19.962096 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 20:13:25.889152 UTC - Slices CLI installed. 2026-07-23 20:13:25.889378 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-23 20:13:25.891452 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-23 20:13:25.892212 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 20:13:25.892326 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-23 20:13:25.892353 UTC - Slices CLI exe: 'slices' 2026-07-23 20:13:25.892369 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 20:13:25.892511 UTC - Run: slices --version 2026-07-23 20:13:29.117080 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-23 20:13:29.117366 UTC - Run: slices pubkey list --format text 2026-07-23 20:13:30.151047 UTC - Pubkey already registered 2026-07-23 20:13:30.151286 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 20:13:30.927439 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 20:13:30.927545 UTC - Check List Flavors 2026-07-23 20:13:30.927720 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-23 20:13:32.094181 UTC - Check List DiskImages 2026-07-23 20:13:32.094375 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-23 20:13:32.709653 UTC - Requesting resources 2026-07-23 20:13:32.710180 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 20:13:32.710296 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-f6cf21a7 --user-data /tmp/tmpy9gmglvu 2026-07-23 20:13:35.630039 UTC - Resource ID: r_be-gent1-bi-baremetal1_01ky89sbvnfsgr30ret7rbsg3z 2026-07-23 20:13:35.630235 UTC - Waiting until resource ready 2026-07-23 20:13:37.630591 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f6cf21a7 tst 2026-07-23 20:13:38.555109 UTC - Status: IMAGING 2026-07-23 20:13:41.482260 UTC - Status: IMAGING 2026-07-23 20:13:44.197996 UTC - Status: IMAGING 2026-07-23 20:13:46.964318 UTC - Status: IMAGING 2026-07-23 20:13:49.830370 UTC - Status: IMAGING 2026-07-23 20:13:52.598341 UTC - Status: IMAGING 2026-07-23 20:13:55.365172 UTC - Status: IMAGING 2026-07-23 20:13:58.182104 UTC - Status: IMAGING 2026-07-23 20:14:00.948088 UTC - Status: IMAGING 2026-07-23 20:14:06.270191 UTC - Status: IMAGING 2026-07-23 20:14:09.036173 UTC - Status: IMAGING 2026-07-23 20:14:11.802492 UTC - Status: IMAGING 2026-07-23 20:14:14.568268 UTC - Status: IMAGING 2026-07-23 20:14:17.384296 UTC - Status: IMAGING 2026-07-23 20:14:20.100163 UTC - Status: IMAGING 2026-07-23 20:14:22.916815 UTC - Status: IMAGING 2026-07-23 20:14:25.682660 UTC - Status: IMAGING 2026-07-23 20:14:28.448425 UTC - Status: IMAGING 2026-07-23 20:14:31.214610 UTC - Status: IMAGING 2026-07-23 20:14:33.981656 UTC - Status: IMAGING 2026-07-23 20:14:36.747763 UTC - Status: IMAGING 2026-07-23 20:14:39.564494 UTC - Status: IMAGING 2026-07-23 20:14:42.381320 UTC - Status: IMAGING 2026-07-23 20:14:45.197285 UTC - Status: IMAGING 2026-07-23 20:14:48.374985 UTC - Status: IMAGING 2026-07-23 20:14:51.140903 UTC - Status: IMAGING 2026-07-23 20:14:53.966151 UTC - Status: IMAGING 2026-07-23 20:14:56.732428 UTC - Status: IMAGING 2026-07-23 20:14:59.599059 UTC - Status: IMAGING 2026-07-23 20:15:02.414833 UTC - Status: IMAGING 2026-07-23 20:15:05.230858 UTC - Status: IMAGING 2026-07-23 20:15:07.997085 UTC - Status: IMAGING 2026-07-23 20:15:10.763902 UTC - Status: IMAGING 2026-07-23 20:15:13.530568 UTC - Status: IMAGING 2026-07-23 20:15:16.397580 UTC - Status: IMAGING 2026-07-23 20:15:19.214324 UTC - Status: IMAGING 2026-07-23 20:15:21.982116 UTC - Status: IMAGING 2026-07-23 20:15:24.857386 UTC - Status: IMAGING 2026-07-23 20:15:27.987552 UTC - Status: IMAGING 2026-07-23 20:15:31.201964 UTC - Status: IMAGING 2026-07-23 20:15:36.012256 UTC - Status: IMAGING 2026-07-23 20:15:40.690760 UTC - Status: IMAGING 2026-07-23 20:15:44.432732 UTC - Status: IMAGING 2026-07-23 20:15:47.650407 UTC - Status: IMAGING 2026-07-23 20:15:51.294406 UTC - Status: IMAGING 2026-07-23 20:15:54.574749 UTC - Status: IMAGING 2026-07-23 20:15:58.211184 UTC - Status: IMAGING 2026-07-23 20:16:01.555337 UTC - Status: IMAGING 2026-07-23 20:16:04.640510 UTC - Status: IMAGING 2026-07-23 20:16:07.507660 UTC - Status: IMAGING 2026-07-23 20:16:10.481390 UTC - Status: IMAGING 2026-07-23 20:16:13.777174 UTC - Status: IMAGING 2026-07-23 20:16:16.708235 UTC - Status: IMAGING 2026-07-23 20:16:20.017233 UTC - Status: IMAGING 2026-07-23 20:16:23.224579 UTC - Status: IMAGING 2026-07-23 20:16:26.609054 UTC - Status: IMAGING 2026-07-23 20:16:29.732586 UTC - Status: IMAGING 2026-07-23 20:16:32.899184 UTC - Status: IMAGING 2026-07-23 20:16:35.665030 UTC - Status: STARTING 2026-07-23 20:16:38.584243 UTC - Status: STARTING 2026-07-23 20:16:41.350445 UTC - Status: UP 2026-07-23 20:16:41.350521 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky89satyekk9s9jyxea1rfce 2026-07-23 20:16:41.350563 UTC - Validate resources 2026-07-23 20:16:42.119113 UTC - The fields of the created resource were validated. 2026-07-23 20:16:42.119215 UTC - Check if resources are registered in experiment 2026-07-23 20:16:42.119363 UTC - Run: slices experiment list-resources --format json tst-f6cf21a7 2026-07-23 20:16:42.935146 UTC - Status (on expauth): UP 2026-07-23 20:16:42.935286 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 20:16:42.935407 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-f6cf21a7 2026-07-23 20:16:50.314674 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f6cf21a7 tst 2026-07-23 20:16:51.908895 UTC - Run: slices experiment list-resources --format json tst-f6cf21a7 2026-07-23 20:16:52.943071 UTC - expires_at (on expauth): 2026-07-23T23:16:00Z (correctly extended) 2026-07-23 20:16:52.945990 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 20:16:52.946189 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-f6cf21a7 2026-07-23 20:16:58.369085 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f6cf21a7 tst 2026-07-23 20:17:00.095480 UTC - Run: slices experiment list-resources --format json tst-f6cf21a7 2026-07-23 20:17:01.398969 UTC - expires_at (on expauth): 2026-07-24T00:16:00Z (correctly extended) 2026-07-23 20:17:01.402470 UTC - Testing ssh login 2026-07-23 20:17:01.451401 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f6cf21a7 tst 2026-07-23 20:17:02.947569 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f6cf21a7 tst 2026-07-23 20:17:03.969185 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-f6cf21a7 tst 2026-07-23 20:17:04.997538 UTC - Logging in using 'slices bi ssh' 2026-07-23 20:17:04.997615 UTC - Forcing IPv4 only. 2026-07-23 20:17:04.997732 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f6cf21a7 tst -- -4 uname -a 2026-07-23 20:17:06.377393 UTC - Forcing IPv4 only. 2026-07-23 20:17:06.377571 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f6cf21a7 tst -- -4 uptime 2026-07-23 20:17:07.714456 UTC - CLI SSH Test passed. 2026-07-23 20:17:07.714526 UTC - Uname: Linux tst.rf7gny.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-23 20:17:07.714545 UTC - Uptime: 22:17:07 up 1 min, 0 users, load average: 0.15, 0.07, 0.02 2026-07-23 20:17:07.714579 UTC - Logging in using 'slices bi ssh' 2026-07-23 20:17:07.714626 UTC - Forcing IPv6 only. 2026-07-23 20:17:07.714731 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f6cf21a7 tst -- -6 uname -a 2026-07-23 20:17:08.686451 UTC - Forcing IPv6 only. 2026-07-23 20:17:08.686637 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f6cf21a7 tst -- -6 uptime 2026-07-23 20:17:09.818141 UTC - CLI SSH Test passed. 2026-07-23 20:17:09.818226 UTC - Uname: Linux tst.rf7gny.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-23 20:17:09.818246 UTC - Uptime: 22:17:09 up 1 min, 0 users, load average: 0.14, 0.06, 0.02 2026-07-23 20:17:09.818282 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-23 20:17:09.818826 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:09.819010 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:09.819068 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:09.819258 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 2026-07-23 20:17:10.260837 UTC - SSH Test output: 2026-07-23 20:17:10.260915 UTC - Uname: Linux tst.rf7gny.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-23 20:17:10.260964 UTC - Uptime: 22:17:10 up 1 min, 0 users, load average: 0.14, 0.06, 0.02 2026-07-23 20:17:10.365224 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-23 20:17:10.365306 UTC - lsb_release matches expected value 2026-07-23 20:17:10.365329 UTC - SSH Test passed. 2026-07-23 20:17:10.410507 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 22:16:32 +0200! 2026-07-23 20:17:10.410687 UTC - Logging in using SSH over SSH proxy 2026-07-23 20:17:10.410838 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-f6cf21a7 tst 2026-07-23 20:17:11.279742 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:11.279888 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:11.279939 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-23 20:17:11.280259 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:17:11.280326 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:17:11.280440 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 20:17:11.280462 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-23 20:17:11.554606 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 over proxy 2026-07-23 20:17:11.863103 UTC - SSH Test output: 2026-07-23 20:17:11.863184 UTC - Uname: Linux tst.rf7gny.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-23 20:17:11.863206 UTC - Uptime: 22:17:11 up 1 min, 0 users, load average: 0.14, 0.06, 0.02 2026-07-23 20:17:11.934760 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-23 20:17:11.934836 UTC - lsb_release matches expected value 2026-07-23 20:17:11.934855 UTC - SSH Test passed. 2026-07-23 20:17:11.981586 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 22:16:32 +0200! 2026-07-23 20:17:11.989120 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-23 20:17:11.989184 UTC - Destroying tst-f6cf21a7 tst 2026-07-23 20:17:11.989332 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-f6cf21a7 tst