Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.35s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 516ms 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 894ms + 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-22 04:12:42.962675 UTC - Installing Slices CLI (latest stable version). 2026-07-22 04:12:42.962937 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 04:12:48.343590 UTC - Slices CLI installed. 2026-07-22 04:12:48.344153 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-22 04:12:48.347002 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-22 04:12:48.349124 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 04:12:48.349272 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-22 04:12:48.349302 UTC - Slices CLI exe: 'slices' 2026-07-22 04:12:48.349321 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 04:12:48.349450 UTC - Run: slices --version 2026-07-22 04:12:51.172000 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-22 04:12:51.172300 UTC - Run: slices pubkey list --format text 2026-07-22 04:12:51.938332 UTC - Pubkey already registered 2026-07-22 04:12:51.938649 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 04:12:52.604752 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 04:12:52.604902 UTC - Check List Flavors 2026-07-22 04:12:52.605148 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-22 04:12:53.571481 UTC - Check List DiskImages 2026-07-22 04:12:53.571686 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-22 04:12:54.296630 UTC - Requesting resources 2026-07-22 04:12:54.297519 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 04:12:54.297640 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-10414e1b --user-data /tmp/tmppba9697d 2026-07-22 04:12:56.265975 UTC - Resource ID: r_be-gent1-bi-baremetal1_01ky40dmdtfh9s0dtbrc8jp2k7 2026-07-22 04:12:56.266161 UTC - Waiting until resource ready 2026-07-22 04:12:58.266616 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-10414e1b tst 2026-07-22 04:12:58.982501 UTC - Status: IMAGING 2026-07-22 04:13:01.798964 UTC - Status: IMAGING 2026-07-22 04:13:04.622114 UTC - Status: IMAGING 2026-07-22 04:13:07.388444 UTC - Status: IMAGING 2026-07-22 04:13:10.254824 UTC - Status: IMAGING 2026-07-22 04:13:13.071028 UTC - Status: IMAGING 2026-07-22 04:13:15.887249 UTC - Status: IMAGING 2026-07-22 04:13:18.703377 UTC - Status: IMAGING 2026-07-22 04:13:21.519845 UTC - Status: IMAGING 2026-07-22 04:13:24.336065 UTC - Status: IMAGING 2026-07-22 04:13:27.152436 UTC - Status: IMAGING 2026-07-22 04:13:29.968900 UTC - Status: IMAGING 2026-07-22 04:13:32.785236 UTC - Status: IMAGING 2026-07-22 04:13:35.601131 UTC - Status: IMAGING 2026-07-22 04:13:38.417557 UTC - Status: IMAGING 2026-07-22 04:13:41.284205 UTC - Status: IMAGING 2026-07-22 04:13:44.100164 UTC - Status: IMAGING 2026-07-22 04:13:46.916127 UTC - Status: IMAGING 2026-07-22 04:13:49.732257 UTC - Status: IMAGING 2026-07-22 04:13:52.498063 UTC - Status: IMAGING 2026-07-22 04:13:55.314358 UTC - Status: IMAGING 2026-07-22 04:13:58.130582 UTC - Status: IMAGING 2026-07-22 04:14:00.946906 UTC - Status: IMAGING 2026-07-22 04:14:06.319481 UTC - Status: IMAGING 2026-07-22 04:14:09.450471 UTC - Status: IMAGING 2026-07-22 04:14:12.266677 UTC - Status: IMAGING 2026-07-22 04:14:15.185292 UTC - Status: IMAGING 2026-07-22 04:14:17.951243 UTC - Status: IMAGING 2026-07-22 04:14:20.768159 UTC - Status: IMAGING 2026-07-22 04:14:23.634446 UTC - Status: IMAGING 2026-07-22 04:14:26.450492 UTC - Status: IMAGING 2026-07-22 04:14:29.266795 UTC - Status: IMAGING 2026-07-22 04:14:32.083112 UTC - Status: IMAGING 2026-07-22 04:14:34.849159 UTC - Status: IMAGING 2026-07-22 04:14:37.665419 UTC - Status: IMAGING 2026-07-22 04:14:40.482243 UTC - Status: IMAGING 2026-07-22 04:14:43.298522 UTC - Status: IMAGING 2026-07-22 04:14:46.114655 UTC - Status: IMAGING 2026-07-22 04:14:48.930970 UTC - Status: IMAGING 2026-07-22 04:14:51.747477 UTC - Status: IMAGING 2026-07-22 04:14:54.614190 UTC - Status: IMAGING 2026-07-22 04:14:57.480419 UTC - Status: IMAGING 2026-07-22 04:15:00.296462 UTC - Status: IMAGING 2026-07-22 04:15:03.112935 UTC - Status: IMAGING 2026-07-22 04:15:06.029108 UTC - Status: IMAGING 2026-07-22 04:15:08.895350 UTC - Status: IMAGING 2026-07-22 04:15:11.711389 UTC - Status: IMAGING 2026-07-22 04:15:14.527769 UTC - Status: IMAGING 2026-07-22 04:15:17.343799 UTC - Status: IMAGING 2026-07-22 04:15:20.160053 UTC - Status: IMAGING 2026-07-22 04:15:22.926245 UTC - Status: IMAGING 2026-07-22 04:15:25.795319 UTC - Status: IMAGING 2026-07-22 04:15:28.890254 UTC - Status: IMAGING 2026-07-22 04:15:32.244196 UTC - Status: IMAGING 2026-07-22 04:15:36.057241 UTC - Status: IMAGING 2026-07-22 04:15:39.515218 UTC - Status: IMAGING 2026-07-22 04:15:43.493818 UTC - Status: IMAGING 2026-07-22 04:15:47.088998 UTC - Status: IMAGING 2026-07-22 04:15:50.415624 UTC - Status: IMAGING 2026-07-22 04:15:53.931422 UTC - Status: STARTING 2026-07-22 04:15:56.953086 UTC - Status: STARTING 2026-07-22 04:15:59.970012 UTC - Status: STARTING 2026-07-22 04:16:02.786344 UTC - Status: UP 2026-07-22 04:16:02.786439 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky40dktaegparxaerpd00bsq 2026-07-22 04:16:02.786491 UTC - Validate resources 2026-07-22 04:16:03.767544 UTC - The fields of the created resource were validated. 2026-07-22 04:16:03.767669 UTC - Check if resources are registered in experiment 2026-07-22 04:16:03.767834 UTC - Run: slices experiment list-resources --format json tst-10414e1b 2026-07-22 04:16:04.806007 UTC - Status (on expauth): UP 2026-07-22 04:16:04.806161 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 04:16:04.806289 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-10414e1b 2026-07-22 04:16:10.817459 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-10414e1b tst 2026-07-22 04:16:11.733607 UTC - Run: slices experiment list-resources --format json tst-10414e1b 2026-07-22 04:16:12.549376 UTC - expires_at (on expauth): 2026-07-22T07:16:00Z (correctly extended) 2026-07-22 04:16:12.549502 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 04:16:12.549676 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-10414e1b 2026-07-22 04:16:16.233008 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-10414e1b tst 2026-07-22 04:16:17.310129 UTC - Run: slices experiment list-resources --format json tst-10414e1b 2026-07-22 04:16:18.595620 UTC - expires_at (on expauth): 2026-07-22T08:16:00Z (correctly extended) 2026-07-22 04:16:18.595971 UTC - Testing ssh login 2026-07-22 04:16:18.625072 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-10414e1b tst 2026-07-22 04:16:19.761399 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-10414e1b tst 2026-07-22 04:16:21.409625 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-10414e1b tst 2026-07-22 04:16:22.967424 UTC - Logging in using 'slices bi ssh' 2026-07-22 04:16:22.967520 UTC - Forcing IPv4 only. 2026-07-22 04:16:22.967695 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-10414e1b tst -- -4 uname -a 2026-07-22 04:16:24.882191 UTC - Forcing IPv4 only. 2026-07-22 04:16:24.882410 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-10414e1b tst -- -4 uptime 2026-07-22 04:16:26.870422 UTC - CLI SSH Test passed. 2026-07-22 04:16:26.870513 UTC - Uname: Linux tst.1mc7wm.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-22 04:16:26.870534 UTC - Uptime: 06:16:26 up 1 min, 0 users, load average: 0.24, 0.10, 0.03 2026-07-22 04:16:26.870570 UTC - Logging in using 'slices bi ssh' 2026-07-22 04:16:26.870624 UTC - Forcing IPv6 only. 2026-07-22 04:16:26.870772 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-10414e1b tst -- -6 uname -a 2026-07-22 04:16:28.725195 UTC - Forcing IPv6 only. 2026-07-22 04:16:28.725387 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-10414e1b tst -- -6 uptime 2026-07-22 04:16:30.021438 UTC - CLI SSH Test passed. 2026-07-22 04:16:30.021516 UTC - Uname: Linux tst.1mc7wm.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-22 04:16:30.021566 UTC - Uptime: 06:16:30 up 1 min, 0 users, load average: 0.22, 0.09, 0.03 2026-07-22 04:16:30.021628 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-22 04:16:30.022124 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:30.022302 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:30.022361 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:30.022544 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 2026-07-22 04:16:30.387860 UTC - SSH Test output: 2026-07-22 04:16:30.387933 UTC - Uname: Linux tst.1mc7wm.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-22 04:16:30.387954 UTC - Uptime: 06:16:30 up 1 min, 0 users, load average: 0.22, 0.09, 0.03 2026-07-22 04:16:30.463752 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-22 04:16:30.463836 UTC - lsb_release matches expected value 2026-07-22 04:16:30.463857 UTC - SSH Test passed. 2026-07-22 04:16:30.510155 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 06:15:52 +0200! 2026-07-22 04:16:30.510368 UTC - Logging in using SSH over SSH proxy 2026-07-22 04:16:30.510529 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-10414e1b tst 2026-07-22 04:16:32.020513 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:32.020682 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:32.020942 UTC - Added paramiko HostKeyEntry for n077-04.wall1.ilabt.imec.be 2026-07-22 04:16:32.021219 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 04:16:32.021274 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 04:16:32.021386 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-22 04:16:32.021408 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-22 04:16:32.322251 UTC - Connecting to n077-04.wall1.ilabt.imec.be:22 over proxy 2026-07-22 04:16:32.628456 UTC - SSH Test output: 2026-07-22 04:16:32.628548 UTC - Uname: Linux tst.1mc7wm.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-22 04:16:32.628570 UTC - Uptime: 06:16:32 up 1 min, 0 users, load average: 0.22, 0.09, 0.03 2026-07-22 04:16:32.721880 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-22 04:16:32.721963 UTC - lsb_release matches expected value 2026-07-22 04:16:32.721982 UTC - SSH Test passed. 2026-07-22 04:16:32.726417 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 06:15:52 +0200! 2026-07-22 04:16:32.734521 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-22 04:16:32.734562 UTC - Destroying tst-10414e1b tst 2026-07-22 04:16:32.734743 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-10414e1b tst