Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 13.67s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 897ms 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.00s + 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-12 13:03:03.380026 UTC - Installing Slices CLI (latest stable version). 2026-07-12 13:03:03.380383 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 13:03:09.847429 UTC - Slices CLI installed. 2026-07-12 13:03:09.847685 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-12 13:03:09.849886 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-12 13:03:09.851032 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 13:03:09.851179 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-12 13:03:09.851209 UTC - Slices CLI exe: 'slices' 2026-07-12 13:03:09.851228 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 13:03:09.851358 UTC - Run: slices --version 2026-07-12 13:03:16.076634 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-12 13:03:16.079769 UTC - Run: slices pubkey list --format text 2026-07-12 13:03:17.737413 UTC - Pubkey already registered 2026-07-12 13:03:17.737640 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 13:03:19.153393 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 13:03:19.153493 UTC - Check List Flavors 2026-07-12 13:03:19.153643 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-12 13:03:22.213489 UTC - Check List DiskImages 2026-07-12 13:03:22.213669 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-12 13:03:23.914486 UTC - Requesting resources 2026-07-12 13:03:23.915340 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-12 13:03:23.915485 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b796c544 --user-data /tmp/tmp20ayu7rg 2026-07-12 13:03:29.452467 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxb6sx5eep888addnh8vpj16 2026-07-12 13:03:29.452615 UTC - Waiting until resource ready 2026-07-12 13:03:31.452889 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b796c544 tst 2026-07-12 13:03:34.449118 UTC - Status: IMAGING 2026-07-12 13:03:38.642181 UTC - Status: IMAGING 2026-07-12 13:03:42.439221 UTC - Status: IMAGING 2026-07-12 13:03:45.355534 UTC - Status: IMAGING 2026-07-12 13:03:49.536866 UTC - Status: IMAGING 2026-07-12 13:03:53.129630 UTC - Status: IMAGING 2026-07-12 13:03:57.022597 UTC - Status: IMAGING 2026-07-12 13:04:01.123346 UTC - Status: IMAGING 2026-07-12 13:04:05.011238 UTC - Status: IMAGING 2026-07-12 13:04:08.341740 UTC - Status: IMAGING 2026-07-12 13:04:11.915716 UTC - Status: IMAGING 2026-07-12 13:04:15.718877 UTC - Status: IMAGING 2026-07-12 13:04:18.853084 UTC - Status: IMAGING 2026-07-12 13:04:21.719181 UTC - Status: IMAGING 2026-07-12 13:04:24.585507 UTC - Status: IMAGING 2026-07-12 13:04:27.629752 UTC - Status: IMAGING 2026-07-12 13:04:30.395596 UTC - Status: IMAGING 2026-07-12 13:04:33.161562 UTC - Status: IMAGING 2026-07-12 13:04:35.977708 UTC - Status: IMAGING 2026-07-12 13:04:38.743841 UTC - Status: IMAGING 2026-07-12 13:04:41.509782 UTC - Status: IMAGING 2026-07-12 13:04:44.225641 UTC - Status: IMAGING 2026-07-12 13:04:46.941442 UTC - Status: IMAGING 2026-07-12 13:04:49.707883 UTC - Status: IMAGING 2026-07-12 13:04:52.473890 UTC - Status: IMAGING 2026-07-12 13:04:55.189890 UTC - Status: IMAGING 2026-07-12 13:04:57.955827 UTC - Status: IMAGING 2026-07-12 13:05:00.671665 UTC - Status: IMAGING 2026-07-12 13:05:03.438385 UTC - Status: IMAGING 2026-07-12 13:05:06.254772 UTC - Status: IMAGING 2026-07-12 13:05:09.021981 UTC - Status: IMAGING 2026-07-12 13:05:11.838162 UTC - Status: IMAGING 2026-07-12 13:05:14.604970 UTC - Status: IMAGING 2026-07-12 13:05:17.370864 UTC - Status: IMAGING 2026-07-12 13:05:20.136832 UTC - Status: UP 2026-07-12 13:05:20.136938 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxb6svygevf8d61dcf558mhc 2026-07-12 13:05:20.137007 UTC - Validate resources 2026-07-12 13:05:20.852657 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-12 13:05:20.852749 UTC - The fields of the created resource were validated. 2026-07-12 13:05:20.852790 UTC - Check if resources are registered in experiment 2026-07-12 13:05:20.852939 UTC - Run: slices experiment list-resources --format json tst-b796c544 2026-07-12 13:05:21.518308 UTC - Status (on expauth): UP 2026-07-12 13:05:21.518460 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 13:05:21.518621 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-b796c544 2026-07-12 13:05:24.389268 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b796c544 tst 2026-07-12 13:05:25.154859 UTC - Run: slices experiment list-resources --format json tst-b796c544 2026-07-12 13:05:25.820157 UTC - expires_at (on expauth): 2026-07-12T16:05:00Z (correctly extended) 2026-07-12 13:05:25.820249 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 13:05:25.820382 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-b796c544 2026-07-12 13:05:28.926083 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-b796c544 tst 2026-07-12 13:05:30.208335 UTC - Run: slices experiment list-resources --format json tst-b796c544 2026-07-12 13:05:31.129343 UTC - expires_at (on expauth): 2026-07-12T17:05:00Z (correctly extended) 2026-07-12 13:05:31.129464 UTC - Testing ssh login 2026-07-12 13:05:31.155153 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b796c544 tst 2026-07-12 13:05:32.539995 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b796c544 tst 2026-07-12 13:05:33.625347 UTC - Logging in using 'slices bi ssh' 2026-07-12 13:05:33.625435 UTC - Forcing IPv4 only. 2026-07-12 13:05:33.625567 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-b796c544 tst -- -4 uname -a 2026-07-12 13:05:36.962452 UTC - Forcing IPv4 only. 2026-07-12 13:05:36.963674 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-b796c544 tst -- -4 uptime 2026-07-12 13:05:39.875656 UTC - CLI SSH Test passed. 2026-07-12 13:05:39.875733 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-12 13:05:39.875754 UTC - Uptime: 13:05:39 up 1 min, 1 user, load average: 0.27, 0.10, 0.03 2026-07-12 13:05:39.875788 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-12 13:05:39.876240 UTC - Added paramiko HostKeyEntry for 5.53.108.100 2026-07-12 13:05:39.876329 UTC - Added paramiko HostKeyEntry for 5.53.108.100 2026-07-12 13:05:39.876470 UTC - Added paramiko HostKeyEntry for 5.53.108.100 2026-07-12 13:05:39.876633 UTC - Connecting to 5.53.108.100:22 2026-07-12 13:05:40.427927 UTC - SSH Test output: 2026-07-12 13:05:40.428013 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-12 13:05:40.428035 UTC - Uptime: 13:05:40 up 1 min, 1 user, load average: 0.27, 0.10, 0.03 2026-07-12 13:05:40.522923 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-12 13:05:40.523043 UTC - lsb_release matches expected value 2026-07-12 13:05:40.523080 UTC - SSH Test passed. 2026-07-12 13:05:40.604513 UTC - Cloud-init user-data: Hello World. The time is now Sun, 12 Jul 2026 13:05:01 +0000! 2026-07-12 13:05:40.604644 UTC - Skip SSH proxy test because no jump proxy 2026-07-12 13:05:40.697937 UTC - Destroying tst-b796c544 tst 2026-07-12 13:05:40.698179 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-b796c544 tst