Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.30s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 694ms 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 686ms + 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-10 20:03:49.395390 UTC - Installing Slices CLI (latest stable version). 2026-07-10 20:03:49.395630 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 20:03:58.243005 UTC - Slices CLI installed. 2026-07-10 20:03:58.243893 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-10 20:03:58.245370 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-10 20:03:58.246469 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 20:03:58.246605 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-10 20:03:58.246635 UTC - Slices CLI exe: 'slices' 2026-07-10 20:03:58.246663 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 20:03:58.246813 UTC - Run: slices --version 2026-07-10 20:04:06.489222 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-10 20:04:06.489535 UTC - Run: slices pubkey list --format text 2026-07-10 20:04:08.493120 UTC - Pubkey already registered 2026-07-10 20:04:08.493375 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 20:04:10.734293 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 20:04:10.734387 UTC - Check List Flavors 2026-07-10 20:04:10.734550 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-10 20:04:13.613404 UTC - Check List DiskImages 2026-07-10 20:04:13.613582 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-10 20:04:14.997928 UTC - Requesting resources 2026-07-10 20:04:14.998802 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 20:04:14.998959 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-764d9172 --user-data /tmp/tmph3gl3qnn 2026-07-10 20:04:19.636076 UTC - Resource ID: r_it-genoa1-bi-vm1_01kx6t31rdfr18vk81gkar0yek 2026-07-10 20:04:19.636233 UTC - Waiting until resource ready 2026-07-10 20:04:21.636575 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-764d9172 tst 2026-07-10 20:04:23.061134 UTC - Status: IMAGING 2026-07-10 20:04:26.133588 UTC - Status: IMAGING 2026-07-10 20:04:29.672267 UTC - Status: BOOTING 2026-07-10 20:04:32.594401 UTC - Status: BOOTING 2026-07-10 20:04:35.511457 UTC - Status: BOOTING 2026-07-10 20:04:38.327351 UTC - Status: BOOTING 2026-07-10 20:04:41.243764 UTC - Status: BOOTING 2026-07-10 20:04:44.111153 UTC - Status: BOOTING 2026-07-10 20:04:46.927329 UTC - Status: BOOTING 2026-07-10 20:04:49.743613 UTC - Status: BOOTING 2026-07-10 20:04:52.610191 UTC - Status: BOOTING 2026-07-10 20:04:55.426909 UTC - Status: UP 2026-07-10 20:04:55.427014 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx6t2zfvfe7bt8vht0nct7dv 2026-07-10 20:04:55.427060 UTC - Validate resources 2026-07-10 20:04:56.243159 UTC - The fields of the created resource were validated. 2026-07-10 20:04:56.243254 UTC - Check if resources are registered in experiment 2026-07-10 20:04:56.243404 UTC - Run: slices experiment list-resources --format json tst-764d9172 2026-07-10 20:04:56.908706 UTC - Status (on expauth): UP 2026-07-10 20:04:56.908895 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 20:04:56.909033 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-764d9172 2026-07-10 20:04:59.879644 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-764d9172 tst 2026-07-10 20:05:00.745822 UTC - Run: slices experiment list-resources --format json tst-764d9172 2026-07-10 20:05:01.411438 UTC - expires_at (on expauth): 2026-07-10T23:04:00Z (correctly extended) 2026-07-10 20:05:01.411529 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 20:05:01.411664 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-764d9172 2026-07-10 20:05:04.533032 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-764d9172 tst 2026-07-10 20:05:05.399218 UTC - Run: slices experiment list-resources --format json tst-764d9172 2026-07-10 20:05:06.115128 UTC - expires_at (on expauth): 2026-07-11T00:05:00Z (correctly extended) 2026-07-10 20:05:06.115263 UTC - Testing ssh login 2026-07-10 20:05:06.126463 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-764d9172 tst 2026-07-10 20:05:07.143499 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-764d9172 tst 2026-07-10 20:05:08.109836 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-764d9172 tst 2026-07-10 20:05:09.126802 UTC - Logging in using 'slices bi ssh' 2026-07-10 20:05:09.126895 UTC - Forcing IPv4 only. 2026-07-10 20:05:09.127052 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-764d9172 tst -- -4 uname -a 2026-07-10 20:05:11.800407 UTC - Forcing IPv4 only. 2026-07-10 20:05:11.800595 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-764d9172 tst -- -4 uptime 2026-07-10 20:05:13.769051 UTC - CLI SSH Test passed. 2026-07-10 20:05:13.769128 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-10 20:05:13.769147 UTC - Uptime: 20:05:12 up 0 min, 1 user, load average: 0.20, 0.06, 0.02 2026-07-10 20:05:13.769181 UTC - Logging in using SSH over SSH proxy 2026-07-10 20:05:13.769318 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-764d9172 tst 2026-07-10 20:05:14.736085 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 20:05:14.736210 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 20:05:14.736361 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-10 20:05:14.736582 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 20:05:14.736702 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 20:05:14.736809 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-10 20:05:14.736832 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-10 20:05:15.232573 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-10 20:05:16.115123 UTC - SSH Test output: 2026-07-10 20:05:16.115220 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-10 20:05:16.115254 UTC - Uptime: 20:05:14 up 0 min, 1 user, load average: 0.20, 0.06, 0.02 2026-07-10 20:05:16.304047 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 20:05:16.304170 UTC - lsb_release matches expected value 2026-07-10 20:05:16.304195 UTC - SSH Test passed. 2026-07-10 20:05:16.490186 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 20:04:54 +0000! 2026-07-10 20:05:16.497126 UTC - Destroying tst-764d9172 tst 2026-07-10 20:05:16.497313 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-764d9172 tst