Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.51s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 283ms 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 764ms + 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 04:04:03.225292 UTC - Installing Slices CLI (latest stable version). 2026-07-26 04:04:03.225718 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 04:04:08.993848 UTC - Slices CLI installed. 2026-07-26 04:04:08.999506 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 04:04:09.023950 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 04:04:09.025545 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 04:04:09.025721 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 04:04:09.025763 UTC - Slices CLI exe: 'slices' 2026-07-26 04:04:09.025794 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 04:04:09.025990 UTC - Run: slices --version 2026-07-26 04:04:12.710198 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 04:04:12.710518 UTC - Run: slices pubkey list --format text 2026-07-26 04:04:13.737900 UTC - Pubkey already registered 2026-07-26 04:04:13.738310 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 04:04:14.453956 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 04:04:14.454080 UTC - Check List Flavors 2026-07-26 04:04:14.454290 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-26 04:04:15.422009 UTC - Check List DiskImages 2026-07-26 04:04:15.422180 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-26 04:04:16.289175 UTC - Requesting resources 2026-07-26 04:04:16.289726 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 04:04:16.289836 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-84de8e35 --user-data /tmp/tmpqa2lnzs4 2026-07-26 04:04:17.947377 UTC - Resource ID: r_ch-geneva-bi-vm1_01kye9gp7mfjssgc1a6vtesx4h 2026-07-26 04:04:17.947552 UTC - Waiting until resource ready 2026-07-26 04:04:19.947890 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-84de8e35 tst 2026-07-26 04:04:20.965340 UTC - Status: IMAGING 2026-07-26 04:04:24.044940 UTC - Status: IMAGING 2026-07-26 04:04:28.376793 UTC - Status: IMAGING 2026-07-26 04:04:31.952462 UTC - Status: IMAGING 2026-07-26 04:04:34.824134 UTC - Status: IMAGING 2026-07-26 04:04:37.741564 UTC - Status: IMAGING 2026-07-26 04:04:40.922246 UTC - Status: IMAGING 2026-07-26 04:04:44.551836 UTC - Status: IMAGING 2026-07-26 04:04:47.733007 UTC - Status: IMAGING 2026-07-26 04:04:51.252635 UTC - Status: IMAGING 2026-07-26 04:04:54.438681 UTC - Status: IMAGING 2026-07-26 04:04:57.775554 UTC - Status: IMAGING 2026-07-26 04:05:01.317214 UTC - Status: IMAGING 2026-07-26 04:05:04.594287 UTC - Status: IMAGING 2026-07-26 04:05:07.770107 UTC - Status: IMAGING 2026-07-26 04:05:10.793430 UTC - Status: IMAGING 2026-07-26 04:05:14.233299 UTC - Status: IMAGING 2026-07-26 04:05:19.651372 UTC - Status: IMAGING 2026-07-26 04:05:22.880246 UTC - Status: IMAGING 2026-07-26 04:05:25.999691 UTC - Status: IMAGING 2026-07-26 04:05:29.071079 UTC - Status: IMAGING 2026-07-26 04:05:32.261706 UTC - Status: IMAGING 2026-07-26 04:05:35.536582 UTC - Status: IMAGING 2026-07-26 04:05:38.928376 UTC - Status: IMAGING 2026-07-26 04:05:41.745035 UTC - Status: IMAGING 2026-07-26 04:05:44.613076 UTC - Status: IMAGING 2026-07-26 04:05:47.596338 UTC - Status: IMAGING 2026-07-26 04:05:50.464584 UTC - Status: IMAGING 2026-07-26 04:05:53.633622 UTC - Status: IMAGING 2026-07-26 04:05:56.760290 UTC - Status: IMAGING 2026-07-26 04:05:59.730004 UTC - Status: IMAGING 2026-07-26 04:06:02.697941 UTC - Status: IMAGING 2026-07-26 04:06:05.798829 UTC - Status: IMAGING 2026-07-26 04:06:09.108569 UTC - Status: UP 2026-07-26 04:06:09.108881 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kye9gnpxeebbm47ejjf9ngf1 2026-07-26 04:06:09.108949 UTC - Validate resources 2026-07-26 04:06:10.028610 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 04:06:10.028702 UTC - The fields of the created resource were validated. 2026-07-26 04:06:10.028784 UTC - Check if resources are registered in experiment 2026-07-26 04:06:10.028953 UTC - Run: slices experiment list-resources --format json tst-84de8e35 2026-07-26 04:06:11.070445 UTC - Status (on expauth): UP 2026-07-26 04:06:11.070607 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 04:06:11.070752 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-84de8e35 2026-07-26 04:06:12.717530 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-84de8e35 tst 2026-07-26 04:06:14.141367 UTC - Run: slices experiment list-resources --format json tst-84de8e35 2026-07-26 04:06:14.976914 UTC - expires_at (on expauth): 2026-07-26T07:06:00Z (correctly extended) 2026-07-26 04:06:14.977166 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 04:06:14.977338 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-84de8e35 2026-07-26 04:06:16.395541 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-84de8e35 tst 2026-07-26 04:06:17.262006 UTC - Run: slices experiment list-resources --format json tst-84de8e35 2026-07-26 04:06:17.999202 UTC - expires_at (on expauth): 2026-07-26T08:06:00Z (correctly extended) 2026-07-26 04:06:17.999354 UTC - Testing ssh login 2026-07-26 04:06:18.031492 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-84de8e35 tst 2026-07-26 04:06:19.113284 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-84de8e35 tst 2026-07-26 04:06:20.201457 UTC - Logging in using 'slices bi ssh' 2026-07-26 04:06:20.201563 UTC - Forcing IPv4 only. 2026-07-26 04:06:20.201692 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-84de8e35 tst -- -4 uname -a 2026-07-26 04:06:23.503305 UTC - Forcing IPv4 only. 2026-07-26 04:06:23.503590 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-84de8e35 tst -- -4 uptime 2026-07-26 04:06:24.874677 UTC - CLI SSH Test passed. 2026-07-26 04:06:24.874781 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 04:06:24.874805 UTC - Uptime: 04:06:24 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-26 04:06:24.874846 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-26 04:06:24.875693 UTC - Added paramiko HostKeyEntry for 5.53.108.101 2026-07-26 04:06:24.875822 UTC - Added paramiko HostKeyEntry for 5.53.108.101 2026-07-26 04:06:24.875987 UTC - Added paramiko HostKeyEntry for 5.53.108.101 2026-07-26 04:06:24.876192 UTC - Connecting to 5.53.108.101:22 2026-07-26 04:06:25.436317 UTC - SSH Test output: 2026-07-26 04:06:25.436398 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 04:06:25.436420 UTC - Uptime: 04:06:25 up 1 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-26 04:06:25.533454 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 04:06:25.533538 UTC - lsb_release matches expected value 2026-07-26 04:06:25.533561 UTC - SSH Test passed. 2026-07-26 04:06:25.616881 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 04:05:52 +0000! 2026-07-26 04:06:25.617032 UTC - Skip SSH proxy test because no jump proxy 2026-07-26 04:06:25.628659 UTC - Destroying tst-84de8e35 tst 2026-07-26 04:06:25.628928 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-84de8e35 tst