Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.50s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 203ms 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 669ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + 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.3 + 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-07 04:02:44.700811 UTC - Installing Slices CLI (latest stable version). 2026-08-07 04:02:44.701440 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-07 04:02:49.727242 UTC - Slices CLI installed. 2026-08-07 04:02:49.727549 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-07 04:02:49.728736 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-07 04:02:49.728805 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-07 04:02:49.728873 UTC - which slices: /app/.venv/bin/slices 2026-08-07 04:02:49.728892 UTC - Slices CLI exe: 'slices' 2026-08-07 04:02:49.728910 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-07 04:02:49.729040 UTC - Run: slices --version 2026-08-07 04:02:52.451784 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-07 04:02:52.452044 UTC - Run: slices pubkey list --format text 2026-08-07 04:02:53.177146 UTC - Pubkey already registered 2026-08-07 04:02:53.177390 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-07 04:02:53.792549 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-07 04:02:53.792656 UTC - Check List Flavors 2026-08-07 04:02:53.792874 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-08-07 04:02:54.809465 UTC - Check List DiskImages 2026-08-07 04:02:54.809689 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-08-07 04:02:55.525330 UTC - Requesting resources 2026-08-07 04:02:55.525806 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-07 04:02:55.525918 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-971722eb --user-data /tmp/tmpctj3bxpy 2026-08-07 04:02:57.895505 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kzd66w0hfr8a576zed65z4gs 2026-08-07 04:02:57.895675 UTC - Waiting until resource ready 2026-08-07 04:02:59.896107 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-971722eb tst 2026-08-07 04:03:00.662038 UTC - Status: IMAGING 2026-08-07 04:03:03.479226 UTC - Status: IMAGING 2026-08-07 04:03:06.295889 UTC - Status: IMAGING 2026-08-07 04:03:09.212858 UTC - Status: IMAGING 2026-08-07 04:03:12.079448 UTC - Status: BOOTING 2026-08-07 04:03:14.945495 UTC - Status: BOOTING 2026-08-07 04:03:17.811672 UTC - Status: BOOTING 2026-08-07 04:03:20.632921 UTC - Status: BOOTING 2026-08-07 04:03:23.499027 UTC - Status: BOOTING 2026-08-07 04:03:26.315649 UTC - Status: BOOTING 2026-08-07 04:03:29.133899 UTC - Status: UP 2026-08-07 04:03:29.133973 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzd66tjnefsvd7mvthackpt4 2026-08-07 04:03:29.134014 UTC - Validate resources 2026-08-07 04:03:29.950130 UTC - The fields of the created resource were validated. 2026-08-07 04:03:29.950233 UTC - Check if resources are registered in experiment 2026-08-07 04:03:29.950397 UTC - Run: slices experiment list-resources --format json tst-971722eb 2026-08-07 04:03:30.615862 UTC - Status (on expauth): UP 2026-08-07 04:03:30.616000 UTC - Testing extend expires_at (all resources in experiment) 2026-08-07 04:03:30.616128 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-971722eb 2026-08-07 04:03:32.484459 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-971722eb tst 2026-08-07 04:03:33.300824 UTC - Run: slices experiment list-resources --format json tst-971722eb 2026-08-07 04:03:33.966739 UTC - expires_at (on expauth): 2026-08-07T07:03:00Z (correctly extended) 2026-08-07 04:03:33.966837 UTC - Testing extend expires_at (single resource in experiment) 2026-08-07 04:03:33.967019 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-971722eb 2026-08-07 04:03:35.835341 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-971722eb tst 2026-08-07 04:03:36.701316 UTC - Run: slices experiment list-resources --format json tst-971722eb 2026-08-07 04:03:37.366844 UTC - expires_at (on expauth): 2026-08-07T08:03:00Z (correctly extended) 2026-08-07 04:03:37.366995 UTC - Testing ssh login 2026-08-07 04:03:37.379723 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-971722eb tst 2026-08-07 04:03:38.396064 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-971722eb tst 2026-08-07 04:03:39.462781 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-971722eb tst 2026-08-07 04:03:40.487128 UTC - Logging in using 'slices bi ssh' 2026-08-07 04:03:40.487216 UTC - Forcing IPv4 only. 2026-08-07 04:03:40.487352 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-971722eb tst -- -4 uname -a 2026-08-07 04:03:43.407673 UTC - Forcing IPv4 only. 2026-08-07 04:03:43.407859 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-971722eb tst -- -4 uptime 2026-08-07 04:03:45.376359 UTC - CLI SSH Test passed. 2026-08-07 04:03:45.376446 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-07 04:03:45.376475 UTC - Uptime: 04:03:44 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-08-07 04:03:45.376526 UTC - Logging in using SSH over SSH proxy 2026-08-07 04:03:45.376684 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-971722eb tst 2026-08-07 04:03:46.393468 UTC - Added paramiko HostKeyEntry for 10.11.0.91 2026-08-07 04:03:46.393664 UTC - Added paramiko HostKeyEntry for 10.11.0.91 2026-08-07 04:03:46.393736 UTC - Added paramiko HostKeyEntry for 10.11.0.91 2026-08-07 04:03:46.394252 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-07 04:03:46.394367 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-07 04:03:46.394417 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-07 04:03:46.394434 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-08-07 04:03:46.883924 UTC - Connecting to 10.11.0.91:22 over proxy 2026-08-07 04:03:47.622876 UTC - SSH Test output: 2026-08-07 04:03:47.622945 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-07 04:03:47.622966 UTC - Uptime: 04:03:46 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-08-07 04:03:47.776162 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 04:03:47.776248 UTC - lsb_release matches expected value 2026-08-07 04:03:47.776270 UTC - SSH Test passed. 2026-08-07 04:03:47.932527 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 04:03:25 +0000! 2026-08-07 04:03:47.940539 UTC - Destroying tst-971722eb tst 2026-08-07 04:03:47.940811 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-971722eb tst