Run Test: slices-bi-singlenode-login 2026-06-08 08:20:00.998837 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpzu7r_ro1.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-08 08:20:05.072862 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/24b84793-ed09-4732-8461-967cf4a906a4/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-08 08:20:13.157141 CEST - Run: slices --version 2026-06-08 08:20:13.371572 CEST - 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-06-08 08:20:13.371880 CEST - Run: slices pubkey list --format text 2026-06-08 08:20:13.937285 CEST - Pubkey already registered 2026-06-08 08:20:13.937638 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-08 08:20:14.503277 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-08 08:20:14.503424 CEST - Check List Flavors 2026-06-08 08:20:14.503651 CEST - Run: slices bi --infra hu-budapest-bi-vm1 flavor list -f json 2026-06-08 08:20:15.470656 CEST - Check List DiskImages 2026-06-08 08:20:15.470900 CEST - Run: slices bi --infra hu-budapest-bi-vm1 diskimage list -f json 2026-06-08 08:20:16.137022 CEST - Requesting resources 2026-06-08 08:20:16.137448 CEST - Run: slices bi --infra hu-budapest-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e6dbd6dc 2026-06-08 08:20:18.306958 CEST - Resource ID: r_hu-budapest-bi-vm1_01ktjy7797e39ven987nbv0vsc 2026-06-08 08:20:18.307109 CEST - Waiting until resource ready 2026-06-08 08:20:20.307532 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-e6dbd6dc tst 2026-06-08 08:20:21.073426 CEST - Status: IMAGING 2026-06-08 08:20:23.839572 CEST - Status: IMAGING 2026-06-08 08:20:26.656193 CEST - Status: IMAGING 2026-06-08 08:20:29.422235 CEST - Status: IMAGING 2026-06-08 08:20:32.188374 CEST - Status: IMAGING 2026-06-08 08:20:34.954684 CEST - Status: IMAGING 2026-06-08 08:20:37.720880 CEST - Status: IMAGING 2026-06-08 08:20:40.487075 CEST - Status: IMAGING 2026-06-08 08:20:43.253301 CEST - Status: IMAGING 2026-06-08 08:20:46.019855 CEST - Status: IMAGING 2026-06-08 08:20:48.786253 CEST - Status: IMAGING 2026-06-08 08:20:51.502159 CEST - Status: IMAGING 2026-06-08 08:20:54.268275 CEST - Status: IMAGING 2026-06-08 08:20:57.034453 CEST - Status: IMAGING 2026-06-08 08:20:59.800669 CEST - Status: IMAGING 2026-06-08 08:21:02.566919 CEST - Status: IMAGING 2026-06-08 08:21:05.333148 CEST - Status: IMAGING 2026-06-08 08:21:08.099297 CEST - Status: IMAGING 2026-06-08 08:21:10.865549 CEST - Status: IMAGING 2026-06-08 08:21:13.632168 CEST - Status: IMAGING 2026-06-08 08:21:16.398211 CEST - Status: IMAGING 2026-06-08 08:21:19.164394 CEST - Status: IMAGING 2026-06-08 08:21:21.981536 CEST - Status: IMAGING 2026-06-08 08:21:24.798047 CEST - Status: IMAGING 2026-06-08 08:21:27.565010 CEST - Status: IMAGING 2026-06-08 08:21:30.331272 CEST - Status: IMAGING 2026-06-08 08:21:33.097575 CEST - Status: IMAGING 2026-06-08 08:21:35.913854 CEST - Status: IMAGING 2026-06-08 08:21:38.680898 CEST - Status: IMAGING 2026-06-08 08:21:41.447403 CEST - Status: IMAGING 2026-06-08 08:21:44.213566 CEST - Status: IMAGING 2026-06-08 08:21:46.979681 CEST - Status: IMAGING 2026-06-08 08:21:49.796252 CEST - Status: IMAGING 2026-06-08 08:21:52.562369 CEST - Status: IMAGING 2026-06-08 08:21:55.328502 CEST - Status: IMAGING 2026-06-08 08:21:58.095039 CEST - Status: IMAGING 2026-06-08 08:22:00.861300 CEST - Status: IMAGING 2026-06-08 08:22:03.728142 CEST - Status: UP 2026-06-08 08:22:03.728225 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ktjy76akegvvw922kkrztcyv 2026-06-08 08:22:03.728286 CEST - Validate resources 2026-06-08 08:22:04.494697 CEST - The fields of the created resource were validated. 2026-06-08 08:22:04.494787 CEST - Check if resources are registered in experiment 2026-06-08 08:22:04.494949 CEST - Run: slices experiment list-resources --format json tst-e6dbd6dc 2026-06-08 08:22:05.110252 CEST - Status (on expauth): UP 2026-06-08 08:22:05.110392 CEST - Testing extend expires_at (all resources in experiment) 2026-06-08 08:22:05.110518 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend --duration 3h --experiment tst-e6dbd6dc 2026-06-08 08:22:06.978868 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-e6dbd6dc tst 2026-06-08 08:22:07.745014 CEST - Run: slices experiment list-resources --format json tst-e6dbd6dc 2026-06-08 08:22:08.360419 CEST - expires_at (on expauth): 2026-06-08T09:22:00Z (correctly extended) 2026-06-08 08:22:08.360490 CEST - Testing extend expires_at (single resource in experiment) 2026-06-08 08:22:08.360629 CEST - Run: slices bi --infra hu-budapest-bi-vm1 extend tst --duration 4h --experiment tst-e6dbd6dc 2026-06-08 08:22:10.229311 CEST - Run: slices bi --infra hu-budapest-bi-vm1 list-resources --format json --experiment tst-e6dbd6dc tst 2026-06-08 08:22:11.045396 CEST - Run: slices experiment list-resources --format json tst-e6dbd6dc 2026-06-08 08:22:11.660938 CEST - expires_at (on expauth): 2026-06-08T10:22:00Z (correctly extended) 2026-06-08 08:22:11.661063 CEST - Testing ssh login 2026-06-08 08:22:11.671920 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e6dbd6dc tst 2026-06-08 08:22:12.538251 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e6dbd6dc tst 2026-06-08 08:22:13.454443 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-e6dbd6dc tst 2026-06-08 08:22:14.370758 CEST - Logging in using 'slices bi ssh' 2026-06-08 08:22:14.370833 CEST - Forcing IPv4 only. 2026-06-08 08:22:14.370995 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-e6dbd6dc tst -- -4 uname -a 2026-06-08 08:22:18.495610 CEST - Forcing IPv4 only. 2026-06-08 08:22:18.495774 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --show nothing --experiment tst-e6dbd6dc tst -- -4 uptime 2026-06-08 08:22:20.314257 CEST - CLI SSH Test passed. 2026-06-08 08:22:20.314355 CEST - 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-06-08 08:22:20.314374 CEST - Uptime: 06:22:20 up 0 min, 1 user, load average: 0.33, 0.12, 0.04 2026-06-08 08:22:20.314405 CEST - Logging in using SSH over SSH proxy 2026-06-08 08:22:20.314581 CEST - Run: slices bi --infra hu-budapest-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-e6dbd6dc tst 2026-06-08 08:22:21.230993 CEST - Added paramiko HostKeyEntry for 192.168.0.190 2026-06-08 08:22:21.231101 CEST - Added paramiko HostKeyEntry for 192.168.0.190 2026-06-08 08:22:21.231257 CEST - Added paramiko HostKeyEntry for 192.168.0.190 2026-06-08 08:22:21.231474 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-06-08 08:22:21.231519 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-06-08 08:22:21.231627 CEST - Added paramiko HostKeyEntry for 193.225.251.128 2026-06-08 08:22:21.231642 CEST - Connecting to proxy 193.225.251.128:22 2026-06-08 08:22:21.757289 CEST - Connecting to 192.168.0.190:22 over proxy 2026-06-08 08:22:22.543816 CEST - SSH Test output: 2026-06-08 08:22:22.543891 CEST - 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-06-08 08:22:22.543917 CEST - Uptime: 06:22:22 up 0 min, 1 user, load average: 0.31, 0.11, 0.04 2026-06-08 08:22:22.762166 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-06-08 08:22:22.762244 CEST - lsb_release matches expected value 2026-06-08 08:22:22.762264 CEST - SSH Test passed. 2026-06-08 08:22:22.769103 CEST - Destroying tst-e6dbd6dc tst 2026-06-08 08:22:22.769330 CEST - Run: slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-e6dbd6dc tst 2026-06-08 08:23:22.778305 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/24b84793-ed09-4732-8461-967cf4a906a4/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra hu-budapest-bi-vm1 destroy --experiment tst-e6dbd6dc tst 2026-06-08 08:23:22.778466 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/24b84793-ed09-4732-8461-967cf4a906a4/destroy.txt': 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 0.0% - waiting for task start 0.0% \ waiting for task start 0.0% | waiting for task start 0.0% / waiting for task start 10.0% - task starting ***Terminating process due to timeout*** ***Process terminated*** 2026-06-08 08:23:22.778517 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is -15) 2026-06-08 08:23:22.778539 CEST - Step 'Destroy' took 60.01 seconds, which is longer than the warning threshold of 15 seconds 2026-06-08 08:23:22.778560 CEST - Wait 2s before retry