Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.85s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 349ms 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 781ms + annotated-types==0.7.0 + anyio==4.14.2 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-19 13:03:56.336799 UTC - Installing Slices CLI (latest stable version). 2026-07-19 13:03:56.375319 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 13:04:02.740978 UTC - Slices CLI installed. 2026-07-19 13:04:02.744893 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-19 13:04:02.750480 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-19 13:04:02.751824 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 13:04:02.752803 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-19 13:04:02.752855 UTC - Slices CLI exe: 'slices' 2026-07-19 13:04:02.752875 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 13:04:02.756835 UTC - Run: slices --version 2026-07-19 13:04:09.880321 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-19 13:04:09.881592 UTC - Run: slices pubkey list --format text 2026-07-19 13:04:12.093002 UTC - Pubkey already registered 2026-07-19 13:04:12.093351 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 13:04:13.697916 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 13:04:13.698036 UTC - Check List Flavors 2026-07-19 13:04:13.698189 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-19 13:04:16.643223 UTC - Check List DiskImages 2026-07-19 13:04:16.643448 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-19 13:04:18.146930 UTC - Requesting resources 2026-07-19 13:04:18.147696 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 13:04:18.147812 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-00652186 --user-data /tmp/tmpx0dg714g 2026-07-19 13:04:22.306323 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxx7mj0nfkhs8brd0a9tk5ex 2026-07-19 13:04:22.306507 UTC - Waiting until resource ready 2026-07-19 13:04:24.306873 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-00652186 tst 2026-07-19 13:04:26.564091 UTC - Status: IMAGING 2026-07-19 13:04:29.907927 UTC - Status: IMAGING 2026-07-19 13:04:33.056891 UTC - Status: IMAGING 2026-07-19 13:04:36.097818 UTC - Status: IMAGING 2026-07-19 13:04:39.533962 UTC - Status: IMAGING 2026-07-19 13:04:42.500594 UTC - Status: IMAGING 2026-07-19 13:04:45.551024 UTC - Status: IMAGING 2026-07-19 13:04:48.977964 UTC - Status: IMAGING 2026-07-19 13:04:52.298789 UTC - Status: IMAGING 2026-07-19 13:04:56.273284 UTC - Status: IMAGING 2026-07-19 13:04:59.620616 UTC - Status: IMAGING 2026-07-19 13:05:03.130777 UTC - Status: IMAGING 2026-07-19 13:05:06.505996 UTC - Status: IMAGING 2026-07-19 13:05:09.575073 UTC - Status: IMAGING 2026-07-19 13:05:13.270502 UTC - Status: IMAGING 2026-07-19 13:05:16.490713 UTC - Status: IMAGING 2026-07-19 13:05:19.331083 UTC - Status: IMAGING 2026-07-19 13:05:22.147627 UTC - Status: IMAGING 2026-07-19 13:05:25.724586 UTC - Status: IMAGING 2026-07-19 13:05:28.693725 UTC - Status: IMAGING 2026-07-19 13:05:32.316526 UTC - Status: IMAGING 2026-07-19 13:05:35.555348 UTC - Status: IMAGING 2026-07-19 13:05:38.629324 UTC - Status: IMAGING 2026-07-19 13:05:41.812015 UTC - Status: IMAGING 2026-07-19 13:05:45.209454 UTC - Status: IMAGING 2026-07-19 13:05:48.205083 UTC - Status: IMAGING 2026-07-19 13:05:51.388666 UTC - Status: IMAGING 2026-07-19 13:05:54.516239 UTC - Status: IMAGING 2026-07-19 13:05:57.412796 UTC - Status: IMAGING 2026-07-19 13:06:00.690815 UTC - Status: IMAGING 2026-07-19 13:06:03.709505 UTC - Status: IMAGING 2026-07-19 13:06:06.579673 UTC - Status: IMAGING 2026-07-19 13:06:09.467966 UTC - Status: IMAGING 2026-07-19 13:06:12.805677 UTC - Status: UP 2026-07-19 13:06:12.805945 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxx7mgrkewv8ad4kvz3xc5h3 2026-07-19 13:06:12.805998 UTC - Validate resources 2026-07-19 13:06:14.214069 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-19 13:06:14.214163 UTC - The fields of the created resource were validated. 2026-07-19 13:06:14.214204 UTC - Check if resources are registered in experiment 2026-07-19 13:06:14.214394 UTC - Run: slices experiment list-resources --format json tst-00652186 2026-07-19 13:06:15.190986 UTC - Status (on expauth): UP 2026-07-19 13:06:15.191160 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 13:06:15.191291 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-00652186 2026-07-19 13:06:18.167008 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-00652186 tst 2026-07-19 13:06:18.983141 UTC - Run: slices experiment list-resources --format json tst-00652186 2026-07-19 13:06:19.799352 UTC - expires_at (on expauth): 2026-07-19T16:06:00Z (correctly extended) 2026-07-19 13:06:19.799924 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 13:06:19.800087 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-00652186 2026-07-19 13:06:22.849270 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-00652186 tst 2026-07-19 13:06:23.944775 UTC - Run: slices experiment list-resources --format json tst-00652186 2026-07-19 13:06:24.982720 UTC - expires_at (on expauth): 2026-07-19T17:06:00Z (correctly extended) 2026-07-19 13:06:24.984606 UTC - Testing ssh login 2026-07-19 13:06:25.018853 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-00652186 tst 2026-07-19 13:06:26.102968 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-00652186 tst 2026-07-19 13:06:26.975153 UTC - Logging in using 'slices bi ssh' 2026-07-19 13:06:26.975231 UTC - Forcing IPv4 only. 2026-07-19 13:06:26.975344 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-00652186 tst -- -4 uname -a 2026-07-19 13:06:30.195938 UTC - Forcing IPv4 only. 2026-07-19 13:06:30.196271 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-00652186 tst -- -4 uptime 2026-07-19 13:06:31.518034 UTC - CLI SSH Test passed. 2026-07-19 13:06:31.518729 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-19 13:06:31.518759 UTC - Uptime: 13:06:31 up 1 min, 1 user, load average: 0.39, 0.13, 0.04 2026-07-19 13:06:31.518794 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-19 13:06:31.519591 UTC - Added paramiko HostKeyEntry for 5.53.108.89 2026-07-19 13:06:31.519699 UTC - Added paramiko HostKeyEntry for 5.53.108.89 2026-07-19 13:06:31.519861 UTC - Added paramiko HostKeyEntry for 5.53.108.89 2026-07-19 13:06:31.520171 UTC - Connecting to 5.53.108.89:22 2026-07-19 13:06:32.103180 UTC - SSH Test output: 2026-07-19 13:06:32.103253 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-19 13:06:32.103275 UTC - Uptime: 13:06:31 up 1 min, 1 user, load average: 0.39, 0.13, 0.04 2026-07-19 13:06:32.201160 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-19 13:06:32.201246 UTC - lsb_release matches expected value 2026-07-19 13:06:32.201268 UTC - SSH Test passed. 2026-07-19 13:06:32.285093 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 13:05:52 +0000! 2026-07-19 13:06:32.285243 UTC - Skip SSH proxy test because no jump proxy 2026-07-19 13:06:32.293122 UTC - Destroying tst-00652186 tst 2026-07-19 13:06:32.293309 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-00652186 tst