Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.06s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 679ms 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 846ms + 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.4 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-20 04:03:39.150581 UTC - Installing Slices CLI (latest stable version). 2026-07-20 04:03:39.151080 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 04:03:45.648144 UTC - Slices CLI installed. 2026-07-20 04:03:45.648643 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-20 04:03:45.652793 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-20 04:03:45.657423 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 04:03:45.660351 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-20 04:03:45.660409 UTC - Slices CLI exe: 'slices' 2026-07-20 04:03:45.660429 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 04:03:45.660575 UTC - Run: slices --version 2026-07-20 04:03:52.684062 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-20 04:03:52.685054 UTC - Run: slices pubkey list --format text 2026-07-20 04:03:55.211305 UTC - Pubkey already registered 2026-07-20 04:03:55.211558 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 04:03:57.053756 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 04:03:57.053878 UTC - Check List Flavors 2026-07-20 04:03:57.054045 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-20 04:03:59.783908 UTC - Check List DiskImages 2026-07-20 04:03:59.784131 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-20 04:04:01.348364 UTC - Requesting resources 2026-07-20 04:04:01.349085 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 04:04:01.349205 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-45c0aab5 --user-data /tmp/tmpazhqwzmc 2026-07-20 04:04:06.855958 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxyv40zqeb8s78z2tt6mwrts 2026-07-20 04:04:06.856416 UTC - Waiting until resource ready 2026-07-20 04:04:08.856781 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-45c0aab5 tst 2026-07-20 04:04:10.869602 UTC - Status: IMAGING 2026-07-20 04:04:13.957970 UTC - Status: BOOTING 2026-07-20 04:04:17.381036 UTC - Status: BOOTING 2026-07-20 04:04:20.448440 UTC - Status: BOOTING 2026-07-20 04:04:23.424204 UTC - Status: BOOTING 2026-07-20 04:04:26.291086 UTC - Status: BOOTING 2026-07-20 04:04:29.258133 UTC - Status: BOOTING 2026-07-20 04:04:32.086153 UTC - Status: BOOTING 2026-07-20 04:04:35.207823 UTC - Status: BOOTING 2026-07-20 04:04:38.274649 UTC - Status: BOOTING 2026-07-20 04:04:41.264596 UTC - Status: BOOTING 2026-07-20 04:04:44.407377 UTC - Status: UP 2026-07-20 04:04:44.407469 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxyv3z1ce5ktzpzbnd7rry9q 2026-07-20 04:04:44.407510 UTC - Validate resources 2026-07-20 04:04:45.233521 UTC - The fields of the created resource were validated. 2026-07-20 04:04:45.233618 UTC - Check if resources are registered in experiment 2026-07-20 04:04:45.233758 UTC - Run: slices experiment list-resources --format json tst-45c0aab5 2026-07-20 04:04:46.213712 UTC - Status (on expauth): UP 2026-07-20 04:04:46.213867 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 04:04:46.213986 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-45c0aab5 2026-07-20 04:04:49.739325 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-45c0aab5 tst 2026-07-20 04:04:50.555140 UTC - Run: slices experiment list-resources --format json tst-45c0aab5 2026-07-20 04:04:51.220964 UTC - expires_at (on expauth): 2026-07-20T07:04:00Z (correctly extended) 2026-07-20 04:04:51.221226 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 04:04:51.221414 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-45c0aab5 2026-07-20 04:04:53.992809 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-45c0aab5 tst 2026-07-20 04:04:54.965215 UTC - Run: slices experiment list-resources --format json tst-45c0aab5 2026-07-20 04:04:55.832023 UTC - expires_at (on expauth): 2026-07-20T08:04:00Z (correctly extended) 2026-07-20 04:04:55.832135 UTC - Testing ssh login 2026-07-20 04:04:55.881638 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-45c0aab5 tst 2026-07-20 04:04:56.867086 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-45c0aab5 tst 2026-07-20 04:04:57.784829 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-45c0aab5 tst 2026-07-20 04:04:58.701331 UTC - Logging in using 'slices bi ssh' 2026-07-20 04:04:58.701403 UTC - Forcing IPv4 only. 2026-07-20 04:04:58.701528 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-45c0aab5 tst -- -4 uname -a 2026-07-20 04:05:01.471784 UTC - Forcing IPv4 only. 2026-07-20 04:05:01.471967 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-45c0aab5 tst -- -4 uptime 2026-07-20 04:05:03.399513 UTC - CLI SSH Test passed. 2026-07-20 04:05:03.399589 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-20 04:05:03.399608 UTC - Uptime: 04:05:03 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-20 04:05:03.399644 UTC - Logging in using SSH over SSH proxy 2026-07-20 04:05:03.399786 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-45c0aab5 tst 2026-07-20 04:05:04.366741 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-20 04:05:04.366887 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-20 04:05:04.367078 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-20 04:05:04.367345 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-20 04:05:04.367470 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-20 04:05:04.367553 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-20 04:05:04.367576 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-20 04:05:04.864504 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-20 04:05:05.735086 UTC - SSH Test output: 2026-07-20 04:05:05.735161 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-20 04:05:05.735180 UTC - Uptime: 04:05:05 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-20 04:05:05.922172 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 04:05:05.922250 UTC - lsb_release matches expected value 2026-07-20 04:05:05.922269 UTC - SSH Test passed. 2026-07-20 04:05:06.107880 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 04:04:41 +0000! 2026-07-20 04:05:06.116046 UTC - Destroying tst-45c0aab5 tst 2026-07-20 04:05:06.116298 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-45c0aab5 tst