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 199ms 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 245ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-17 13:00:51.737482 UTC - Installing Slices CLI (latest stable version). 2026-07-17 13:00:51.737986 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 13:00:56.963033 UTC - Slices CLI installed. 2026-07-17 13:00:56.963241 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-17 13:00:56.964401 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-17 13:00:56.965129 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 13:00:56.965241 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-17 13:00:56.965269 UTC - Slices CLI exe: 'slices' 2026-07-17 13:00:56.965286 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 13:00:56.965407 UTC - Run: slices --version 2026-07-17 13:00:59.484149 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-17 13:00:59.484415 UTC - Run: slices pubkey list --format text 2026-07-17 13:01:00.349910 UTC - Pubkey already registered 2026-07-17 13:01:00.350150 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 13:01:00.965303 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 13:01:00.965415 UTC - Check List Flavors 2026-07-17 13:01:00.965630 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-17 13:01:02.633782 UTC - Check List DiskImages 2026-07-17 13:01:02.634099 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-17 13:01:03.349679 UTC - Requesting resources 2026-07-17 13:01:03.350187 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 13:01:03.350297 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-4b914178 --user-data /tmp/tmp81dtjnul 2026-07-17 13:01:07.673505 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxr2n5zqf5987996ytxpkkxy 2026-07-17 13:01:07.673726 UTC - Waiting until resource ready 2026-07-17 13:01:09.674136 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-4b914178 tst 2026-07-17 13:01:10.640320 UTC - Status: IMAGING 2026-07-17 13:01:13.457064 UTC - Status: IMAGING 2026-07-17 13:01:16.323696 UTC - Status: IMAGING 2026-07-17 13:01:19.140058 UTC - Status: IMAGING 2026-07-17 13:01:22.056142 UTC - Status: BOOTING 2026-07-17 13:01:24.829798 UTC - Status: BOOTING 2026-07-17 13:01:28.698838 UTC - Status: BOOTING 2026-07-17 13:01:31.514746 UTC - Status: BOOTING 2026-07-17 13:01:34.330743 UTC - Status: BOOTING 2026-07-17 13:01:37.146784 UTC - Status: BOOTING 2026-07-17 13:01:39.963459 UTC - Status: UP 2026-07-17 13:01:39.963603 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxr2n4eweky9fb2pvr2drvmp 2026-07-17 13:01:39.963659 UTC - Validate resources 2026-07-17 13:01:40.780124 UTC - The fields of the created resource were validated. 2026-07-17 13:01:40.780246 UTC - Check if resources are registered in experiment 2026-07-17 13:01:40.780427 UTC - Run: slices experiment list-resources --format json tst-4b914178 2026-07-17 13:01:41.445878 UTC - Status (on expauth): UP 2026-07-17 13:01:41.446021 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 13:01:41.446140 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-4b914178 2026-07-17 13:01:45.269265 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-4b914178 tst 2026-07-17 13:01:46.136039 UTC - Run: slices experiment list-resources --format json tst-4b914178 2026-07-17 13:01:46.851942 UTC - expires_at (on expauth): 2026-07-17T16:01:00Z (correctly extended) 2026-07-17 13:01:46.852073 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 13:01:46.852249 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-4b914178 2026-07-17 13:01:50.526575 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-4b914178 tst 2026-07-17 13:01:51.405893 UTC - Run: slices experiment list-resources --format json tst-4b914178 2026-07-17 13:01:52.071435 UTC - expires_at (on expauth): 2026-07-17T17:01:00Z (correctly extended) 2026-07-17 13:01:52.071578 UTC - Testing ssh login 2026-07-17 13:01:52.084538 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4b914178 tst 2026-07-17 13:01:53.160771 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4b914178 tst 2026-07-17 13:01:54.078083 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-4b914178 tst 2026-07-17 13:01:55.194464 UTC - Logging in using 'slices bi ssh' 2026-07-17 13:01:55.194553 UTC - Forcing IPv4 only. 2026-07-17 13:01:55.194675 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-4b914178 tst -- -4 uname -a 2026-07-17 13:01:58.496554 UTC - Forcing IPv4 only. 2026-07-17 13:01:58.496995 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-4b914178 tst -- -4 uptime 2026-07-17 13:02:00.737597 UTC - CLI SSH Test passed. 2026-07-17 13:02:00.737676 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-17 13:02:00.737695 UTC - Uptime: 13:02:00 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-07-17 13:02:00.737730 UTC - Logging in using SSH over SSH proxy 2026-07-17 13:02:00.737854 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-4b914178 tst 2026-07-17 13:02:01.959554 UTC - Added paramiko HostKeyEntry for 10.11.0.31 2026-07-17 13:02:01.959780 UTC - Added paramiko HostKeyEntry for 10.11.0.31 2026-07-17 13:02:01.959861 UTC - Added paramiko HostKeyEntry for 10.11.0.31 2026-07-17 13:02:01.960153 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-17 13:02:01.960250 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-17 13:02:01.960292 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-17 13:02:01.960311 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-17 13:02:02.470455 UTC - Connecting to 10.11.0.31:22 over proxy 2026-07-17 13:02:03.199873 UTC - SSH Test output: 2026-07-17 13:02:03.199942 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-17 13:02:03.199962 UTC - Uptime: 13:02:03 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-17 13:02:03.352256 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 13:02:03.352328 UTC - lsb_release matches expected value 2026-07-17 13:02:03.352348 UTC - SSH Test passed. 2026-07-17 13:02:03.505679 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 13:01:38 +0000! 2026-07-17 13:02:03.515041 UTC - Destroying tst-4b914178 tst 2026-07-17 13:02:03.515218 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-4b914178 tst