Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.00s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 212ms 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 293ms + 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-11 04:07:00.894983 UTC - Installing Slices CLI (latest stable version). 2026-07-11 04:07:00.897467 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 04:07:08.257833 UTC - Slices CLI installed. 2026-07-11 04:07:08.258519 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-11 04:07:08.263989 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-11 04:07:08.266280 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 04:07:08.266775 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-11 04:07:08.266818 UTC - Slices CLI exe: 'slices' 2026-07-11 04:07:08.266837 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 04:07:08.267129 UTC - Run: slices --version 2026-07-11 04:07:15.137556 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-11 04:07:15.138003 UTC - Run: slices pubkey list --format text 2026-07-11 04:07:17.998337 UTC - Pubkey already registered 2026-07-11 04:07:17.998625 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 04:07:20.485230 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 04:07:20.485345 UTC - Check List Flavors 2026-07-11 04:07:20.485532 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-11 04:07:23.584220 UTC - Check List DiskImages 2026-07-11 04:07:23.584407 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-11 04:07:25.372809 UTC - Requesting resources 2026-07-11 04:07:25.398413 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 04:07:25.398576 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-759abdd3 --user-data /tmp/tmpzvdxco14 2026-07-11 04:07:34.035272 UTC - Resource ID: r_gr-volos-bi-vm1_01kx7nqwmwen1s7054pk4y9h2k 2026-07-11 04:07:34.035795 UTC - Waiting until resource ready 2026-07-11 04:07:36.036215 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-759abdd3 tst 2026-07-11 04:07:37.002694 UTC - Status: BOOTING 2026-07-11 04:07:39.922883 UTC - Status: BOOTING 2026-07-11 04:07:42.939879 UTC - Status: BOOTING 2026-07-11 04:07:45.957051 UTC - Status: BOOTING 2026-07-11 04:07:49.035405 UTC - Status: BOOTING 2026-07-11 04:07:52.210537 UTC - Status: BOOTING 2026-07-11 04:07:55.789139 UTC - Status: BOOTING 2026-07-11 04:07:59.823469 UTC - Status: BOOTING 2026-07-11 04:08:03.773886 UTC - Status: BOOTING 2026-07-11 04:08:07.196097 UTC - Status: BOOTING 2026-07-11 04:08:10.868066 UTC - Status: BOOTING 2026-07-11 04:08:15.305636 UTC - Status: BOOTING 2026-07-11 04:08:19.444132 UTC - Status: UP 2026-07-11 04:08:19.444235 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx7nqq86fg7ry6wdfvxvf50v 2026-07-11 04:08:19.444284 UTC - Validate resources 2026-07-11 04:08:20.511602 UTC - The fields of the created resource were validated. 2026-07-11 04:08:20.511723 UTC - Check if resources are registered in experiment 2026-07-11 04:08:20.511903 UTC - Run: slices experiment list-resources --format json tst-759abdd3 2026-07-11 04:08:22.031109 UTC - Status (on expauth): UP 2026-07-11 04:08:22.031294 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 04:08:22.031447 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-759abdd3 2026-07-11 04:08:26.038543 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-759abdd3 tst 2026-07-11 04:08:27.106013 UTC - Run: slices experiment list-resources --format json tst-759abdd3 2026-07-11 04:08:28.029626 UTC - expires_at (on expauth): 2026-07-11T07:08:00Z (correctly extended) 2026-07-11 04:08:28.029757 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 04:08:28.029938 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-759abdd3 2026-07-11 04:08:32.338504 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-759abdd3 tst 2026-07-11 04:08:34.085657 UTC - Run: slices experiment list-resources --format json tst-759abdd3 2026-07-11 04:08:35.303297 UTC - expires_at (on expauth): 2026-07-11T08:08:00Z (correctly extended) 2026-07-11 04:08:35.303458 UTC - Testing ssh login 2026-07-11 04:08:35.334082 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-759abdd3 tst 2026-07-11 04:08:37.101216 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-759abdd3 tst 2026-07-11 04:08:38.697039 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-759abdd3 tst 2026-07-11 04:08:40.131251 UTC - Logging in using 'slices bi ssh' 2026-07-11 04:08:40.131343 UTC - Forcing IPv4 only. 2026-07-11 04:08:40.131495 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-759abdd3 tst -- -4 uname -a 2026-07-11 04:08:44.970570 UTC - Forcing IPv4 only. 2026-07-11 04:08:44.970812 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-759abdd3 tst -- -4 uptime 2026-07-11 04:08:48.751849 UTC - CLI SSH Test passed. 2026-07-11 04:08:48.751954 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-11 04:08:48.751976 UTC - Uptime: 04:08:47 up 1 min, 1 user, load average: 0.84, 0.28, 0.10 2026-07-11 04:08:48.752014 UTC - Logging in using SSH over SSH proxy 2026-07-11 04:08:48.752180 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-759abdd3 tst 2026-07-11 04:08:51.142660 UTC - Added paramiko HostKeyEntry for 10.64.45.247 2026-07-11 04:08:51.142899 UTC - Added paramiko HostKeyEntry for 10.64.45.247 2026-07-11 04:08:51.142989 UTC - Added paramiko HostKeyEntry for 10.64.45.247 2026-07-11 04:08:51.143549 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 04:08:51.143674 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 04:08:51.143735 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 04:08:51.143755 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-11 04:08:51.869148 UTC - Connecting to 10.64.45.247:22 over proxy 2026-07-11 04:08:53.839976 UTC - SSH Test output: 2026-07-11 04:08:53.840056 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-11 04:08:53.840078 UTC - Uptime: 04:08:52 up 1 min, 1 user, load average: 0.78, 0.27, 0.10 2026-07-11 04:08:54.138870 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 04:08:54.138979 UTC - lsb_release matches expected value 2026-07-11 04:08:54.139002 UTC - SSH Test passed. 2026-07-11 04:08:54.713970 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 04:08:17 +0000! 2026-07-11 04:08:54.728139 UTC - Destroying tst-759abdd3 tst 2026-07-11 04:08:54.728346 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-759abdd3 tst