Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.78s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 521ms 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.47s + 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 20:10:33.239014 UTC - Installing Slices CLI (latest stable version). 2026-07-13 20:10:33.239873 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 20:10:46.923287 UTC - Slices CLI installed. 2026-07-13 20:10:46.925022 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 20:10:46.930824 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 20:10:46.940858 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 20:10:46.941055 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 20:10:46.941143 UTC - Slices CLI exe: 'slices' 2026-07-13 20:10:46.941163 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 20:10:46.941846 UTC - Run: slices --version 2026-07-13 20:10:51.455304 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 20:10:51.455657 UTC - Run: slices pubkey list --format text 2026-07-13 20:10:53.396447 UTC - Pubkey already registered 2026-07-13 20:10:53.397211 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 20:10:56.016534 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 20:10:56.016811 UTC - Check List Flavors 2026-07-13 20:10:56.016986 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-13 20:10:59.321398 UTC - Check List DiskImages 2026-07-13 20:10:59.321794 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-13 20:11:02.599366 UTC - Requesting resources 2026-07-13 20:11:02.600324 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 20:11:02.600456 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-98971c6d --user-data /tmp/tmpfkoyoe3m 2026-07-13 20:11:08.664101 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxehnp1ffyhrp0z229p5knf7 2026-07-13 20:11:08.665178 UTC - Waiting until resource ready 2026-07-13 20:11:10.665537 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-98971c6d tst 2026-07-13 20:11:12.043390 UTC - Status: IMAGING 2026-07-13 20:11:15.165192 UTC - Status: IMAGING 2026-07-13 20:11:18.088631 UTC - Status: IMAGING 2026-07-13 20:11:20.955250 UTC - Status: IMAGING 2026-07-13 20:11:23.922981 UTC - Status: IMAGING 2026-07-13 20:11:26.739132 UTC - Status: IMAGING 2026-07-13 20:11:29.555659 UTC - Status: IMAGING 2026-07-13 20:11:32.559604 UTC - Status: IMAGING 2026-07-13 20:11:35.997931 UTC - Status: IMAGING 2026-07-13 20:11:40.693301 UTC - Status: IMAGING 2026-07-13 20:11:44.531807 UTC - Status: IMAGING 2026-07-13 20:11:48.404389 UTC - Status: IMAGING 2026-07-13 20:11:51.726575 UTC - Status: IMAGING 2026-07-13 20:11:55.496694 UTC - Status: IMAGING 2026-07-13 20:11:58.367391 UTC - Status: IMAGING 2026-07-13 20:12:01.607772 UTC - Status: IMAGING 2026-07-13 20:12:04.424119 UTC - Status: IMAGING 2026-07-13 20:12:07.594365 UTC - Status: IMAGING 2026-07-13 20:12:10.460774 UTC - Status: IMAGING 2026-07-13 20:12:13.277242 UTC - Status: IMAGING 2026-07-13 20:12:16.148282 UTC - Status: IMAGING 2026-07-13 20:12:18.964879 UTC - Status: IMAGING 2026-07-13 20:12:21.883027 UTC - Status: IMAGING 2026-07-13 20:12:24.861716 UTC - Status: IMAGING 2026-07-13 20:12:27.677918 UTC - Status: IMAGING 2026-07-13 20:12:31.019025 UTC - Status: IMAGING 2026-07-13 20:12:34.036135 UTC - Status: IMAGING 2026-07-13 20:12:37.408130 UTC - Status: IMAGING 2026-07-13 20:12:40.224694 UTC - Status: IMAGING 2026-07-13 20:12:43.091377 UTC - Status: IMAGING 2026-07-13 20:12:45.958430 UTC - Status: IMAGING 2026-07-13 20:12:48.775479 UTC - Status: IMAGING 2026-07-13 20:12:51.643600 UTC - Status: IMAGING 2026-07-13 20:12:54.611262 UTC - Status: IMAGING 2026-07-13 20:12:57.529077 UTC - Status: IMAGING 2026-07-13 20:13:00.346143 UTC - Status: IMAGING 2026-07-13 20:13:03.162564 UTC - Status: IMAGING 2026-07-13 20:13:06.029570 UTC - Status: IMAGING 2026-07-13 20:13:09.198930 UTC - Status: IMAGING 2026-07-13 20:13:12.066202 UTC - Status: IMAGING 2026-07-13 20:13:15.083845 UTC - Status: IMAGING 2026-07-13 20:13:18.001588 UTC - Status: IMAGING 2026-07-13 20:13:21.070431 UTC - Status: IMAGING 2026-07-13 20:13:23.939393 UTC - Status: IMAGING 2026-07-13 20:13:26.806323 UTC - Status: IMAGING 2026-07-13 20:13:29.674122 UTC - Status: IMAGING 2026-07-13 20:13:32.540938 UTC - Status: IMAGING 2026-07-13 20:13:35.508536 UTC - Status: IMAGING 2026-07-13 20:13:38.375520 UTC - Status: IMAGING 2026-07-13 20:13:41.242796 UTC - Status: IMAGING 2026-07-13 20:13:44.260760 UTC - Status: IMAGING 2026-07-13 20:13:47.278802 UTC - Status: IMAGING 2026-07-13 20:13:50.095670 UTC - Status: IMAGING 2026-07-13 20:13:53.013171 UTC - Status: IMAGING 2026-07-13 20:13:55.981296 UTC - Status: IMAGING 2026-07-13 20:13:58.848332 UTC - Status: IMAGING 2026-07-13 20:14:01.816528 UTC - Status: IMAGING 2026-07-13 20:14:07.191569 UTC - Status: IMAGING 2026-07-13 20:14:10.058254 UTC - Status: STARTING 2026-07-13 20:14:12.928333 UTC - Status: STARTING 2026-07-13 20:14:15.745178 UTC - Status: UP 2026-07-13 20:14:15.745254 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxehnmyefg3txtn93cvdyr7q 2026-07-13 20:14:15.745296 UTC - Validate resources 2026-07-13 20:14:16.612892 UTC - The fields of the created resource were validated. 2026-07-13 20:14:16.613010 UTC - Check if resources are registered in experiment 2026-07-13 20:14:16.613170 UTC - Run: slices experiment list-resources --format json tst-98971c6d 2026-07-13 20:14:17.328967 UTC - Status (on expauth): UP 2026-07-13 20:14:17.329110 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 20:14:17.329252 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-98971c6d 2026-07-13 20:14:24.114434 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-98971c6d tst 2026-07-13 20:14:24.930590 UTC - Run: slices experiment list-resources --format json tst-98971c6d 2026-07-13 20:14:25.646481 UTC - expires_at (on expauth): 2026-07-13T23:14:00Z (correctly extended) 2026-07-13 20:14:25.646587 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 20:14:25.646720 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-98971c6d 2026-07-13 20:14:30.775527 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-98971c6d tst 2026-07-13 20:14:31.742599 UTC - Run: slices experiment list-resources --format json tst-98971c6d 2026-07-13 20:14:32.559799 UTC - expires_at (on expauth): 2026-07-14T00:14:00Z (correctly extended) 2026-07-13 20:14:32.559991 UTC - Testing ssh login 2026-07-13 20:14:32.578735 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-98971c6d tst 2026-07-13 20:14:33.396549 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-98971c6d tst 2026-07-13 20:14:34.213287 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-98971c6d tst 2026-07-13 20:14:34.979263 UTC - Logging in using 'slices bi ssh' 2026-07-13 20:14:34.979360 UTC - Forcing IPv4 only. 2026-07-13 20:14:34.979551 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-98971c6d tst -- -4 uname -a 2026-07-13 20:14:35.996344 UTC - Forcing IPv4 only. 2026-07-13 20:14:35.996554 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-98971c6d tst -- -4 uptime 2026-07-13 20:14:36.963091 UTC - CLI SSH Test passed. 2026-07-13 20:14:36.963170 UTC - Uname: Linux tst.3m6oro.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 20:14:36.963192 UTC - Uptime: 22:14:36 up 1 min, 0 users, load average: 0.06, 0.03, 0.00 2026-07-13 20:14:36.963227 UTC - Logging in using 'slices bi ssh' 2026-07-13 20:14:36.963277 UTC - Forcing IPv6 only. 2026-07-13 20:14:36.963399 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-98971c6d tst -- -6 uname -a 2026-07-13 20:14:37.980204 UTC - Forcing IPv6 only. 2026-07-13 20:14:37.980451 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-98971c6d tst -- -6 uptime 2026-07-13 20:14:38.947098 UTC - CLI SSH Test passed. 2026-07-13 20:14:38.947173 UTC - Uname: Linux tst.3m6oro.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 20:14:38.947192 UTC - Uptime: 22:14:38 up 1 min, 0 users, load average: 0.06, 0.03, 0.00 2026-07-13 20:14:38.947230 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-13 20:14:38.947783 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:38.948023 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:38.948098 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:38.948304 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 2026-07-13 20:14:39.291257 UTC - SSH Test output: 2026-07-13 20:14:39.291330 UTC - Uname: Linux tst.3m6oro.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 20:14:39.291351 UTC - Uptime: 22:14:39 up 1 min, 0 users, load average: 0.06, 0.03, 0.00 2026-07-13 20:14:39.362342 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-13 20:14:39.362451 UTC - lsb_release matches expected value 2026-07-13 20:14:39.362484 UTC - SSH Test passed. 2026-07-13 20:14:39.407990 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 22:14:08 +0200! 2026-07-13 20:14:39.408195 UTC - Logging in using SSH over SSH proxy 2026-07-13 20:14:39.408384 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-98971c6d tst 2026-07-13 20:14:40.224915 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:40.225084 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:40.225143 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-13 20:14:40.225404 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:14:40.225462 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:14:40.225564 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 20:14:40.225585 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-13 20:14:40.486718 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 over proxy 2026-07-13 20:14:40.826748 UTC - SSH Test output: 2026-07-13 20:14:40.826845 UTC - Uname: Linux tst.3m6oro.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 20:14:40.826878 UTC - Uptime: 22:14:40 up 1 min, 0 users, load average: 0.06, 0.03, 0.00 2026-07-13 20:14:40.934964 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-13 20:14:40.935059 UTC - lsb_release matches expected value 2026-07-13 20:14:40.935079 UTC - SSH Test passed. 2026-07-13 20:14:40.939426 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 22:14:08 +0200! 2026-07-13 20:14:40.946838 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-13 20:14:40.946891 UTC - Destroying tst-98971c6d tst 2026-07-13 20:14:40.947059 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-98971c6d tst