Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.74s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 236ms 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 805ms + 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.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-07-31 13:02:24.157338 UTC - Installing Slices CLI (latest stable version). 2026-07-31 13:02:24.157590 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-31 13:02:29.654751 UTC - Slices CLI installed. 2026-07-31 13:02:29.656498 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-31 13:02:29.665559 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-07-31 13:02:29.665673 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-07-31 13:02:29.665966 UTC - which slices: /app/.venv/bin/slices 2026-07-31 13:02:29.665996 UTC - Slices CLI exe: 'slices' 2026-07-31 13:02:29.666016 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-31 13:02:29.666162 UTC - Run: slices --version 2026-07-31 13:02:32.485738 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-07-31 13:02:32.486034 UTC - Run: slices pubkey list --format text 2026-07-31 13:02:33.251498 UTC - Pubkey already registered 2026-07-31 13:02:33.251727 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-31 13:02:33.866848 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-31 13:02:33.866941 UTC - Check List Flavors 2026-07-31 13:02:33.867079 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-07-31 13:02:34.632694 UTC - Check List DiskImages 2026-07-31 13:02:34.632986 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-07-31 13:02:35.417338 UTC - Requesting resources 2026-07-31 13:02:35.418143 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-31 13:02:35.418265 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ac95dc6c --user-data /tmp/tmp5jos7bfz 2026-07-31 13:02:36.915258 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kyw49z4efxr9539m84nkqgjg 2026-07-31 13:02:36.915427 UTC - Waiting until resource ready 2026-07-31 13:02:38.915761 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-ac95dc6c tst 2026-07-31 13:02:39.781803 UTC - Status: IMAGING 2026-07-31 13:02:42.801687 UTC - Status: BOOTING 2026-07-31 13:02:45.672530 UTC - Status: BOOTING 2026-07-31 13:02:48.592496 UTC - Status: BOOTING 2026-07-31 13:02:52.385391 UTC - Status: BOOTING 2026-07-31 13:02:55.632199 UTC - Status: BOOTING 2026-07-31 13:02:59.452115 UTC - Status: BOOTING 2026-07-31 13:03:02.730493 UTC - Status: UP 2026-07-31 13:03:02.730592 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyw49yp1fvxtveea4e6x883e 2026-07-31 13:03:02.730633 UTC - Validate resources 2026-07-31 13:03:03.598118 UTC - The fields of the created resource were validated. 2026-07-31 13:03:03.598209 UTC - Check if resources are registered in experiment 2026-07-31 13:03:03.598352 UTC - Run: slices experiment list-resources --format json tst-ac95dc6c 2026-07-31 13:03:04.328504 UTC - Status (on expauth): UP 2026-07-31 13:03:04.328655 UTC - Testing extend expires_at (all resources in experiment) 2026-07-31 13:03:04.328809 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-ac95dc6c 2026-07-31 13:03:05.661140 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-ac95dc6c tst 2026-07-31 13:03:06.733211 UTC - Run: slices experiment list-resources --format json tst-ac95dc6c 2026-07-31 13:03:07.673942 UTC - expires_at (on expauth): 2026-07-31T16:03:00Z (correctly extended) 2026-07-31 13:03:07.677425 UTC - Testing extend expires_at (single resource in experiment) 2026-07-31 13:03:07.677655 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-ac95dc6c 2026-07-31 13:03:10.124535 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-ac95dc6c tst 2026-07-31 13:03:12.044288 UTC - Run: slices experiment list-resources --format json tst-ac95dc6c 2026-07-31 13:03:13.997850 UTC - expires_at (on expauth): 2026-07-31T17:03:00Z (correctly extended) 2026-07-31 13:03:14.004699 UTC - Testing ssh login 2026-07-31 13:03:14.044903 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-ac95dc6c tst 2026-07-31 13:03:16.379313 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ac95dc6c tst 2026-07-31 13:03:17.767822 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ac95dc6c tst 2026-07-31 13:03:19.009463 UTC - Logging in using 'slices bi ssh' 2026-07-31 13:03:19.009532 UTC - Forcing IPv4 only. 2026-07-31 13:03:19.009653 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-ac95dc6c tst -- -4 uname -a 2026-07-31 13:03:21.662164 UTC - Forcing IPv4 only. 2026-07-31 13:03:21.662442 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-ac95dc6c tst -- -4 uptime 2026-07-31 13:03:24.187862 UTC - CLI SSH Test passed. 2026-07-31 13:03:24.187938 UTC - Uname: Linux tst 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-31 13:03:24.187957 UTC - Uptime: 13:03:23 up 0 min, 1 user, load average: 0.37, 0.10, 0.04 2026-07-31 13:03:24.187990 UTC - Logging in using SSH over SSH proxy 2026-07-31 13:03:24.188123 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ac95dc6c tst 2026-07-31 13:03:26.900915 UTC - Added paramiko HostKeyEntry for 172.28.11.140 2026-07-31 13:03:26.901127 UTC - Added paramiko HostKeyEntry for 172.28.11.140 2026-07-31 13:03:26.901189 UTC - Added paramiko HostKeyEntry for 172.28.11.140 2026-07-31 13:03:26.901490 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-31 13:03:26.901584 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-31 13:03:26.901634 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-31 13:03:26.901661 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-31 13:03:27.287695 UTC - Connecting to 172.28.11.140:22 over proxy 2026-07-31 13:03:27.970414 UTC - SSH Test output: 2026-07-31 13:03:27.970485 UTC - Uname: Linux tst 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-31 13:03:27.970504 UTC - Uptime: 13:03:27 up 0 min, 1 user, load average: 0.34, 0.10, 0.03 2026-07-31 13:03:28.113339 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-31 13:03:28.113434 UTC - lsb_release matches expected value 2026-07-31 13:03:28.113453 UTC - SSH Test passed. 2026-07-31 13:03:28.248047 UTC - Cloud-init user-data: Hello World. The time is now Fri, 31 Jul 2026 13:02:59 +0000! 2026-07-31 13:03:28.259045 UTC - Destroying tst-ac95dc6c tst 2026-07-31 13:03:28.259227 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-ac95dc6c tst