Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.19s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 525ms 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 931ms + annotated-types==0.7.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-22 04:03:31.628918 UTC - Installing Slices CLI (latest stable version). 2026-07-22 04:03:31.633001 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 04:03:37.083612 UTC - Slices CLI installed. 2026-07-22 04:03:37.083964 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-22 04:03:37.092897 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-22 04:03:37.094076 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 04:03:37.094378 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-22 04:03:37.094431 UTC - Slices CLI exe: 'slices' 2026-07-22 04:03:37.094451 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 04:03:37.094582 UTC - Run: slices --version 2026-07-22 04:03:42.496197 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-22 04:03:42.496481 UTC - Run: slices pubkey list --format text 2026-07-22 04:03:44.524783 UTC - Pubkey already registered 2026-07-22 04:03:44.525067 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 04:03:45.943265 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 04:03:45.943515 UTC - Check List Flavors 2026-07-22 04:03:45.943701 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-22 04:03:47.953901 UTC - Check List DiskImages 2026-07-22 04:03:47.954112 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-22 04:03:49.475751 UTC - Requesting resources 2026-07-22 04:03:49.476827 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 04:03:49.476984 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b219049d --user-data /tmp/tmpycunu70f 2026-07-22 04:03:52.501450 UTC - Resource ID: r_ch-geneva-bi-vm1_01ky3zx10seg1a0pr7yq6dge1e 2026-07-22 04:03:52.501818 UTC - Waiting until resource ready 2026-07-22 04:03:54.502181 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b219049d tst 2026-07-22 04:03:55.423469 UTC - Status: IMAGING 2026-07-22 04:03:58.508212 UTC - Status: IMAGING 2026-07-22 04:04:01.933276 UTC - Status: IMAGING 2026-07-22 04:04:05.947612 UTC - Status: IMAGING 2026-07-22 04:04:09.127610 UTC - Status: IMAGING 2026-07-22 04:04:11.894097 UTC - Status: IMAGING 2026-07-22 04:04:15.021460 UTC - Status: IMAGING 2026-07-22 04:04:18.141464 UTC - Status: IMAGING 2026-07-22 04:04:21.423305 UTC - Status: IMAGING 2026-07-22 04:04:24.378646 UTC - Status: IMAGING 2026-07-22 04:04:27.688599 UTC - Status: IMAGING 2026-07-22 04:04:30.865103 UTC - Status: IMAGING 2026-07-22 04:04:33.889134 UTC - Status: IMAGING 2026-07-22 04:04:36.907879 UTC - Status: IMAGING 2026-07-22 04:04:39.980079 UTC - Status: IMAGING 2026-07-22 04:04:43.154035 UTC - Status: IMAGING 2026-07-22 04:04:46.273916 UTC - Status: IMAGING 2026-07-22 04:04:50.089660 UTC - Status: IMAGING 2026-07-22 04:04:53.060371 UTC - Status: IMAGING 2026-07-22 04:04:55.926378 UTC - Status: IMAGING 2026-07-22 04:04:58.693316 UTC - Status: IMAGING 2026-07-22 04:05:01.711827 UTC - Status: IMAGING 2026-07-22 04:05:04.578186 UTC - Status: IMAGING 2026-07-22 04:05:07.344656 UTC - Status: IMAGING 2026-07-22 04:05:10.160701 UTC - Status: IMAGING 2026-07-22 04:05:12.926929 UTC - Status: IMAGING 2026-07-22 04:05:15.743384 UTC - Status: IMAGING 2026-07-22 04:05:18.560081 UTC - Status: IMAGING 2026-07-22 04:05:21.327520 UTC - Status: IMAGING 2026-07-22 04:05:24.094094 UTC - Status: IMAGING 2026-07-22 04:05:27.076402 UTC - Status: IMAGING 2026-07-22 04:05:30.108298 UTC - Status: IMAGING 2026-07-22 04:05:33.844984 UTC - Status: IMAGING 2026-07-22 04:05:37.955743 UTC - Status: IMAGING 2026-07-22 04:05:41.651197 UTC - Status: IMAGING 2026-07-22 04:05:44.939814 UTC - Status: UP 2026-07-22 04:05:44.939926 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky3zx06pey18mqrxrgnb5cza 2026-07-22 04:05:44.939986 UTC - Validate resources 2026-07-22 04:05:46.440681 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-22 04:05:46.440810 UTC - The fields of the created resource were validated. 2026-07-22 04:05:46.440869 UTC - Check if resources are registered in experiment 2026-07-22 04:05:46.441048 UTC - Run: slices experiment list-resources --format json tst-b219049d 2026-07-22 04:05:48.091550 UTC - Status (on expauth): UP 2026-07-22 04:05:48.091784 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 04:05:48.091991 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-b219049d 2026-07-22 04:05:50.678816 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b219049d tst 2026-07-22 04:05:51.646534 UTC - Run: slices experiment list-resources --format json tst-b219049d 2026-07-22 04:05:52.362620 UTC - expires_at (on expauth): 2026-07-22T07:05:00Z (correctly extended) 2026-07-22 04:05:52.362750 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 04:05:52.362916 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-b219049d 2026-07-22 04:05:54.081565 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b219049d tst 2026-07-22 04:05:55.048162 UTC - Run: slices experiment list-resources --format json tst-b219049d 2026-07-22 04:05:55.863709 UTC - expires_at (on expauth): 2026-07-22T08:05:00Z (correctly extended) 2026-07-22 04:05:55.863822 UTC - Testing ssh login 2026-07-22 04:05:55.900687 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b219049d tst 2026-07-22 04:05:57.184656 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b219049d tst 2026-07-22 04:05:58.000308 UTC - Logging in using 'slices bi ssh' 2026-07-22 04:05:58.000417 UTC - Forcing IPv4 only. 2026-07-22 04:05:58.000594 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-b219049d tst -- -4 uname -a 2026-07-22 04:06:01.093040 UTC - Forcing IPv4 only. 2026-07-22 04:06:01.093237 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-b219049d tst -- -4 uptime 2026-07-22 04:06:03.013293 UTC - CLI SSH Test passed. 2026-07-22 04:06:03.013368 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-22 04:06:03.013386 UTC - Uptime: 04:06:02 up 1 min, 1 user, load average: 0.20, 0.07, 0.02 2026-07-22 04:06:03.013420 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-22 04:06:03.014179 UTC - Added paramiko HostKeyEntry for 5.53.108.163 2026-07-22 04:06:03.014295 UTC - Added paramiko HostKeyEntry for 5.53.108.163 2026-07-22 04:06:03.014443 UTC - Added paramiko HostKeyEntry for 5.53.108.163 2026-07-22 04:06:03.014795 UTC - Connecting to 5.53.108.163:22 2026-07-22 04:06:03.560408 UTC - SSH Test output: 2026-07-22 04:06:03.560532 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-22 04:06:03.560678 UTC - Uptime: 04:06:03 up 1 min, 1 user, load average: 0.27, 0.09, 0.03 2026-07-22 04:06:03.671138 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 04:06:03.671487 UTC - lsb_release matches expected value 2026-07-22 04:06:03.671519 UTC - SSH Test passed. 2026-07-22 04:06:03.741348 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 04:05:25 +0000! 2026-07-22 04:06:03.741510 UTC - Skip SSH proxy test because no jump proxy 2026-07-22 04:06:03.754965 UTC - Destroying tst-b219049d tst 2026-07-22 04:06:03.755228 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-b219049d tst