Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 8.84s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 811ms 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.07s + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-19 13:04:13.622562 UTC - Installing Slices CLI (latest stable version). 2026-07-19 13:04:13.628761 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 13:04:19.560750 UTC - Slices CLI installed. 2026-07-19 13:04:19.561644 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-19 13:04:19.562777 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-19 13:04:19.565331 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 13:04:19.565472 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-19 13:04:19.565501 UTC - Slices CLI exe: 'slices' 2026-07-19 13:04:19.565518 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 13:04:19.565668 UTC - Run: slices --version 2026-07-19 13:04:24.183367 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-19 13:04:24.184208 UTC - Run: slices pubkey list --format text 2026-07-19 13:04:26.340063 UTC - Pubkey already registered 2026-07-19 13:04:26.340311 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 13:04:27.856238 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 13:04:27.856384 UTC - Check List Flavors 2026-07-19 13:04:27.856552 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-19 13:04:29.901841 UTC - Check List DiskImages 2026-07-19 13:04:29.902042 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-19 13:04:30.770796 UTC - Requesting resources 2026-07-19 13:04:30.771375 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 13:04:30.771516 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-46bd2752 --user-data /tmp/tmpoh8_qeij 2026-07-19 13:04:36.163339 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxx7mzgzecrskj2r9p5rd79s 2026-07-19 13:04:36.163517 UTC - Waiting until resource ready 2026-07-19 13:04:38.163838 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-46bd2752 tst 2026-07-19 13:04:39.621228 UTC - Status: IMAGING 2026-07-19 13:04:42.637501 UTC - Status: IMAGING 2026-07-19 13:04:45.611254 UTC - Status: BOOTING 2026-07-19 13:04:48.996256 UTC - Status: BOOTING 2026-07-19 13:04:52.356380 UTC - Status: BOOTING 2026-07-19 13:04:56.212233 UTC - Status: BOOTING 2026-07-19 13:04:59.661435 UTC - Status: BOOTING 2026-07-19 13:05:03.375977 UTC - Status: BOOTING 2026-07-19 13:05:06.904627 UTC - Status: BOOTING 2026-07-19 13:05:09.895999 UTC - Status: BOOTING 2026-07-19 13:05:13.468473 UTC - Status: UP 2026-07-19 13:05:13.468816 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxx7mwxgf94tcttzsmbv6yf0 2026-07-19 13:05:13.468890 UTC - Validate resources 2026-07-19 13:05:14.593847 UTC - The fields of the created resource were validated. 2026-07-19 13:05:14.593952 UTC - Check if resources are registered in experiment 2026-07-19 13:05:14.594093 UTC - Run: slices experiment list-resources --format json tst-46bd2752 2026-07-19 13:05:15.967689 UTC - Status (on expauth): UP 2026-07-19 13:05:15.968141 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 13:05:15.968427 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-46bd2752 2026-07-19 13:05:19.541010 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-46bd2752 tst 2026-07-19 13:05:20.308155 UTC - Run: slices experiment list-resources --format json tst-46bd2752 2026-07-19 13:05:20.973377 UTC - expires_at (on expauth): 2026-07-19T16:05:00Z (correctly extended) 2026-07-19 13:05:20.973480 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 13:05:20.973628 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-46bd2752 2026-07-19 13:05:24.647709 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-46bd2752 tst 2026-07-19 13:05:26.274673 UTC - Run: slices experiment list-resources --format json tst-46bd2752 2026-07-19 13:05:26.991034 UTC - expires_at (on expauth): 2026-07-19T17:05:00Z (correctly extended) 2026-07-19 13:05:26.992331 UTC - Testing ssh login 2026-07-19 13:05:27.008304 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-46bd2752 tst 2026-07-19 13:05:28.280361 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-46bd2752 tst 2026-07-19 13:05:29.406812 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-46bd2752 tst 2026-07-19 13:05:30.373440 UTC - Logging in using 'slices bi ssh' 2026-07-19 13:05:30.373671 UTC - Forcing IPv4 only. 2026-07-19 13:05:30.373840 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-46bd2752 tst -- -4 uname -a 2026-07-19 13:05:33.830786 UTC - Forcing IPv4 only. 2026-07-19 13:05:33.831220 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-46bd2752 tst -- -4 uptime 2026-07-19 13:05:36.521080 UTC - CLI SSH Test passed. 2026-07-19 13:05:36.521176 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-19 13:05:36.521197 UTC - Uptime: 13:05:36 up 0 min, 1 user, load average: 0.42, 0.12, 0.04 2026-07-19 13:05:36.521233 UTC - Logging in using SSH over SSH proxy 2026-07-19 13:05:36.522371 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-46bd2752 tst 2026-07-19 13:05:38.051796 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 13:05:38.052080 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 13:05:38.052256 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 13:05:38.052522 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 13:05:38.052669 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 13:05:38.052780 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 13:05:38.052808 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-19 13:05:38.511919 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-19 13:05:39.308639 UTC - SSH Test output: 2026-07-19 13:05:39.308752 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-19 13:05:39.308777 UTC - Uptime: 13:05:39 up 0 min, 1 user, load average: 0.39, 0.12, 0.04 2026-07-19 13:05:39.475596 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-19 13:05:39.475674 UTC - lsb_release matches expected value 2026-07-19 13:05:39.475694 UTC - SSH Test passed. 2026-07-19 13:05:39.642827 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 13:05:12 +0000! 2026-07-19 13:05:39.656482 UTC - Destroying tst-46bd2752 tst 2026-07-19 13:05:39.656874 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-46bd2752 tst