Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.36s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 600ms 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.46s + 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 20:03:53.024865 UTC - Installing Slices CLI (latest stable version). 2026-07-15 20:03:53.025394 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 20:04:03.894592 UTC - Slices CLI installed. 2026-07-15 20:04:03.894785 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 20:04:03.896938 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 20:04:03.898131 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 20:04:03.898265 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 20:04:03.898293 UTC - Slices CLI exe: 'slices' 2026-07-15 20:04:03.898310 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 20:04:03.898431 UTC - Run: slices --version 2026-07-15 20:04:12.999385 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 20:04:12.999681 UTC - Run: slices pubkey list --format text 2026-07-15 20:04:15.635058 UTC - Pubkey already registered 2026-07-15 20:04:15.635337 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 20:04:17.794361 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 20:04:17.794457 UTC - Check List Flavors 2026-07-15 20:04:17.794599 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-15 20:04:20.436261 UTC - Check List DiskImages 2026-07-15 20:04:20.436506 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-15 20:04:21.724543 UTC - Requesting resources 2026-07-15 20:04:21.725217 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 20:04:21.725339 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-aaa84e6f --user-data /tmp/tmpywpbk5al 2026-07-15 20:04:27.035889 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxkp2w09fc8smzn2c5b9ncze 2026-07-15 20:04:27.036033 UTC - Waiting until resource ready 2026-07-15 20:04:29.036352 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-aaa84e6f tst 2026-07-15 20:04:30.002280 UTC - Status: IMAGING 2026-07-15 20:04:33.021119 UTC - Status: IMAGING 2026-07-15 20:04:35.887279 UTC - Status: BOOTING 2026-07-15 20:04:38.876939 UTC - Status: BOOTING 2026-07-15 20:04:42.001850 UTC - Status: BOOTING 2026-07-15 20:04:45.321089 UTC - Status: BOOTING 2026-07-15 20:04:48.299673 UTC - Status: BOOTING 2026-07-15 20:04:51.179434 UTC - Status: BOOTING 2026-07-15 20:04:54.045418 UTC - Status: BOOTING 2026-07-15 20:04:57.227143 UTC - Status: BOOTING 2026-07-15 20:05:00.043093 UTC - Status: BOOTING 2026-07-15 20:05:02.859054 UTC - Status: BOOTING 2026-07-15 20:05:05.775675 UTC - Status: UP 2026-07-15 20:05:05.775750 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxkp2t8vey6av9gg1dqc2yn4 2026-07-15 20:05:05.775790 UTC - Validate resources 2026-07-15 20:05:06.591962 UTC - The fields of the created resource were validated. 2026-07-15 20:05:06.592076 UTC - Check if resources are registered in experiment 2026-07-15 20:05:06.592236 UTC - Run: slices experiment list-resources --format json tst-aaa84e6f 2026-07-15 20:05:07.207544 UTC - Status (on expauth): UP 2026-07-15 20:05:07.207693 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 20:05:07.207818 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-aaa84e6f 2026-07-15 20:05:10.679264 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-aaa84e6f tst 2026-07-15 20:05:11.494964 UTC - Run: slices experiment list-resources --format json tst-aaa84e6f 2026-07-15 20:05:12.160332 UTC - expires_at (on expauth): 2026-07-15T23:05:00Z (correctly extended) 2026-07-15 20:05:12.160432 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 20:05:12.160574 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-aaa84e6f 2026-07-15 20:05:15.782714 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-aaa84e6f tst 2026-07-15 20:05:16.598757 UTC - Run: slices experiment list-resources --format json tst-aaa84e6f 2026-07-15 20:05:17.264492 UTC - expires_at (on expauth): 2026-07-16T00:05:00Z (correctly extended) 2026-07-15 20:05:17.264676 UTC - Testing ssh login 2026-07-15 20:05:17.279727 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-aaa84e6f tst 2026-07-15 20:05:18.246460 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-aaa84e6f tst 2026-07-15 20:05:19.162402 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-aaa84e6f tst 2026-07-15 20:05:20.029293 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:05:20.029369 UTC - Forcing IPv4 only. 2026-07-15 20:05:20.029526 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-aaa84e6f tst -- -4 uname -a 2026-07-15 20:05:22.699790 UTC - Forcing IPv4 only. 2026-07-15 20:05:22.699979 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-aaa84e6f tst -- -4 uptime 2026-07-15 20:05:24.567922 UTC - CLI SSH Test passed. 2026-07-15 20:05:24.567997 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 20:05:24.568039 UTC - Uptime: 20:05:24 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-15 20:05:24.568078 UTC - Logging in using SSH over SSH proxy 2026-07-15 20:05:24.568231 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-aaa84e6f tst 2026-07-15 20:05:25.757991 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 20:05:25.758135 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 20:05:25.758432 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-15 20:05:25.758796 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 20:05:25.760850 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 20:05:25.760971 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-15 20:05:25.760993 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-15 20:05:26.268556 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-15 20:05:27.156979 UTC - SSH Test output: 2026-07-15 20:05:27.157089 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 20:05:27.157111 UTC - Uptime: 20:05:26 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-15 20:05:27.346466 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 20:05:27.346545 UTC - lsb_release matches expected value 2026-07-15 20:05:27.346564 UTC - SSH Test passed. 2026-07-15 20:05:27.532526 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 20:05:03 +0000! 2026-07-15 20:05:27.548688 UTC - Destroying tst-aaa84e6f tst 2026-07-15 20:05:27.548901 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-aaa84e6f tst