Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.71s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 373ms 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 537ms + 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-14 04:09:55.034890 UTC - Installing Slices CLI (latest stable version). 2026-07-14 04:09:55.035178 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 04:10:00.674086 UTC - Slices CLI installed. 2026-07-14 04:10:00.674298 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-14 04:10:00.676761 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-14 04:10:00.678192 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 04:10:00.678504 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-14 04:10:00.678543 UTC - Slices CLI exe: 'slices' 2026-07-14 04:10:00.678563 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 04:10:00.678721 UTC - Run: slices --version 2026-07-14 04:10:04.128485 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-14 04:10:04.128787 UTC - Run: slices pubkey list --format text 2026-07-14 04:10:04.994712 UTC - Pubkey already registered 2026-07-14 04:10:04.994980 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 04:10:05.715116 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 04:10:05.715224 UTC - Check List Flavors 2026-07-14 04:10:05.715378 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-14 04:10:07.451977 UTC - Check List DiskImages 2026-07-14 04:10:07.452180 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-14 04:10:08.379721 UTC - Requesting resources 2026-07-14 04:10:08.380618 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 04:10:08.380781 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-29b061b9 --user-data /tmp/tmp_6n1scfy 2026-07-14 04:10:12.667775 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxfd2wm1fg0vdy3xdbm5m40m 2026-07-14 04:10:12.668022 UTC - Waiting until resource ready 2026-07-14 04:10:14.668519 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-29b061b9 tst 2026-07-14 04:10:15.735695 UTC - Status: IMAGING 2026-07-14 04:10:18.502461 UTC - Status: IMAGING 2026-07-14 04:10:21.268278 UTC - Status: IMAGING 2026-07-14 04:10:24.086371 UTC - Status: IMAGING 2026-07-14 04:10:27.406897 UTC - Status: IMAGING 2026-07-14 04:10:30.823122 UTC - Status: IMAGING 2026-07-14 04:10:34.337885 UTC - Status: IMAGING 2026-07-14 04:10:38.299547 UTC - Status: IMAGING 2026-07-14 04:10:42.312551 UTC - Status: IMAGING 2026-07-14 04:10:46.975182 UTC - Status: IMAGING 2026-07-14 04:10:50.770981 UTC - Status: IMAGING 2026-07-14 04:10:54.151052 UTC - Status: IMAGING 2026-07-14 04:10:57.639946 UTC - Status: IMAGING 2026-07-14 04:11:00.611523 UTC - Status: IMAGING 2026-07-14 04:11:03.911164 UTC - Status: IMAGING 2026-07-14 04:11:07.149593 UTC - Status: IMAGING 2026-07-14 04:11:10.243595 UTC - Status: IMAGING 2026-07-14 04:11:13.818196 UTC - Status: IMAGING 2026-07-14 04:11:17.419825 UTC - Status: IMAGING 2026-07-14 04:11:20.709394 UTC - Status: IMAGING 2026-07-14 04:11:24.090468 UTC - Status: IMAGING 2026-07-14 04:11:27.374827 UTC - Status: IMAGING 2026-07-14 04:11:30.635018 UTC - Status: IMAGING 2026-07-14 04:11:33.652446 UTC - Status: IMAGING 2026-07-14 04:11:37.166241 UTC - Status: IMAGING 2026-07-14 04:11:40.095522 UTC - Status: IMAGING 2026-07-14 04:11:42.920151 UTC - Status: IMAGING 2026-07-14 04:11:45.786625 UTC - Status: IMAGING 2026-07-14 04:11:48.552684 UTC - Status: IMAGING 2026-07-14 04:11:51.368927 UTC - Status: IMAGING 2026-07-14 04:11:54.185075 UTC - Status: IMAGING 2026-07-14 04:11:57.054673 UTC - Status: IMAGING 2026-07-14 04:11:59.870818 UTC - Status: IMAGING 2026-07-14 04:12:03.163964 UTC - Status: IMAGING 2026-07-14 04:12:06.081301 UTC - Status: IMAGING 2026-07-14 04:12:09.476181 UTC - Status: IMAGING 2026-07-14 04:12:12.605767 UTC - Status: IMAGING 2026-07-14 04:12:15.676487 UTC - Status: IMAGING 2026-07-14 04:12:18.442808 UTC - Status: IMAGING 2026-07-14 04:12:21.261740 UTC - Status: IMAGING 2026-07-14 04:12:24.028453 UTC - Status: IMAGING 2026-07-14 04:12:26.845835 UTC - Status: IMAGING 2026-07-14 04:12:29.662268 UTC - Status: IMAGING 2026-07-14 04:12:32.428820 UTC - Status: IMAGING 2026-07-14 04:12:35.194952 UTC - Status: IMAGING 2026-07-14 04:12:37.961077 UTC - Status: IMAGING 2026-07-14 04:12:40.777817 UTC - Status: IMAGING 2026-07-14 04:12:43.594450 UTC - Status: IMAGING 2026-07-14 04:12:46.360789 UTC - Status: IMAGING 2026-07-14 04:12:49.127571 UTC - Status: IMAGING 2026-07-14 04:12:51.893830 UTC - Status: IMAGING 2026-07-14 04:12:54.710892 UTC - Status: IMAGING 2026-07-14 04:12:57.728767 UTC - Status: IMAGING 2026-07-14 04:13:00.696523 UTC - Status: IMAGING 2026-07-14 04:13:03.764004 UTC - Status: IMAGING 2026-07-14 04:13:06.631620 UTC - Status: IMAGING 2026-07-14 04:13:09.397814 UTC - Status: IMAGING 2026-07-14 04:13:12.264544 UTC - Status: STARTING 2026-07-14 04:13:15.080991 UTC - Status: STARTING 2026-07-14 04:13:17.947907 UTC - Status: STARTING 2026-07-14 04:13:20.814396 UTC - Status: UP 2026-07-14 04:13:20.814530 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxfd2v8ye068anvm5fn5qkzq 2026-07-14 04:13:20.814599 UTC - Validate resources 2026-07-14 04:13:21.733065 UTC - The fields of the created resource were validated. 2026-07-14 04:13:21.733167 UTC - Check if resources are registered in experiment 2026-07-14 04:13:21.733348 UTC - Run: slices experiment list-resources --format json tst-29b061b9 2026-07-14 04:13:22.449280 UTC - Status (on expauth): UP 2026-07-14 04:13:22.449478 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 04:13:22.449629 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-29b061b9 2026-07-14 04:13:29.432036 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-29b061b9 tst 2026-07-14 04:13:30.298779 UTC - Run: slices experiment list-resources --format json tst-29b061b9 2026-07-14 04:13:31.014975 UTC - expires_at (on expauth): 2026-07-14T07:13:00Z (correctly extended) 2026-07-14 04:13:31.015093 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 04:13:31.015253 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-29b061b9 2026-07-14 04:13:35.841873 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-29b061b9 tst 2026-07-14 04:13:36.758789 UTC - Run: slices experiment list-resources --format json tst-29b061b9 2026-07-14 04:13:37.475101 UTC - expires_at (on expauth): 2026-07-14T08:13:00Z (correctly extended) 2026-07-14 04:13:37.475256 UTC - Testing ssh login 2026-07-14 04:13:37.491243 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-29b061b9 tst 2026-07-14 04:13:43.271519 UTC - Step 'Ssh info ssh_config' took 5.78 seconds, which is longer than the warning threshold of 5 seconds 2026-07-14 04:13:43.271797 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-29b061b9 tst 2026-07-14 04:13:44.039308 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-29b061b9 tst 2026-07-14 04:13:44.855993 UTC - Logging in using 'slices bi ssh' 2026-07-14 04:13:44.856087 UTC - Forcing IPv4 only. 2026-07-14 04:13:44.856250 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-29b061b9 tst -- -4 uname -a 2026-07-14 04:13:45.873281 UTC - Forcing IPv4 only. 2026-07-14 04:13:45.873575 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-29b061b9 tst -- -4 uptime 2026-07-14 04:13:46.940567 UTC - CLI SSH Test passed. 2026-07-14 04:13:46.940670 UTC - Uname: Linux tst.jgpger.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-14 04:13:46.940703 UTC - Uptime: 06:13:46 up 1 min, 0 users, load average: 0.10, 0.04, 0.01 2026-07-14 04:13:46.940778 UTC - Logging in using 'slices bi ssh' 2026-07-14 04:13:46.940842 UTC - Forcing IPv6 only. 2026-07-14 04:13:46.941021 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-29b061b9 tst -- -6 uname -a 2026-07-14 04:13:47.907475 UTC - Forcing IPv6 only. 2026-07-14 04:13:47.907671 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-29b061b9 tst -- -6 uptime 2026-07-14 04:13:48.873967 UTC - CLI SSH Test passed. 2026-07-14 04:13:48.874045 UTC - Uname: Linux tst.jgpger.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-14 04:13:48.874065 UTC - Uptime: 06:13:48 up 1 min, 0 users, load average: 0.10, 0.04, 0.01 2026-07-14 04:13:48.874103 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-14 04:13:48.874672 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:48.874884 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:48.874956 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:48.875168 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 2026-07-14 04:13:49.252779 UTC - SSH Test output: 2026-07-14 04:13:49.252853 UTC - Uname: Linux tst.jgpger.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-14 04:13:49.252875 UTC - Uptime: 06:13:49 up 1 min, 0 users, load average: 0.09, 0.04, 0.01 2026-07-14 04:13:49.322906 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-14 04:13:49.322985 UTC - lsb_release matches expected value 2026-07-14 04:13:49.323008 UTC - SSH Test passed. 2026-07-14 04:13:49.370170 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 06:13:09 +0200! 2026-07-14 04:13:49.370366 UTC - Logging in using SSH over SSH proxy 2026-07-14 04:13:49.370535 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-29b061b9 tst 2026-07-14 04:13:50.136751 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:50.136952 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:50.137006 UTC - Added paramiko HostKeyEntry for n078-05.wall1.ilabt.imec.be 2026-07-14 04:13:50.137283 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:13:50.137333 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:13:50.137457 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:13:50.137480 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-14 04:13:50.408225 UTC - Connecting to n078-05.wall1.ilabt.imec.be:22 over proxy 2026-07-14 04:13:50.717870 UTC - SSH Test output: 2026-07-14 04:13:50.717962 UTC - Uname: Linux tst.jgpger.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-14 04:13:50.717987 UTC - Uptime: 06:13:50 up 1 min, 0 users, load average: 0.09, 0.04, 0.01 2026-07-14 04:13:50.789480 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-14 04:13:50.789575 UTC - lsb_release matches expected value 2026-07-14 04:13:50.789600 UTC - SSH Test passed. 2026-07-14 04:13:50.844848 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 06:13:09 +0200! 2026-07-14 04:13:50.853746 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-14 04:13:50.853816 UTC - Destroying tst-29b061b9 tst 2026-07-14 04:13:50.854021 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-29b061b9 tst