Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.23s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 319ms 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 689ms + 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.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-24 04:13:32.110691 UTC - Installing Slices CLI (latest stable version). 2026-07-24 04:13:32.110932 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-24 04:13:37.539561 UTC - Slices CLI installed. 2026-07-24 04:13:37.539782 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-24 04:13:37.542192 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-24 04:13:37.543341 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-24 04:13:37.543464 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-24 04:13:37.543491 UTC - Slices CLI exe: 'slices' 2026-07-24 04:13:37.543507 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-24 04:13:37.543625 UTC - Run: slices --version 2026-07-24 04:13:40.163056 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-24 04:13:40.163418 UTC - Run: slices pubkey list --format text 2026-07-24 04:13:41.031587 UTC - Pubkey already registered 2026-07-24 04:13:41.031898 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-24 04:13:41.748886 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-24 04:13:41.749007 UTC - Check List Flavors 2026-07-24 04:13:41.749184 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-24 04:13:43.268378 UTC - Check List DiskImages 2026-07-24 04:13:43.268584 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-24 04:13:43.884063 UTC - Requesting resources 2026-07-24 04:13:43.884848 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-24 04:13:43.885036 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-8902c296 --user-data /tmp/tmp53y5u4ti 2026-07-24 04:13:46.955214 UTC - Resource ID: r_be-gent1-bi-baremetal1_01ky958kxyer9as2twvpr9qgwh 2026-07-24 04:13:46.955400 UTC - Waiting until resource ready 2026-07-24 04:13:48.955792 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8902c296 tst 2026-07-24 04:13:49.739456 UTC - Status: IMAGING 2026-07-24 04:13:52.455378 UTC - Status: IMAGING 2026-07-24 04:13:55.121081 UTC - Status: IMAGING 2026-07-24 04:13:57.887524 UTC - Status: IMAGING 2026-07-24 04:14:00.653710 UTC - Status: IMAGING 2026-07-24 04:14:03.422555 UTC - Status: IMAGING 2026-07-24 04:14:08.845317 UTC - Status: IMAGING 2026-07-24 04:14:11.661693 UTC - Status: IMAGING 2026-07-24 04:14:14.427861 UTC - Status: IMAGING 2026-07-24 04:14:17.244436 UTC - Status: IMAGING 2026-07-24 04:14:20.060858 UTC - Status: IMAGING 2026-07-24 04:14:22.877291 UTC - Status: IMAGING 2026-07-24 04:14:25.643878 UTC - Status: IMAGING 2026-07-24 04:14:28.460827 UTC - Status: IMAGING 2026-07-24 04:14:31.283230 UTC - Status: IMAGING 2026-07-24 04:14:34.099512 UTC - Status: IMAGING 2026-07-24 04:14:36.915665 UTC - Status: IMAGING 2026-07-24 04:14:39.731968 UTC - Status: IMAGING 2026-07-24 04:14:42.548028 UTC - Status: IMAGING 2026-07-24 04:14:45.364104 UTC - Status: IMAGING 2026-07-24 04:14:48.129931 UTC - Status: IMAGING 2026-07-24 04:14:50.946199 UTC - Status: IMAGING 2026-07-24 04:14:54.034604 UTC - Status: IMAGING 2026-07-24 04:14:56.954322 UTC - Status: IMAGING 2026-07-24 04:14:59.872163 UTC - Status: IMAGING 2026-07-24 04:15:02.738387 UTC - Status: IMAGING 2026-07-24 04:15:05.605374 UTC - Status: IMAGING 2026-07-24 04:15:08.424408 UTC - Status: IMAGING 2026-07-24 04:15:11.302229 UTC - Status: IMAGING 2026-07-24 04:15:14.070737 UTC - Status: IMAGING 2026-07-24 04:15:16.886855 UTC - Status: IMAGING 2026-07-24 04:15:19.653146 UTC - Status: IMAGING 2026-07-24 04:15:22.479208 UTC - Status: IMAGING 2026-07-24 04:15:25.395465 UTC - Status: IMAGING 2026-07-24 04:15:28.312282 UTC - Status: IMAGING 2026-07-24 04:15:31.378962 UTC - Status: IMAGING 2026-07-24 04:15:34.860463 UTC - Status: IMAGING 2026-07-24 04:15:38.575228 UTC - Status: IMAGING 2026-07-24 04:15:41.853520 UTC - Status: IMAGING 2026-07-24 04:15:46.080838 UTC - Status: IMAGING 2026-07-24 04:15:49.767102 UTC - Status: IMAGING 2026-07-24 04:15:53.249245 UTC - Status: IMAGING 2026-07-24 04:15:56.788662 UTC - Status: IMAGING 2026-07-24 04:16:00.052900 UTC - Status: IMAGING 2026-07-24 04:16:03.221067 UTC - Status: IMAGING 2026-07-24 04:16:06.187372 UTC - Status: IMAGING 2026-07-24 04:16:09.960947 UTC - Status: IMAGING 2026-07-24 04:16:13.100630 UTC - Status: IMAGING 2026-07-24 04:16:16.018000 UTC - Status: IMAGING 2026-07-24 04:16:18.934287 UTC - Status: IMAGING 2026-07-24 04:16:21.900565 UTC - Status: IMAGING 2026-07-24 04:16:25.215184 UTC - Status: IMAGING 2026-07-24 04:16:28.952089 UTC - Status: IMAGING 2026-07-24 04:16:32.081046 UTC - Status: IMAGING 2026-07-24 04:16:34.897247 UTC - Status: IMAGING 2026-07-24 04:16:37.813813 UTC - Status: IMAGING 2026-07-24 04:16:41.092212 UTC - Status: IMAGING 2026-07-24 04:16:44.009198 UTC - Status: IMAGING 2026-07-24 04:16:48.059067 UTC - Status: IMAGING 2026-07-24 04:16:51.131733 UTC - Status: STARTING 2026-07-24 04:16:54.671075 UTC - Status: UP 2026-07-24 04:16:54.671173 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky958jwreatby0hghkqezz1g 2026-07-24 04:16:54.671213 UTC - Validate resources 2026-07-24 04:16:56.104684 UTC - The fields of the created resource were validated. 2026-07-24 04:16:56.104885 UTC - Check if resources are registered in experiment 2026-07-24 04:16:56.105054 UTC - Run: slices experiment list-resources --format json tst-8902c296 2026-07-24 04:16:57.453999 UTC - Status (on expauth): UP 2026-07-24 04:16:57.454149 UTC - Testing extend expires_at (all resources in experiment) 2026-07-24 04:16:57.454273 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-8902c296 2026-07-24 04:17:05.585744 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8902c296 tst 2026-07-24 04:17:07.218367 UTC - Run: slices experiment list-resources --format json tst-8902c296 2026-07-24 04:17:08.336402 UTC - expires_at (on expauth): 2026-07-24T07:16:00Z (correctly extended) 2026-07-24 04:17:08.336586 UTC - Testing extend expires_at (single resource in experiment) 2026-07-24 04:17:08.336821 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-8902c296 2026-07-24 04:17:13.682842 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-8902c296 tst 2026-07-24 04:17:14.449435 UTC - Run: slices experiment list-resources --format json tst-8902c296 2026-07-24 04:17:15.064526 UTC - expires_at (on expauth): 2026-07-24T08:17:00Z (correctly extended) 2026-07-24 04:17:15.064896 UTC - Testing ssh login 2026-07-24 04:17:15.081273 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8902c296 tst 2026-07-24 04:17:15.901965 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8902c296 tst 2026-07-24 04:17:16.819127 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8902c296 tst 2026-07-24 04:17:17.634971 UTC - Logging in using 'slices bi ssh' 2026-07-24 04:17:17.635075 UTC - Forcing IPv4 only. 2026-07-24 04:17:17.635206 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8902c296 tst -- -4 uname -a 2026-07-24 04:17:18.651126 UTC - Forcing IPv4 only. 2026-07-24 04:17:18.651314 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8902c296 tst -- -4 uptime 2026-07-24 04:17:19.617258 UTC - CLI SSH Test passed. 2026-07-24 04:17:19.617331 UTC - Uname: Linux tst.x9trby.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-24 04:17:19.617350 UTC - Uptime: 06:17:19 up 1 min, 0 users, load average: 0.18, 0.07, 0.02 2026-07-24 04:17:19.617382 UTC - Logging in using 'slices bi ssh' 2026-07-24 04:17:19.617438 UTC - Forcing IPv6 only. 2026-07-24 04:17:19.617552 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8902c296 tst -- -6 uname -a 2026-07-24 04:17:20.583474 UTC - Forcing IPv6 only. 2026-07-24 04:17:20.583655 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-8902c296 tst -- -6 uptime 2026-07-24 04:17:21.699923 UTC - CLI SSH Test passed. 2026-07-24 04:17:21.699990 UTC - Uname: Linux tst.x9trby.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-24 04:17:21.700009 UTC - Uptime: 06:17:21 up 1 min, 0 users, load average: 0.18, 0.07, 0.02 2026-07-24 04:17:21.700047 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-24 04:17:21.700536 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:21.700713 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:21.700821 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:21.700976 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 2026-07-24 04:17:22.031232 UTC - SSH Test output: 2026-07-24 04:17:22.031299 UTC - Uname: Linux tst.x9trby.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-24 04:17:22.031320 UTC - Uptime: 06:17:21 up 1 min, 0 users, load average: 0.18, 0.07, 0.02 2026-07-24 04:17:22.131439 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-24 04:17:22.131516 UTC - lsb_release matches expected value 2026-07-24 04:17:22.131539 UTC - SSH Test passed. 2026-07-24 04:17:22.135500 UTC - Cloud-init user-data: Hello World. The time is now Fri, 24 Jul 2026 06:16:48 +0200! 2026-07-24 04:17:22.135650 UTC - Logging in using SSH over SSH proxy 2026-07-24 04:17:22.135798 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8902c296 tst 2026-07-24 04:17:23.458903 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:23.459062 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:23.459114 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-24 04:17:23.459378 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:17:23.459452 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:17:23.459563 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-24 04:17:23.459583 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-24 04:17:23.713205 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 over proxy 2026-07-24 04:17:24.022919 UTC - SSH Test output: 2026-07-24 04:17:24.023019 UTC - Uname: Linux tst.x9trby.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-24 04:17:24.023045 UTC - Uptime: 06:17:23 up 1 min, 0 users, load average: 0.16, 0.07, 0.02 2026-07-24 04:17:24.110605 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-24 04:17:24.110709 UTC - lsb_release matches expected value 2026-07-24 04:17:24.110732 UTC - SSH Test passed. 2026-07-24 04:17:24.159068 UTC - Cloud-init user-data: Hello World. The time is now Fri, 24 Jul 2026 06:16:48 +0200! 2026-07-24 04:17:24.169667 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-24 04:17:24.169728 UTC - Destroying tst-8902c296 tst 2026-07-24 04:17:24.169924 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-8902c296 tst