Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.66s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 229ms 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 251ms + 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-10 20:06:56.524280 UTC - Installing Slices CLI (latest stable version). 2026-07-10 20:06:56.524656 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 20:07:03.126174 UTC - Slices CLI installed. 2026-07-10 20:07:03.126373 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-10 20:07:03.128746 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-10 20:07:03.129938 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 20:07:03.130091 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-10 20:07:03.130147 UTC - Slices CLI exe: 'slices' 2026-07-10 20:07:03.130167 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 20:07:03.130320 UTC - Run: slices --version 2026-07-10 20:07:07.893214 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-10 20:07:07.893719 UTC - Run: slices pubkey list --format text 2026-07-10 20:07:09.839055 UTC - Pubkey already registered 2026-07-10 20:07:09.839359 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 20:07:11.531153 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 20:07:11.531268 UTC - Check List Flavors 2026-07-10 20:07:11.531432 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-10 20:07:13.823436 UTC - Check List DiskImages 2026-07-10 20:07:13.823814 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-10 20:07:16.347353 UTC - Requesting resources 2026-07-10 20:07:16.348335 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 20:07:16.348456 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9a456255 --user-data /tmp/tmpp7gqw_2x 2026-07-10 20:07:23.219862 UTC - Resource ID: r_gr-volos-bi-vm1_01kx6t8mq7e9sb39npa7pmzh1r 2026-07-10 20:07:23.221839 UTC - Waiting until resource ready 2026-07-10 20:07:25.222520 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9a456255 tst 2026-07-10 20:07:26.238950 UTC - Status: BOOTING 2026-07-10 20:07:29.205794 UTC - Status: BOOTING 2026-07-10 20:07:32.330727 UTC - Status: BOOTING 2026-07-10 20:07:36.033443 UTC - Status: BOOTING 2026-07-10 20:07:39.049439 UTC - Status: BOOTING 2026-07-10 20:07:42.018105 UTC - Status: BOOTING 2026-07-10 20:07:45.100711 UTC - Status: BOOTING 2026-07-10 20:07:48.478536 UTC - Status: BOOTING 2026-07-10 20:07:52.147182 UTC - Status: BOOTING 2026-07-10 20:07:55.425747 UTC - Status: BOOTING 2026-07-10 20:07:59.648619 UTC - Status: BOOTING 2026-07-10 20:08:03.493306 UTC - Status: BOOTING 2026-07-10 20:08:07.505974 UTC - Status: UP 2026-07-10 20:08:07.506078 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx6t8h68ebs80eym1f50b8f7 2026-07-10 20:08:07.506122 UTC - Validate resources 2026-07-10 20:08:08.526887 UTC - The fields of the created resource were validated. 2026-07-10 20:08:08.526993 UTC - Check if resources are registered in experiment 2026-07-10 20:08:08.527153 UTC - Run: slices experiment list-resources --format json tst-9a456255 2026-07-10 20:08:09.243442 UTC - Status (on expauth): UP 2026-07-10 20:08:09.243592 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 20:08:09.243720 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-9a456255 2026-07-10 20:08:13.819097 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9a456255 tst 2026-07-10 20:08:15.429336 UTC - Run: slices experiment list-resources --format json tst-9a456255 2026-07-10 20:08:16.569039 UTC - expires_at (on expauth): 2026-07-10T23:08:00Z (correctly extended) 2026-07-10 20:08:16.569136 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 20:08:16.569274 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-9a456255 2026-07-10 20:08:20.451132 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9a456255 tst 2026-07-10 20:08:22.637424 UTC - Run: slices experiment list-resources --format json tst-9a456255 2026-07-10 20:08:23.406279 UTC - expires_at (on expauth): 2026-07-11T00:08:00Z (correctly extended) 2026-07-10 20:08:23.406458 UTC - Testing ssh login 2026-07-10 20:08:23.425832 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9a456255 tst 2026-07-10 20:08:24.657291 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9a456255 tst 2026-07-10 20:08:26.785247 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9a456255 tst 2026-07-10 20:08:28.216915 UTC - Logging in using 'slices bi ssh' 2026-07-10 20:08:28.217003 UTC - Forcing IPv4 only. 2026-07-10 20:08:28.217149 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-9a456255 tst -- -4 uname -a 2026-07-10 20:08:33.245101 UTC - Forcing IPv4 only. 2026-07-10 20:08:33.245287 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-9a456255 tst -- -4 uptime 2026-07-10 20:08:36.549014 UTC - CLI SSH Test passed. 2026-07-10 20:08:36.549103 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-10 20:08:36.549124 UTC - Uptime: 20:08:36 up 1 min, 1 user, load average: 0.66, 0.24, 0.09 2026-07-10 20:08:36.549157 UTC - Logging in using SSH over SSH proxy 2026-07-10 20:08:36.549294 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9a456255 tst 2026-07-10 20:08:38.255716 UTC - Added paramiko HostKeyEntry for 10.64.45.242 2026-07-10 20:08:38.255949 UTC - Added paramiko HostKeyEntry for 10.64.45.242 2026-07-10 20:08:38.256025 UTC - Added paramiko HostKeyEntry for 10.64.45.242 2026-07-10 20:08:38.256308 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 20:08:38.256389 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 20:08:38.256433 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-10 20:08:38.256452 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-10 20:08:39.024472 UTC - Connecting to 10.64.45.242:22 over proxy 2026-07-10 20:08:40.428558 UTC - SSH Test output: 2026-07-10 20:08:40.428627 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-10 20:08:40.428645 UTC - Uptime: 20:08:40 up 1 min, 1 user, load average: 0.61, 0.24, 0.09 2026-07-10 20:08:40.734422 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 20:08:40.734496 UTC - lsb_release matches expected value 2026-07-10 20:08:40.734515 UTC - SSH Test passed. 2026-07-10 20:08:41.052180 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 20:08:03 +0000! 2026-07-10 20:08:41.064983 UTC - Destroying tst-9a456255 tst 2026-07-10 20:08:41.065178 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-9a456255 tst