Run Test: slices-bi-singlenode-login 2026-05-01 17:17:45.722579 CEST - Run: /usr/local/bin/uv pip install --pre --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-01 17:17:49.996052 CEST - Run: slices --version 2026-05-01 17:17:51.212617 CEST - version: Slices CLI v2026.0.0b7 Slices CLI core v1.2.0 Slices CLI bi v2.1.0b22 Slices clientlib bi v6.1.1 Slices clientlib core v5.5.1 2026-05-01 17:17:51.212908 CEST - Run: slices pubkey list --format text 2026-05-01 17:17:51.778070 CEST - Pubkey already registered 2026-05-01 17:17:51.778289 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-01 17:17:52.343336 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-01 17:17:52.343442 CEST - Check List Flavors 2026-05-01 17:17:52.343625 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-05-01 17:17:52.908676 CEST - Check List DiskImages 2026-05-01 17:17:52.908886 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-05-01 17:17:53.473993 CEST - Requesting resources 2026-05-01 17:17:53.474475 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 24.04.3' --flavor pc --duration 2h --experiment tst-80ba96af 2026-05-01 17:17:55.492622 CEST - Resource ID: r_be-gent1-bi-baremetal1_01kqj1wagwenqrqpwr308470vm 2026-05-01 17:17:55.492767 CEST - Waiting until resource ready 2026-05-01 17:17:57.493137 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-80ba96af tst 2026-05-01 17:17:58.108272 CEST - Status: IMAGING 2026-05-01 17:18:00.724104 CEST - Status: IMAGING 2026-05-01 17:18:03.389910 CEST - Status: IMAGING 2026-05-01 17:18:06.106352 CEST - Status: IMAGING 2026-05-01 17:18:08.772159 CEST - Status: IMAGING 2026-05-01 17:18:11.488041 CEST - Status: IMAGING 2026-05-01 17:18:14.153808 CEST - Status: IMAGING 2026-05-01 17:18:16.869507 CEST - Status: IMAGING 2026-05-01 17:18:19.585341 CEST - Status: IMAGING 2026-05-01 17:18:22.501606 CEST - Status: IMAGING 2026-05-01 17:18:25.268240 CEST - Status: IMAGING 2026-05-01 17:18:27.984121 CEST - Status: IMAGING 2026-05-01 17:18:30.700078 CEST - Status: IMAGING 2026-05-01 17:18:33.416046 CEST - Status: IMAGING 2026-05-01 17:18:36.131878 CEST - Status: IMAGING 2026-05-01 17:18:38.898091 CEST - Status: IMAGING 2026-05-01 17:18:41.714555 CEST - Status: IMAGING 2026-05-01 17:18:44.480729 CEST - Status: IMAGING 2026-05-01 17:18:47.246881 CEST - Status: IMAGING 2026-05-01 17:18:49.962654 CEST - Status: IMAGING 2026-05-01 17:18:52.728523 CEST - Status: IMAGING 2026-05-01 17:18:55.444289 CEST - Status: STARTING 2026-05-01 17:18:58.160140 CEST - Status: STARTING 2026-05-01 17:19:00.876082 CEST - Status: UP 2026-05-01 17:19:00.876153 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kqj1w9vafxaajcsg6awn56mg 2026-05-01 17:19:00.876190 CEST - Validate resources 2026-05-01 17:19:01.592562 CEST - The fields of the created resource were validated. 2026-05-01 17:19:01.592654 CEST - Check if resources are registered in experiment 2026-05-01 17:19:01.592881 CEST - Run: slices experiment list-resources --format json tst-80ba96af 2026-05-01 17:19:02.158176 CEST - Status (on expauth): UP 2026-05-01 17:19:02.158363 CEST - Testing extend expires_at (all resources in experiment) 2026-05-01 17:19:02.158507 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-80ba96af 2026-05-01 17:19:07.735739 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-80ba96af tst 2026-05-01 17:19:11.007748 CEST - Testing extend expires_at (single resource in experiment) 2026-05-01 17:19:11.007999 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-80ba96af 2026-05-01 17:19:15.031693 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-80ba96af tst 2026-05-01 17:19:15.747617 CEST - Testing ssh login 2026-05-01 17:19:15.758596 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-80ba96af tst 2026-05-01 17:19:16.474067 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-80ba96af tst 2026-05-01 17:19:17.189752 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-80ba96af tst 2026-05-01 17:19:17.905434 CEST - Logging in using 'slices bi ssh' 2026-05-01 17:19:17.905495 CEST - Forcing IPv4 only. 2026-05-01 17:19:17.905616 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-80ba96af tst -- -4 uname -a 2026-05-01 17:19:18.871851 CEST - Forcing IPv4 only. 2026-05-01 17:19:18.872013 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-80ba96af tst -- -4 uptime 2026-05-01 17:19:19.687735 CEST - CLI SSH Test passed. 2026-05-01 17:19:19.687797 CEST - Uname: Linux tst.rexcxy.fed4fire-eu.wall1.ilabt.imec.be 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-05-01 17:19:19.687811 CEST - Uptime: 17:19:19 up 0 min, 2 users, load average: 0.05, 0.01, 0.00 2026-05-01 17:19:19.687837 CEST - Logging in using 'slices bi ssh' 2026-05-01 17:19:19.687896 CEST - Forcing IPv6 only. 2026-05-01 17:19:19.688007 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-80ba96af tst -- -6 uname -a 2026-05-01 17:19:20.503694 CEST - Forcing IPv6 only. 2026-05-01 17:19:20.503915 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-80ba96af tst -- -6 uptime 2026-05-01 17:19:21.319816 CEST - CLI SSH Test passed. 2026-05-01 17:19:21.319888 CEST - Uname: Linux tst.rexcxy.fed4fire-eu.wall1.ilabt.imec.be 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-05-01 17:19:21.319902 CEST - Uptime: 17:19:21 up 0 min, 2 users, load average: 0.05, 0.01, 0.00 2026-05-01 17:19:21.319940 CEST - Logging in using SSH (extra test: ignore SSH proxy) 2026-05-01 17:19:21.320469 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:21.320672 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:21.320772 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:21.320962 CEST - Connecting to n076-28.wall1.ilabt.imec.be:22 2026-05-01 17:19:21.672958 CEST - SSH Test output: 2026-05-01 17:19:21.673024 CEST - Uname: Linux tst.rexcxy.fed4fire-eu.wall1.ilabt.imec.be 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-05-01 17:19:21.673039 CEST - Uptime: 17:19:21 up 0 min, 2 users, load average: 0.05, 0.01, 0.00 2026-05-01 17:19:21.748095 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-01 17:19:21.748158 CEST - lsb_release matches expected value 2026-05-01 17:19:21.748172 CEST - SSH Test passed. 2026-05-01 17:19:21.748245 CEST - Logging in using SSH over SSH proxy 2026-05-01 17:19:21.748402 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-80ba96af tst 2026-05-01 17:19:22.464532 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:22.464767 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:22.464838 CEST - Added paramiko HostKeyEntry for n076-28.wall1.ilabt.imec.be 2026-05-01 17:19:22.465120 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-05-01 17:19:22.465172 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-05-01 17:19:22.465279 CEST - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-05-01 17:19:22.465295 CEST - Connecting to proxy bastion2.slices-be.eu:22 2026-05-01 17:19:22.621254 CEST - Connecting to n076-28.wall1.ilabt.imec.be:22 over proxy 2026-05-01 17:19:22.953632 CEST - SSH Test output: 2026-05-01 17:19:22.953679 CEST - Uname: Linux tst.rexcxy.fed4fire-eu.wall1.ilabt.imec.be 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-05-01 17:19:22.953693 CEST - Uptime: 17:19:22 up 0 min, 3 users, load average: 0.05, 0.01, 0.00 2026-05-01 17:19:23.057616 CEST - lsb_release: Ubuntu 24.04.3 LTS 2026-05-01 17:19:23.057670 CEST - lsb_release matches expected value 2026-05-01 17:19:23.057682 CEST - SSH Test passed. 2026-05-01 17:19:23.064376 CEST - Destroying tst-80ba96af tst 2026-05-01 17:19:23.064523 CEST - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-80ba96af tst 2026-05-01 17:20:23.073318 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6088d79e-765f-4a0f-bfe2-f44b5769168f/step_Destroy_command_1.txt': /opt/slices-monitor-worker/slices-cli-pre-venv/bin/slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-80ba96af tst 2026-05-01 17:20:23.073430 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/6088d79e-765f-4a0f-bfe2-f44b5769168f/destroy.txt': 10.0% / task starting 30.0% - task starting 30.0% \ task starting 30.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting 60.0% - task starting 60.0% \ task starting 60.0% | task starting 60.0% / task starting ***Terminating process due to timeout*** ***Process terminated*** 2026-05-01 17:20:23.073461 CEST - Error in test step 'Destroy': "slices bi destroy" failed (return value is -15) 2026-05-01 17:20:23.073481 CEST - Step 'Destroy' took 60.01 seconds, which is longer than the warning threshold of 15 seconds 2026-05-01 17:20:23.073500 CEST - Wait 2s before retry