Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.36s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 434ms 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.32s + 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 04:04:08.844141 UTC - Installing Slices CLI (latest stable version). 2026-07-19 04:04:08.845269 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 04:04:15.727105 UTC - Slices CLI installed. 2026-07-19 04:04:15.727490 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 04:04:15.730135 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 04:04:15.731273 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 04:04:15.731420 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 04:04:15.731454 UTC - Slices CLI exe: 'slices' 2026-07-19 04:04:15.731473 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 04:04:15.731599 UTC - Run: slices --version 2026-07-19 04:04:19.354711 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 04:04:19.355012 UTC - Run: slices pubkey list --format text 2026-07-19 04:04:20.271493 UTC - Pubkey already registered 2026-07-19 04:04:20.271886 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 04:04:22.083732 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 04:04:22.083890 UTC - Check List Flavors 2026-07-19 04:04:22.084066 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-19 04:04:24.110227 UTC - Check List DiskImages 2026-07-19 04:04:24.110425 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-19 04:04:25.405859 UTC - Requesting resources 2026-07-19 04:04:25.407594 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 04:04:25.408005 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-40309514 --user-data /tmp/tmp5kjag9rx 2026-07-19 04:04:30.402052 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxw8r1c1fpratceq99mz3bjf 2026-07-19 04:04:30.402405 UTC - Waiting until resource ready 2026-07-19 04:04:32.402845 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-40309514 tst 2026-07-19 04:04:33.542083 UTC - Status: IMAGING 2026-07-19 04:04:36.408672 UTC - Status: IMAGING 2026-07-19 04:04:39.916603 UTC - Status: BOOTING 2026-07-19 04:04:42.997991 UTC - Status: BOOTING 2026-07-19 04:04:46.570645 UTC - Status: BOOTING 2026-07-19 04:04:50.245180 UTC - Status: BOOTING 2026-07-19 04:04:53.474794 UTC - Status: BOOTING 2026-07-19 04:04:57.174858 UTC - Status: BOOTING 2026-07-19 04:05:00.831192 UTC - Status: BOOTING 2026-07-19 04:05:04.078179 UTC - Status: BOOTING 2026-07-19 04:05:07.730341 UTC - Status: UP 2026-07-19 04:05:07.730616 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxw8qz02ecdvkg7avc5nv4mj 2026-07-19 04:05:07.730675 UTC - Validate resources 2026-07-19 04:05:09.106299 UTC - The fields of the created resource were validated. 2026-07-19 04:05:09.106420 UTC - Check if resources are registered in experiment 2026-07-19 04:05:09.106580 UTC - Run: slices experiment list-resources --format json tst-40309514 2026-07-19 04:05:09.777033 UTC - Status (on expauth): UP 2026-07-19 04:05:09.777489 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 04:05:09.777617 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-40309514 2026-07-19 04:05:13.071247 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-40309514 tst 2026-07-19 04:05:14.294196 UTC - Run: slices experiment list-resources --format json tst-40309514 2026-07-19 04:05:15.110427 UTC - expires_at (on expauth): 2026-07-19T07:05:00Z (correctly extended) 2026-07-19 04:05:15.110517 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 04:05:15.110642 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-40309514 2026-07-19 04:05:18.433583 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-40309514 tst 2026-07-19 04:05:19.199251 UTC - Run: slices experiment list-resources --format json tst-40309514 2026-07-19 04:05:19.865683 UTC - expires_at (on expauth): 2026-07-19T08:05:00Z (correctly extended) 2026-07-19 04:05:19.865825 UTC - Testing ssh login 2026-07-19 04:05:19.878160 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-40309514 tst 2026-07-19 04:05:20.743879 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-40309514 tst 2026-07-19 04:05:21.659682 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-40309514 tst 2026-07-19 04:05:23.191674 UTC - Logging in using 'slices bi ssh' 2026-07-19 04:05:23.192179 UTC - Forcing IPv4 only. 2026-07-19 04:05:23.192337 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-40309514 tst -- -4 uname -a 2026-07-19 04:05:26.015565 UTC - Forcing IPv4 only. 2026-07-19 04:05:26.015749 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-40309514 tst -- -4 uptime 2026-07-19 04:05:27.984307 UTC - CLI SSH Test passed. 2026-07-19 04:05:27.984381 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 04:05:27.984401 UTC - Uptime: 04:05:27 up 0 min, 1 user, load average: 0.29, 0.09, 0.03 2026-07-19 04:05:27.984433 UTC - Logging in using SSH over SSH proxy 2026-07-19 04:05:27.984570 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-40309514 tst 2026-07-19 04:05:29.207500 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 04:05:29.207642 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 04:05:29.207812 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-19 04:05:29.208293 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 04:05:29.208426 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 04:05:29.208502 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-19 04:05:29.208523 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-19 04:05:29.663816 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-19 04:05:30.468669 UTC - SSH Test output: 2026-07-19 04:05:30.468782 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 04:05:30.468941 UTC - Uptime: 04:05:30 up 0 min, 1 user, load average: 0.29, 0.09, 0.03 2026-07-19 04:05:30.636584 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-19 04:05:30.636662 UTC - lsb_release matches expected value 2026-07-19 04:05:30.636682 UTC - SSH Test passed. 2026-07-19 04:05:30.803602 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 04:05:06 +0000! 2026-07-19 04:05:30.820972 UTC - Destroying tst-40309514 tst 2026-07-19 04:05:30.821193 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-40309514 tst