Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.87s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 214ms 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 247ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-09 13:13:20.848398 UTC - Installing Slices CLI (latest stable version). 2026-07-09 13:13:20.848611 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 13:13:26.033460 UTC - Slices CLI installed. 2026-07-09 13:13:26.033840 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-09 13:13:26.036621 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-09 13:13:26.037637 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-09 13:13:26.037766 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-09 13:13:26.037792 UTC - Slices CLI exe: 'slices' 2026-07-09 13:13:26.037809 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 13:13:26.037920 UTC - Run: slices --version 2026-07-09 13:13:28.965261 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-09 13:13:28.965560 UTC - Run: slices pubkey list --format text 2026-07-09 13:13:29.981607 UTC - Pubkey already registered 2026-07-09 13:13:29.981853 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 13:13:30.597039 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 13:13:30.597134 UTC - Check List Flavors 2026-07-09 13:13:30.597322 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-09 13:13:31.413081 UTC - Check List DiskImages 2026-07-09 13:13:31.413278 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-09 13:13:32.028496 UTC - Requesting resources 2026-07-09 13:13:32.029006 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 13:13:32.029116 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5a7fd09b --user-data /tmp/tmp3llj0g7i 2026-07-09 13:13:34.949427 UTC - Resource ID: r_be-gent1-bi-vm1_01kx3g67tsfdf9tzb4zkpsb9wc 2026-07-09 13:13:34.949639 UTC - Waiting until resource ready 2026-07-09 13:13:36.950032 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5a7fd09b tst 2026-07-09 13:13:37.715638 UTC - Status: IMAGING 2026-07-09 13:13:40.481479 UTC - Status: IMAGING 2026-07-09 13:13:43.197144 UTC - Status: IMAGING 2026-07-09 13:13:45.913087 UTC - Status: BOOTING 2026-07-09 13:13:48.629190 UTC - Status: BOOTING 2026-07-09 13:13:51.295198 UTC - Status: BOOTING 2026-07-09 13:13:53.961087 UTC - Status: BOOTING 2026-07-09 13:13:56.727437 UTC - Status: BOOTING 2026-07-09 13:13:59.443441 UTC - Status: INITIALIZING 2026-07-09 13:14:02.109423 UTC - Status: UP 2026-07-09 13:14:02.109517 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx3g66zefvfvfdd6q5bbkbgx 2026-07-09 13:14:02.109572 UTC - Validate resources 2026-07-09 13:14:02.825677 UTC - The fields of the created resource were validated. 2026-07-09 13:14:02.825777 UTC - Check if resources are registered in experiment 2026-07-09 13:14:02.825949 UTC - Run: slices experiment list-resources --format json tst-5a7fd09b 2026-07-09 13:14:03.491385 UTC - Status (on expauth): UP 2026-07-09 13:14:03.491568 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 13:14:03.491748 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-5a7fd09b 2026-07-09 13:14:06.161765 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5a7fd09b tst 2026-07-09 13:14:06.877265 UTC - Run: slices experiment list-resources --format json tst-5a7fd09b 2026-07-09 13:14:07.542699 UTC - expires_at (on expauth): 2026-07-09T16:14:00Z (correctly extended) 2026-07-09 13:14:07.542819 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 13:14:07.542987 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-5a7fd09b 2026-07-09 13:14:10.062689 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5a7fd09b tst 2026-07-09 13:14:10.778514 UTC - Run: slices experiment list-resources --format json tst-5a7fd09b 2026-07-09 13:14:11.443979 UTC - expires_at (on expauth): 2026-07-09T17:14:00Z (correctly extended) 2026-07-09 13:14:11.444157 UTC - Testing ssh login 2026-07-09 13:14:11.455956 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5a7fd09b tst 2026-07-09 13:14:12.221724 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5a7fd09b tst 2026-07-09 13:14:12.987690 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5a7fd09b tst 2026-07-09 13:14:13.703782 UTC - Logging in using 'slices bi ssh' 2026-07-09 13:14:13.703857 UTC - Forcing IPv4 only. 2026-07-09 13:14:13.703987 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5a7fd09b tst -- -4 uname -a 2026-07-09 13:14:15.070941 UTC - Forcing IPv4 only. 2026-07-09 13:14:15.071118 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5a7fd09b tst -- -4 uptime 2026-07-09 13:14:15.937513 UTC - CLI SSH Test passed. 2026-07-09 13:14:15.937614 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-09 13:14:15.937635 UTC - Uptime: 15:14:15 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-09 13:14:15.937677 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-09 13:14:15.938267 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:15.938477 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:15.938541 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:15.938761 UTC - Connecting to 10.10.217.2:22 2026-07-09 13:14:16.171119 UTC - SSH Test output: 2026-07-09 13:14:16.171226 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-09 13:14:16.171257 UTC - Uptime: 15:14:15 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-09 13:14:16.220940 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-09 13:14:16.221041 UTC - lsb_release matches expected value 2026-07-09 13:14:16.221063 UTC - SSH Test passed. 2026-07-09 13:14:16.265124 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 15:13:58 +0200! 2026-07-09 13:14:16.265310 UTC - Logging in using SSH over SSH proxy 2026-07-09 13:14:16.265480 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5a7fd09b tst 2026-07-09 13:14:17.181962 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:17.182116 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:17.182172 UTC - Added paramiko HostKeyEntry for 10.10.217.2 2026-07-09 13:14:17.182444 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 13:14:17.182499 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 13:14:17.182624 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-09 13:14:17.182649 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-09 13:14:17.312431 UTC - Connecting to 10.10.217.2:22 over proxy 2026-07-09 13:14:17.596068 UTC - SSH Test output: 2026-07-09 13:14:17.596139 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-09 13:14:17.596159 UTC - Uptime: 15:14:16 up 0 min, 2 users, load average: 0.18, 0.05, 0.01 2026-07-09 13:14:17.648632 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-09 13:14:17.648757 UTC - lsb_release matches expected value 2026-07-09 13:14:17.648781 UTC - SSH Test passed. 2026-07-09 13:14:17.697081 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 15:13:58 +0200! 2026-07-09 13:14:17.704314 UTC - Destroying tst-5a7fd09b tst 2026-07-09 13:14:17.704509 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-5a7fd09b tst