Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.73s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 552ms 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 1.95s + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + 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.3 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-13 13:10:15.875791 UTC - Installing Slices CLI (latest stable version). 2026-07-13 13:10:15.876024 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 13:10:23.260674 UTC - Slices CLI installed. 2026-07-13 13:10:23.260876 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-13 13:10:23.264394 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-13 13:10:23.265764 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 13:10:23.265934 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-13 13:10:23.265963 UTC - Slices CLI exe: 'slices' 2026-07-13 13:10:23.265980 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 13:10:23.266115 UTC - Run: slices --version 2026-07-13 13:10:27.643307 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-13 13:10:27.645408 UTC - Run: slices pubkey list --format text 2026-07-13 13:10:29.601474 UTC - Pubkey already registered 2026-07-13 13:10:29.601875 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 13:10:30.998917 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 13:10:30.999033 UTC - Check List Flavors 2026-07-13 13:10:30.999186 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-13 13:10:33.181179 UTC - Check List DiskImages 2026-07-13 13:10:33.181622 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-13 13:10:34.899537 UTC - Requesting resources 2026-07-13 13:10:34.900515 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 13:10:34.900632 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-6aebeb2f --user-data /tmp/tmpio5ygaxs 2026-07-13 13:10:39.959516 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxdskrt9e208k0qrtkwws23k 2026-07-13 13:10:39.959689 UTC - Waiting until resource ready 2026-07-13 13:10:41.968827 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-6aebeb2f tst 2026-07-13 13:10:44.359107 UTC - Status: IMAGING 2026-07-13 13:10:48.441710 UTC - Status: IMAGING 2026-07-13 13:10:52.449000 UTC - Status: IMAGING 2026-07-13 13:10:56.207608 UTC - Status: IMAGING 2026-07-13 13:10:59.627924 UTC - Status: IMAGING 2026-07-13 13:11:02.947367 UTC - Status: IMAGING 2026-07-13 13:11:06.264502 UTC - Status: IMAGING 2026-07-13 13:11:09.879171 UTC - Status: IMAGING 2026-07-13 13:11:13.218539 UTC - Status: IMAGING 2026-07-13 13:11:16.995440 UTC - Status: IMAGING 2026-07-13 13:11:20.391569 UTC - Status: IMAGING 2026-07-13 13:11:24.281377 UTC - Status: IMAGING 2026-07-13 13:11:27.422576 UTC - Status: IMAGING 2026-07-13 13:11:31.840532 UTC - Status: IMAGING 2026-07-13 13:11:35.533073 UTC - Status: IMAGING 2026-07-13 13:11:39.261892 UTC - Status: IMAGING 2026-07-13 13:11:42.232243 UTC - Status: IMAGING 2026-07-13 13:11:45.406627 UTC - Status: IMAGING 2026-07-13 13:11:48.375142 UTC - Status: IMAGING 2026-07-13 13:11:51.543107 UTC - Status: IMAGING 2026-07-13 13:11:54.678951 UTC - Status: IMAGING 2026-07-13 13:11:57.546543 UTC - Status: IMAGING 2026-07-13 13:12:00.644220 UTC - Status: IMAGING 2026-07-13 13:12:04.186132 UTC - Status: IMAGING 2026-07-13 13:12:07.641539 UTC - Status: IMAGING 2026-07-13 13:12:11.067774 UTC - Status: IMAGING 2026-07-13 13:12:14.129122 UTC - Status: IMAGING 2026-07-13 13:12:17.096182 UTC - Status: IMAGING 2026-07-13 13:12:19.964922 UTC - Status: IMAGING 2026-07-13 13:12:22.933431 UTC - Status: IMAGING 2026-07-13 13:12:26.051591 UTC - Status: IMAGING 2026-07-13 13:12:28.869382 UTC - Status: IMAGING 2026-07-13 13:12:31.685900 UTC - Status: IMAGING 2026-07-13 13:12:34.703694 UTC - Status: IMAGING 2026-07-13 13:12:37.773850 UTC - Status: IMAGING 2026-07-13 13:12:40.841451 UTC - Status: IMAGING 2026-07-13 13:12:43.909737 UTC - Status: IMAGING 2026-07-13 13:12:46.726991 UTC - Status: IMAGING 2026-07-13 13:12:49.895514 UTC - Status: IMAGING 2026-07-13 13:12:52.863391 UTC - Status: IMAGING 2026-07-13 13:12:55.630033 UTC - Status: IMAGING 2026-07-13 13:12:58.697654 UTC - Status: IMAGING 2026-07-13 13:13:01.515294 UTC - Status: IMAGING 2026-07-13 13:13:04.383179 UTC - Status: IMAGING 2026-07-13 13:13:07.502157 UTC - Status: IMAGING 2026-07-13 13:13:10.523223 UTC - Status: IMAGING 2026-07-13 13:13:13.341001 UTC - Status: IMAGING 2026-07-13 13:13:16.258638 UTC - Status: IMAGING 2026-07-13 13:13:19.126526 UTC - Status: IMAGING 2026-07-13 13:13:21.994397 UTC - Status: IMAGING 2026-07-13 13:13:24.912468 UTC - Status: IMAGING 2026-07-13 13:13:27.779295 UTC - Status: IMAGING 2026-07-13 13:13:30.747639 UTC - Status: IMAGING 2026-07-13 13:13:33.615301 UTC - Status: IMAGING 2026-07-13 13:13:36.482860 UTC - Status: IMAGING 2026-07-13 13:13:39.300077 UTC - Status: IMAGING 2026-07-13 13:13:42.167553 UTC - Status: STARTING 2026-07-13 13:13:44.984245 UTC - Status: STARTING 2026-07-13 13:13:47.952044 UTC - Status: UP 2026-07-13 13:13:47.952156 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxdskqbwffp9zn2tzf2jq1n1 2026-07-13 13:13:47.952206 UTC - Validate resources 2026-07-13 13:13:48.822514 UTC - The fields of the created resource were validated. 2026-07-13 13:13:48.822615 UTC - Check if resources are registered in experiment 2026-07-13 13:13:48.822786 UTC - Run: slices experiment list-resources --format json tst-6aebeb2f 2026-07-13 13:13:49.538930 UTC - Status (on expauth): UP 2026-07-13 13:13:49.539118 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 13:13:49.539331 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-6aebeb2f 2026-07-13 13:13:56.524122 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-6aebeb2f tst 2026-07-13 13:13:57.390558 UTC - Run: slices experiment list-resources --format json tst-6aebeb2f 2026-07-13 13:13:58.056703 UTC - expires_at (on expauth): 2026-07-13T16:13:00Z (correctly extended) 2026-07-13 13:13:58.056913 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 13:13:58.057106 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-6aebeb2f 2026-07-13 13:14:02.936374 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-6aebeb2f tst 2026-07-13 13:14:06.310260 UTC - Run: slices experiment list-resources --format json tst-6aebeb2f 2026-07-13 13:14:06.976428 UTC - expires_at (on expauth): 2026-07-13T17:13:00Z (correctly extended) 2026-07-13 13:14:06.976573 UTC - Testing ssh login 2026-07-13 13:14:06.991785 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-6aebeb2f tst 2026-07-13 13:14:07.757858 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6aebeb2f tst 2026-07-13 13:14:08.624068 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-6aebeb2f tst 2026-07-13 13:14:09.494460 UTC - Logging in using 'slices bi ssh' 2026-07-13 13:14:09.494587 UTC - Forcing IPv4 only. 2026-07-13 13:14:09.494837 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-6aebeb2f tst -- -4 uname -a 2026-07-13 13:14:10.562431 UTC - Forcing IPv4 only. 2026-07-13 13:14:10.562637 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-6aebeb2f tst -- -4 uptime 2026-07-13 13:14:11.579333 UTC - CLI SSH Test passed. 2026-07-13 13:14:11.579402 UTC - Uname: Linux tst.2c3hvx.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-13 13:14:11.579420 UTC - Uptime: 15:14:11 up 1 min, 0 users, load average: 0.23, 0.08, 0.03 2026-07-13 13:14:11.579452 UTC - Logging in using 'slices bi ssh' 2026-07-13 13:14:11.579500 UTC - Forcing IPv6 only. 2026-07-13 13:14:11.579619 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-6aebeb2f tst -- -6 uname -a 2026-07-13 13:14:12.546036 UTC - Forcing IPv6 only. 2026-07-13 13:14:12.546257 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-6aebeb2f tst -- -6 uptime 2026-07-13 13:14:13.512878 UTC - CLI SSH Test passed. 2026-07-13 13:14:13.512977 UTC - Uname: Linux tst.2c3hvx.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-13 13:14:13.512997 UTC - Uptime: 15:14:13 up 1 min, 0 users, load average: 0.23, 0.08, 0.03 2026-07-13 13:14:13.513040 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-13 13:14:13.513586 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:13.514258 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:13.514368 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:13.514575 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 2026-07-13 13:14:13.869172 UTC - SSH Test output: 2026-07-13 13:14:13.869263 UTC - Uname: Linux tst.2c3hvx.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-13 13:14:13.869286 UTC - Uptime: 15:14:13 up 1 min, 0 users, load average: 0.23, 0.08, 0.03 2026-07-13 13:14:13.976987 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-13 13:14:13.977111 UTC - lsb_release matches expected value 2026-07-13 13:14:13.977145 UTC - SSH Test passed. 2026-07-13 13:14:13.981145 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 15:13:39 +0200! 2026-07-13 13:14:13.981361 UTC - Logging in using SSH over SSH proxy 2026-07-13 13:14:13.981576 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-6aebeb2f tst 2026-07-13 13:14:14.751304 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:14.751520 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:14.751635 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 13:14:14.751986 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:14:14.752068 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:14:14.752257 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:14:14.752292 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-13 13:14:15.033281 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 over proxy 2026-07-13 13:14:15.338683 UTC - SSH Test output: 2026-07-13 13:14:15.338757 UTC - Uname: Linux tst.2c3hvx.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-13 13:14:15.338776 UTC - Uptime: 15:14:15 up 1 min, 0 users, load average: 0.21, 0.08, 0.03 2026-07-13 13:14:15.426268 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-13 13:14:15.426342 UTC - lsb_release matches expected value 2026-07-13 13:14:15.426361 UTC - SSH Test passed. 2026-07-13 13:14:15.473926 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 15:13:39 +0200! 2026-07-13 13:14:15.481462 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-13 13:14:15.481517 UTC - Destroying tst-6aebeb2f tst 2026-07-13 13:14:15.481684 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-6aebeb2f tst