Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.30s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 799ms 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.65s + 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-15 04:03:13.882395 UTC - Installing Slices CLI (latest stable version). 2026-07-15 04:03:13.884837 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 04:03:21.561373 UTC - Slices CLI installed. 2026-07-15 04:03:21.561718 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-15 04:03:21.563685 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-15 04:03:21.564614 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 04:03:21.564752 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-15 04:03:21.564782 UTC - Slices CLI exe: 'slices' 2026-07-15 04:03:21.564799 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 04:03:21.564919 UTC - Run: slices --version 2026-07-15 04:03:30.656752 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-15 04:03:30.657049 UTC - Run: slices pubkey list --format text 2026-07-15 04:03:33.419138 UTC - Pubkey already registered 2026-07-15 04:03:33.419412 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 04:03:34.910898 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 04:03:34.910989 UTC - Check List Flavors 2026-07-15 04:03:34.911167 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-15 04:03:37.265431 UTC - Check List DiskImages 2026-07-15 04:03:37.265605 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-15 04:03:39.097278 UTC - Requesting resources 2026-07-15 04:03:39.097761 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 04:03:39.097869 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e710b7dd --user-data /tmp/tmptaowfeqz 2026-07-15 04:03:44.954514 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxhz3rxtf5gvpvzcprqzm51d 2026-07-15 04:03:44.954679 UTC - Waiting until resource ready 2026-07-15 04:03:46.954984 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-e710b7dd tst 2026-07-15 04:03:48.862795 UTC - Status: IMAGING 2026-07-15 04:03:52.661439 UTC - Status: IMAGING 2026-07-15 04:03:56.289564 UTC - Status: BOOTING 2026-07-15 04:03:59.462820 UTC - Status: BOOTING 2026-07-15 04:04:03.098994 UTC - Status: BOOTING 2026-07-15 04:04:06.534615 UTC - Status: BOOTING 2026-07-15 04:04:09.567383 UTC - Status: BOOTING 2026-07-15 04:04:12.789162 UTC - Status: BOOTING 2026-07-15 04:04:15.959718 UTC - Status: BOOTING 2026-07-15 04:04:18.883177 UTC - Status: BOOTING 2026-07-15 04:04:21.749713 UTC - Status: UP 2026-07-15 04:04:21.749810 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxhz3pevfbyr2ye917ztg1sx 2026-07-15 04:04:21.749854 UTC - Validate resources 2026-07-15 04:04:22.722098 UTC - The fields of the created resource were validated. 2026-07-15 04:04:22.722227 UTC - Check if resources are registered in experiment 2026-07-15 04:04:22.722415 UTC - Run: slices experiment list-resources --format json tst-e710b7dd 2026-07-15 04:04:23.388297 UTC - Status (on expauth): UP 2026-07-15 04:04:23.388455 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 04:04:23.388591 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-e710b7dd 2026-07-15 04:04:26.559955 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-e710b7dd tst 2026-07-15 04:04:27.626855 UTC - Run: slices experiment list-resources --format json tst-e710b7dd 2026-07-15 04:04:28.393400 UTC - expires_at (on expauth): 2026-07-15T07:04:00Z (correctly extended) 2026-07-15 04:04:28.393577 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 04:04:28.393849 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-e710b7dd 2026-07-15 04:04:31.415936 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-e710b7dd tst 2026-07-15 04:04:32.282235 UTC - Run: slices experiment list-resources --format json tst-e710b7dd 2026-07-15 04:04:32.897579 UTC - expires_at (on expauth): 2026-07-15T08:04:00Z (correctly extended) 2026-07-15 04:04:32.897712 UTC - Testing ssh login 2026-07-15 04:04:32.908771 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e710b7dd tst 2026-07-15 04:04:33.824683 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e710b7dd tst 2026-07-15 04:04:34.741043 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-e710b7dd tst 2026-07-15 04:04:35.656848 UTC - Logging in using 'slices bi ssh' 2026-07-15 04:04:35.656916 UTC - Forcing IPv4 only. 2026-07-15 04:04:35.657045 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-e710b7dd tst -- -4 uname -a 2026-07-15 04:04:38.277049 UTC - Forcing IPv4 only. 2026-07-15 04:04:38.277237 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-e710b7dd tst -- -4 uptime 2026-07-15 04:04:40.103234 UTC - CLI SSH Test passed. 2026-07-15 04:04:40.103311 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-15 04:04:40.103333 UTC - Uptime: 04:04:39 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-15 04:04:40.103368 UTC - Logging in using SSH over SSH proxy 2026-07-15 04:04:40.103536 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-e710b7dd tst 2026-07-15 04:04:41.070898 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 04:04:41.071050 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 04:04:41.071237 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 04:04:41.071488 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 04:04:41.071634 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 04:04:41.071730 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 04:04:41.071754 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-15 04:04:41.572550 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-15 04:04:42.445638 UTC - SSH Test output: 2026-07-15 04:04:42.445747 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-15 04:04:42.445770 UTC - Uptime: 04:04:42 up 0 min, 1 user, load average: 0.13, 0.04, 0.01 2026-07-15 04:04:42.633579 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 04:04:42.633683 UTC - lsb_release matches expected value 2026-07-15 04:04:42.633706 UTC - SSH Test passed. 2026-07-15 04:04:42.819545 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 04:04:19 +0000! 2026-07-15 04:04:42.827305 UTC - Destroying tst-e710b7dd tst 2026-07-15 04:04:42.827525 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-e710b7dd tst