Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.49s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 310ms 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.23s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.dev192+gcac2d3fe6 (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.1 + typing-extensions==4.16.0 + typing-inspection==0.4.3 Run Test: slices-bi-singlenode-login 2026-08-11 04:29:51.516343 UTC - Installing Slices CLI (latest stable version). 2026-08-11 04:29:51.516776 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-11 04:30:00.365458 UTC - Slices CLI installed. 2026-08-11 04:30:00.365818 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-08-11 04:30:00.370134 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-11 04:30:00.370231 UTC - env: {'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-08-11 04:30:00.370313 UTC - which slices: /app/.venv/bin/slices 2026-08-11 04:30:00.370332 UTC - Slices CLI exe: 'slices' 2026-08-11 04:30:00.370348 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-11 04:30:00.370493 UTC - Run: slices --version 2026-08-11 04:30:04.611400 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-11 04:30:04.612351 UTC - Run: slices pubkey list --format text 2026-08-11 04:30:05.780310 UTC - Pubkey already registered 2026-08-11 04:30:05.780595 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-11 04:30:06.446430 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-11 04:30:06.446527 UTC - Check List Flavors 2026-08-11 04:30:06.446668 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-08-11 04:30:07.112156 UTC - Check List DiskImages 2026-08-11 04:30:07.112760 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-08-11 04:30:07.987010 UTC - Requesting resources 2026-08-11 04:30:07.987524 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-11 04:30:07.987672 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-f5dbd8f2 --user-data /tmp/tmp_o0cu6qq 2026-08-11 04:30:09.255743 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kzqhbh71er1927m30rxhsxay 2026-08-11 04:30:09.255922 UTC - Waiting until resource ready 2026-08-11 04:30:11.256823 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f5dbd8f2 tst 2026-08-11 04:30:12.073224 UTC - Status: IMAGING 2026-08-11 04:30:14.789589 UTC - Status: IMAGING 2026-08-11 04:30:17.605444 UTC - Status: IMAGING 2026-08-11 04:30:20.471842 UTC - Status: IMAGING 2026-08-11 04:30:23.238159 UTC - Status: IMAGING 2026-08-11 04:30:26.004233 UTC - Status: IMAGING 2026-08-11 04:30:28.820569 UTC - Status: IMAGING 2026-08-11 04:30:31.588845 UTC - Status: IMAGING 2026-08-11 04:30:34.405174 UTC - Status: IMAGING 2026-08-11 04:30:37.223256 UTC - Status: IMAGING 2026-08-11 04:30:40.039750 UTC - Status: IMAGING 2026-08-11 04:30:43.013456 UTC - Status: IMAGING 2026-08-11 04:30:45.990303 UTC - Status: IMAGING 2026-08-11 04:30:49.115698 UTC - Status: IMAGING 2026-08-11 04:30:52.038862 UTC - Status: IMAGING 2026-08-11 04:30:55.273723 UTC - Status: IMAGING 2026-08-11 04:30:58.292251 UTC - Status: IMAGING 2026-08-11 04:31:01.769157 UTC - Status: IMAGING 2026-08-11 04:31:04.938117 UTC - Status: IMAGING 2026-08-11 04:31:08.275632 UTC - Status: IMAGING 2026-08-11 04:31:11.470915 UTC - Status: IMAGING 2026-08-11 04:31:14.387544 UTC - Status: IMAGING 2026-08-11 04:31:17.254540 UTC - Status: IMAGING 2026-08-11 04:31:20.121363 UTC - Status: IMAGING 2026-08-11 04:31:22.985278 UTC - Status: IMAGING 2026-08-11 04:31:25.901936 UTC - Status: IMAGING 2026-08-11 04:31:28.824884 UTC - Status: IMAGING 2026-08-11 04:31:32.060988 UTC - Status: IMAGING 2026-08-11 04:31:34.927934 UTC - Status: IMAGING 2026-08-11 04:31:37.797462 UTC - Status: IMAGING 2026-08-11 04:31:40.679953 UTC - Status: IMAGING 2026-08-11 04:31:43.770403 UTC - Status: IMAGING 2026-08-11 04:31:46.692116 UTC - Status: IMAGING 2026-08-11 04:31:49.662196 UTC - Status: IMAGING 2026-08-11 04:31:52.579043 UTC - Status: IMAGING 2026-08-11 04:31:55.395353 UTC - Status: IMAGING 2026-08-11 04:31:58.363683 UTC - Status: IMAGING 2026-08-11 04:32:01.285265 UTC - Status: IMAGING 2026-08-11 04:32:04.276700 UTC - Status: IMAGING 2026-08-11 04:32:07.267441 UTC - Status: IMAGING 2026-08-11 04:32:10.284789 UTC - Status: IMAGING 2026-08-11 04:32:13.156816 UTC - Status: IMAGING 2026-08-11 04:32:16.136244 UTC - Status: IMAGING 2026-08-11 04:32:19.038113 UTC - Status: IMAGING 2026-08-11 04:32:22.185644 UTC - Status: IMAGING 2026-08-11 04:32:25.208839 UTC - Status: IMAGING 2026-08-11 04:32:28.081848 UTC - Status: IMAGING 2026-08-11 04:32:31.301552 UTC - Status: IMAGING 2026-08-11 04:32:34.174492 UTC - Status: IMAGING 2026-08-11 04:32:37.130299 UTC - Status: IMAGING 2026-08-11 04:32:40.097333 UTC - Status: IMAGING 2026-08-11 04:32:42.928092 UTC - Status: IMAGING 2026-08-11 04:32:45.794505 UTC - Status: IMAGING 2026-08-11 04:32:48.864005 UTC - Status: IMAGING 2026-08-11 04:32:51.950410 UTC - Status: IMAGING 2026-08-11 04:32:55.513265 UTC - Status: STARTING 2026-08-11 04:32:58.709292 UTC - Status: STARTING 2026-08-11 04:33:01.996435 UTC - Status: STARTING 2026-08-11 04:33:05.016827 UTC - Status: UP 2026-08-11 04:33:05.016911 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzqhbgxgejybx5dr40dpy4sq 2026-08-11 04:33:05.016957 UTC - Validate resources 2026-08-11 04:33:05.884469 UTC - The fields of the created resource were validated. 2026-08-11 04:33:05.884574 UTC - Check if resources are registered in experiment 2026-08-11 04:33:05.884760 UTC - Run: slices experiment list-resources --format json tst-f5dbd8f2 2026-08-11 04:33:06.600432 UTC - Status (on expauth): UP 2026-08-11 04:33:06.600570 UTC - Testing extend expires_at (all resources in experiment) 2026-08-11 04:33:06.600693 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-f5dbd8f2 2026-08-11 04:33:09.571333 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:10.387155 UTC - Run: slices experiment list-resources --format json tst-f5dbd8f2 2026-08-11 04:33:11.052522 UTC - expires_at (on expauth): 2026-08-11T07:33:00Z (correctly extended) 2026-08-11 04:33:11.052624 UTC - Testing extend expires_at (single resource in experiment) 2026-08-11 04:33:11.052823 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-f5dbd8f2 2026-08-11 04:33:14.076133 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:15.152668 UTC - Run: slices experiment list-resources --format json tst-f5dbd8f2 2026-08-11 04:33:16.137234 UTC - expires_at (on expauth): 2026-08-11T08:33:00Z (correctly extended) 2026-08-11 04:33:16.137355 UTC - Testing ssh login 2026-08-11 04:33:16.219733 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:17.320295 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:18.194254 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:18.966145 UTC - Logging in using 'slices bi ssh' 2026-08-11 04:33:18.966227 UTC - Forcing IPv4 only. 2026-08-11 04:33:18.966358 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f5dbd8f2 tst -- -4 uname -a 2026-08-11 04:33:20.032873 UTC - Forcing IPv4 only. 2026-08-11 04:33:20.033106 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f5dbd8f2 tst -- -4 uptime 2026-08-11 04:33:21.208642 UTC - CLI SSH Test passed. 2026-08-11 04:33:21.208759 UTC - Uname: Linux tst.8ypmbq.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-08-11 04:33:21.208806 UTC - Uptime: 06:33:21 up 0 min, 0 users, load average: 0.12, 0.04, 0.01 2026-08-11 04:33:21.208844 UTC - Logging in using 'slices bi ssh' 2026-08-11 04:33:21.208918 UTC - Forcing IPv6 only. 2026-08-11 04:33:21.209111 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f5dbd8f2 tst -- -6 uname -a 2026-08-11 04:33:22.585780 UTC - Forcing IPv6 only. 2026-08-11 04:33:22.586013 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-f5dbd8f2 tst -- -6 uptime 2026-08-11 04:33:24.010616 UTC - CLI SSH Test passed. 2026-08-11 04:33:24.010944 UTC - Uname: Linux tst.8ypmbq.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-08-11 04:33:24.011010 UTC - Uptime: 06:33:23 up 0 min, 0 users, load average: 0.12, 0.04, 0.01 2026-08-11 04:33:24.011061 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-11 04:33:24.011609 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:24.011812 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:24.011873 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:24.012050 UTC - Connecting to n078-12.wall1.ilabt.imec.be:22 2026-08-11 04:33:24.385126 UTC - SSH Test output: 2026-08-11 04:33:24.385195 UTC - Uname: Linux tst.8ypmbq.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-08-11 04:33:24.385215 UTC - Uptime: 06:33:24 up 0 min, 0 users, load average: 0.12, 0.04, 0.01 2026-08-11 04:33:24.494618 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-08-11 04:33:24.494695 UTC - lsb_release matches expected value 2026-08-11 04:33:24.494716 UTC - SSH Test passed. 2026-08-11 04:33:24.542755 UTC - Cloud-init user-data: Hello World. The time is now Tue, 11 Aug 2026 06:32:54 +0200! 2026-08-11 04:33:24.542920 UTC - Logging in using SSH over SSH proxy 2026-08-11 04:33:24.543094 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-f5dbd8f2 tst 2026-08-11 04:33:25.924000 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:25.924170 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:25.924224 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-11 04:33:25.924441 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-11 04:33:25.924488 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-11 04:33:25.924587 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-11 04:33:25.924605 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-11 04:33:26.187545 UTC - Connecting to n078-12.wall1.ilabt.imec.be:22 over proxy 2026-08-11 04:33:26.552706 UTC - SSH Test output: 2026-08-11 04:33:26.552825 UTC - Uname: Linux tst.8ypmbq.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-08-11 04:33:26.552845 UTC - Uptime: 06:33:26 up 1 min, 0 users, load average: 0.11, 0.04, 0.01 2026-08-11 04:33:26.642179 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-08-11 04:33:26.642263 UTC - lsb_release matches expected value 2026-08-11 04:33:26.642284 UTC - SSH Test passed. 2026-08-11 04:33:26.689719 UTC - Cloud-init user-data: Hello World. The time is now Tue, 11 Aug 2026 06:32:54 +0200! 2026-08-11 04:33:26.701570 UTC - Skipping ping connectivity test: no ping present on test system 2026-08-11 04:33:26.701642 UTC - Destroying tst-f5dbd8f2 tst 2026-08-11 04:33:26.701867 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-f5dbd8f2 tst