Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.29s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 186ms 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 235ms + 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.3 Run Test: slices-bi-singlenode-login 2026-08-11 04:01:57.419332 UTC - Installing Slices CLI (latest stable version). 2026-08-11 04:01:57.419546 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-11 04:02:03.097970 UTC - Slices CLI installed. 2026-08-11 04:02:03.098270 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-11 04:02:03.099449 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-11 04:02:03.099526 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-11 04:02:03.099606 UTC - which slices: /app/.venv/bin/slices 2026-08-11 04:02:03.099625 UTC - Slices CLI exe: 'slices' 2026-08-11 04:02:03.099640 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-11 04:02:03.099796 UTC - Run: slices --version 2026-08-11 04:02:05.822434 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-11 04:02:05.822736 UTC - Run: slices pubkey list --format text 2026-08-11 04:02:06.547443 UTC - Pubkey already registered 2026-08-11 04:02:06.547680 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-11 04:02:07.162929 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-11 04:02:07.163034 UTC - Check List Flavors 2026-08-11 04:02:07.163196 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-08-11 04:02:08.179569 UTC - Check List DiskImages 2026-08-11 04:02:08.179768 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-08-11 04:02:08.895895 UTC - Requesting resources 2026-08-11 04:02:08.896627 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-11 04:02:08.896777 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-17550a12 --user-data /tmp/tmpzvxfw__r 2026-08-11 04:02:11.365808 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kzqfrajffahtrwm3mtv4nxzr 2026-08-11 04:02:11.365954 UTC - Waiting until resource ready 2026-08-11 04:02:13.366330 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-17550a12 tst 2026-08-11 04:02:14.181984 UTC - Status: IMAGING 2026-08-11 04:02:16.997926 UTC - Status: IMAGING 2026-08-11 04:02:19.816697 UTC - Status: IMAGING 2026-08-11 04:02:22.632859 UTC - Status: IMAGING 2026-08-11 04:02:25.448970 UTC - Status: BOOTING 2026-08-11 04:02:28.265315 UTC - Status: BOOTING 2026-08-11 04:02:31.131757 UTC - Status: BOOTING 2026-08-11 04:02:33.998304 UTC - Status: BOOTING 2026-08-11 04:02:36.814297 UTC - Status: BOOTING 2026-08-11 04:02:39.739698 UTC - Status: BOOTING 2026-08-11 04:02:42.657558 UTC - Status: UP 2026-08-11 04:02:42.657652 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzqfr91tevh97tp4gz3zghmj 2026-08-11 04:02:42.657693 UTC - Validate resources 2026-08-11 04:02:43.523872 UTC - The fields of the created resource were validated. 2026-08-11 04:02:43.523960 UTC - Check if resources are registered in experiment 2026-08-11 04:02:43.524134 UTC - Run: slices experiment list-resources --format json tst-17550a12 2026-08-11 04:02:44.189551 UTC - Status (on expauth): UP 2026-08-11 04:02:44.189705 UTC - Testing extend expires_at (all resources in experiment) 2026-08-11 04:02:44.189873 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-17550a12 2026-08-11 04:02:46.107979 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-17550a12 tst 2026-08-11 04:02:46.974302 UTC - Run: slices experiment list-resources --format json tst-17550a12 2026-08-11 04:02:47.689874 UTC - expires_at (on expauth): 2026-08-11T07:02:00Z (correctly extended) 2026-08-11 04:02:47.689965 UTC - Testing extend expires_at (single resource in experiment) 2026-08-11 04:02:47.690138 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-17550a12 2026-08-11 04:02:49.712525 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-17550a12 tst 2026-08-11 04:02:50.680850 UTC - Run: slices experiment list-resources --format json tst-17550a12 2026-08-11 04:02:51.449444 UTC - expires_at (on expauth): 2026-08-11T08:02:00Z (correctly extended) 2026-08-11 04:02:51.449567 UTC - Testing ssh login 2026-08-11 04:02:51.465061 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-17550a12 tst 2026-08-11 04:02:52.482665 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-17550a12 tst 2026-08-11 04:02:53.449532 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-17550a12 tst 2026-08-11 04:02:54.467796 UTC - Logging in using 'slices bi ssh' 2026-08-11 04:02:54.467921 UTC - Forcing IPv4 only. 2026-08-11 04:02:54.468154 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-17550a12 tst -- -4 uname -a 2026-08-11 04:02:57.097422 UTC - Forcing IPv4 only. 2026-08-11 04:02:57.097808 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-17550a12 tst -- -4 uptime 2026-08-11 04:02:59.067585 UTC - CLI SSH Test passed. 2026-08-11 04:02:59.067673 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-11 04:02:59.067692 UTC - Uptime: 04:02:58 up 0 min, 1 user, load average: 1.66, 0.42, 0.14 2026-08-11 04:02:59.067726 UTC - Logging in using SSH over SSH proxy 2026-08-11 04:02:59.067860 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-17550a12 tst 2026-08-11 04:03:00.295024 UTC - Added paramiko HostKeyEntry for 10.11.0.12 2026-08-11 04:03:00.295223 UTC - Added paramiko HostKeyEntry for 10.11.0.12 2026-08-11 04:03:00.295291 UTC - Added paramiko HostKeyEntry for 10.11.0.12 2026-08-11 04:03:00.295547 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-11 04:03:00.295627 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-11 04:03:00.295670 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-11 04:03:00.295687 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-08-11 04:03:00.804106 UTC - Connecting to 10.11.0.12:22 over proxy 2026-08-11 04:03:01.545038 UTC - SSH Test output: 2026-08-11 04:03:01.545107 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-11 04:03:01.545129 UTC - Uptime: 04:03:01 up 0 min, 1 user, load average: 1.52, 0.42, 0.14 2026-08-11 04:03:01.698851 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-11 04:03:01.698925 UTC - lsb_release matches expected value 2026-08-11 04:03:01.698946 UTC - SSH Test passed. 2026-08-11 04:03:01.853909 UTC - Cloud-init user-data: Hello World. The time is now Tue, 11 Aug 2026 04:02:40 +0000! 2026-08-11 04:03:01.866178 UTC - Destroying tst-17550a12 tst 2026-08-11 04:03:01.866359 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-17550a12 tst