Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 9.81s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 256ms 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 644ms + 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-10 04:02:52.652108 UTC - Installing Slices CLI (latest stable version). 2026-07-10 04:02:52.652365 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 04:02:58.230482 UTC - Slices CLI installed. 2026-07-10 04:02:58.230674 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-10 04:02:58.233382 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-10 04:02:58.234418 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 04:02:58.234535 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-10 04:02:58.234563 UTC - Slices CLI exe: 'slices' 2026-07-10 04:02:58.234580 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 04:02:58.234696 UTC - Run: slices --version 2026-07-10 04:03:01.330605 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-10 04:03:01.331194 UTC - Run: slices pubkey list --format text 2026-07-10 04:03:03.499876 UTC - Pubkey already registered 2026-07-10 04:03:03.500133 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 04:03:04.165367 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 04:03:04.165465 UTC - Check List Flavors 2026-07-10 04:03:04.165607 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-10 04:03:05.682682 UTC - Check List DiskImages 2026-07-10 04:03:05.682884 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-10 04:03:06.348065 UTC - Requesting resources 2026-07-10 04:03:06.348536 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-10 04:03:06.348658 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-19f0f14e --user-data /tmp/tmpm6t596ug 2026-07-10 04:03:09.888770 UTC - Resource ID: r_ch-geneva-bi-vm1_01kx5333kte5hb9bp32ygjrwcx 2026-07-10 04:03:09.889424 UTC - Waiting until resource ready 2026-07-10 04:03:11.890018 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-19f0f14e tst 2026-07-10 04:03:14.569027 UTC - Status: IMAGING 2026-07-10 04:03:19.415230 UTC - Status: IMAGING 2026-07-10 04:03:23.670698 UTC - Status: IMAGING 2026-07-10 04:03:27.555367 UTC - Status: IMAGING 2026-07-10 04:03:31.314576 UTC - Status: IMAGING 2026-07-10 04:03:35.267206 UTC - Status: IMAGING 2026-07-10 04:03:39.975612 UTC - Status: IMAGING 2026-07-10 04:03:44.238346 UTC - Status: IMAGING 2026-07-10 04:03:47.210522 UTC - Status: IMAGING 2026-07-10 04:03:50.134335 UTC - Status: IMAGING 2026-07-10 04:03:53.054187 UTC - Status: IMAGING 2026-07-10 04:03:56.951293 UTC - Status: IMAGING 2026-07-10 04:04:01.156347 UTC - Status: IMAGING 2026-07-10 04:04:05.627982 UTC - Status: IMAGING 2026-07-10 04:04:09.396413 UTC - Status: IMAGING 2026-07-10 04:04:13.875330 UTC - Status: IMAGING 2026-07-10 04:04:18.060364 UTC - Status: IMAGING 2026-07-10 04:04:22.062247 UTC - Status: IMAGING 2026-07-10 04:04:25.945308 UTC - Status: IMAGING 2026-07-10 04:04:28.935637 UTC - Status: IMAGING 2026-07-10 04:04:32.206873 UTC - Status: IMAGING 2026-07-10 04:04:35.230874 UTC - Status: IMAGING 2026-07-10 04:04:38.301885 UTC - Status: IMAGING 2026-07-10 04:04:41.117961 UTC - Status: IMAGING 2026-07-10 04:04:43.883774 UTC - Status: IMAGING 2026-07-10 04:04:46.649606 UTC - Status: IMAGING 2026-07-10 04:04:49.415451 UTC - Status: IMAGING 2026-07-10 04:04:52.131226 UTC - Status: IMAGING 2026-07-10 04:04:54.897048 UTC - Status: IMAGING 2026-07-10 04:04:57.713305 UTC - Status: IMAGING 2026-07-10 04:05:00.579415 UTC - Status: UP 2026-07-10 04:05:00.579521 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx53329cf67a5shxk1njx00m 2026-07-10 04:05:00.579564 UTC - Validate resources 2026-07-10 04:05:01.396207 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-10 04:05:01.396295 UTC - The fields of the created resource were validated. 2026-07-10 04:05:01.396350 UTC - Check if resources are registered in experiment 2026-07-10 04:05:01.396525 UTC - Run: slices experiment list-resources --format json tst-19f0f14e 2026-07-10 04:05:02.061858 UTC - Status (on expauth): UP 2026-07-10 04:05:02.062011 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 04:05:02.062139 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-19f0f14e 2026-07-10 04:05:04.882479 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-19f0f14e tst 2026-07-10 04:05:05.698223 UTC - Run: slices experiment list-resources --format json tst-19f0f14e 2026-07-10 04:05:06.363706 UTC - expires_at (on expauth): 2026-07-10T07:05:00Z (correctly extended) 2026-07-10 04:05:06.363818 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 04:05:06.363988 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-19f0f14e 2026-07-10 04:05:09.184894 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-19f0f14e tst 2026-07-10 04:05:09.951110 UTC - Run: slices experiment list-resources --format json tst-19f0f14e 2026-07-10 04:05:10.616440 UTC - expires_at (on expauth): 2026-07-10T08:05:00Z (correctly extended) 2026-07-10 04:05:10.616556 UTC - Testing ssh login 2026-07-10 04:05:10.627279 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-19f0f14e tst 2026-07-10 04:05:11.443137 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-19f0f14e tst 2026-07-10 04:05:12.258936 UTC - Logging in using 'slices bi ssh' 2026-07-10 04:05:12.259010 UTC - Forcing IPv4 only. 2026-07-10 04:05:12.259146 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-19f0f14e tst -- -4 uname -a 2026-07-10 04:05:14.378191 UTC - Forcing IPv4 only. 2026-07-10 04:05:14.378393 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-19f0f14e tst -- -4 uptime 2026-07-10 04:05:15.494693 UTC - CLI SSH Test passed. 2026-07-10 04:05:15.494767 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-10 04:05:15.494787 UTC - Uptime: 04:05:15 up 1 min, 1 user, load average: 0.41, 0.13, 0.04 2026-07-10 04:05:15.494821 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-10 04:05:15.495330 UTC - Added paramiko HostKeyEntry for 5.53.108.254 2026-07-10 04:05:15.495420 UTC - Added paramiko HostKeyEntry for 5.53.108.254 2026-07-10 04:05:15.495568 UTC - Added paramiko HostKeyEntry for 5.53.108.254 2026-07-10 04:05:15.495753 UTC - Connecting to 5.53.108.254:22 2026-07-10 04:05:16.030493 UTC - SSH Test output: 2026-07-10 04:05:16.030569 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-10 04:05:16.030589 UTC - Uptime: 04:05:15 up 1 min, 1 user, load average: 0.38, 0.13, 0.04 2026-07-10 04:05:16.127279 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-10 04:05:16.127357 UTC - lsb_release matches expected value 2026-07-10 04:05:16.127376 UTC - SSH Test passed. 2026-07-10 04:05:16.213858 UTC - Cloud-init user-data: Hello World. The time is now Fri, 10 Jul 2026 04:04:43 +0000! 2026-07-10 04:05:16.214012 UTC - Skip SSH proxy test because no jump proxy 2026-07-10 04:05:16.220780 UTC - Destroying tst-19f0f14e tst 2026-07-10 04:05:16.220982 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-19f0f14e tst