Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.07s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 429ms 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 691ms + 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 13:10:02.056914 UTC - Installing Slices CLI (latest stable version). 2026-07-14 13:10:02.057133 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 13:10:13.511120 UTC - Slices CLI installed. 2026-07-14 13:10:13.511342 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 13:10:13.513958 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 13:10:13.515306 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 13:10:13.515454 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 13:10:13.515483 UTC - Slices CLI exe: 'slices' 2026-07-14 13:10:13.515501 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 13:10:13.515631 UTC - Run: slices --version 2026-07-14 13:10:16.235505 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 13:10:16.235780 UTC - Run: slices pubkey list --format text 2026-07-14 13:10:17.101291 UTC - Pubkey already registered 2026-07-14 13:10:17.101633 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 13:10:17.716735 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 13:10:17.716825 UTC - Check List Flavors 2026-07-14 13:10:17.716977 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-14 13:10:19.434364 UTC - Check List DiskImages 2026-07-14 13:10:19.434554 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-14 13:10:20.049854 UTC - Requesting resources 2026-07-14 13:10:20.050448 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 13:10:20.050565 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-784b5cbb --user-data /tmp/tmpdmwqam7w 2026-07-14 13:10:24.675132 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxgc00zke2rv4xgj2qytbbbh 2026-07-14 13:10:24.675303 UTC - Waiting until resource ready 2026-07-14 13:10:26.675657 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-784b5cbb tst 2026-07-14 13:10:28.228890 UTC - Status: IMAGING 2026-07-14 13:10:32.047068 UTC - Status: IMAGING 2026-07-14 13:10:35.890941 UTC - Status: IMAGING 2026-07-14 13:10:40.334531 UTC - Status: IMAGING 2026-07-14 13:10:44.175172 UTC - Status: IMAGING 2026-07-14 13:10:48.097210 UTC - Status: IMAGING 2026-07-14 13:10:51.769004 UTC - Status: IMAGING 2026-07-14 13:10:55.181203 UTC - Status: IMAGING 2026-07-14 13:10:58.153047 UTC - Status: IMAGING 2026-07-14 13:11:00.969194 UTC - Status: IMAGING 2026-07-14 13:11:04.037360 UTC - Status: IMAGING 2026-07-14 13:11:06.810184 UTC - Status: IMAGING 2026-07-14 13:11:10.403520 UTC - Status: IMAGING 2026-07-14 13:11:13.809119 UTC - Status: IMAGING 2026-07-14 13:11:16.625071 UTC - Status: IMAGING 2026-07-14 13:11:20.532642 UTC - Status: IMAGING 2026-07-14 13:11:23.399144 UTC - Status: IMAGING 2026-07-14 13:11:27.094549 UTC - Status: IMAGING 2026-07-14 13:11:29.910583 UTC - Status: IMAGING 2026-07-14 13:11:33.291136 UTC - Status: IMAGING 2026-07-14 13:11:36.270708 UTC - Status: IMAGING 2026-07-14 13:11:39.589830 UTC - Status: IMAGING 2026-07-14 13:11:42.620223 UTC - Status: IMAGING 2026-07-14 13:11:45.387736 UTC - Status: IMAGING 2026-07-14 13:11:48.203838 UTC - Status: IMAGING 2026-07-14 13:11:50.969748 UTC - Status: IMAGING 2026-07-14 13:11:53.785737 UTC - Status: IMAGING 2026-07-14 13:11:56.778275 UTC - Status: IMAGING 2026-07-14 13:11:59.544165 UTC - Status: IMAGING 2026-07-14 13:12:02.957252 UTC - Status: IMAGING 2026-07-14 13:12:05.773364 UTC - Status: IMAGING 2026-07-14 13:12:08.914546 UTC - Status: IMAGING 2026-07-14 13:12:11.730853 UTC - Status: IMAGING 2026-07-14 13:12:14.546807 UTC - Status: IMAGING 2026-07-14 13:12:17.312843 UTC - Status: IMAGING 2026-07-14 13:12:20.078699 UTC - Status: IMAGING 2026-07-14 13:12:22.844804 UTC - Status: IMAGING 2026-07-14 13:12:25.610708 UTC - Status: IMAGING 2026-07-14 13:12:28.376963 UTC - Status: IMAGING 2026-07-14 13:12:31.243735 UTC - Status: IMAGING 2026-07-14 13:12:34.009945 UTC - Status: IMAGING 2026-07-14 13:12:36.776040 UTC - Status: IMAGING 2026-07-14 13:12:39.542250 UTC - Status: IMAGING 2026-07-14 13:12:42.308379 UTC - Status: IMAGING 2026-07-14 13:12:45.074360 UTC - Status: IMAGING 2026-07-14 13:12:47.840547 UTC - Status: IMAGING 2026-07-14 13:12:50.606683 UTC - Status: IMAGING 2026-07-14 13:12:53.373642 UTC - Status: IMAGING 2026-07-14 13:12:56.189878 UTC - Status: IMAGING 2026-07-14 13:12:58.956034 UTC - Status: IMAGING 2026-07-14 13:13:01.722092 UTC - Status: IMAGING 2026-07-14 13:13:04.488206 UTC - Status: IMAGING 2026-07-14 13:13:07.304352 UTC - Status: IMAGING 2026-07-14 13:13:10.070331 UTC - Status: IMAGING 2026-07-14 13:13:12.886662 UTC - Status: IMAGING 2026-07-14 13:13:15.652582 UTC - Status: IMAGING 2026-07-14 13:13:18.418589 UTC - Status: IMAGING 2026-07-14 13:13:21.234930 UTC - Status: UP 2026-07-14 13:13:21.235030 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxgbzzcxf8hsfxt4zyn2sn1t 2026-07-14 13:13:21.235082 UTC - Validate resources 2026-07-14 13:13:22.002676 UTC - The fields of the created resource were validated. 2026-07-14 13:13:22.002787 UTC - Check if resources are registered in experiment 2026-07-14 13:13:22.002962 UTC - Run: slices experiment list-resources --format json tst-784b5cbb 2026-07-14 13:13:22.668333 UTC - Status (on expauth): UP 2026-07-14 13:13:22.668509 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 13:13:22.668681 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-784b5cbb 2026-07-14 13:13:29.603856 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-784b5cbb tst 2026-07-14 13:13:30.419596 UTC - Run: slices experiment list-resources --format json tst-784b5cbb 2026-07-14 13:13:31.034805 UTC - expires_at (on expauth): 2026-07-14T16:13:00Z (correctly extended) 2026-07-14 13:13:31.034907 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 13:13:31.035041 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-784b5cbb 2026-07-14 13:13:35.910526 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-784b5cbb tst 2026-07-14 13:13:36.726784 UTC - Run: slices experiment list-resources --format json tst-784b5cbb 2026-07-14 13:13:37.342135 UTC - expires_at (on expauth): 2026-07-14T17:13:00Z (correctly extended) 2026-07-14 13:13:37.342298 UTC - Testing ssh login 2026-07-14 13:13:37.355608 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-784b5cbb tst 2026-07-14 13:13:38.071493 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-784b5cbb tst 2026-07-14 13:13:38.787229 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-784b5cbb tst 2026-07-14 13:13:39.502993 UTC - Logging in using 'slices bi ssh' 2026-07-14 13:13:39.503061 UTC - Forcing IPv4 only. 2026-07-14 13:13:39.503188 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-784b5cbb tst -- -4 uname -a 2026-07-14 13:13:40.469370 UTC - Forcing IPv4 only. 2026-07-14 13:13:40.469558 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-784b5cbb tst -- -4 uptime 2026-07-14 13:13:41.385617 UTC - CLI SSH Test passed. 2026-07-14 13:13:41.385691 UTC - Uname: Linux tst.uldkmu.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 13:13:41.385711 UTC - Uptime: 15:13:41 up 1 min, 0 users, load average: 0.16, 0.07, 0.02 2026-07-14 13:13:41.385745 UTC - Logging in using 'slices bi ssh' 2026-07-14 13:13:41.385795 UTC - Forcing IPv6 only. 2026-07-14 13:13:41.385919 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-784b5cbb tst -- -6 uname -a 2026-07-14 13:13:42.301789 UTC - Forcing IPv6 only. 2026-07-14 13:13:42.301978 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-784b5cbb tst -- -6 uptime 2026-07-14 13:13:43.269306 UTC - CLI SSH Test passed. 2026-07-14 13:13:43.269391 UTC - Uname: Linux tst.uldkmu.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 13:13:43.269410 UTC - Uptime: 15:13:43 up 1 min, 0 users, load average: 0.15, 0.07, 0.02 2026-07-14 13:13:43.269447 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-14 13:13:43.269910 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:43.270068 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:43.270122 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:43.270295 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 2026-07-14 13:13:43.574429 UTC - SSH Test output: 2026-07-14 13:13:43.574508 UTC - Uname: Linux tst.uldkmu.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 13:13:43.574535 UTC - Uptime: 15:13:43 up 1 min, 0 users, load average: 0.15, 0.07, 0.02 2026-07-14 13:13:43.671478 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-14 13:13:43.671563 UTC - lsb_release matches expected value 2026-07-14 13:13:43.671584 UTC - SSH Test passed. 2026-07-14 13:13:43.675227 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 15:13:18 +0200! 2026-07-14 13:13:43.675404 UTC - Logging in using SSH over SSH proxy 2026-07-14 13:13:43.675559 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-784b5cbb tst 2026-07-14 13:13:44.391306 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:44.391474 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:44.391526 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-14 13:13:44.391752 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:13:44.391802 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:13:44.391910 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 13:13:44.391931 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-14 13:13:44.665050 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 over proxy 2026-07-14 13:13:44.966243 UTC - SSH Test output: 2026-07-14 13:13:44.966317 UTC - Uname: Linux tst.uldkmu.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 13:13:44.966345 UTC - Uptime: 15:13:44 up 1 min, 0 users, load average: 0.15, 0.07, 0.02 2026-07-14 13:13:45.036362 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-14 13:13:45.036438 UTC - lsb_release matches expected value 2026-07-14 13:13:45.036457 UTC - SSH Test passed. 2026-07-14 13:13:45.082004 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 15:13:18 +0200! 2026-07-14 13:13:45.088874 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-14 13:13:45.088923 UTC - Destroying tst-784b5cbb tst 2026-07-14 13:13:45.089083 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-784b5cbb tst