Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 12.01s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 518ms 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 548ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-12 13:03:16.002769 UTC - Installing Slices CLI (latest stable version). 2026-07-12 13:03:16.012378 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 13:03:22.372311 UTC - Slices CLI installed. 2026-07-12 13:03:22.372573 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-12 13:03:22.374038 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-12 13:03:22.375057 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 13:03:22.375182 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-12 13:03:22.375212 UTC - Slices CLI exe: 'slices' 2026-07-12 13:03:22.375230 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 13:03:22.375364 UTC - Run: slices --version 2026-07-12 13:03:28.931075 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-12 13:03:28.931337 UTC - Run: slices pubkey list --format text 2026-07-12 13:03:30.711332 UTC - Pubkey already registered 2026-07-12 13:03:30.711553 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 13:03:32.765367 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 13:03:32.765471 UTC - Check List Flavors 2026-07-12 13:03:32.765607 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-12 13:03:35.791633 UTC - Check List DiskImages 2026-07-12 13:03:35.792050 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-12 13:03:37.935163 UTC - Requesting resources 2026-07-12 13:03:37.935831 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-12 13:03:37.935960 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b988280e --user-data /tmp/tmp8lhn8e83 2026-07-12 13:03:43.521890 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxb6tb5eecs8zxnt0s05ae4g 2026-07-12 13:03:43.522107 UTC - Waiting until resource ready 2026-07-12 13:03:45.522431 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-b988280e tst 2026-07-12 13:03:48.147141 UTC - Status: IMAGING 2026-07-12 13:03:52.450267 UTC - Status: BOOTING 2026-07-12 13:03:56.890564 UTC - Status: BOOTING 2026-07-12 13:04:01.018496 UTC - Status: BOOTING 2026-07-12 13:04:04.951383 UTC - Status: BOOTING 2026-07-12 13:04:08.428535 UTC - Status: BOOTING 2026-07-12 13:04:11.859372 UTC - Status: BOOTING 2026-07-12 13:04:14.948398 UTC - Status: BOOTING 2026-07-12 13:04:18.019511 UTC - Status: UP 2026-07-12 13:04:18.019585 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxb6t93sftkas7tfvq46qf15 2026-07-12 13:04:18.019625 UTC - Validate resources 2026-07-12 13:04:19.052411 UTC - The fields of the created resource were validated. 2026-07-12 13:04:19.052495 UTC - Check if resources are registered in experiment 2026-07-12 13:04:19.052632 UTC - Run: slices experiment list-resources --format json tst-b988280e 2026-07-12 13:04:20.043437 UTC - Status (on expauth): UP 2026-07-12 13:04:20.043569 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 13:04:20.043695 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-b988280e 2026-07-12 13:04:23.079110 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-b988280e tst 2026-07-12 13:04:23.894766 UTC - Run: slices experiment list-resources --format json tst-b988280e 2026-07-12 13:04:24.686687 UTC - expires_at (on expauth): 2026-07-12T16:04:00Z (correctly extended) 2026-07-12 13:04:24.686795 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 13:04:24.686949 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-b988280e 2026-07-12 13:04:27.360047 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-b988280e tst 2026-07-12 13:04:28.176485 UTC - Run: slices experiment list-resources --format json tst-b988280e 2026-07-12 13:04:28.791768 UTC - expires_at (on expauth): 2026-07-12T17:04:00Z (correctly extended) 2026-07-12 13:04:28.791879 UTC - Testing ssh login 2026-07-12 13:04:28.801852 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b988280e tst 2026-07-12 13:04:29.717750 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b988280e tst 2026-07-12 13:04:30.683684 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b988280e tst 2026-07-12 13:04:31.655887 UTC - Logging in using 'slices bi ssh' 2026-07-12 13:04:31.655971 UTC - Forcing IPv4 only. 2026-07-12 13:04:31.656095 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-b988280e tst -- -4 uname -a 2026-07-12 13:04:34.526166 UTC - Forcing IPv4 only. 2026-07-12 13:04:34.526350 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-b988280e tst -- -4 uptime 2026-07-12 13:04:36.494903 UTC - CLI SSH Test passed. 2026-07-12 13:04:36.495010 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-12 13:04:36.495043 UTC - Uptime: 13:04:36 up 0 min, 1 user, load average: 0.25, 0.08, 0.02 2026-07-12 13:04:36.495096 UTC - Logging in using SSH over SSH proxy 2026-07-12 13:04:36.495308 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b988280e tst 2026-07-12 13:04:37.411876 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-12 13:04:37.412011 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-12 13:04:37.412165 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-12 13:04:37.412383 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-12 13:04:37.412507 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-12 13:04:37.412587 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-12 13:04:37.412609 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-12 13:04:37.916552 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-12 13:04:38.794549 UTC - SSH Test output: 2026-07-12 13:04:38.794620 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-12 13:04:38.794639 UTC - Uptime: 13:04:38 up 0 min, 1 user, load average: 0.25, 0.08, 0.02 2026-07-12 13:04:38.981863 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-12 13:04:38.982001 UTC - lsb_release matches expected value 2026-07-12 13:04:38.982024 UTC - SSH Test passed. 2026-07-12 13:04:39.167611 UTC - Cloud-init user-data: Hello World. The time is now Sun, 12 Jul 2026 13:04:17 +0000! 2026-07-12 13:04:39.174593 UTC - Destroying tst-b988280e tst 2026-07-12 13:04:39.174789 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-b988280e tst