Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.94s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 453ms 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 730ms + annotated-types==0.8.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.3.post1 + 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-25 20:14:50.374336 UTC - Installing Slices CLI (latest stable version). 2026-07-25 20:14:50.374585 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 20:14:56.605371 UTC - Slices CLI installed. 2026-07-25 20:14:56.605599 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-25 20:14:56.607933 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-25 20:14:56.609056 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 20:14:56.609201 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-25 20:14:56.609232 UTC - Slices CLI exe: 'slices' 2026-07-25 20:14:56.609250 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 20:14:56.609386 UTC - Run: slices --version 2026-07-25 20:14:59.637862 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-25 20:14:59.638141 UTC - Run: slices pubkey list --format text 2026-07-25 20:15:00.457771 UTC - Pubkey already registered 2026-07-25 20:15:00.458438 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 20:15:01.282886 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 20:15:01.282980 UTC - Check List Flavors 2026-07-25 20:15:01.283127 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-25 20:15:02.157078 UTC - Check List DiskImages 2026-07-25 20:15:02.157262 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-25 20:15:03.047749 UTC - Requesting resources 2026-07-25 20:15:03.048334 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 20:15:03.048455 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-4d105997 --user-data /tmp/tmpalftzbfi 2026-07-25 20:15:04.420774 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kydenghdf49a2vxq8phcd5j5 2026-07-25 20:15:04.420922 UTC - Waiting until resource ready 2026-07-25 20:15:06.421342 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-4d105997 tst 2026-07-25 20:15:07.236968 UTC - Status: IMAGING 2026-07-25 20:15:10.152820 UTC - Status: IMAGING 2026-07-25 20:15:13.173753 UTC - Status: IMAGING 2026-07-25 20:15:15.989673 UTC - Status: IMAGING 2026-07-25 20:15:18.805612 UTC - Status: IMAGING 2026-07-25 20:15:21.623527 UTC - Status: IMAGING 2026-07-25 20:15:24.389371 UTC - Status: IMAGING 2026-07-25 20:15:27.413614 UTC - Status: IMAGING 2026-07-25 20:15:30.533676 UTC - Status: IMAGING 2026-07-25 20:15:34.296117 UTC - Status: IMAGING 2026-07-25 20:15:37.946617 UTC - Status: IMAGING 2026-07-25 20:15:40.918092 UTC - Status: IMAGING 2026-07-25 20:15:45.051779 UTC - Status: IMAGING 2026-07-25 20:15:48.730007 UTC - Status: IMAGING 2026-07-25 20:15:52.464057 UTC - Status: IMAGING 2026-07-25 20:15:55.813105 UTC - Status: IMAGING 2026-07-25 20:15:58.839152 UTC - Status: IMAGING 2026-07-25 20:16:01.812786 UTC - Status: IMAGING 2026-07-25 20:16:05.252082 UTC - Status: IMAGING 2026-07-25 20:16:08.373639 UTC - Status: IMAGING 2026-07-25 20:16:12.193263 UTC - Status: IMAGING 2026-07-25 20:16:15.059965 UTC - Status: IMAGING 2026-07-25 20:16:17.876541 UTC - Status: IMAGING 2026-07-25 20:16:20.694416 UTC - Status: IMAGING 2026-07-25 20:16:23.731204 UTC - Status: IMAGING 2026-07-25 20:16:26.971851 UTC - Status: IMAGING 2026-07-25 20:16:29.988849 UTC - Status: IMAGING 2026-07-25 20:16:33.462360 UTC - Status: IMAGING 2026-07-25 20:16:36.303654 UTC - Status: IMAGING 2026-07-25 20:16:39.585589 UTC - Status: IMAGING 2026-07-25 20:16:42.908981 UTC - Status: IMAGING 2026-07-25 20:16:46.162772 UTC - Status: IMAGING 2026-07-25 20:16:49.280279 UTC - Status: IMAGING 2026-07-25 20:16:52.763139 UTC - Status: IMAGING 2026-07-25 20:16:55.738572 UTC - Status: IMAGING 2026-07-25 20:16:58.962153 UTC - Status: IMAGING 2026-07-25 20:17:02.282142 UTC - Status: IMAGING 2026-07-25 20:17:05.638025 UTC - Status: IMAGING 2026-07-25 20:17:09.009461 UTC - Status: IMAGING 2026-07-25 20:17:12.611175 UTC - Status: IMAGING 2026-07-25 20:17:15.680395 UTC - Status: IMAGING 2026-07-25 20:17:18.867980 UTC - Status: IMAGING 2026-07-25 20:17:22.369459 UTC - Status: IMAGING 2026-07-25 20:17:25.690649 UTC - Status: IMAGING 2026-07-25 20:17:28.715300 UTC - Status: IMAGING 2026-07-25 20:17:31.994468 UTC - Status: IMAGING 2026-07-25 20:17:35.221747 UTC - Status: IMAGING 2026-07-25 20:17:38.091573 UTC - Status: IMAGING 2026-07-25 20:17:41.075739 UTC - Status: IMAGING 2026-07-25 20:17:43.892124 UTC - Status: IMAGING 2026-07-25 20:17:46.758937 UTC - Status: IMAGING 2026-07-25 20:17:49.780941 UTC - Status: IMAGING 2026-07-25 20:17:52.764881 UTC - Status: IMAGING 2026-07-25 20:17:55.993996 UTC - Status: IMAGING 2026-07-25 20:17:58.911795 UTC - Status: IMAGING 2026-07-25 20:18:02.346655 UTC - Status: STARTING 2026-07-25 20:18:05.753520 UTC - Status: STARTING 2026-07-25 20:18:08.671758 UTC - Status: UP 2026-07-25 20:18:08.671834 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kydeng8xf8br3r1f8rd8xd7t 2026-07-25 20:18:08.671873 UTC - Validate resources 2026-07-25 20:18:09.844315 UTC - The fields of the created resource were validated. 2026-07-25 20:18:09.844426 UTC - Check if resources are registered in experiment 2026-07-25 20:18:09.844573 UTC - Run: slices experiment list-resources --format json tst-4d105997 2026-07-25 20:18:10.762307 UTC - Status (on expauth): UP 2026-07-25 20:18:10.762438 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 20:18:10.762556 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-4d105997 2026-07-25 20:18:14.490935 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-4d105997 tst 2026-07-25 20:18:15.306702 UTC - Run: slices experiment list-resources --format json tst-4d105997 2026-07-25 20:18:15.997889 UTC - expires_at (on expauth): 2026-07-25T23:18:00Z (correctly extended) 2026-07-25 20:18:15.998055 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 20:18:15.998267 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-4d105997 2026-07-25 20:18:19.220893 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-4d105997 tst 2026-07-25 20:18:20.086989 UTC - Run: slices experiment list-resources --format json tst-4d105997 2026-07-25 20:18:20.752221 UTC - expires_at (on expauth): 2026-07-26T00:18:00Z (correctly extended) 2026-07-25 20:18:20.752370 UTC - Testing ssh login 2026-07-25 20:18:20.765998 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4d105997 tst 2026-07-25 20:18:21.531560 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4d105997 tst 2026-07-25 20:18:22.247092 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-4d105997 tst 2026-07-25 20:18:23.013426 UTC - Logging in using 'slices bi ssh' 2026-07-25 20:18:23.013498 UTC - Forcing IPv4 only. 2026-07-25 20:18:23.013647 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-4d105997 tst -- -4 uname -a 2026-07-25 20:18:23.979535 UTC - Forcing IPv4 only. 2026-07-25 20:18:23.979740 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-4d105997 tst -- -4 uptime 2026-07-25 20:18:24.995632 UTC - CLI SSH Test passed. 2026-07-25 20:18:24.995728 UTC - Uname: Linux tst.ruzxs8.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-25 20:18:24.995750 UTC - Uptime: 22:18:24 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-25 20:18:24.995783 UTC - Logging in using 'slices bi ssh' 2026-07-25 20:18:24.995830 UTC - Forcing IPv6 only. 2026-07-25 20:18:24.995954 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-4d105997 tst -- -6 uname -a 2026-07-25 20:18:25.912516 UTC - Forcing IPv6 only. 2026-07-25 20:18:25.912680 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-4d105997 tst -- -6 uptime 2026-07-25 20:18:26.831225 UTC - CLI SSH Test passed. 2026-07-25 20:18:26.831297 UTC - Uname: Linux tst.ruzxs8.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-25 20:18:26.831331 UTC - Uptime: 22:18:26 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-25 20:18:26.831369 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-25 20:18:26.831983 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:26.832226 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:26.832307 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:26.832504 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 2026-07-25 20:18:27.206049 UTC - SSH Test output: 2026-07-25 20:18:27.206125 UTC - Uname: Linux tst.ruzxs8.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-25 20:18:27.206147 UTC - Uptime: 22:18:27 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-25 20:18:27.315244 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-25 20:18:27.315333 UTC - lsb_release matches expected value 2026-07-25 20:18:27.315355 UTC - SSH Test passed. 2026-07-25 20:18:27.319282 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 22:17:59 +0200! 2026-07-25 20:18:27.319451 UTC - Logging in using SSH over SSH proxy 2026-07-25 20:18:27.319604 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-4d105997 tst 2026-07-25 20:18:28.236062 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:28.236271 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:28.236343 UTC - Added paramiko HostKeyEntry for n077-05.wall1.ilabt.imec.be 2026-07-25 20:18:28.236679 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 20:18:28.236764 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 20:18:28.236881 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 20:18:28.236905 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-25 20:18:28.522770 UTC - Connecting to n077-05.wall1.ilabt.imec.be:22 over proxy 2026-07-25 20:18:28.858614 UTC - SSH Test output: 2026-07-25 20:18:28.858709 UTC - Uname: Linux tst.ruzxs8.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-25 20:18:28.858730 UTC - Uptime: 22:18:28 up 1 min, 0 users, load average: 0.19, 0.07, 0.02 2026-07-25 20:18:28.927943 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-25 20:18:28.928028 UTC - lsb_release matches expected value 2026-07-25 20:18:28.928049 UTC - SSH Test passed. 2026-07-25 20:18:28.973836 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 22:17:59 +0200! 2026-07-25 20:18:28.982690 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-25 20:18:28.982754 UTC - Destroying tst-4d105997 tst 2026-07-25 20:18:28.982934 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-4d105997 tst