Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.03s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 349ms 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 891ms + 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-15 20:12:26.683194 UTC - Installing Slices CLI (latest stable version). 2026-07-15 20:12:26.683463 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 20:12:39.176709 UTC - Slices CLI installed. 2026-07-15 20:12:39.177841 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-15 20:12:39.182365 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-15 20:12:39.183394 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 20:12:39.183684 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-15 20:12:39.183721 UTC - Slices CLI exe: 'slices' 2026-07-15 20:12:39.183748 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 20:12:39.183881 UTC - Run: slices --version 2026-07-15 20:12:41.702521 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-15 20:12:41.702844 UTC - Run: slices pubkey list --format text 2026-07-15 20:12:42.618379 UTC - Pubkey already registered 2026-07-15 20:12:42.618624 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 20:12:43.284160 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 20:12:43.284299 UTC - Check List Flavors 2026-07-15 20:12:43.284499 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-15 20:12:44.651837 UTC - Check List DiskImages 2026-07-15 20:12:44.652069 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-15 20:12:45.217264 UTC - Requesting resources 2026-07-15 20:12:45.217803 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 20:12:45.217920 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-11b5ec14 --user-data /tmp/tmpdl142gku 2026-07-15 20:12:48.738493 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxkpj61kfe882tjsr14dzmm7 2026-07-15 20:12:48.738652 UTC - Waiting until resource ready 2026-07-15 20:12:50.739010 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-11b5ec14 tst 2026-07-15 20:12:51.561322 UTC - Status: IMAGING 2026-07-15 20:12:54.277754 UTC - Status: IMAGING 2026-07-15 20:12:56.993751 UTC - Status: IMAGING 2026-07-15 20:12:59.759852 UTC - Status: IMAGING 2026-07-15 20:13:02.575837 UTC - Status: IMAGING 2026-07-15 20:13:05.345861 UTC - Status: IMAGING 2026-07-15 20:13:08.162412 UTC - Status: IMAGING 2026-07-15 20:13:10.978853 UTC - Status: IMAGING 2026-07-15 20:13:13.745349 UTC - Status: IMAGING 2026-07-15 20:13:16.511312 UTC - Status: IMAGING 2026-07-15 20:13:19.527965 UTC - Status: IMAGING 2026-07-15 20:13:22.444511 UTC - Status: IMAGING 2026-07-15 20:13:25.211068 UTC - Status: IMAGING 2026-07-15 20:13:27.989099 UTC - Status: IMAGING 2026-07-15 20:13:30.754984 UTC - Status: IMAGING 2026-07-15 20:13:33.721262 UTC - Status: IMAGING 2026-07-15 20:13:36.487073 UTC - Status: IMAGING 2026-07-15 20:13:39.252849 UTC - Status: IMAGING 2026-07-15 20:13:42.018790 UTC - Status: IMAGING 2026-07-15 20:13:44.784679 UTC - Status: IMAGING 2026-07-15 20:13:47.650945 UTC - Status: IMAGING 2026-07-15 20:13:50.617436 UTC - Status: IMAGING 2026-07-15 20:13:53.383438 UTC - Status: IMAGING 2026-07-15 20:13:56.368074 UTC - Status: IMAGING 2026-07-15 20:14:00.239425 UTC - Status: IMAGING 2026-07-15 20:14:03.106354 UTC - Status: IMAGING 2026-07-15 20:14:08.828937 UTC - Status: IMAGING 2026-07-15 20:14:12.697433 UTC - Status: IMAGING 2026-07-15 20:14:15.463110 UTC - Status: IMAGING 2026-07-15 20:14:18.228935 UTC - Status: IMAGING 2026-07-15 20:14:20.995055 UTC - Status: IMAGING 2026-07-15 20:14:23.811053 UTC - Status: IMAGING 2026-07-15 20:14:26.576903 UTC - Status: IMAGING 2026-07-15 20:14:29.342775 UTC - Status: IMAGING 2026-07-15 20:14:32.161307 UTC - Status: IMAGING 2026-07-15 20:14:34.927158 UTC - Status: IMAGING 2026-07-15 20:14:37.693109 UTC - Status: IMAGING 2026-07-15 20:14:40.659384 UTC - Status: IMAGING 2026-07-15 20:14:43.425304 UTC - Status: IMAGING 2026-07-15 20:14:46.191199 UTC - Status: IMAGING 2026-07-15 20:14:48.957227 UTC - Status: IMAGING 2026-07-15 20:14:51.773165 UTC - Status: IMAGING 2026-07-15 20:14:54.589004 UTC - Status: IMAGING 2026-07-15 20:14:57.354597 UTC - Status: IMAGING 2026-07-15 20:15:00.170628 UTC - Status: IMAGING 2026-07-15 20:15:02.936623 UTC - Status: IMAGING 2026-07-15 20:15:05.702336 UTC - Status: IMAGING 2026-07-15 20:15:08.521625 UTC - Status: IMAGING 2026-07-15 20:15:11.287704 UTC - Status: IMAGING 2026-07-15 20:15:14.053686 UTC - Status: IMAGING 2026-07-15 20:15:17.035420 UTC - Status: IMAGING 2026-07-15 20:15:20.001717 UTC - Status: IMAGING 2026-07-15 20:15:22.768045 UTC - Status: IMAGING 2026-07-15 20:15:25.636660 UTC - Status: IMAGING 2026-07-15 20:15:29.277537 UTC - Status: IMAGING 2026-07-15 20:15:32.299756 UTC - Status: IMAGING 2026-07-15 20:15:37.326240 UTC - Status: IMAGING 2026-07-15 20:15:41.335276 UTC - Status: IMAGING 2026-07-15 20:15:45.583142 UTC - Status: IMAGING 2026-07-15 20:15:50.435929 UTC - Status: IMAGING 2026-07-15 20:15:54.642065 UTC - Status: STARTING 2026-07-15 20:15:58.223227 UTC - Status: UP 2026-07-15 20:15:58.223303 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxkpj4xjf49r23d21m3vwke5 2026-07-15 20:15:58.223344 UTC - Validate resources 2026-07-15 20:16:00.015447 UTC - The fields of the created resource were validated. 2026-07-15 20:16:00.015537 UTC - Check if resources are registered in experiment 2026-07-15 20:16:00.015719 UTC - Run: slices experiment list-resources --format json tst-11b5ec14 2026-07-15 20:16:01.101925 UTC - Status (on expauth): UP 2026-07-15 20:16:01.102073 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 20:16:01.102224 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-11b5ec14 2026-07-15 20:16:08.531807 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-11b5ec14 tst 2026-07-15 20:16:09.297957 UTC - Run: slices experiment list-resources --format json tst-11b5ec14 2026-07-15 20:16:10.201321 UTC - expires_at (on expauth): 2026-07-15T23:16:00Z (correctly extended) 2026-07-15 20:16:10.201450 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 20:16:10.201620 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-11b5ec14 2026-07-15 20:16:18.041399 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-11b5ec14 tst 2026-07-15 20:16:19.231502 UTC - Run: slices experiment list-resources --format json tst-11b5ec14 2026-07-15 20:16:20.313147 UTC - expires_at (on expauth): 2026-07-16T00:16:00Z (correctly extended) 2026-07-15 20:16:20.313288 UTC - Testing ssh login 2026-07-15 20:16:20.330340 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-11b5ec14 tst 2026-07-15 20:16:21.465306 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-11b5ec14 tst 2026-07-15 20:16:22.915237 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-11b5ec14 tst 2026-07-15 20:16:24.395411 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:16:24.395480 UTC - Forcing IPv4 only. 2026-07-15 20:16:24.395600 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-11b5ec14 tst -- -4 uname -a 2026-07-15 20:16:25.681007 UTC - Forcing IPv4 only. 2026-07-15 20:16:25.681186 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-11b5ec14 tst -- -4 uptime 2026-07-15 20:16:27.213782 UTC - CLI SSH Test passed. 2026-07-15 20:16:27.213860 UTC - Uname: Linux tst.zgytbz.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-15 20:16:27.213882 UTC - Uptime: 22:16:27 up 1 min, 0 users, load average: 0.12, 0.05, 0.02 2026-07-15 20:16:27.213916 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:16:27.213967 UTC - Forcing IPv6 only. 2026-07-15 20:16:27.214100 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-11b5ec14 tst -- -6 uname -a 2026-07-15 20:16:28.715436 UTC - Forcing IPv6 only. 2026-07-15 20:16:28.715605 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-11b5ec14 tst -- -6 uptime 2026-07-15 20:16:30.504275 UTC - CLI SSH Test passed. 2026-07-15 20:16:30.504355 UTC - Uname: Linux tst.zgytbz.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-15 20:16:30.504375 UTC - Uptime: 22:16:30 up 1 min, 0 users, load average: 0.11, 0.05, 0.02 2026-07-15 20:16:30.504413 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-15 20:16:30.505010 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:30.505214 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:30.505273 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:30.505448 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 2026-07-15 20:16:30.831794 UTC - SSH Test output: 2026-07-15 20:16:30.831867 UTC - Uname: Linux tst.zgytbz.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-15 20:16:30.831895 UTC - Uptime: 22:16:30 up 1 min, 0 users, load average: 0.11, 0.05, 0.02 2026-07-15 20:16:30.901488 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-15 20:16:30.901562 UTC - lsb_release matches expected value 2026-07-15 20:16:30.901584 UTC - SSH Test passed. 2026-07-15 20:16:30.948863 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 22:15:52 +0200! 2026-07-15 20:16:30.949031 UTC - Logging in using SSH over SSH proxy 2026-07-15 20:16:30.949181 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-11b5ec14 tst 2026-07-15 20:16:33.247109 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:33.247460 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:33.247527 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 20:16:33.247788 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 20:16:33.247845 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 20:16:33.247952 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 20:16:33.247972 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-15 20:16:33.664179 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 over proxy 2026-07-15 20:16:33.985661 UTC - SSH Test output: 2026-07-15 20:16:33.985731 UTC - Uname: Linux tst.zgytbz.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-15 20:16:33.985750 UTC - Uptime: 22:16:33 up 1 min, 0 users, load average: 0.11, 0.05, 0.02 2026-07-15 20:16:34.060231 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-15 20:16:34.060305 UTC - lsb_release matches expected value 2026-07-15 20:16:34.060326 UTC - SSH Test passed. 2026-07-15 20:16:34.135308 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 22:15:52 +0200! 2026-07-15 20:16:34.152113 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-15 20:16:34.152174 UTC - Destroying tst-11b5ec14 tst 2026-07-15 20:16:34.152358 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-11b5ec14 tst