Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 15.45s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 452ms 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 832ms + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-15 13:03:13.056177 UTC - Installing Slices CLI (latest stable version). 2026-07-15 13:03:13.056440 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 13:03:33.522991 UTC - Slices CLI installed. 2026-07-15 13:03:33.523985 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-15 13:03:33.525670 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-15 13:03:33.526873 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 13:03:33.527176 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-15 13:03:33.527221 UTC - Slices CLI exe: 'slices' 2026-07-15 13:03:33.527241 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 13:03:33.527550 UTC - Run: slices --version 2026-07-15 13:03:40.777813 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-15 13:03:40.783102 UTC - Run: slices pubkey list --format text 2026-07-15 13:03:42.960284 UTC - Pubkey already registered 2026-07-15 13:03:42.960544 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 13:03:43.626306 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 13:03:43.627105 UTC - Check List Flavors 2026-07-15 13:03:43.627381 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-15 13:03:46.607851 UTC - Check List DiskImages 2026-07-15 13:03:46.608052 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-15 13:03:48.814762 UTC - Requesting resources 2026-07-15 13:03:48.815564 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 13:03:48.815691 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9d7272a7 --user-data /tmp/tmp3yslao5g 2026-07-15 13:03:54.236569 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxjy0tfneshrk7a8x0vw9yk6 2026-07-15 13:03:54.237494 UTC - Waiting until resource ready 2026-07-15 13:03:56.237927 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9d7272a7 tst 2026-07-15 13:03:58.671921 UTC - Status: IMAGING 2026-07-15 13:04:02.714783 UTC - Status: IMAGING 2026-07-15 13:04:06.562452 UTC - Status: IMAGING 2026-07-15 13:04:10.471116 UTC - Status: IMAGING 2026-07-15 13:04:14.728877 UTC - Status: IMAGING 2026-07-15 13:04:18.208832 UTC - Status: IMAGING 2026-07-15 13:04:22.157054 UTC - Status: IMAGING 2026-07-15 13:04:26.603370 UTC - Status: IMAGING 2026-07-15 13:04:31.662400 UTC - Status: IMAGING 2026-07-15 13:04:35.141892 UTC - Status: IMAGING 2026-07-15 13:04:39.739943 UTC - Status: IMAGING 2026-07-15 13:04:42.919037 UTC - Status: IMAGING 2026-07-15 13:04:46.806493 UTC - Status: IMAGING 2026-07-15 13:04:50.024954 UTC - Status: IMAGING 2026-07-15 13:04:53.507387 UTC - Status: IMAGING 2026-07-15 13:04:56.735340 UTC - Status: IMAGING 2026-07-15 13:04:59.863299 UTC - Status: IMAGING 2026-07-15 13:05:02.744740 UTC - Status: IMAGING 2026-07-15 13:05:06.326134 UTC - Status: IMAGING 2026-07-15 13:05:09.395751 UTC - Status: IMAGING 2026-07-15 13:05:12.211969 UTC - Status: IMAGING 2026-07-15 13:05:15.028404 UTC - Status: IMAGING 2026-07-15 13:05:17.845025 UTC - Status: IMAGING 2026-07-15 13:05:20.661344 UTC - Status: IMAGING 2026-07-15 13:05:23.427880 UTC - Status: IMAGING 2026-07-15 13:05:26.244210 UTC - Status: IMAGING 2026-07-15 13:05:29.129135 UTC - Status: IMAGING 2026-07-15 13:05:32.577417 UTC - Status: IMAGING 2026-07-15 13:05:36.243172 UTC - Status: IMAGING 2026-07-15 13:05:40.325405 UTC - Status: IMAGING 2026-07-15 13:05:43.798329 UTC - Status: IMAGING 2026-07-15 13:05:47.531287 UTC - Status: UP 2026-07-15 13:05:47.531366 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxjy0s23epnamaskrtqm16xf 2026-07-15 13:05:47.531406 UTC - Validate resources 2026-07-15 13:05:49.379649 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-15 13:05:49.380073 UTC - The fields of the created resource were validated. 2026-07-15 13:05:49.380153 UTC - Check if resources are registered in experiment 2026-07-15 13:05:49.380324 UTC - Run: slices experiment list-resources --format json tst-9d7272a7 2026-07-15 13:05:51.659091 UTC - Status (on expauth): UP 2026-07-15 13:05:51.659241 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 13:05:51.659356 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-9d7272a7 2026-07-15 13:05:55.494850 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9d7272a7 tst 2026-07-15 13:05:56.460972 UTC - Run: slices experiment list-resources --format json tst-9d7272a7 2026-07-15 13:05:57.327949 UTC - expires_at (on expauth): 2026-07-15T16:05:00Z (correctly extended) 2026-07-15 13:05:57.328041 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 13:05:57.328189 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-9d7272a7 2026-07-15 13:06:01.631183 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9d7272a7 tst 2026-07-15 13:06:02.648619 UTC - Run: slices experiment list-resources --format json tst-9d7272a7 2026-07-15 13:06:03.465638 UTC - expires_at (on expauth): 2026-07-15T17:05:00Z (correctly extended) 2026-07-15 13:06:03.465747 UTC - Testing ssh login 2026-07-15 13:06:03.484408 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9d7272a7 tst 2026-07-15 13:06:04.807174 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9d7272a7 tst 2026-07-15 13:06:06.048814 UTC - Logging in using 'slices bi ssh' 2026-07-15 13:06:06.048898 UTC - Forcing IPv4 only. 2026-07-15 13:06:06.049024 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9d7272a7 tst -- -4 uname -a 2026-07-15 13:06:09.439724 UTC - Forcing IPv4 only. 2026-07-15 13:06:09.439924 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9d7272a7 tst -- -4 uptime 2026-07-15 13:06:10.707840 UTC - CLI SSH Test passed. 2026-07-15 13:06:10.707943 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-15 13:06:10.707966 UTC - Uptime: 13:06:10 up 1 min, 1 user, load average: 0.10, 0.04, 0.01 2026-07-15 13:06:10.708009 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-15 13:06:10.709046 UTC - Added paramiko HostKeyEntry for 5.53.108.115 2026-07-15 13:06:10.709181 UTC - Added paramiko HostKeyEntry for 5.53.108.115 2026-07-15 13:06:10.709410 UTC - Added paramiko HostKeyEntry for 5.53.108.115 2026-07-15 13:06:10.709604 UTC - Connecting to 5.53.108.115:22 2026-07-15 13:06:11.253609 UTC - SSH Test output: 2026-07-15 13:06:11.253681 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-15 13:06:11.253703 UTC - Uptime: 13:06:11 up 1 min, 1 user, load average: 0.10, 0.04, 0.01 2026-07-15 13:06:11.351059 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 13:06:11.351147 UTC - lsb_release matches expected value 2026-07-15 13:06:11.351168 UTC - SSH Test passed. 2026-07-15 13:06:11.435568 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 13:05:25 +0000! 2026-07-15 13:06:11.435758 UTC - Skip SSH proxy test because no jump proxy 2026-07-15 13:06:11.448244 UTC - Destroying tst-9d7272a7 tst 2026-07-15 13:06:11.448812 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-9d7272a7 tst