Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.05s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 218ms 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 632ms + annotated-types==0.7.0 + anyio==4.14.2 + 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.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.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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-20 13:01:05.195455 UTC - Installing Slices CLI (latest stable version). 2026-07-20 13:01:05.195673 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 13:01:11.674406 UTC - Slices CLI installed. 2026-07-20 13:01:11.674589 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-20 13:01:11.676635 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-20 13:01:11.677357 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 13:01:11.677469 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-20 13:01:11.677494 UTC - Slices CLI exe: 'slices' 2026-07-20 13:01:11.677511 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 13:01:11.677628 UTC - Run: slices --version 2026-07-20 13:01:14.196679 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-20 13:01:14.197063 UTC - Run: slices pubkey list --format text 2026-07-20 13:01:15.012650 UTC - Pubkey already registered 2026-07-20 13:01:15.012925 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 13:01:15.577982 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 13:01:15.578078 UTC - Check List Flavors 2026-07-20 13:01:15.578233 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-20 13:01:17.596602 UTC - Check List DiskImages 2026-07-20 13:01:17.596800 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-20 13:01:18.312324 UTC - Requesting resources 2026-07-20 13:01:18.312873 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 13:01:18.313004 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-bf6b74cd --user-data /tmp/tmpmc3gczsy 2026-07-20 13:01:23.639062 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxzsvth9f00acvm3qeg0rtrf 2026-07-20 13:01:23.639226 UTC - Waiting until resource ready 2026-07-20 13:01:25.639585 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-bf6b74cd tst 2026-07-20 13:01:26.405052 UTC - Status: IMAGING 2026-07-20 13:01:29.171387 UTC - Status: IMAGING 2026-07-20 13:01:32.037724 UTC - Status: IMAGING 2026-07-20 13:01:34.854177 UTC - Status: IMAGING 2026-07-20 13:01:37.727061 UTC - Status: BOOTING 2026-07-20 13:01:40.545861 UTC - Status: BOOTING 2026-07-20 13:01:43.365989 UTC - Status: BOOTING 2026-07-20 13:01:46.181910 UTC - Status: BOOTING 2026-07-20 13:01:49.000084 UTC - Status: BOOTING 2026-07-20 13:01:51.765819 UTC - Status: BOOTING 2026-07-20 13:01:54.581630 UTC - Status: UP 2026-07-20 13:01:54.581753 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxzsvqzqfvw9pqftz5xce2px 2026-07-20 13:01:54.581796 UTC - Validate resources 2026-07-20 13:01:55.397687 UTC - The fields of the created resource were validated. 2026-07-20 13:01:55.397779 UTC - Check if resources are registered in experiment 2026-07-20 13:01:55.397937 UTC - Run: slices experiment list-resources --format json tst-bf6b74cd 2026-07-20 13:01:56.013103 UTC - Status (on expauth): UP 2026-07-20 13:01:56.013234 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 13:01:56.013375 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-bf6b74cd 2026-07-20 13:01:59.086490 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-bf6b74cd tst 2026-07-20 13:01:59.902319 UTC - Run: slices experiment list-resources --format json tst-bf6b74cd 2026-07-20 13:02:00.517896 UTC - expires_at (on expauth): 2026-07-20T16:01:00Z (correctly extended) 2026-07-20 13:02:00.517991 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 13:02:00.518131 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-bf6b74cd 2026-07-20 13:02:03.388758 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-bf6b74cd tst 2026-07-20 13:02:04.204843 UTC - Run: slices experiment list-resources --format json tst-bf6b74cd 2026-07-20 13:02:04.820225 UTC - expires_at (on expauth): 2026-07-20T17:02:00Z (correctly extended) 2026-07-20 13:02:04.820382 UTC - Testing ssh login 2026-07-20 13:02:04.831566 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-bf6b74cd tst 2026-07-20 13:02:05.851533 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-bf6b74cd tst 2026-07-20 13:02:06.869016 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-bf6b74cd tst 2026-07-20 13:02:07.836982 UTC - Logging in using 'slices bi ssh' 2026-07-20 13:02:07.837103 UTC - Forcing IPv4 only. 2026-07-20 13:02:07.837284 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-bf6b74cd tst -- -4 uname -a 2026-07-20 13:02:10.664947 UTC - Forcing IPv4 only. 2026-07-20 13:02:10.665699 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-bf6b74cd tst -- -4 uptime 2026-07-20 13:02:12.583998 UTC - CLI SSH Test passed. 2026-07-20 13:02:12.584236 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-20 13:02:12.584275 UTC - Uptime: 13:02:12 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-07-20 13:02:12.584311 UTC - Logging in using SSH over SSH proxy 2026-07-20 13:02:12.584451 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-bf6b74cd tst 2026-07-20 13:02:13.616445 UTC - Added paramiko HostKeyEntry for 10.11.0.41 2026-07-20 13:02:13.616688 UTC - Added paramiko HostKeyEntry for 10.11.0.41 2026-07-20 13:02:13.616795 UTC - Added paramiko HostKeyEntry for 10.11.0.41 2026-07-20 13:02:13.618007 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 13:02:13.618158 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 13:02:13.618257 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 13:02:13.618288 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-20 13:02:14.055802 UTC - Connecting to 10.11.0.41:22 over proxy 2026-07-20 13:02:14.785119 UTC - SSH Test output: 2026-07-20 13:02:14.785193 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-20 13:02:14.785215 UTC - Uptime: 13:02:14 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-07-20 13:02:14.938844 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 13:02:14.938940 UTC - lsb_release matches expected value 2026-07-20 13:02:14.938964 UTC - SSH Test passed. 2026-07-20 13:02:15.091835 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 13:01:51 +0000! 2026-07-20 13:02:15.102280 UTC - Destroying tst-bf6b74cd tst 2026-07-20 13:02:15.102472 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-bf6b74cd tst