Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.04s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 196ms 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 636ms + 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 04:10:24.062651 UTC - Installing Slices CLI (latest stable version). 2026-07-15 04:10:24.062877 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 04:10:31.916913 UTC - Slices CLI installed. 2026-07-15 04:10:31.917290 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 04:10:31.919733 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 04:10:31.920738 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 04:10:31.920865 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 04:10:31.920892 UTC - Slices CLI exe: 'slices' 2026-07-15 04:10:31.920909 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 04:10:31.921023 UTC - Run: slices --version 2026-07-15 04:10:36.873250 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 04:10:36.873565 UTC - Run: slices pubkey list --format text 2026-07-15 04:10:38.351244 UTC - Pubkey already registered 2026-07-15 04:10:38.358508 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 04:10:39.877415 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 04:10:39.877534 UTC - Check List Flavors 2026-07-15 04:10:39.877686 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-07-15 04:10:43.104397 UTC - Check List DiskImages 2026-07-15 04:10:43.104686 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-07-15 04:10:45.488195 UTC - Requesting resources 2026-07-15 04:10:45.501958 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 04:10:45.502182 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 22.04.5' --flavor pc --duration 2h --experiment tst-c4aeb84c --user-data /tmp/tmp620vx2z2 2026-07-15 04:10:51.178845 UTC - Resource ID: r_be-gent1-bi-baremetal1_01kxhzgs2sebgsrwn96ttr16ef 2026-07-15 04:10:51.180911 UTC - Waiting until resource ready 2026-07-15 04:10:53.181245 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-c4aeb84c tst 2026-07-15 04:10:54.476540 UTC - Status: IMAGING 2026-07-15 04:10:57.917208 UTC - Status: IMAGING 2026-07-15 04:11:00.683147 UTC - Status: IMAGING 2026-07-15 04:11:03.800399 UTC - Status: IMAGING 2026-07-15 04:11:06.767321 UTC - Status: IMAGING 2026-07-15 04:11:10.122176 UTC - Status: IMAGING 2026-07-15 04:11:13.346297 UTC - Status: IMAGING 2026-07-15 04:11:16.112250 UTC - Status: IMAGING 2026-07-15 04:11:18.978735 UTC - Status: IMAGING 2026-07-15 04:11:22.270030 UTC - Status: IMAGING 2026-07-15 04:11:25.811280 UTC - Status: IMAGING 2026-07-15 04:11:29.914281 UTC - Status: IMAGING 2026-07-15 04:11:33.610297 UTC - Status: IMAGING 2026-07-15 04:11:38.085349 UTC - Status: IMAGING 2026-07-15 04:11:41.670335 UTC - Status: IMAGING 2026-07-15 04:11:45.683986 UTC - Status: IMAGING 2026-07-15 04:11:48.931283 UTC - Status: IMAGING 2026-07-15 04:11:52.521933 UTC - Status: IMAGING 2026-07-15 04:11:55.897965 UTC - Status: IMAGING 2026-07-15 04:11:58.664109 UTC - Status: IMAGING 2026-07-15 04:12:01.684272 UTC - Status: IMAGING 2026-07-15 04:12:04.500251 UTC - Status: IMAGING 2026-07-15 04:12:07.320957 UTC - Status: IMAGING 2026-07-15 04:12:10.098590 UTC - Status: IMAGING 2026-07-15 04:12:12.919065 UTC - Status: IMAGING 2026-07-15 04:12:15.739817 UTC - Status: IMAGING 2026-07-15 04:12:18.887700 UTC - Status: IMAGING 2026-07-15 04:12:22.076446 UTC - Status: IMAGING 2026-07-15 04:12:24.942536 UTC - Status: IMAGING 2026-07-15 04:12:27.711164 UTC - Status: IMAGING 2026-07-15 04:12:30.476994 UTC - Status: IMAGING 2026-07-15 04:12:33.353679 UTC - Status: IMAGING 2026-07-15 04:12:36.069470 UTC - Status: IMAGING 2026-07-15 04:12:38.835726 UTC - Status: IMAGING 2026-07-15 04:12:41.601802 UTC - Status: IMAGING 2026-07-15 04:12:44.367810 UTC - Status: IMAGING 2026-07-15 04:12:47.133783 UTC - Status: IMAGING 2026-07-15 04:12:49.899864 UTC - Status: IMAGING 2026-07-15 04:12:52.665853 UTC - Status: IMAGING 2026-07-15 04:12:55.432247 UTC - Status: IMAGING 2026-07-15 04:12:58.198792 UTC - Status: IMAGING 2026-07-15 04:13:00.965143 UTC - Status: IMAGING 2026-07-15 04:13:03.731295 UTC - Status: IMAGING 2026-07-15 04:13:06.447193 UTC - Status: IMAGING 2026-07-15 04:13:09.213181 UTC - Status: IMAGING 2026-07-15 04:13:11.979252 UTC - Status: IMAGING 2026-07-15 04:13:14.745335 UTC - Status: IMAGING 2026-07-15 04:13:17.511306 UTC - Status: IMAGING 2026-07-15 04:13:20.277591 UTC - Status: IMAGING 2026-07-15 04:13:23.043699 UTC - Status: IMAGING 2026-07-15 04:13:25.810839 UTC - Status: IMAGING 2026-07-15 04:13:28.577036 UTC - Status: IMAGING 2026-07-15 04:13:31.393630 UTC - Status: IMAGING 2026-07-15 04:13:34.210402 UTC - Status: IMAGING 2026-07-15 04:13:37.027050 UTC - Status: STARTING 2026-07-15 04:13:39.843898 UTC - Status: STARTING 2026-07-15 04:13:42.712314 UTC - Status: STARTING 2026-07-15 04:13:45.478602 UTC - Status: STARTING 2026-07-15 04:13:48.244562 UTC - Status: UP 2026-07-15 04:13:48.244636 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxhzgqj2f6196try0nyk462s 2026-07-15 04:13:48.244701 UTC - Validate resources 2026-07-15 04:13:49.011755 UTC - The fields of the created resource were validated. 2026-07-15 04:13:49.011854 UTC - Check if resources are registered in experiment 2026-07-15 04:13:49.012019 UTC - Run: slices experiment list-resources --format json tst-c4aeb84c 2026-07-15 04:13:49.677482 UTC - Status (on expauth): UP 2026-07-15 04:13:49.677676 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 04:13:49.677873 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-c4aeb84c 2026-07-15 04:13:56.558674 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-c4aeb84c tst 2026-07-15 04:13:57.325142 UTC - Run: slices experiment list-resources --format json tst-c4aeb84c 2026-07-15 04:13:57.990651 UTC - expires_at (on expauth): 2026-07-15T07:13:00Z (correctly extended) 2026-07-15 04:13:57.990771 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 04:13:57.990909 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-c4aeb84c 2026-07-15 04:14:03.067516 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-c4aeb84c tst 2026-07-15 04:14:06.590734 UTC - Run: slices experiment list-resources --format json tst-c4aeb84c 2026-07-15 04:14:07.206273 UTC - expires_at (on expauth): 2026-07-15T08:13:00Z (correctly extended) 2026-07-15 04:14:07.206446 UTC - Testing ssh login 2026-07-15 04:14:07.220695 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c4aeb84c tst 2026-07-15 04:14:07.936362 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c4aeb84c tst 2026-07-15 04:14:08.652055 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c4aeb84c tst 2026-07-15 04:14:09.367705 UTC - Logging in using 'slices bi ssh' 2026-07-15 04:14:09.367775 UTC - Forcing IPv4 only. 2026-07-15 04:14:09.367903 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-c4aeb84c tst -- -4 uname -a 2026-07-15 04:14:10.333965 UTC - Forcing IPv4 only. 2026-07-15 04:14:10.334146 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-c4aeb84c tst -- -4 uptime 2026-07-15 04:14:11.250049 UTC - CLI SSH Test passed. 2026-07-15 04:14:11.250131 UTC - Uname: Linux tst.cwnenv.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 04:14:11.250153 UTC - Uptime: 06:14:11 up 1 min, 0 users, load average: 0.21, 0.08, 0.03 2026-07-15 04:14:11.250199 UTC - Logging in using 'slices bi ssh' 2026-07-15 04:14:11.250254 UTC - Forcing IPv6 only. 2026-07-15 04:14:11.250389 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-c4aeb84c tst -- -6 uname -a 2026-07-15 04:14:12.116156 UTC - Forcing IPv6 only. 2026-07-15 04:14:12.116416 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-c4aeb84c tst -- -6 uptime 2026-07-15 04:14:12.982477 UTC - CLI SSH Test passed. 2026-07-15 04:14:12.982552 UTC - Uname: Linux tst.cwnenv.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 04:14:12.982571 UTC - Uptime: 06:14:12 up 1 min, 0 users, load average: 0.21, 0.08, 0.03 2026-07-15 04:14:12.982608 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-15 04:14:12.983111 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:12.983302 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:12.983361 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:12.983539 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 2026-07-15 04:14:13.302267 UTC - SSH Test output: 2026-07-15 04:14:13.302341 UTC - Uname: Linux tst.cwnenv.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 04:14:13.302363 UTC - Uptime: 06:14:13 up 1 min, 0 users, load average: 0.21, 0.08, 0.03 2026-07-15 04:14:13.408330 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-15 04:14:13.408416 UTC - lsb_release matches expected value 2026-07-15 04:14:13.408445 UTC - SSH Test passed. 2026-07-15 04:14:13.412287 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 06:13:36 +0200! 2026-07-15 04:14:13.412452 UTC - Logging in using SSH over SSH proxy 2026-07-15 04:14:13.412610 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c4aeb84c tst 2026-07-15 04:14:14.128351 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:14.128495 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:14.128547 UTC - Added paramiko HostKeyEntry for n077-06.wall1.ilabt.imec.be 2026-07-15 04:14:14.128812 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:14:14.128869 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:14:14.128975 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-15 04:14:14.128995 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-15 04:14:14.389236 UTC - Connecting to n077-06.wall1.ilabt.imec.be:22 over proxy 2026-07-15 04:14:14.704738 UTC - SSH Test output: 2026-07-15 04:14:14.704813 UTC - Uname: Linux tst.cwnenv.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 04:14:14.704832 UTC - Uptime: 06:14:14 up 1 min, 0 users, load average: 0.20, 0.08, 0.03 2026-07-15 04:14:14.812354 UTC - lsb_release: Ubuntu 22.04.5 LTS 2026-07-15 04:14:14.812462 UTC - lsb_release matches expected value 2026-07-15 04:14:14.812486 UTC - SSH Test passed. 2026-07-15 04:14:14.817345 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 06:13:36 +0200! 2026-07-15 04:14:14.824963 UTC - Skipping ping connectivity test: no ping present on test system 2026-07-15 04:14:14.825021 UTC - Destroying tst-c4aeb84c tst 2026-07-15 04:14:14.825229 UTC - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-c4aeb84c tst