Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.98s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 522ms 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.22s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.0 + cryptography==50.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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev192+gcac2d3fe6 (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-08-03 20:05:22.259265 UTC - Installing Slices CLI (latest stable version). 2026-08-03 20:05:22.259514 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-03 20:05:29.143273 UTC - Slices CLI installed. 2026-08-03 20:05:29.143525 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-08-03 20:05:29.144786 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-03 20:05:29.144871 UTC - env: {'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-08-03 20:05:29.144946 UTC - which slices: /app/.venv/bin/slices 2026-08-03 20:05:29.144967 UTC - Slices CLI exe: 'slices' 2026-08-03 20:05:29.144983 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-03 20:05:29.145111 UTC - Run: slices --version 2026-08-03 20:05:33.013280 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-03 20:05:33.013563 UTC - Run: slices pubkey list --format text 2026-08-03 20:05:34.280027 UTC - Pubkey already registered 2026-08-03 20:05:34.280265 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-03 20:05:35.251798 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-03 20:05:35.251921 UTC - Check List Flavors 2026-08-03 20:05:35.252104 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-08-03 20:05:36.334339 UTC - Check List DiskImages 2026-08-03 20:05:36.334629 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-08-03 20:05:37.383459 UTC - Requesting resources 2026-08-03 20:05:37.383932 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-03 20:05:37.384072 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-c6b76f12 --user-data /tmp/tmpg3egy4nz 2026-08-03 20:05:40.578881 UTC - Resource ID: r_it-genoa1-bi-vm1_01kz4kprx0fb1ss1vm8taq9a9b 2026-08-03 20:05:40.579080 UTC - Waiting until resource ready 2026-08-03 20:05:42.579520 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-c6b76f12 tst 2026-08-03 20:05:43.447907 UTC - Status: IMAGING 2026-08-03 20:05:46.466824 UTC - Status: IMAGING 2026-08-03 20:05:49.392961 UTC - Status: BOOTING 2026-08-03 20:05:52.564215 UTC - Status: BOOTING 2026-08-03 20:05:55.807421 UTC - Status: BOOTING 2026-08-03 20:05:59.042936 UTC - Status: BOOTING 2026-08-03 20:06:02.326477 UTC - Status: BOOTING 2026-08-03 20:06:06.121412 UTC - Status: BOOTING 2026-08-03 20:06:09.213144 UTC - Status: BOOTING 2026-08-03 20:06:12.379991 UTC - Status: BOOTING 2026-08-03 20:06:15.828889 UTC - Status: BOOTING 2026-08-03 20:06:18.964606 UTC - Status: UP 2026-08-03 20:06:18.964846 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz4kpq6se269j3mmdedzzy8d 2026-08-03 20:06:18.964897 UTC - Validate resources 2026-08-03 20:06:19.885968 UTC - The fields of the created resource were validated. 2026-08-03 20:06:19.886105 UTC - Check if resources are registered in experiment 2026-08-03 20:06:19.886301 UTC - Run: slices experiment list-resources --format json tst-c6b76f12 2026-08-03 20:06:20.913572 UTC - Status (on expauth): UP 2026-08-03 20:06:20.913719 UTC - Testing extend expires_at (all resources in experiment) 2026-08-03 20:06:20.913870 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-c6b76f12 2026-08-03 20:06:22.445412 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-c6b76f12 tst 2026-08-03 20:06:23.764187 UTC - Run: slices experiment list-resources --format json tst-c6b76f12 2026-08-03 20:06:24.887204 UTC - expires_at (on expauth): 2026-08-03T23:06:00Z (correctly extended) 2026-08-03 20:06:24.887571 UTC - Testing extend expires_at (single resource in experiment) 2026-08-03 20:06:24.887771 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-c6b76f12 2026-08-03 20:06:26.573388 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-c6b76f12 tst 2026-08-03 20:06:27.700379 UTC - Run: slices experiment list-resources --format json tst-c6b76f12 2026-08-03 20:06:29.029368 UTC - expires_at (on expauth): 2026-08-04T00:06:00Z (correctly extended) 2026-08-03 20:06:29.029495 UTC - Testing ssh login 2026-08-03 20:06:29.052323 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c6b76f12 tst 2026-08-03 20:06:30.298173 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c6b76f12 tst 2026-08-03 20:06:31.467359 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c6b76f12 tst 2026-08-03 20:06:32.899669 UTC - Logging in using 'slices bi ssh' 2026-08-03 20:06:32.899775 UTC - Forcing IPv4 only. 2026-08-03 20:06:32.899948 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-c6b76f12 tst -- -4 uname -a 2026-08-03 20:06:36.239105 UTC - Forcing IPv4 only. 2026-08-03 20:06:36.239300 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-c6b76f12 tst -- -4 uptime 2026-08-03 20:06:38.520362 UTC - CLI SSH Test passed. 2026-08-03 20:06:38.520449 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-08-03 20:06:38.520479 UTC - Uptime: 20:06:38 up 0 min, 1 user, load average: 0.30, 0.09, 0.03 2026-08-03 20:06:38.520516 UTC - Logging in using SSH over SSH proxy 2026-08-03 20:06:38.520684 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c6b76f12 tst 2026-08-03 20:06:39.843062 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-03 20:06:39.843237 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-03 20:06:39.843456 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-08-03 20:06:39.843768 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-03 20:06:39.843912 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-03 20:06:39.844179 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-08-03 20:06:39.844221 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-08-03 20:06:40.357700 UTC - Connecting to 172.30.44.3:22 over proxy 2026-08-03 20:06:41.255195 UTC - SSH Test output: 2026-08-03 20:06:41.255296 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-08-03 20:06:41.255320 UTC - Uptime: 20:06:41 up 0 min, 1 user, load average: 0.28, 0.09, 0.03 2026-08-03 20:06:41.444488 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-03 20:06:41.444572 UTC - lsb_release matches expected value 2026-08-03 20:06:41.444594 UTC - SSH Test passed. 2026-08-03 20:06:41.632155 UTC - Cloud-init user-data: Hello World. The time is now Mon, 03 Aug 2026 20:06:15 +0000! 2026-08-03 20:06:41.651434 UTC - Destroying tst-c6b76f12 tst 2026-08-03 20:06:41.653546 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-c6b76f12 tst