Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.69s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 249ms 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 734ms + 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-26 04:20:07.775544 UTC - Installing Slices CLI (latest stable version). 2026-07-26 04:20:07.775797 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 04:20:13.003133 UTC - Slices CLI installed. 2026-07-26 04:20:13.003888 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-26 04:20:13.007097 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-26 04:20:13.008239 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 04:20:13.008395 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-26 04:20:13.008427 UTC - Slices CLI exe: 'slices' 2026-07-26 04:20:13.008444 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 04:20:13.008605 UTC - Run: slices --version 2026-07-26 04:20:15.731822 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-26 04:20:15.732103 UTC - Run: slices pubkey list --format text 2026-07-26 04:20:16.549873 UTC - Pubkey already registered 2026-07-26 04:20:16.550113 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 04:20:17.165337 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 04:20:17.165427 UTC - Check List Flavors 2026-07-26 04:20:17.165579 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-26 04:20:17.830752 UTC - Check List DiskImages 2026-07-26 04:20:17.830932 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-26 04:20:18.446098 UTC - Requesting resources 2026-07-26 04:20:18.446908 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 04:20:18.447029 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-184b3f0c --user-data /tmp/tmpzv7taohe 2026-07-26 04:20:19.513001 UTC - Resource ID: r_be-gent1-bi-vm1_01kyeae194fhtbbhp7xmsryta4 2026-07-26 04:20:19.513158 UTC - Waiting until resource ready 2026-07-26 04:20:21.513631 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-184b3f0c tst 2026-07-26 04:20:22.439539 UTC - Status: IMAGING 2026-07-26 04:20:25.788875 UTC - Status: IMAGING 2026-07-26 04:20:28.756566 UTC - Status: IMAGING 2026-07-26 04:20:32.279457 UTC - Status: BOOTING 2026-07-26 04:20:35.688633 UTC - Status: BOOTING 2026-07-26 04:20:38.614126 UTC - Status: BOOTING 2026-07-26 04:20:41.754894 UTC - Status: BOOTING 2026-07-26 04:20:44.885932 UTC - Status: INITIALIZING 2026-07-26 04:20:48.135165 UTC - Status: UP 2026-07-26 04:20:48.135439 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyeae11pedsb3crqeda5nhr3 2026-07-26 04:20:48.135547 UTC - Validate resources 2026-07-26 04:20:49.154169 UTC - The fields of the created resource were validated. 2026-07-26 04:20:49.154263 UTC - Check if resources are registered in experiment 2026-07-26 04:20:49.154406 UTC - Run: slices experiment list-resources --format json tst-184b3f0c 2026-07-26 04:20:49.871364 UTC - Status (on expauth): UP 2026-07-26 04:20:49.871517 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 04:20:49.871674 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-184b3f0c 2026-07-26 04:20:52.065490 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-184b3f0c tst 2026-07-26 04:20:53.418444 UTC - Run: slices experiment list-resources --format json tst-184b3f0c 2026-07-26 04:20:55.239013 UTC - expires_at (on expauth): 2026-07-26T07:20:00Z (correctly extended) 2026-07-26 04:20:55.239390 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 04:20:55.239604 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-184b3f0c 2026-07-26 04:20:56.357247 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-184b3f0c tst 2026-07-26 04:20:57.326724 UTC - Run: slices experiment list-resources --format json tst-184b3f0c 2026-07-26 04:20:58.374988 UTC - expires_at (on expauth): 2026-07-26T08:20:00Z (correctly extended) 2026-07-26 04:20:58.375245 UTC - Testing ssh login 2026-07-26 04:20:58.392103 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-184b3f0c tst 2026-07-26 04:20:59.308456 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-184b3f0c tst 2026-07-26 04:21:00.074372 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-184b3f0c tst 2026-07-26 04:21:00.854620 UTC - Logging in using 'slices bi ssh' 2026-07-26 04:21:00.854711 UTC - Forcing IPv4 only. 2026-07-26 04:21:00.854866 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-184b3f0c tst -- -4 uname -a 2026-07-26 04:21:02.535804 UTC - Forcing IPv4 only. 2026-07-26 04:21:02.536186 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-184b3f0c tst -- -4 uptime 2026-07-26 04:21:03.763905 UTC - CLI SSH Test passed. 2026-07-26 04:21:03.763976 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 04:21:03.763995 UTC - Uptime: 06:21:03 up 0 min, 1 user, load average: 0.68, 0.19, 0.06 2026-07-26 04:21:03.764029 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-26 04:21:03.764487 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:03.764667 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:03.764736 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:03.764903 UTC - Connecting to 10.10.219.243:22 2026-07-26 04:21:04.147503 UTC - SSH Test output: 2026-07-26 04:21:04.147609 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 04:21:04.147644 UTC - Uptime: 06:21:03 up 0 min, 1 user, load average: 0.68, 0.19, 0.06 2026-07-26 04:21:04.197534 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 04:21:04.197636 UTC - lsb_release matches expected value 2026-07-26 04:21:04.197656 UTC - SSH Test passed. 2026-07-26 04:21:04.241496 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 06:20:44 +0200! 2026-07-26 04:21:04.241703 UTC - Logging in using SSH over SSH proxy 2026-07-26 04:21:04.241867 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-184b3f0c tst 2026-07-26 04:21:06.002028 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:06.002194 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:06.002250 UTC - Added paramiko HostKeyEntry for 10.10.219.243 2026-07-26 04:21:06.002498 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:21:06.002569 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:21:06.002680 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:21:06.002701 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-26 04:21:06.277484 UTC - Connecting to 10.10.219.243:22 over proxy 2026-07-26 04:21:06.565855 UTC - SSH Test output: 2026-07-26 04:21:06.565940 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-26 04:21:06.565959 UTC - Uptime: 06:21:05 up 0 min, 2 users, load average: 0.68, 0.19, 0.06 2026-07-26 04:21:06.619024 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 04:21:06.619117 UTC - lsb_release matches expected value 2026-07-26 04:21:06.619141 UTC - SSH Test passed. 2026-07-26 04:21:06.664915 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 06:20:44 +0200! 2026-07-26 04:21:06.672608 UTC - Destroying tst-184b3f0c tst 2026-07-26 04:21:06.672827 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-184b3f0c tst