Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 188ms 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 232ms + 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-12 04:06:54.766749 UTC - Installing Slices CLI (latest stable version). 2026-07-12 04:06:54.766960 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 04:07:00.069878 UTC - Slices CLI installed. 2026-07-12 04:07:00.070683 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-12 04:07:00.072841 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-12 04:07:00.073739 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 04:07:00.073865 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-12 04:07:00.073892 UTC - Slices CLI exe: 'slices' 2026-07-12 04:07:00.073909 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 04:07:00.074035 UTC - Run: slices --version 2026-07-12 04:07:04.941668 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-12 04:07:04.942010 UTC - Run: slices pubkey list --format text 2026-07-12 04:07:06.315119 UTC - Pubkey already registered 2026-07-12 04:07:06.315453 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 04:07:08.022472 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 04:07:08.022591 UTC - Check List Flavors 2026-07-12 04:07:08.022743 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-12 04:07:10.428905 UTC - Check List DiskImages 2026-07-12 04:07:10.432534 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-12 04:07:12.127379 UTC - Requesting resources 2026-07-12 04:07:12.136300 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-12 04:07:12.136464 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-46f04db4 --user-data /tmp/tmps6f3adgo 2026-07-12 04:07:18.459091 UTC - Resource ID: r_gr-volos-bi-vm1_01kxa844bgfv08v7phmk96wcvf 2026-07-12 04:07:18.468113 UTC - Waiting until resource ready 2026-07-12 04:07:20.468485 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-46f04db4 tst 2026-07-12 04:07:22.105100 UTC - Status: BOOTING 2026-07-12 04:07:25.021131 UTC - Status: BOOTING 2026-07-12 04:07:27.948221 UTC - Status: BOOTING 2026-07-12 04:07:30.864377 UTC - Status: BOOTING 2026-07-12 04:07:33.782302 UTC - Status: BOOTING 2026-07-12 04:07:37.013199 UTC - Status: BOOTING 2026-07-12 04:07:39.879546 UTC - Status: BOOTING 2026-07-12 04:07:42.907901 UTC - Status: BOOTING 2026-07-12 04:07:45.774335 UTC - Status: BOOTING 2026-07-12 04:07:48.740661 UTC - Status: BOOTING 2026-07-12 04:07:51.757447 UTC - Status: BOOTING 2026-07-12 04:07:55.044399 UTC - Status: BOOTING 2026-07-12 04:07:57.910454 UTC - Status: BOOTING 2026-07-12 04:08:01.283421 UTC - Status: BOOTING 2026-07-12 04:08:04.502713 UTC - Status: UP 2026-07-12 04:08:04.502799 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxa841cyft597as9gsynx6rr 2026-07-12 04:08:04.502843 UTC - Validate resources 2026-07-12 04:08:06.225615 UTC - The fields of the created resource were validated. 2026-07-12 04:08:06.225715 UTC - Check if resources are registered in experiment 2026-07-12 04:08:06.225863 UTC - Run: slices experiment list-resources --format json tst-46f04db4 2026-07-12 04:08:07.112083 UTC - Status (on expauth): UP 2026-07-12 04:08:07.112221 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 04:08:07.112356 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-46f04db4 2026-07-12 04:08:10.655898 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-46f04db4 tst 2026-07-12 04:08:13.397490 UTC - Run: slices experiment list-resources --format json tst-46f04db4 2026-07-12 04:08:14.738261 UTC - expires_at (on expauth): 2026-07-12T07:08:00Z (correctly extended) 2026-07-12 04:08:14.738676 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 04:08:14.738849 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-46f04db4 2026-07-12 04:08:18.214884 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-46f04db4 tst 2026-07-12 04:08:19.181083 UTC - Run: slices experiment list-resources --format json tst-46f04db4 2026-07-12 04:08:20.149294 UTC - expires_at (on expauth): 2026-07-12T08:08:00Z (correctly extended) 2026-07-12 04:08:20.149404 UTC - Testing ssh login 2026-07-12 04:08:20.165990 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-46f04db4 tst 2026-07-12 04:08:21.770295 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-46f04db4 tst 2026-07-12 04:08:22.786408 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-46f04db4 tst 2026-07-12 04:08:24.063515 UTC - Logging in using 'slices bi ssh' 2026-07-12 04:08:24.063587 UTC - Forcing IPv4 only. 2026-07-12 04:08:24.063736 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-46f04db4 tst -- -4 uname -a 2026-07-12 04:08:28.742687 UTC - Forcing IPv4 only. 2026-07-12 04:08:28.742856 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-46f04db4 tst -- -4 uptime 2026-07-12 04:08:31.621349 UTC - CLI SSH Test passed. 2026-07-12 04:08:31.621420 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-12 04:08:31.621440 UTC - Uptime: 04:08:31 up 1 min, 1 user, load average: 0.88, 0.31, 0.11 2026-07-12 04:08:31.621474 UTC - Logging in using SSH over SSH proxy 2026-07-12 04:08:31.621608 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-46f04db4 tst 2026-07-12 04:08:32.904698 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-12 04:08:32.904937 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-12 04:08:32.904999 UTC - Added paramiko HostKeyEntry for 10.64.45.254 2026-07-12 04:08:32.905282 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-12 04:08:32.905374 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-12 04:08:32.905417 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-12 04:08:32.905435 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-12 04:08:33.649939 UTC - Connecting to 10.64.45.254:22 over proxy 2026-07-12 04:08:35.054144 UTC - SSH Test output: 2026-07-12 04:08:35.054216 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-12 04:08:35.054235 UTC - Uptime: 04:08:34 up 1 min, 1 user, load average: 0.88, 0.31, 0.11 2026-07-12 04:08:35.360796 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-12 04:08:35.360871 UTC - lsb_release matches expected value 2026-07-12 04:08:35.360893 UTC - SSH Test passed. 2026-07-12 04:08:35.664983 UTC - Cloud-init user-data: Hello World. The time is now Sun, 12 Jul 2026 04:07:59 +0000! 2026-07-12 04:08:35.673192 UTC - Destroying tst-46f04db4 tst 2026-07-12 04:08:35.673431 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-46f04db4 tst