Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.63s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 847ms 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.08s + 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.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-21 20:04:09.218138 UTC - Installing Slices CLI (latest stable version). 2026-07-21 20:04:09.218419 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 20:04:15.384185 UTC - Slices CLI installed. 2026-07-21 20:04:15.384714 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-21 20:04:15.387994 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-21 20:04:15.389718 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 20:04:15.389942 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-21 20:04:15.389985 UTC - Slices CLI exe: 'slices' 2026-07-21 20:04:15.390012 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 20:04:15.390186 UTC - Run: slices --version 2026-07-21 20:04:23.345101 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-21 20:04:23.346273 UTC - Run: slices pubkey list --format text 2026-07-21 20:04:25.010318 UTC - Pubkey already registered 2026-07-21 20:04:25.010609 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 20:04:26.865746 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 20:04:26.866074 UTC - Check List Flavors 2026-07-21 20:04:26.866340 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-21 20:04:30.277137 UTC - Check List DiskImages 2026-07-21 20:04:30.277412 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-21 20:04:31.343986 UTC - Requesting resources 2026-07-21 20:04:31.345022 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-21 20:04:31.345185 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-005a8ac7 --user-data /tmp/tmp2biclo9_ 2026-07-21 20:04:34.992107 UTC - Resource ID: r_ch-geneva-bi-vm1_01ky34fdt5ef882jah3s65jxpe 2026-07-21 20:04:34.992744 UTC - Waiting until resource ready 2026-07-21 20:04:36.993244 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-005a8ac7 tst 2026-07-21 20:04:39.221482 UTC - Status: IMAGING 2026-07-21 20:04:43.016210 UTC - Status: IMAGING 2026-07-21 20:04:47.008680 UTC - Status: IMAGING 2026-07-21 20:04:49.825351 UTC - Status: IMAGING 2026-07-21 20:04:52.994275 UTC - Status: IMAGING 2026-07-21 20:04:56.320042 UTC - Status: IMAGING 2026-07-21 20:05:00.402059 UTC - Status: IMAGING 2026-07-21 20:05:03.984919 UTC - Status: IMAGING 2026-07-21 20:05:07.101553 UTC - Status: IMAGING 2026-07-21 20:05:10.737026 UTC - Status: IMAGING 2026-07-21 20:05:14.027329 UTC - Status: IMAGING 2026-07-21 20:05:18.140029 UTC - Status: IMAGING 2026-07-21 20:05:22.092131 UTC - Status: IMAGING 2026-07-21 20:05:26.437121 UTC - Status: IMAGING 2026-07-21 20:05:29.987157 UTC - Status: IMAGING 2026-07-21 20:05:34.326800 UTC - Status: IMAGING 2026-07-21 20:05:37.348494 UTC - Status: IMAGING 2026-07-21 20:05:40.466463 UTC - Status: IMAGING 2026-07-21 20:05:43.877283 UTC - Status: IMAGING 2026-07-21 20:05:48.327115 UTC - Status: IMAGING 2026-07-21 20:05:52.347363 UTC - Status: IMAGING 2026-07-21 20:05:56.543830 UTC - Status: IMAGING 2026-07-21 20:05:59.781589 UTC - Status: IMAGING 2026-07-21 20:06:03.521353 UTC - Status: IMAGING 2026-07-21 20:06:06.992882 UTC - Status: IMAGING 2026-07-21 20:06:10.179845 UTC - Status: IMAGING 2026-07-21 20:06:13.046649 UTC - Status: IMAGING 2026-07-21 20:06:15.966601 UTC - Status: IMAGING 2026-07-21 20:06:18.783174 UTC - Status: IMAGING 2026-07-21 20:06:21.699690 UTC - Status: IMAGING 2026-07-21 20:06:24.616284 UTC - Status: IMAGING 2026-07-21 20:06:27.585910 UTC - Status: UP 2026-07-21 20:06:27.585997 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky34fcmperdsy09a6pcc955a 2026-07-21 20:06:27.586056 UTC - Validate resources 2026-07-21 20:06:28.607464 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-21 20:06:28.607598 UTC - The fields of the created resource were validated. 2026-07-21 20:06:28.607675 UTC - Check if resources are registered in experiment 2026-07-21 20:06:28.607904 UTC - Run: slices experiment list-resources --format json tst-005a8ac7 2026-07-21 20:06:29.374189 UTC - Status (on expauth): UP 2026-07-21 20:06:29.374374 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 20:06:29.374617 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-005a8ac7 2026-07-21 20:06:32.829451 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-005a8ac7 tst 2026-07-21 20:06:34.025377 UTC - Run: slices experiment list-resources --format json tst-005a8ac7 2026-07-21 20:06:35.597768 UTC - expires_at (on expauth): 2026-07-21T23:06:00Z (correctly extended) 2026-07-21 20:06:35.599051 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 20:06:35.599327 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-005a8ac7 2026-07-21 20:06:39.159606 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-005a8ac7 tst 2026-07-21 20:06:40.766122 UTC - Run: slices experiment list-resources --format json tst-005a8ac7 2026-07-21 20:06:41.583729 UTC - expires_at (on expauth): 2026-07-22T00:06:00Z (correctly extended) 2026-07-21 20:06:41.583900 UTC - Testing ssh login 2026-07-21 20:06:41.624946 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-005a8ac7 tst 2026-07-21 20:06:43.532649 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-005a8ac7 tst 2026-07-21 20:06:45.817668 UTC - Logging in using 'slices bi ssh' 2026-07-21 20:06:45.817767 UTC - Forcing IPv4 only. 2026-07-21 20:06:45.817912 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-005a8ac7 tst -- -4 uname -a 2026-07-21 20:06:49.398411 UTC - Forcing IPv4 only. 2026-07-21 20:06:49.398645 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-005a8ac7 tst -- -4 uptime 2026-07-21 20:06:50.929153 UTC - CLI SSH Test passed. 2026-07-21 20:06:50.929277 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-21 20:06:50.929297 UTC - Uptime: 20:06:50 up 1 min, 1 user, load average: 0.14, 0.05, 0.02 2026-07-21 20:06:50.929340 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-21 20:06:51.110039 UTC - Added paramiko HostKeyEntry for 5.53.108.227 2026-07-21 20:06:51.110313 UTC - Added paramiko HostKeyEntry for 5.53.108.227 2026-07-21 20:06:51.110528 UTC - Added paramiko HostKeyEntry for 5.53.108.227 2026-07-21 20:06:51.110812 UTC - Connecting to 5.53.108.227:22 2026-07-21 20:06:52.654943 UTC - SSH Test output: 2026-07-21 20:06:52.655223 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-21 20:06:52.655266 UTC - Uptime: 20:06:52 up 1 min, 1 user, load average: 0.14, 0.05, 0.02 2026-07-21 20:06:52.747469 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-21 20:06:52.747571 UTC - lsb_release matches expected value 2026-07-21 20:06:52.747594 UTC - SSH Test passed. 2026-07-21 20:06:52.826746 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 20:06:05 +0000! 2026-07-21 20:06:52.826900 UTC - Skip SSH proxy test because no jump proxy 2026-07-21 20:06:52.844588 UTC - Destroying tst-005a8ac7 tst 2026-07-21 20:06:52.845457 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-005a8ac7 tst