Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.72s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 605ms 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 1.54s + 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:03:35.390970 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:03:35.391684 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:03:43.498612 UTC - Slices CLI installed. 2026-07-16 20:03:43.505842 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:03:43.510828 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:03:43.512807 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:03:43.512958 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:03:43.512987 UTC - Slices CLI exe: 'slices' 2026-07-16 20:03:43.513005 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:03:43.513134 UTC - Run: slices --version 2026-07-16 20:03:51.713752 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:03:51.714140 UTC - Run: slices pubkey list --format text 2026-07-16 20:03:53.060857 UTC - Pubkey already registered 2026-07-16 20:03:53.061103 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:03:54.962103 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:03:54.962230 UTC - Check List Flavors 2026-07-16 20:03:54.962411 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-16 20:03:57.259457 UTC - Check List DiskImages 2026-07-16 20:03:57.259629 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-16 20:03:59.263620 UTC - Requesting resources 2026-07-16 20:03:59.264113 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:03:59.264229 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-776b4360 --user-data /tmp/tmpxqr_s7_5 2026-07-16 20:04:04.174383 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxp8ewp7f7rvw4d6h4196gy9 2026-07-16 20:04:04.174860 UTC - Waiting until resource ready 2026-07-16 20:04:06.175244 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-776b4360 tst 2026-07-16 20:04:07.525328 UTC - Status: IMAGING 2026-07-16 20:04:10.913404 UTC - Status: IMAGING 2026-07-16 20:04:13.981131 UTC - Status: BOOTING 2026-07-16 20:04:16.998289 UTC - Status: BOOTING 2026-07-16 20:04:19.820873 UTC - Status: BOOTING 2026-07-16 20:04:23.130230 UTC - Status: BOOTING 2026-07-16 20:04:26.353333 UTC - Status: BOOTING 2026-07-16 20:04:29.474825 UTC - Status: BOOTING 2026-07-16 20:04:32.441375 UTC - Status: BOOTING 2026-07-16 20:04:35.207192 UTC - Status: BOOTING 2026-07-16 20:04:37.973135 UTC - Status: BOOTING 2026-07-16 20:04:40.790777 UTC - Status: UP 2026-07-16 20:04:40.790872 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp8etvmejc846y2d7rjpnxv 2026-07-16 20:04:40.790912 UTC - Validate resources 2026-07-16 20:04:41.656923 UTC - The fields of the created resource were validated. 2026-07-16 20:04:41.657037 UTC - Check if resources are registered in experiment 2026-07-16 20:04:41.657206 UTC - Run: slices experiment list-resources --format json tst-776b4360 2026-07-16 20:04:42.322602 UTC - Status (on expauth): UP 2026-07-16 20:04:42.322809 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:04:42.322969 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-776b4360 2026-07-16 20:04:45.243594 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-776b4360 tst 2026-07-16 20:04:46.009150 UTC - Run: slices experiment list-resources --format json tst-776b4360 2026-07-16 20:04:46.674416 UTC - expires_at (on expauth): 2026-07-16T23:04:00Z (correctly extended) 2026-07-16 20:04:46.674511 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:04:46.674678 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-776b4360 2026-07-16 20:04:49.848216 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-776b4360 tst 2026-07-16 20:04:50.614020 UTC - Run: slices experiment list-resources --format json tst-776b4360 2026-07-16 20:04:51.229288 UTC - expires_at (on expauth): 2026-07-17T00:04:00Z (correctly extended) 2026-07-16 20:04:51.229414 UTC - Testing ssh login 2026-07-16 20:04:51.242173 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-776b4360 tst 2026-07-16 20:04:52.211085 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-776b4360 tst 2026-07-16 20:04:53.177374 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-776b4360 tst 2026-07-16 20:04:54.143759 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:04:54.143861 UTC - Forcing IPv4 only. 2026-07-16 20:04:54.144003 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-776b4360 tst -- -4 uname -a 2026-07-16 20:04:57.015851 UTC - Forcing IPv4 only. 2026-07-16 20:04:57.016032 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-776b4360 tst -- -4 uptime 2026-07-16 20:04:58.886758 UTC - CLI SSH Test passed. 2026-07-16 20:04:58.886881 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-16 20:04:58.886904 UTC - Uptime: 20:04:58 up 0 min, 1 user, load average: 0.25, 0.08, 0.02 2026-07-16 20:04:58.886951 UTC - Logging in using SSH over SSH proxy 2026-07-16 20:04:58.887851 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-776b4360 tst 2026-07-16 20:05:00.005581 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-16 20:05:00.005722 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-16 20:05:00.005941 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-16 20:05:00.006234 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-16 20:05:00.006373 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-16 20:05:00.006459 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-16 20:05:00.006483 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-16 20:05:00.537111 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-16 20:05:01.485207 UTC - SSH Test output: 2026-07-16 20:05:01.485307 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-16 20:05:01.485330 UTC - Uptime: 20:05:01 up 0 min, 1 user, load average: 0.25, 0.08, 0.02 2026-07-16 20:05:01.688274 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:05:01.688378 UTC - lsb_release matches expected value 2026-07-16 20:05:01.688399 UTC - SSH Test passed. 2026-07-16 20:05:01.891605 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 20:04:40 +0000! 2026-07-16 20:05:01.899162 UTC - Destroying tst-776b4360 tst 2026-07-16 20:05:01.899380 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-776b4360 tst