Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.05s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 299ms 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 745ms + 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-26 13:04:08.443480 UTC - Installing Slices CLI (latest stable version). 2026-07-26 13:04:08.443869 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 13:04:14.780002 UTC - Slices CLI installed. 2026-07-26 13:04:14.780899 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-26 13:04:14.785522 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-26 13:04:14.787164 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 13:04:14.787461 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-26 13:04:14.787506 UTC - Slices CLI exe: 'slices' 2026-07-26 13:04:14.787526 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 13:04:14.787651 UTC - Run: slices --version 2026-07-26 13:04:18.537045 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-26 13:04:18.537370 UTC - Run: slices pubkey list --format text 2026-07-26 13:04:19.503995 UTC - Pubkey already registered 2026-07-26 13:04:19.504238 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 13:04:20.169877 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 13:04:20.169969 UTC - Check List Flavors 2026-07-26 13:04:20.170102 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-26 13:04:21.537673 UTC - Check List DiskImages 2026-07-26 13:04:21.537845 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-26 13:04:22.305605 UTC - Requesting resources 2026-07-26 13:04:22.306272 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 13:04:22.306404 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-d5e46805 --user-data /tmp/tmphshrhbkb 2026-07-26 13:04:24.088234 UTC - Resource ID: r_ch-geneva-bi-vm1_01kyf8dmvreph9vxf0yj74bdc3 2026-07-26 13:04:24.088383 UTC - Waiting until resource ready 2026-07-26 13:04:26.088761 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d5e46805 tst 2026-07-26 13:04:26.854345 UTC - Status: IMAGING 2026-07-26 13:04:29.773451 UTC - Status: IMAGING 2026-07-26 13:04:32.746476 UTC - Status: IMAGING 2026-07-26 13:04:36.415058 UTC - Status: IMAGING 2026-07-26 13:04:39.601310 UTC - Status: IMAGING 2026-07-26 13:04:42.572527 UTC - Status: IMAGING 2026-07-26 13:04:45.691461 UTC - Status: IMAGING 2026-07-26 13:04:48.560595 UTC - Status: IMAGING 2026-07-26 13:04:51.663171 UTC - Status: IMAGING 2026-07-26 13:04:54.792013 UTC - Status: IMAGING 2026-07-26 13:04:57.778113 UTC - Status: IMAGING 2026-07-26 13:05:00.844876 UTC - Status: IMAGING 2026-07-26 13:05:04.127531 UTC - Status: IMAGING 2026-07-26 13:05:07.260850 UTC - Status: IMAGING 2026-07-26 13:05:10.187925 UTC - Status: IMAGING 2026-07-26 13:05:13.108120 UTC - Status: IMAGING 2026-07-26 13:05:16.285153 UTC - Status: IMAGING 2026-07-26 13:05:19.323074 UTC - Status: IMAGING 2026-07-26 13:05:22.801093 UTC - Status: IMAGING 2026-07-26 13:05:26.355357 UTC - Status: IMAGING 2026-07-26 13:05:29.552195 UTC - Status: IMAGING 2026-07-26 13:05:32.572410 UTC - Status: IMAGING 2026-07-26 13:05:35.897419 UTC - Status: IMAGING 2026-07-26 13:05:39.025234 UTC - Status: IMAGING 2026-07-26 13:05:41.943777 UTC - Status: IMAGING 2026-07-26 13:05:44.820121 UTC - Status: IMAGING 2026-07-26 13:05:47.585968 UTC - Status: IMAGING 2026-07-26 13:05:50.877282 UTC - Status: IMAGING 2026-07-26 13:05:53.903601 UTC - Status: IMAGING 2026-07-26 13:05:56.719983 UTC - Status: IMAGING 2026-07-26 13:05:59.649876 UTC - Status: IMAGING 2026-07-26 13:06:02.741272 UTC - Status: IMAGING 2026-07-26 13:06:05.617114 UTC - Status: IMAGING 2026-07-26 13:06:08.835250 UTC - Status: IMAGING 2026-07-26 13:06:12.415216 UTC - Status: IMAGING 2026-07-26 13:06:15.492197 UTC - Status: UP 2026-07-26 13:06:15.492319 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyf8dmc0ek7r093q41rvt6zg 2026-07-26 13:06:15.492369 UTC - Validate resources 2026-07-26 13:06:16.413482 UTC - "slices bi list-resources" returned resource without private IPv4 address, but with a public IPv4 address. As test_allow_public_ipv4_instead_of_private is set, that is acceptable. 2026-07-26 13:06:16.413556 UTC - The fields of the created resource were validated. 2026-07-26 13:06:16.413597 UTC - Check if resources are registered in experiment 2026-07-26 13:06:16.413745 UTC - Run: slices experiment list-resources --format json tst-d5e46805 2026-07-26 13:06:17.279757 UTC - Status (on expauth): UP 2026-07-26 13:06:17.279921 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 13:06:17.280041 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-d5e46805 2026-07-26 13:06:18.951312 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d5e46805 tst 2026-07-26 13:06:20.386230 UTC - Run: slices experiment list-resources --format json tst-d5e46805 2026-07-26 13:06:21.406427 UTC - expires_at (on expauth): 2026-07-26T16:06:00Z (correctly extended) 2026-07-26 13:06:21.406533 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 13:06:21.406668 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-d5e46805 2026-07-26 13:06:22.731629 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-d5e46805 tst 2026-07-26 13:06:23.647877 UTC - Run: slices experiment list-resources --format json tst-d5e46805 2026-07-26 13:06:24.418511 UTC - expires_at (on expauth): 2026-07-26T17:06:00Z (correctly extended) 2026-07-26 13:06:24.418636 UTC - Testing ssh login 2026-07-26 13:06:24.472017 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d5e46805 tst 2026-07-26 13:06:25.342640 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d5e46805 tst 2026-07-26 13:06:26.208461 UTC - Logging in using 'slices bi ssh' 2026-07-26 13:06:26.208574 UTC - Forcing IPv4 only. 2026-07-26 13:06:26.208702 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-d5e46805 tst -- -4 uname -a 2026-07-26 13:06:29.232364 UTC - Forcing IPv4 only. 2026-07-26 13:06:29.232574 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-d5e46805 tst -- -4 uptime 2026-07-26 13:06:30.651710 UTC - CLI SSH Test passed. 2026-07-26 13:06:30.651927 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-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-26 13:06:30.651959 UTC - Uptime: 13:06:30 up 1 min, 1 user, load average: 0.47, 0.16, 0.05 2026-07-26 13:06:30.651993 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-26 13:06:30.652473 UTC - Added paramiko HostKeyEntry for 5.53.108.130 2026-07-26 13:06:30.652555 UTC - Added paramiko HostKeyEntry for 5.53.108.130 2026-07-26 13:06:30.652707 UTC - Added paramiko HostKeyEntry for 5.53.108.130 2026-07-26 13:06:30.652982 UTC - Connecting to 5.53.108.130:22 2026-07-26 13:06:31.212100 UTC - SSH Test output: 2026-07-26 13:06:31.212186 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-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-26 13:06:31.212220 UTC - Uptime: 13:06:31 up 1 min, 1 user, load average: 0.47, 0.16, 0.05 2026-07-26 13:06:31.309868 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 13:06:31.309945 UTC - lsb_release matches expected value 2026-07-26 13:06:31.309966 UTC - SSH Test passed. 2026-07-26 13:06:31.394810 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 13:05:54 +0000! 2026-07-26 13:06:31.394975 UTC - Skip SSH proxy test because no jump proxy 2026-07-26 13:06:31.402662 UTC - Destroying tst-d5e46805 tst 2026-07-26 13:06:31.402836 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-d5e46805 tst