Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 17.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 531ms 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.19s + annotated-types==0.7.0 + anyio==4.14.1 + 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.3 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-11 04:03:01.605414 UTC - Installing Slices CLI (latest stable version). 2026-07-11 04:03:01.605949 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 04:03:07.333552 UTC - Slices CLI installed. 2026-07-11 04:03:07.333800 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-11 04:03:07.336944 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-11 04:03:07.338493 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 04:03:07.338648 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-11 04:03:07.338692 UTC - Slices CLI exe: 'slices' 2026-07-11 04:03:07.338712 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 04:03:07.339332 UTC - Run: slices --version 2026-07-11 04:03:12.603099 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-11 04:03:12.603399 UTC - Run: slices pubkey list --format text 2026-07-11 04:03:14.783293 UTC - Pubkey already registered 2026-07-11 04:03:14.783534 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 04:03:16.430297 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 04:03:16.430411 UTC - Check List Flavors 2026-07-11 04:03:16.430582 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-11 04:03:19.881339 UTC - Check List DiskImages 2026-07-11 04:03:19.882322 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-11 04:03:22.219336 UTC - Requesting resources 2026-07-11 04:03:22.220066 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 04:03:22.220188 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e49c25ba --user-data /tmp/tmpmfr2dcao 2026-07-11 04:03:28.055151 UTC - Resource ID: r_ch-geneva-bi-vm1_01kx7ngc7kfv9a828yvvmxvd2y 2026-07-11 04:03:28.055641 UTC - Waiting until resource ready 2026-07-11 04:03:30.056493 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e49c25ba tst 2026-07-11 04:03:32.278243 UTC - Status: IMAGING 2026-07-11 04:03:36.842325 UTC - Status: IMAGING 2026-07-11 04:03:40.675607 UTC - Status: IMAGING 2026-07-11 04:03:43.843809 UTC - Status: IMAGING 2026-07-11 04:03:47.282371 UTC - Status: IMAGING 2026-07-11 04:03:51.834441 UTC - Status: IMAGING 2026-07-11 04:03:55.488759 UTC - Status: IMAGING 2026-07-11 04:03:59.769147 UTC - Status: IMAGING 2026-07-11 04:04:03.267977 UTC - Status: IMAGING 2026-07-11 04:04:06.938979 UTC - Status: IMAGING 2026-07-11 04:04:10.301773 UTC - Status: IMAGING 2026-07-11 04:04:13.369179 UTC - Status: IMAGING 2026-07-11 04:04:16.286610 UTC - Status: IMAGING 2026-07-11 04:04:19.159656 UTC - Status: IMAGING 2026-07-11 04:04:23.229764 UTC - Status: IMAGING 2026-07-11 04:04:25.995600 UTC - Status: IMAGING 2026-07-11 04:04:28.763212 UTC - Status: IMAGING 2026-07-11 04:04:31.529232 UTC - Status: IMAGING 2026-07-11 04:04:34.295170 UTC - Status: IMAGING 2026-07-11 04:04:37.061590 UTC - Status: IMAGING 2026-07-11 04:04:39.877806 UTC - Status: IMAGING 2026-07-11 04:04:42.694812 UTC - Status: IMAGING 2026-07-11 04:04:45.461422 UTC - Status: IMAGING 2026-07-11 04:04:48.227580 UTC - Status: IMAGING 2026-07-11 04:04:50.994136 UTC - Status: IMAGING 2026-07-11 04:04:53.810566 UTC - Status: IMAGING 2026-07-11 04:04:56.578009 UTC - Status: IMAGING 2026-07-11 04:04:59.344282 UTC - Status: IMAGING 2026-07-11 04:05:02.162143 UTC - Status: IMAGING 2026-07-11 04:05:04.928947 UTC - Status: IMAGING 2026-07-11 04:05:07.695054 UTC - Status: IMAGING 2026-07-11 04:05:10.461596 UTC - Status: IMAGING 2026-07-11 04:05:13.228056 UTC - Status: IMAGING 2026-07-11 04:05:15.994651 UTC - Status: IMAGING 2026-07-11 04:05:18.760824 UTC - Status: UP 2026-07-11 04:05:18.760905 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx7ngawke6xvnm90yxy6ct94 2026-07-11 04:05:18.760962 UTC - Validate resources 2026-07-11 04:05:19.527219 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-11 04:05:19.527299 UTC - The fields of the created resource were validated. 2026-07-11 04:05:19.527344 UTC - Check if resources are registered in experiment 2026-07-11 04:05:19.527547 UTC - Run: slices experiment list-resources --format json tst-e49c25ba 2026-07-11 04:05:20.243227 UTC - Status (on expauth): UP 2026-07-11 04:05:20.243393 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 04:05:20.243530 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e49c25ba 2026-07-11 04:05:23.165066 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e49c25ba tst 2026-07-11 04:05:23.980906 UTC - Run: slices experiment list-resources --format json tst-e49c25ba 2026-07-11 04:05:24.709459 UTC - expires_at (on expauth): 2026-07-11T07:05:00Z (correctly extended) 2026-07-11 04:05:24.709592 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 04:05:24.709777 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e49c25ba 2026-07-11 04:05:27.738281 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e49c25ba tst 2026-07-11 04:05:28.661311 UTC - Run: slices experiment list-resources --format json tst-e49c25ba 2026-07-11 04:05:29.891692 UTC - expires_at (on expauth): 2026-07-11T08:05:00Z (correctly extended) 2026-07-11 04:05:29.891843 UTC - Testing ssh login 2026-07-11 04:05:29.917714 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e49c25ba tst 2026-07-11 04:05:31.220790 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e49c25ba tst 2026-07-11 04:05:32.618266 UTC - Logging in using 'slices bi ssh' 2026-07-11 04:05:32.618370 UTC - Forcing IPv4 only. 2026-07-11 04:05:32.618505 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e49c25ba tst -- -4 uname -a 2026-07-11 04:05:35.830893 UTC - Forcing IPv4 only. 2026-07-11 04:05:35.846813 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e49c25ba tst -- -4 uptime 2026-07-11 04:05:38.749012 UTC - CLI SSH Test passed. 2026-07-11 04:05:38.749109 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-11 04:05:38.749138 UTC - Uptime: 04:05:38 up 1 min, 1 user, load average: 0.18, 0.06, 0.02 2026-07-11 04:05:38.749188 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-11 04:05:38.749711 UTC - Added paramiko HostKeyEntry for 5.53.108.92 2026-07-11 04:05:38.749816 UTC - Added paramiko HostKeyEntry for 5.53.108.92 2026-07-11 04:05:38.750003 UTC - Added paramiko HostKeyEntry for 5.53.108.92 2026-07-11 04:05:38.750188 UTC - Connecting to 5.53.108.92:22 2026-07-11 04:05:39.306688 UTC - SSH Test output: 2026-07-11 04:05:39.306761 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-11 04:05:39.306782 UTC - Uptime: 04:05:39 up 1 min, 1 user, load average: 0.18, 0.06, 0.02 2026-07-11 04:05:39.407148 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 04:05:39.407233 UTC - lsb_release matches expected value 2026-07-11 04:05:39.407256 UTC - SSH Test passed. 2026-07-11 04:05:39.499476 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 04:05:00 +0000! 2026-07-11 04:05:39.519981 UTC - Skip SSH proxy test because no jump proxy 2026-07-11 04:05:39.618946 UTC - Destroying tst-e49c25ba tst 2026-07-11 04:05:39.622030 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e49c25ba tst