Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.49s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 328ms 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.14s + 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-10 20:25:58.720270 UTC - Installing Slices CLI (latest stable version). 2026-08-10 20:25:58.720524 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 20:26:05.161253 UTC - Slices CLI installed. 2026-08-10 20:26:05.161533 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-10 20:26:05.164735 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-10 20:26:05.164823 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-10 20:26:05.164896 UTC - which slices: /app/.venv/bin/slices 2026-08-10 20:26:05.164916 UTC - Slices CLI exe: 'slices' 2026-08-10 20:26:05.164931 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 20:26:05.165069 UTC - Run: slices --version 2026-08-10 20:26:09.981796 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-10 20:26:09.982332 UTC - Run: slices pubkey list --format text 2026-08-10 20:26:11.368859 UTC - Pubkey already registered 2026-08-10 20:26:11.369130 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 20:26:12.395067 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 20:26:12.395162 UTC - Check List Flavors 2026-08-10 20:26:12.395305 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-08-10 20:26:13.821319 UTC - Check List DiskImages 2026-08-10 20:26:13.821506 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-08-10 20:26:15.209958 UTC - Requesting resources 2026-08-10 20:26:15.210425 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 20:26:15.210557 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-e2a6dc95 --user-data /tmp/tmpis3d3ksf 2026-08-10 20:26:16.689419 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kzpnngz3esr8rr0jqwhcpj4c 2026-08-10 20:26:16.689580 UTC - Waiting until resource ready 2026-08-10 20:26:18.689948 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-e2a6dc95 tst 2026-08-10 20:26:19.767056 UTC - Status: IMAGING 2026-08-10 20:26:23.737331 UTC - Status: IMAGING 2026-08-10 20:26:27.274917 UTC - Status: IMAGING 2026-08-10 20:26:30.956729 UTC - Status: IMAGING 2026-08-10 20:26:33.927229 UTC - Status: IMAGING 2026-08-10 20:26:37.005997 UTC - Status: IMAGING 2026-08-10 20:26:39.875590 UTC - Status: IMAGING 2026-08-10 20:26:42.748299 UTC - Status: IMAGING 2026-08-10 20:26:45.715842 UTC - Status: IMAGING 2026-08-10 20:26:48.632441 UTC - Status: IMAGING 2026-08-10 20:26:51.961223 UTC - Status: IMAGING 2026-08-10 20:26:55.302125 UTC - Status: IMAGING 2026-08-10 20:26:59.112833 UTC - Status: IMAGING 2026-08-10 20:27:02.297889 UTC - Status: IMAGING 2026-08-10 20:27:05.366989 UTC - Status: IMAGING 2026-08-10 20:27:08.334982 UTC - Status: IMAGING 2026-08-10 20:27:11.802399 UTC - Status: IMAGING 2026-08-10 20:27:14.771975 UTC - Status: IMAGING 2026-08-10 20:27:17.900591 UTC - Status: IMAGING 2026-08-10 20:27:21.650552 UTC - Status: IMAGING 2026-08-10 20:27:24.625933 UTC - Status: IMAGING 2026-08-10 20:27:27.808857 UTC - Status: IMAGING 2026-08-10 20:27:30.992141 UTC - Status: IMAGING 2026-08-10 20:27:33.913672 UTC - Status: IMAGING 2026-08-10 20:27:36.780300 UTC - Status: IMAGING 2026-08-10 20:28:01.692095 UTC - Status: IMAGING 2026-08-10 20:28:04.559931 UTC - Status: IMAGING 2026-08-10 20:28:07.426291 UTC - Status: IMAGING 2026-08-10 20:28:10.344646 UTC - Status: IMAGING 2026-08-10 20:28:13.439809 UTC - Status: IMAGING 2026-08-10 20:28:16.521659 UTC - Status: IMAGING 2026-08-10 20:28:19.493784 UTC - Status: IMAGING 2026-08-10 20:28:22.714494 UTC - Status: IMAGING 2026-08-10 20:28:25.691352 UTC - Status: IMAGING 2026-08-10 20:28:28.921513 UTC - Status: IMAGING 2026-08-10 20:28:32.054874 UTC - Status: IMAGING 2026-08-10 20:28:35.021954 UTC - Status: IMAGING 2026-08-10 20:28:37.838374 UTC - Status: IMAGING 2026-08-10 20:28:40.873409 UTC - Status: IMAGING 2026-08-10 20:28:43.942742 UTC - Status: IMAGING 2026-08-10 20:28:46.860896 UTC - Status: IMAGING 2026-08-10 20:28:50.236979 UTC - Status: IMAGING 2026-08-10 20:28:53.263325 UTC - Status: IMAGING 2026-08-10 20:28:56.134756 UTC - Status: IMAGING 2026-08-10 20:28:59.208349 UTC - Status: IMAGING 2026-08-10 20:29:02.302074 UTC - Status: IMAGING 2026-08-10 20:29:07.711586 UTC - Status: IMAGING 2026-08-10 20:29:10.730618 UTC - Status: IMAGING 2026-08-10 20:29:13.807743 UTC - Status: STARTING 2026-08-10 20:29:16.951284 UTC - Status: STARTING 2026-08-10 20:29:19.873098 UTC - Status: STARTING 2026-08-10 20:29:23.075340 UTC - Status: UP 2026-08-10 20:29:23.075413 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzpnngm3ftav61gc7dy4hnts 2026-08-10 20:29:23.075455 UTC - Validate resources 2026-08-10 20:29:23.944182 UTC - The fields of the created resource were validated. 2026-08-10 20:29:23.944290 UTC - Check if resources are registered in experiment 2026-08-10 20:29:23.944467 UTC - Run: slices experiment list-resources --format json tst-e2a6dc95 2026-08-10 20:29:24.659799 UTC - Status (on expauth): UP 2026-08-10 20:29:24.659935 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 20:29:24.660082 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-e2a6dc95 2026-08-10 20:29:27.780431 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-e2a6dc95 tst 2026-08-10 20:29:29.005892 UTC - Run: slices experiment list-resources --format json tst-e2a6dc95 2026-08-10 20:29:29.878742 UTC - expires_at (on expauth): 2026-08-10T23:29:00Z (correctly extended) 2026-08-10 20:29:29.878846 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 20:29:29.879916 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-e2a6dc95 2026-08-10 20:29:32.900599 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-e2a6dc95 tst 2026-08-10 20:29:33.716687 UTC - Run: slices experiment list-resources --format json tst-e2a6dc95 2026-08-10 20:29:34.435289 UTC - expires_at (on expauth): 2026-08-11T00:29:00Z (correctly extended) 2026-08-10 20:29:34.435428 UTC - Testing ssh login 2026-08-10 20:29:34.460551 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e2a6dc95 tst 2026-08-10 20:29:35.335509 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e2a6dc95 tst 2026-08-10 20:29:36.232155 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-e2a6dc95 tst 2026-08-10 20:29:37.048787 UTC - Logging in using 'slices bi ssh' 2026-08-10 20:29:37.048866 UTC - Forcing IPv4 only. 2026-08-10 20:29:37.048996 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-e2a6dc95 tst -- -4 uname -a 2026-08-10 20:29:38.065237 UTC - Forcing IPv4 only. 2026-08-10 20:29:38.065434 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-e2a6dc95 tst -- -4 uptime 2026-08-10 20:29:39.032374 UTC - CLI SSH Test passed. 2026-08-10 20:29:39.032448 UTC - Uname: Linux tst.l9mbpn.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-10 20:29:39.032468 UTC - Uptime: 22:29:39 up 1 min, 0 users, load average: 0.23, 0.07, 0.02 2026-08-10 20:29:39.032501 UTC - Logging in using 'slices bi ssh' 2026-08-10 20:29:39.032551 UTC - Forcing IPv6 only. 2026-08-10 20:29:39.032682 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-e2a6dc95 tst -- -6 uname -a 2026-08-10 20:29:40.055004 UTC - Forcing IPv6 only. 2026-08-10 20:29:40.055216 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-e2a6dc95 tst -- -6 uptime 2026-08-10 20:29:41.032281 UTC - CLI SSH Test passed. 2026-08-10 20:29:41.032356 UTC - Uname: Linux tst.l9mbpn.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-10 20:29:41.032375 UTC - Uptime: 22:29:41 up 1 min, 0 users, load average: 0.23, 0.07, 0.02 2026-08-10 20:29:41.032413 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-10 20:29:41.032883 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:41.033052 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:41.033107 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:41.033294 UTC - Connecting to n078-12.wall1.ilabt.imec.be:22 2026-08-10 20:29:41.434606 UTC - SSH Test output: 2026-08-10 20:29:41.434684 UTC - Uname: Linux tst.l9mbpn.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-10 20:29:41.434706 UTC - Uptime: 22:29:41 up 1 min, 0 users, load average: 0.23, 0.07, 0.02 2026-08-10 20:29:41.548647 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-08-10 20:29:41.548768 UTC - lsb_release matches expected value 2026-08-10 20:29:41.548807 UTC - SSH Test passed. 2026-08-10 20:29:41.558961 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 22:29:12 +0200! 2026-08-10 20:29:41.559224 UTC - Logging in using SSH over SSH proxy 2026-08-10 20:29:41.559386 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-e2a6dc95 tst 2026-08-10 20:29:42.492910 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:42.493072 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:42.493621 UTC - Added paramiko HostKeyEntry for n078-12.wall1.ilabt.imec.be 2026-08-10 20:29:42.493904 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 20:29:42.493956 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 20:29:42.494059 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-10 20:29:42.494079 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-10 20:29:42.789968 UTC - Connecting to n078-12.wall1.ilabt.imec.be:22 over proxy 2026-08-10 20:29:43.114247 UTC - SSH Test output: 2026-08-10 20:29:43.114317 UTC - Uname: Linux tst.l9mbpn.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-10 20:29:43.114338 UTC - Uptime: 22:29:43 up 1 min, 0 users, load average: 0.23, 0.07, 0.02 2026-08-10 20:29:43.186575 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-08-10 20:29:43.186648 UTC - lsb_release matches expected value 2026-08-10 20:29:43.186669 UTC - SSH Test passed. 2026-08-10 20:29:43.232849 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 22:29:12 +0200! 2026-08-10 20:29:43.240763 UTC - Skipping ping connectivity test: no ping present on test system 2026-08-10 20:29:43.240826 UTC - Destroying tst-e2a6dc95 tst 2026-08-10 20:29:43.240986 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-e2a6dc95 tst