Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.38s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 232ms 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 235ms + 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.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-24 04:00:28.154812 UTC - Installing Slices CLI (latest stable version). 2026-07-24 04:00:28.156599 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-24 04:00:35.915172 UTC - Slices CLI installed. 2026-07-24 04:00:35.915398 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-24 04:00:35.918103 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-24 04:00:35.919166 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-24 04:00:35.919293 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-24 04:00:35.919321 UTC - Slices CLI exe: 'slices' 2026-07-24 04:00:35.919340 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-24 04:00:35.919488 UTC - Run: slices --version 2026-07-24 04:00:39.467307 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-24 04:00:39.467575 UTC - Run: slices pubkey list --format text 2026-07-24 04:00:41.635884 UTC - Pubkey already registered 2026-07-24 04:00:41.636157 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-24 04:00:42.253254 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-24 04:00:42.253360 UTC - Check List Flavors 2026-07-24 04:00:42.253534 UTC - Run: slices bi --infra hu-budapest-bi-vm1 flavor list -f json 2026-07-24 04:00:43.771889 UTC - Check List DiskImages 2026-07-24 04:00:43.772086 UTC - Run: slices bi --infra hu-budapest-bi-vm1 diskimage list -f json 2026-07-24 04:00:44.638905 UTC - Requesting resources 2026-07-24 04:00:44.639600 UTC - Run: slices bi --infra hu-budapest-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-45f3dadd 2026-07-24 04:00:49.258729 UTC - Resource ID: r_hu-budapest-bi-vm1_01ky94gw8mebsv6qj7h2r1afnr 2026-07-24 04:00:49.258925 UTC - Waiting until resource ready 2026-07-24 04:00:51.259227 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-45f3dadd tst 2026-07-24 04:00:52.549039 UTC - Status: IMAGING 2026-07-24 04:00:55.686316 UTC - Status: IMAGING 2026-07-24 04:00:58.981112 UTC - Status: IMAGING 2026-07-24 04:01:02.022437 UTC - Status: IMAGING 2026-07-24 04:01:04.888596 UTC - Status: IMAGING 2026-07-24 04:01:07.704936 UTC - Status: IMAGING 2026-07-24 04:01:10.822005 UTC - Status: IMAGING 2026-07-24 04:01:13.637912 UTC - Status: IMAGING 2026-07-24 04:01:16.606846 UTC - Status: IMAGING 2026-07-24 04:01:19.690880 UTC - Status: IMAGING 2026-07-24 04:01:22.666946 UTC - Status: IMAGING 2026-07-24 04:01:25.483182 UTC - Status: IMAGING 2026-07-24 04:01:28.299627 UTC - Status: IMAGING 2026-07-24 04:01:31.115841 UTC - Status: IMAGING 2026-07-24 04:01:34.032427 UTC - Status: IMAGING 2026-07-24 04:01:36.848547 UTC - Status: IMAGING 2026-07-24 04:01:39.664697 UTC - Status: IMAGING 2026-07-24 04:01:42.480785 UTC - Status: IMAGING 2026-07-24 04:01:45.296900 UTC - Status: IMAGING 2026-07-24 04:01:48.113966 UTC - Status: IMAGING 2026-07-24 04:01:51.234257 UTC - Status: IMAGING 2026-07-24 04:01:54.050853 UTC - Status: IMAGING 2026-07-24 04:01:56.866989 UTC - Status: IMAGING 2026-07-24 04:01:59.683257 UTC - Status: IMAGING 2026-07-24 04:02:02.502420 UTC - Status: IMAGING 2026-07-24 04:02:05.323242 UTC - Status: IMAGING 2026-07-24 04:02:08.141050 UTC - Status: IMAGING 2026-07-24 04:02:11.157522 UTC - Status: UP 2026-07-24 04:02:11.157606 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky94gtptep1t20498ttsz1av 2026-07-24 04:02:11.157662 UTC - Validate resources 2026-07-24 04:02:12.133435 UTC - The fields of the created resource were validated. 2026-07-24 04:02:12.133525 UTC - Check if resources are registered in experiment 2026-07-24 04:02:12.133674 UTC - Run: slices experiment list-resources --format json tst-45f3dadd 2026-07-24 04:02:12.950778 UTC - Status (on expauth): UP 2026-07-24 04:02:12.950935 UTC - Testing extend expires_at (all resources in experiment) 2026-07-24 04:02:12.951095 UTC - Run: slices bi --infra hu-budapest-bi-vm1 extend --duration 3h --experiment tst-45f3dadd 2026-07-24 04:02:15.772887 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-45f3dadd tst 2026-07-24 04:02:16.588845 UTC - Run: slices experiment list-resources --format json tst-45f3dadd 2026-07-24 04:02:17.254181 UTC - expires_at (on expauth): 2026-07-24T07:02:00Z (correctly extended) 2026-07-24 04:02:17.254279 UTC - Testing extend expires_at (single resource in experiment) 2026-07-24 04:02:17.254434 UTC - Run: slices bi --infra hu-budapest-bi-vm1 extend tst --duration 4h --experiment tst-45f3dadd 2026-07-24 04:02:20.035819 UTC - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-45f3dadd tst 2026-07-24 04:02:21.192584 UTC - Run: slices experiment list-resources --format json tst-45f3dadd 2026-07-24 04:02:21.911323 UTC - expires_at (on expauth): 2026-07-24T08:02:00Z (correctly extended) 2026-07-24 04:02:21.911442 UTC - Testing ssh login 2026-07-24 04:02:21.929895 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-45f3dadd tst 2026-07-24 04:02:22.997167 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-45f3dadd tst 2026-07-24 04:02:24.221663 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-45f3dadd tst 2026-07-24 04:02:25.517393 UTC - Logging in using 'slices bi ssh' 2026-07-24 04:02:25.517508 UTC - Forcing IPv4 only. 2026-07-24 04:02:25.517651 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-45f3dadd tst -- -4 uname -a 2026-07-24 04:02:30.660381 UTC - Forcing IPv4 only. 2026-07-24 04:02:30.660798 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-45f3dadd tst -- -4 uptime 2026-07-24 04:02:33.285799 UTC - CLI SSH Test passed. 2026-07-24 04:02:33.286055 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-24 04:02:33.286089 UTC - Uptime: 04:02:33 up 1 min, 1 user, load average: 0.51, 0.18, 0.07 2026-07-24 04:02:33.286128 UTC - Logging in using SSH over SSH proxy 2026-07-24 04:02:33.286277 UTC - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-45f3dadd tst 2026-07-24 04:02:35.448329 UTC - Added paramiko HostKeyEntry for 192.168.0.150 2026-07-24 04:02:35.448465 UTC - Added paramiko HostKeyEntry for 192.168.0.150 2026-07-24 04:02:35.448615 UTC - Added paramiko HostKeyEntry for 192.168.0.150 2026-07-24 04:02:35.449170 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-07-24 04:02:35.449252 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-07-24 04:02:35.449366 UTC - Added paramiko HostKeyEntry for 193.225.251.128 2026-07-24 04:02:35.449386 UTC - Connecting to proxy 193.225.251.128:22 2026-07-24 04:02:35.977354 UTC - Connecting to 192.168.0.150:22 over proxy 2026-07-24 04:02:36.742686 UTC - SSH Test output: 2026-07-24 04:02:36.742778 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-24 04:02:36.742808 UTC - Uptime: 04:02:36 up 1 min, 1 user, load average: 0.51, 0.18, 0.07 2026-07-24 04:02:36.891281 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-24 04:02:36.891361 UTC - lsb_release matches expected value 2026-07-24 04:02:36.891379 UTC - SSH Test passed. 2026-07-24 04:02:36.900508 UTC - Destroying tst-45f3dadd tst 2026-07-24 04:02:36.900831 UTC - Run: slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-45f3dadd tst