Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.16s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 194ms 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 242ms + 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.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-16 20:00:48.842879 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:00:48.843118 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:00:54.270304 UTC - Slices CLI installed. 2026-07-16 20:00:54.270513 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-16 20:00:54.271706 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-16 20:00:54.272407 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:00:54.272527 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-16 20:00:54.272553 UTC - Slices CLI exe: 'slices' 2026-07-16 20:00:54.272569 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:00:54.272698 UTC - Run: slices --version 2026-07-16 20:00:56.893429 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-16 20:00:56.893710 UTC - Run: slices pubkey list --format text 2026-07-16 20:00:57.715895 UTC - Pubkey already registered 2026-07-16 20:00:57.716158 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:00:58.281308 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:00:58.281406 UTC - Check List Flavors 2026-07-16 20:00:58.281562 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-16 20:01:00.149661 UTC - Check List DiskImages 2026-07-16 20:01:00.149890 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-16 20:01:00.865194 UTC - Requesting resources 2026-07-16 20:01:00.865702 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:01:00.865860 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3b814992 --user-data /tmp/tmp4dqrqobg 2026-07-16 20:01:06.994373 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxp89fptf4g9xtgshnckcx3z 2026-07-16 20:01:06.994545 UTC - Waiting until resource ready 2026-07-16 20:01:08.994963 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-3b814992 tst 2026-07-16 20:01:09.760621 UTC - Status: IMAGING 2026-07-16 20:01:12.526747 UTC - Status: IMAGING 2026-07-16 20:01:15.343110 UTC - Status: IMAGING 2026-07-16 20:01:18.159196 UTC - Status: IMAGING 2026-07-16 20:01:20.925093 UTC - Status: BOOTING 2026-07-16 20:01:23.740886 UTC - Status: BOOTING 2026-07-16 20:01:26.557071 UTC - Status: BOOTING 2026-07-16 20:01:29.373131 UTC - Status: BOOTING 2026-07-16 20:01:32.188929 UTC - Status: BOOTING 2026-07-16 20:01:35.004876 UTC - Status: BOOTING 2026-07-16 20:01:37.820937 UTC - Status: UP 2026-07-16 20:01:37.821030 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp89cf6ecxadky4qkemc89f 2026-07-16 20:01:37.821071 UTC - Validate resources 2026-07-16 20:01:38.687274 UTC - The fields of the created resource were validated. 2026-07-16 20:01:38.687367 UTC - Check if resources are registered in experiment 2026-07-16 20:01:38.687527 UTC - Run: slices experiment list-resources --format json tst-3b814992 2026-07-16 20:01:39.352845 UTC - Status (on expauth): UP 2026-07-16 20:01:39.352988 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:01:39.353115 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-3b814992 2026-07-16 20:01:43.075056 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-3b814992 tst 2026-07-16 20:01:43.840593 UTC - Run: slices experiment list-resources --format json tst-3b814992 2026-07-16 20:01:44.455839 UTC - expires_at (on expauth): 2026-07-16T23:01:00Z (correctly extended) 2026-07-16 20:01:44.455945 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:01:44.456099 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-3b814992 2026-07-16 20:01:48.127781 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-3b814992 tst 2026-07-16 20:01:48.893580 UTC - Run: slices experiment list-resources --format json tst-3b814992 2026-07-16 20:01:49.508963 UTC - expires_at (on expauth): 2026-07-17T00:01:00Z (correctly extended) 2026-07-16 20:01:49.509079 UTC - Testing ssh login 2026-07-16 20:01:49.519667 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3b814992 tst 2026-07-16 20:01:50.436102 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3b814992 tst 2026-07-16 20:01:51.402605 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-3b814992 tst 2026-07-16 20:01:52.318590 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:01:52.318673 UTC - Forcing IPv4 only. 2026-07-16 20:01:52.318823 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-3b814992 tst -- -4 uname -a 2026-07-16 20:01:55.039215 UTC - Forcing IPv4 only. 2026-07-16 20:01:55.039395 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-3b814992 tst -- -4 uptime 2026-07-16 20:01:57.109646 UTC - CLI SSH Test passed. 2026-07-16 20:01:57.109740 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-16 20:01:57.109759 UTC - Uptime: 20:01:57 up 0 min, 1 user, load average: 0.65, 0.17, 0.06 2026-07-16 20:01:57.109793 UTC - Logging in using SSH over SSH proxy 2026-07-16 20:01:57.109957 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-3b814992 tst 2026-07-16 20:01:58.228977 UTC - Added paramiko HostKeyEntry for 10.11.0.37 2026-07-16 20:01:58.229168 UTC - Added paramiko HostKeyEntry for 10.11.0.37 2026-07-16 20:01:58.229239 UTC - Added paramiko HostKeyEntry for 10.11.0.37 2026-07-16 20:01:58.229529 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-16 20:01:58.229620 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-16 20:01:58.229665 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-16 20:01:58.229685 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-16 20:01:58.728084 UTC - Connecting to 10.11.0.37:22 over proxy 2026-07-16 20:01:59.458655 UTC - SSH Test output: 2026-07-16 20:01:59.458728 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-16 20:01:59.458748 UTC - Uptime: 20:01:59 up 0 min, 1 user, load average: 0.68, 0.19, 0.06 2026-07-16 20:01:59.611451 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:01:59.611526 UTC - lsb_release matches expected value 2026-07-16 20:01:59.611548 UTC - SSH Test passed. 2026-07-16 20:01:59.765960 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 20:01:35 +0000! 2026-07-16 20:01:59.773501 UTC - Destroying tst-3b814992 tst 2026-07-16 20:01:59.773730 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-3b814992 tst