Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.76s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 298ms 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 637ms + 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==50.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.dev192+gcac2d3fe6 (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-08-03 14:16:39.038054 UTC - Installing Slices CLI (latest stable version). 2026-08-03 14:16:39.038459 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-03 14:16:45.499540 UTC - Slices CLI installed. 2026-08-03 14:16:45.504668 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-08-03 14:16:45.530754 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-03 14:16:45.532132 UTC - env: {'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-08-03 14:16:45.532264 UTC - which slices: /app/.venv/bin/slices 2026-08-03 14:16:45.532290 UTC - Slices CLI exe: 'slices' 2026-08-03 14:16:45.532307 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-03 14:16:45.532481 UTC - Run: slices --version 2026-08-03 14:16:50.278135 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-03 14:16:50.278696 UTC - Run: slices pubkey list --format text 2026-08-03 14:16:51.548674 UTC - Pubkey already registered 2026-08-03 14:16:51.549013 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-03 14:16:52.373815 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-03 14:16:52.373944 UTC - Check List Flavors 2026-08-03 14:16:52.374118 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-08-03 14:16:53.842102 UTC - Check List DiskImages 2026-08-03 14:16:53.842346 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-08-03 14:16:55.370302 UTC - Requesting resources 2026-08-03 14:16:55.372108 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-03 14:16:55.372261 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-762bdf86 --user-data /tmp/tmp12h6ilel 2026-08-03 14:16:59.962311 UTC - Resource ID: r_gr-volos-bi-vm1_01kz3zrajbfvhsrrj7vnjgqnh7 2026-08-03 14:16:59.963158 UTC - Waiting until resource ready 2026-08-03 14:17:01.963685 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-762bdf86 tst 2026-08-03 14:17:03.490399 UTC - Status: BOOTING 2026-08-03 14:17:07.516685 UTC - Status: BOOTING 2026-08-03 14:17:10.895434 UTC - Status: BOOTING 2026-08-03 14:17:14.317734 UTC - Status: BOOTING 2026-08-03 14:17:17.713993 UTC - Status: BOOTING 2026-08-03 14:17:20.734142 UTC - Status: BOOTING 2026-08-03 14:17:23.780471 UTC - Status: BOOTING 2026-08-03 14:17:26.847607 UTC - Status: BOOTING 2026-08-03 14:17:29.824015 UTC - Status: BOOTING 2026-08-03 14:17:32.957094 UTC - Status: BOOTING 2026-08-03 14:17:36.139330 UTC - Status: BOOTING 2026-08-03 14:17:39.456626 UTC - Status: UP 2026-08-03 14:17:39.456819 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz3zr77ree3rv3e38tg51kkk 2026-08-03 14:17:39.456887 UTC - Validate resources 2026-08-03 14:17:40.675351 UTC - The fields of the created resource were validated. 2026-08-03 14:17:40.675615 UTC - Check if resources are registered in experiment 2026-08-03 14:17:40.675787 UTC - Run: slices experiment list-resources --format json tst-762bdf86 2026-08-03 14:17:41.542599 UTC - Status (on expauth): UP 2026-08-03 14:17:41.542733 UTC - Testing extend expires_at (all resources in experiment) 2026-08-03 14:17:41.542846 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-762bdf86 2026-08-03 14:17:43.313607 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-762bdf86 tst 2026-08-03 14:17:44.331733 UTC - Run: slices experiment list-resources --format json tst-762bdf86 2026-08-03 14:17:45.198156 UTC - expires_at (on expauth): 2026-08-03T17:17:00Z (correctly extended) 2026-08-03 14:17:45.198298 UTC - Testing extend expires_at (single resource in experiment) 2026-08-03 14:17:45.198540 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-762bdf86 2026-08-03 14:17:46.985934 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-762bdf86 tst 2026-08-03 14:17:48.052554 UTC - Run: slices experiment list-resources --format json tst-762bdf86 2026-08-03 14:17:48.918625 UTC - expires_at (on expauth): 2026-08-03T18:17:00Z (correctly extended) 2026-08-03 14:17:48.918912 UTC - Testing ssh login 2026-08-03 14:17:48.937142 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-762bdf86 tst 2026-08-03 14:17:50.108318 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-762bdf86 tst 2026-08-03 14:17:51.329896 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-762bdf86 tst 2026-08-03 14:17:52.610015 UTC - Logging in using 'slices bi ssh' 2026-08-03 14:17:52.610094 UTC - Forcing IPv4 only. 2026-08-03 14:17:52.610225 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-762bdf86 tst -- -4 uname -a 2026-08-03 14:17:56.996127 UTC - Forcing IPv4 only. 2026-08-03 14:17:56.996632 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-762bdf86 tst -- -4 uptime 2026-08-03 14:18:00.308983 UTC - CLI SSH Test passed. 2026-08-03 14:18:00.309099 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-08-03 14:18:00.309122 UTC - Uptime: 14:17:59 up 0 min, 1 user, load average: 0.80, 0.23, 0.08 2026-08-03 14:18:00.309159 UTC - Logging in using SSH over SSH proxy 2026-08-03 14:18:00.309662 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-762bdf86 tst 2026-08-03 14:18:01.576738 UTC - Added paramiko HostKeyEntry for 10.64.45.216 2026-08-03 14:18:01.577148 UTC - Added paramiko HostKeyEntry for 10.64.45.216 2026-08-03 14:18:01.577246 UTC - Added paramiko HostKeyEntry for 10.64.45.216 2026-08-03 14:18:01.577709 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-03 14:18:01.577810 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-03 14:18:01.577869 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-03 14:18:01.577890 UTC - Connecting to proxy 194.177.207.42:11122 2026-08-03 14:18:02.356752 UTC - Connecting to 10.64.45.216:22 over proxy 2026-08-03 14:18:03.821284 UTC - SSH Test output: 2026-08-03 14:18:03.821355 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-08-03 14:18:03.821376 UTC - Uptime: 14:18:02 up 0 min, 1 user, load average: 0.73, 0.22, 0.08 2026-08-03 14:18:04.126123 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-03 14:18:04.126206 UTC - lsb_release matches expected value 2026-08-03 14:18:04.126227 UTC - SSH Test passed. 2026-08-03 14:18:04.428070 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 14:17:36 +0000! 2026-08-03 14:18:04.439624 UTC - Destroying tst-762bdf86 tst 2026-08-03 14:18:04.439842 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-762bdf86 tst