Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 9.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 658ms 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 888ms + 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 13:03:01.732844 UTC - Installing Slices CLI (latest stable version). 2026-07-11 13:03:01.733528 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 13:03:08.809269 UTC - Slices CLI installed. 2026-07-11 13:03:08.809560 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 13:03:08.811885 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 13:03:08.813644 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 13:03:08.813911 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 13:03:08.813946 UTC - Slices CLI exe: 'slices' 2026-07-11 13:03:08.813966 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 13:03:08.814106 UTC - Run: slices --version 2026-07-11 13:03:15.242432 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 13:03:15.242912 UTC - Run: slices pubkey list --format text 2026-07-11 13:03:17.703466 UTC - Pubkey already registered 2026-07-11 13:03:17.703719 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 13:03:19.979022 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 13:03:19.979139 UTC - Check List Flavors 2026-07-11 13:03:19.979291 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-11 13:03:22.302397 UTC - Check List DiskImages 2026-07-11 13:03:22.302610 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-11 13:03:23.665205 UTC - Requesting resources 2026-07-11 13:03:23.666061 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 13:03:23.666177 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e6afbdb2 --user-data /tmp/tmpfxc2aczn 2026-07-11 13:03:27.855361 UTC - Resource ID: r_ch-geneva-bi-vm1_01kx8md4r2e8grsm6r4jv7352n 2026-07-11 13:03:27.855543 UTC - Waiting until resource ready 2026-07-11 13:03:29.855854 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e6afbdb2 tst 2026-07-11 13:03:32.255530 UTC - Status: IMAGING 2026-07-11 13:03:36.039175 UTC - Status: IMAGING 2026-07-11 13:03:40.005101 UTC - Status: IMAGING 2026-07-11 13:03:43.560691 UTC - Status: IMAGING 2026-07-11 13:03:46.669248 UTC - Status: IMAGING 2026-07-11 13:03:50.341548 UTC - Status: IMAGING 2026-07-11 13:03:54.550630 UTC - Status: IMAGING 2026-07-11 13:03:58.572626 UTC - Status: IMAGING 2026-07-11 13:04:03.065333 UTC - Status: IMAGING 2026-07-11 13:04:07.052164 UTC - Status: IMAGING 2026-07-11 13:04:10.428115 UTC - Status: IMAGING 2026-07-11 13:04:13.521107 UTC - Status: IMAGING 2026-07-11 13:04:16.438568 UTC - Status: IMAGING 2026-07-11 13:04:19.418331 UTC - Status: IMAGING 2026-07-11 13:04:22.335571 UTC - Status: IMAGING 2026-07-11 13:04:25.101286 UTC - Status: IMAGING 2026-07-11 13:04:27.867208 UTC - Status: IMAGING 2026-07-11 13:04:30.632845 UTC - Status: IMAGING 2026-07-11 13:04:33.448658 UTC - Status: IMAGING 2026-07-11 13:04:36.214537 UTC - Status: IMAGING 2026-07-11 13:04:38.980463 UTC - Status: IMAGING 2026-07-11 13:04:41.696239 UTC - Status: IMAGING 2026-07-11 13:04:44.462305 UTC - Status: IMAGING 2026-07-11 13:04:47.228417 UTC - Status: IMAGING 2026-07-11 13:04:49.994408 UTC - Status: IMAGING 2026-07-11 13:04:52.760421 UTC - Status: IMAGING 2026-07-11 13:04:55.626950 UTC - Status: IMAGING 2026-07-11 13:04:58.443652 UTC - Status: IMAGING 2026-07-11 13:05:01.209899 UTC - Status: IMAGING 2026-07-11 13:05:03.976414 UTC - Status: IMAGING 2026-07-11 13:05:06.742877 UTC - Status: IMAGING 2026-07-11 13:05:09.509538 UTC - Status: IMAGING 2026-07-11 13:05:12.326054 UTC - Status: IMAGING 2026-07-11 13:05:15.092204 UTC - Status: IMAGING 2026-07-11 13:05:17.858592 UTC - Status: UP 2026-07-11 13:05:17.858703 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx8md3hfe7798mr0xgmmhdvj 2026-07-11 13:05:17.858754 UTC - Validate resources 2026-07-11 13:05:18.625978 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 13:05:18.626072 UTC - The fields of the created resource were validated. 2026-07-11 13:05:18.626118 UTC - Check if resources are registered in experiment 2026-07-11 13:05:18.626300 UTC - Run: slices experiment list-resources --format json tst-e6afbdb2 2026-07-11 13:05:19.292083 UTC - Status (on expauth): UP 2026-07-11 13:05:19.292276 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 13:05:19.292442 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e6afbdb2 2026-07-11 13:05:22.063447 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e6afbdb2 tst 2026-07-11 13:05:22.829910 UTC - Run: slices experiment list-resources --format json tst-e6afbdb2 2026-07-11 13:05:23.445650 UTC - expires_at (on expauth): 2026-07-11T16:05:00Z (correctly extended) 2026-07-11 13:05:23.445780 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 13:05:23.445956 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e6afbdb2 2026-07-11 13:05:26.370435 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e6afbdb2 tst 2026-07-11 13:05:27.310998 UTC - Run: slices experiment list-resources --format json tst-e6afbdb2 2026-07-11 13:05:28.189455 UTC - expires_at (on expauth): 2026-07-11T17:05:00Z (correctly extended) 2026-07-11 13:05:28.189571 UTC - Testing ssh login 2026-07-11 13:05:28.201100 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e6afbdb2 tst 2026-07-11 13:05:29.529166 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e6afbdb2 tst 2026-07-11 13:05:30.396097 UTC - Logging in using 'slices bi ssh' 2026-07-11 13:05:30.396193 UTC - Forcing IPv4 only. 2026-07-11 13:05:30.396340 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e6afbdb2 tst -- -4 uname -a 2026-07-11 13:05:33.584774 UTC - Forcing IPv4 only. 2026-07-11 13:05:33.590653 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e6afbdb2 tst -- -4 uptime 2026-07-11 13:05:35.143749 UTC - CLI SSH Test passed. 2026-07-11 13:05:35.143847 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 13:05:35.143869 UTC - Uptime: 13:05:35 up 1 min, 1 user, load average: 0.09, 0.04, 0.01 2026-07-11 13:05:35.143907 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-11 13:05:35.147260 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-07-11 13:05:35.147430 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-07-11 13:05:35.147842 UTC - Added paramiko HostKeyEntry for 5.53.108.24 2026-07-11 13:05:35.148166 UTC - Connecting to 5.53.108.24:22 2026-07-11 13:05:35.756838 UTC - SSH Test output: 2026-07-11 13:05:35.756914 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 13:05:35.756938 UTC - Uptime: 13:05:35 up 1 min, 1 user, load average: 0.09, 0.04, 0.01 2026-07-11 13:05:35.855812 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 13:05:35.855890 UTC - lsb_release matches expected value 2026-07-11 13:05:35.855911 UTC - SSH Test passed. 2026-07-11 13:05:35.939525 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 13:04:58 +0000! 2026-07-11 13:05:35.939664 UTC - Skip SSH proxy test because no jump proxy 2026-07-11 13:05:35.955871 UTC - Destroying tst-e6afbdb2 tst 2026-07-11 13:05:35.956048 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e6afbdb2 tst