Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.42s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 473ms 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 949ms + 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==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.3.post1 + 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-25 20:04:15.107530 UTC - Installing Slices CLI (latest stable version). 2026-07-25 20:04:15.107920 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 20:04:21.152455 UTC - Slices CLI installed. 2026-07-25 20:04:21.153540 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-25 20:04:21.155967 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-25 20:04:21.157205 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 20:04:21.157344 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-25 20:04:21.157371 UTC - Slices CLI exe: 'slices' 2026-07-25 20:04:21.157399 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 20:04:21.157532 UTC - Run: slices --version 2026-07-25 20:04:24.610742 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-25 20:04:24.611037 UTC - Run: slices pubkey list --format text 2026-07-25 20:04:25.639907 UTC - Pubkey already registered 2026-07-25 20:04:25.640446 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 20:04:26.764703 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 20:04:26.764826 UTC - Check List Flavors 2026-07-25 20:04:26.765080 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-25 20:04:27.807535 UTC - Check List DiskImages 2026-07-25 20:04:27.807710 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-25 20:04:28.886564 UTC - Requesting resources 2026-07-25 20:04:28.887213 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 20:04:28.887324 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3f1aa86b --user-data /tmp/tmp15q4o7gg 2026-07-25 20:04:31.772007 UTC - Resource ID: r_it-genoa1-bi-vm1_01kyde26nxec080k080edf40jn 2026-07-25 20:04:31.773066 UTC - Waiting until resource ready 2026-07-25 20:04:33.773481 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3f1aa86b tst 2026-07-25 20:04:34.911392 UTC - Status: IMAGING 2026-07-25 20:04:38.041709 UTC - Status: IMAGING 2026-07-25 20:04:41.114756 UTC - Status: BOOTING 2026-07-25 20:04:43.981647 UTC - Status: BOOTING 2026-07-25 20:04:47.003601 UTC - Status: BOOTING 2026-07-25 20:04:49.986487 UTC - Status: BOOTING 2026-07-25 20:04:53.471735 UTC - Status: BOOTING 2026-07-25 20:04:56.549546 UTC - Status: BOOTING 2026-07-25 20:04:59.628401 UTC - Status: BOOTING 2026-07-25 20:05:02.697165 UTC - Status: BOOTING 2026-07-25 20:05:05.781257 UTC - Status: BOOTING 2026-07-25 20:05:08.754302 UTC - Status: BOOTING 2026-07-25 20:05:11.671188 UTC - Status: BOOTING 2026-07-25 20:05:14.788043 UTC - Status: BOOTING 2026-07-25 20:05:17.811769 UTC - Status: BOOTING 2026-07-25 20:05:20.831294 UTC - Status: BOOTING 2026-07-25 20:05:23.955634 UTC - Status: BOOTING 2026-07-25 20:05:26.771595 UTC - Status: BOOTING 2026-07-25 20:05:29.749220 UTC - Status: BOOTING 2026-07-25 20:05:32.772590 UTC - Status: BOOTING 2026-07-25 20:05:36.064344 UTC - Status: BOOTING 2026-07-25 20:05:39.449111 UTC - Status: BOOTING 2026-07-25 20:05:42.721773 UTC - Status: BOOTING 2026-07-25 20:05:46.040076 UTC - Status: BOOTING 2026-07-25 20:05:49.216425 UTC - Status: BOOTING 2026-07-25 20:05:52.133102 UTC - Status: BOOTING 2026-07-25 20:05:55.299987 UTC - Status: BOOTING 2026-07-25 20:05:58.541176 UTC - Status: BOOTING 2026-07-25 20:06:01.823704 UTC - Status: BOOTING 2026-07-25 20:06:05.091886 UTC - Status: BOOTING 2026-07-25 20:06:08.361079 UTC - Status: UP 2026-07-25 20:06:08.361181 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyde25dcfbyrxckpkq0m4nvh 2026-07-25 20:06:08.361243 UTC - Validate resources 2026-07-25 20:06:09.331001 UTC - The fields of the created resource were validated. 2026-07-25 20:06:09.331113 UTC - Check if resources are registered in experiment 2026-07-25 20:06:09.331275 UTC - Run: slices experiment list-resources --format json tst-3f1aa86b 2026-07-25 20:06:10.047779 UTC - Status (on expauth): UP 2026-07-25 20:06:10.047945 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 20:06:10.048101 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-3f1aa86b 2026-07-25 20:06:11.569376 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3f1aa86b tst 2026-07-25 20:06:12.915308 UTC - Run: slices experiment list-resources --format json tst-3f1aa86b 2026-07-25 20:06:14.093074 UTC - expires_at (on expauth): 2026-07-25T23:06:00Z (correctly extended) 2026-07-25 20:06:14.093170 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 20:06:14.093307 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-3f1aa86b 2026-07-25 20:06:16.025865 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-3f1aa86b tst 2026-07-25 20:06:16.896390 UTC - Run: slices experiment list-resources --format json tst-3f1aa86b 2026-07-25 20:06:17.612610 UTC - expires_at (on expauth): 2026-07-26T00:06:00Z (correctly extended) 2026-07-25 20:06:17.638781 UTC - Testing ssh login 2026-07-25 20:06:17.676987 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3f1aa86b tst 2026-07-25 20:06:18.801662 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3f1aa86b tst 2026-07-25 20:06:20.177124 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-3f1aa86b tst 2026-07-25 20:06:21.837543 UTC - Logging in using 'slices bi ssh' 2026-07-25 20:06:21.837637 UTC - Forcing IPv4 only. 2026-07-25 20:06:21.837775 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-3f1aa86b tst -- -4 uname -a 2026-07-25 20:06:25.014228 UTC - Forcing IPv4 only. 2026-07-25 20:06:25.014498 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-3f1aa86b tst -- -4 uptime 2026-07-25 20:06:27.183663 UTC - CLI SSH Test passed. 2026-07-25 20:06:27.183737 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-25 20:06:27.183756 UTC - Uptime: 20:06:27 up 1 min, 1 user, load average: 0.17, 0.08, 0.02 2026-07-25 20:06:27.183790 UTC - Logging in using SSH over SSH proxy 2026-07-25 20:06:27.183933 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-3f1aa86b tst 2026-07-25 20:06:28.357939 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 20:06:28.358073 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 20:06:28.358222 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-25 20:06:28.358578 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 20:06:28.358727 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 20:06:28.358806 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-25 20:06:28.358827 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-25 20:06:28.852361 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-25 20:06:29.733091 UTC - SSH Test output: 2026-07-25 20:06:29.733183 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-25 20:06:29.733210 UTC - Uptime: 20:06:29 up 1 min, 1 user, load average: 0.16, 0.08, 0.02 2026-07-25 20:06:29.920530 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 20:06:29.921482 UTC - lsb_release matches expected value 2026-07-25 20:06:29.921531 UTC - SSH Test passed. 2026-07-25 20:06:30.107173 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 20:06:06 +0000! 2026-07-25 20:06:30.124102 UTC - Destroying tst-3f1aa86b tst 2026-07-25 20:06:30.124277 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-3f1aa86b tst