PLAY [Prepare mock iDRAC server] ***********************************************

TASK [Create mock server directory] ********************************************
Monday 08 June 2026  15:01:20 +0000 (0:00:00.024)       0:00:00.024 ***********
changed: [instance]

TASK [Generate self-signed TLS certificate] ************************************
Monday 08 June 2026  15:01:20 +0000 (0:00:00.440)       0:00:00.464 ***********
changed: [instance]

TASK [Copy mock iDRAC server script] *******************************************
Monday 08 June 2026  15:01:21 +0000 (0:00:00.385)       0:00:00.850 ***********
changed: [instance]

TASK [Start mock iDRAC server] *************************************************
Monday 08 June 2026  15:01:21 +0000 (0:00:00.593)       0:00:01.443 ***********
changed: [instance]

TASK [Record mock server PID] **************************************************
Monday 08 June 2026  15:01:21 +0000 (0:00:00.213)       0:00:01.656 ***********
ok: [instance]

TASK [Wait for mock iDRAC to respond] ******************************************
Monday 08 June 2026  15:01:22 +0000 (0:00:00.204)       0:00:01.861 ***********
ok: [instance]

PLAY RECAP *********************************************************************
instance                   : ok=6    changed=4    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Monday 08 June 2026  15:01:22 +0000 (0:00:00.436)       0:00:02.297 ***********
===============================================================================
Copy mock iDRAC server script ------------------------------------------- 0.59s
Create mock server directory -------------------------------------------- 0.44s
Wait for mock iDRAC to respond ------------------------------------------ 0.44s
Generate self-signed TLS certificate ------------------------------------ 0.39s
Start mock iDRAC server ------------------------------------------------- 0.21s
Record mock server PID -------------------------------------------------- 0.20s

PLAY [Converge -- test bm_* Redfish tasks against mock iDRAC] ******************

TASK [Test bm_power_off] *******************************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.048)       0:00:00.048 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_power_off.yml for instance

TASK [Reset mock to power Off] *************************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.023)       0:00:00.072 ***********
ok: [instance]

TASK [Include bm_power_off] ****************************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.460)       0:00:00.533 ***********

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.049)       0:00:00.582 ***********
ok: [instance]

TASK [bm_sno : Force power off via Redfish] ************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.376)       0:00:00.958 ***********
skipping: [instance]

TASK [bm_sno : Wait for host to power off] *************************************
Monday 08 June 2026  15:01:23 +0000 (0:00:00.028)       0:00:00.986 ***********
skipping: [instance]

TASK [Query mock state after power_off (already off)] **************************
Monday 08 June 2026  15:01:24 +0000 (0:00:00.033)       0:00:01.020 ***********
ok: [instance]

TASK [Assert host is still Off] ************************************************
Monday 08 June 2026  15:01:24 +0000 (0:00:00.371)       0:00:01.391 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock to power On] **************************************************
Monday 08 June 2026  15:01:24 +0000 (0:00:00.023)       0:00:01.415 ***********
ok: [instance]

TASK [Include bm_power_off] ****************************************************
Monday 08 June 2026  15:01:24 +0000 (0:00:00.314)       0:00:01.730 ***********

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:24 +0000 (0:00:00.028)       0:00:01.758 ***********
ok: [instance]

TASK [bm_sno : Force power off via Redfish] ************************************
Monday 08 June 2026  15:01:25 +0000 (0:00:00.320)       0:00:02.078 ***********
ok: [instance]

TASK [bm_sno : Wait for host to power off] *************************************
Monday 08 June 2026  15:01:25 +0000 (0:00:00.360)       0:00:02.439 ***********
ok: [instance]

TASK [Query mock state after power_off (was on)] *******************************
Monday 08 June 2026  15:01:25 +0000 (0:00:00.311)       0:00:02.750 ***********
ok: [instance]

TASK [Assert host is now Off] **************************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.321)       0:00:03.072 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Test bm_power_on] ********************************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.033)       0:00:03.106 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_power_on.yml for instance

TASK [Reset mock to power On] **************************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.048)       0:00:03.154 ***********
ok: [instance]

TASK [Include bm_power_on] *****************************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.320)       0:00:03.475 ***********

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.031)       0:00:03.506 ***********
ok: [instance]

TASK [bm_sno : Power on bare metal host] ***************************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.320)       0:00:03.827 ***********
skipping: [instance]

TASK [bm_sno : Wait for host POST to complete] *********************************
Monday 08 June 2026  15:01:26 +0000 (0:00:00.019)       0:00:03.846 ***********
ok: [instance]

TASK [bm_sno : Show POST state] ************************************************
Monday 08 June 2026  15:01:27 +0000 (0:00:00.341)       0:00:04.188 ***********
ok: [instance] => {
    "msg": "POST state: FinishedPost"
}

TASK [Query mock state after power_on (already on)] ****************************
Monday 08 June 2026  15:01:27 +0000 (0:00:00.041)       0:00:04.229 ***********
ok: [instance]

TASK [Assert host is still On with POST complete] ******************************
Monday 08 June 2026  15:01:27 +0000 (0:00:00.379)       0:00:04.608 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock to power Off] *************************************************
Monday 08 June 2026  15:01:27 +0000 (0:00:00.029)       0:00:04.638 ***********
ok: [instance]

TASK [Include bm_power_on] *****************************************************
Monday 08 June 2026  15:01:27 +0000 (0:00:00.338)       0:00:04.976 ***********

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:28 +0000 (0:00:00.043)       0:00:05.020 ***********
ok: [instance]

TASK [bm_sno : Power on bare metal host] ***************************************
Monday 08 June 2026  15:01:28 +0000 (0:00:00.399)       0:00:05.420 ***********
ok: [instance]

TASK [bm_sno : Wait for host POST to complete] *********************************
Monday 08 June 2026  15:01:28 +0000 (0:00:00.340)       0:00:05.761 ***********
ok: [instance]

TASK [bm_sno : Show POST state] ************************************************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.339)       0:00:06.100 ***********
ok: [instance] => {
    "msg": "POST state: FinishedPost"
}

TASK [Query mock state after power_on (was off)] *******************************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.033)       0:00:06.134 ***********
ok: [instance]

TASK [Assert host is now On with POST complete] ********************************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.344)       0:00:06.479 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock to power Off without PostState support] ***********************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.035)       0:00:06.514 ***********
ok: [instance]

TASK [Include bm_power_on] *****************************************************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.373)       0:00:06.888 ***********

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:29 +0000 (0:00:00.025)       0:00:06.914 ***********
ok: [instance]

TASK [bm_sno : Power on bare metal host] ***************************************
Monday 08 June 2026  15:01:30 +0000 (0:00:00.322)       0:00:07.237 ***********
ok: [instance]

TASK [bm_sno : Wait for host POST to complete] *********************************
Monday 08 June 2026  15:01:30 +0000 (0:00:00.365)       0:00:07.602 ***********
ok: [instance]

TASK [bm_sno : Show POST state] ************************************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.406)       0:00:08.009 ***********
ok: [instance] => {
    "msg": "POST state: not supported by firmware, used PowerState=On"
}

TASK [Query mock state after power_on (no PostState)] **************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.038)       0:00:08.048 ***********
ok: [instance]

TASK [Assert host is On (PostState stays null)] ********************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.305)       0:00:08.354 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Test bm_check_usb_boot] **************************************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.021)       0:00:08.375 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_check_usb_boot.yml for instance

TASK [Reset mock with usb_boot Enabled] ****************************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.024)       0:00:08.400 ***********
ok: [instance]

TASK [Include bm_check_usb_boot] ***********************************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.338)       0:00:08.739 ***********

TASK [bm_sno : Read GenericUsbBoot BIOS attribute] *****************************
Monday 08 June 2026  15:01:31 +0000 (0:00:00.027)       0:00:08.767 ***********
ok: [instance]

TASK [bm_sno : Set GenericUsbBoot status fact] *********************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.326)       0:00:09.093 ***********
ok: [instance]

TASK [bm_sno : Fail if GenericUsbBoot is disabled] *****************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.037)       0:00:09.130 ***********
skipping: [instance]

TASK [Assert _usb_boot_enabled is true] ****************************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.037)       0:00:09.167 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with usb_boot Disabled] ***************************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.032)       0:00:09.200 ***********
ok: [instance]

TASK [Include bm_check_usb_boot (expect failure)] ******************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.326)       0:00:09.527 ***********

TASK [bm_sno : Read GenericUsbBoot BIOS attribute] *****************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.029)       0:00:09.556 ***********
ok: [instance]

TASK [bm_sno : Set GenericUsbBoot status fact] *********************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.362)       0:00:09.919 ***********
ok: [instance]

TASK [bm_sno : Fail if GenericUsbBoot is disabled] *****************************
Monday 08 June 2026  15:01:32 +0000 (0:00:00.057)       0:00:09.977 ***********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}

TASK [Assert failure was about GenericUsbBoot] *********************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.033)       0:00:10.010 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Test bm_ensure_usb_boot] *************************************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.021)       0:00:10.032 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_ensure_usb_boot.yml for instance

TASK [Reset mock with usb_boot Enabled and host Off] ***************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.038)       0:00:10.070 ***********
ok: [instance]

TASK [Include bm_ensure_usb_boot] **********************************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.336)       0:00:10.406 ***********

TASK [bm_sno : Check current GenericUsbBoot state] *****************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.049)       0:00:10.456 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_check_usb_boot.yml for instance

TASK [bm_sno : Read GenericUsbBoot BIOS attribute] *****************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.024)       0:00:10.481 ***********
ok: [instance]

TASK [bm_sno : Set GenericUsbBoot status fact] *********************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.363)       0:00:10.844 ***********
ok: [instance]

TASK [bm_sno : Fail if GenericUsbBoot is disabled] *****************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.031)       0:00:10.876 ***********
skipping: [instance]

TASK [Query mock state] ********************************************************
Monday 08 June 2026  15:01:33 +0000 (0:00:00.033)       0:00:10.910 ***********
ok: [instance]

TASK [Assert host stayed Off (no power cycle needed)] **************************
Monday 08 June 2026  15:01:34 +0000 (0:00:00.325)       0:00:11.236 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with usb_boot Disabled and host Off] **************************
Monday 08 June 2026  15:01:34 +0000 (0:00:00.020)       0:00:11.256 ***********
ok: [instance]

TASK [Include bm_ensure_usb_boot with auto-enable] *****************************
Monday 08 June 2026  15:01:34 +0000 (0:00:00.379)       0:00:11.636 ***********

TASK [bm_sno : Check current GenericUsbBoot state] *****************************
Monday 08 June 2026  15:01:34 +0000 (0:00:00.027)       0:00:11.664 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_check_usb_boot.yml for instance

TASK [bm_sno : Read GenericUsbBoot BIOS attribute] *****************************
Monday 08 June 2026  15:01:34 +0000 (0:00:00.020)       0:00:11.685 ***********
ok: [instance]

TASK [bm_sno : Set GenericUsbBoot status fact] *********************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.372)       0:00:12.057 ***********
ok: [instance]

TASK [bm_sno : Fail if GenericUsbBoot is disabled] *****************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.035)       0:00:12.093 ***********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}

TASK [bm_sno : Fail if auto-enable is off] *************************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.037)       0:00:12.130 ***********
skipping: [instance]

TASK [bm_sno : Set GenericUsbBoot BIOS attribute] ******************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.032)       0:00:12.162 ***********
ok: [instance]

TASK [bm_sno : Create BIOS config job to schedule the change] ******************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.362)       0:00:12.525 ***********
ok: [instance]

TASK [bm_sno : Power off before applying BIOS change] **************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.346)       0:00:12.871 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_power_off.yml for instance

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:35 +0000 (0:00:00.022)       0:00:12.894 ***********
ok: [instance]

TASK [bm_sno : Force power off via Redfish] ************************************
Monday 08 June 2026  15:01:36 +0000 (0:00:00.368)       0:00:13.262 ***********
skipping: [instance]

TASK [bm_sno : Wait for host to power off] *************************************
Monday 08 June 2026  15:01:36 +0000 (0:00:00.015)       0:00:13.278 ***********
skipping: [instance]

TASK [bm_sno : Power on to apply BIOS config job during POST] ******************
Monday 08 June 2026  15:01:36 +0000 (0:00:00.017)       0:00:13.296 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_power_on.yml for instance

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:36 +0000 (0:00:00.025)       0:00:13.321 ***********
ok: [instance]

TASK [bm_sno : Power on bare metal host] ***************************************
Monday 08 June 2026  15:01:36 +0000 (0:00:00.313)       0:00:13.635 ***********
ok: [instance]

TASK [bm_sno : Wait for host POST to complete] *********************************
Monday 08 June 2026  15:01:37 +0000 (0:00:00.360)       0:00:13.995 ***********
ok: [instance]

TASK [bm_sno : Show POST state] ************************************************
Monday 08 June 2026  15:01:37 +0000 (0:00:00.416)       0:00:14.412 ***********
ok: [instance] => {
    "msg": "POST state: FinishedPost"
}

TASK [bm_sno : Power off after BIOS change applied] ****************************
Monday 08 June 2026  15:01:37 +0000 (0:00:00.037)       0:00:14.449 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_power_off.yml for instance

TASK [bm_sno : Query current power state] **************************************
Monday 08 June 2026  15:01:37 +0000 (0:00:00.038)       0:00:14.488 ***********
ok: [instance]

TASK [bm_sno : Force power off via Redfish] ************************************
Monday 08 June 2026  15:01:37 +0000 (0:00:00.321)       0:00:14.809 ***********
ok: [instance]

TASK [bm_sno : Wait for host to power off] *************************************
Monday 08 June 2026  15:01:38 +0000 (0:00:00.356)       0:00:15.166 ***********
ok: [instance]

TASK [Query mock state after auto-enable] **************************************
Monday 08 June 2026  15:01:38 +0000 (0:00:00.351)       0:00:15.517 ***********
ok: [instance]

TASK [Assert BIOS updated and host left Off] ***********************************
Monday 08 June 2026  15:01:38 +0000 (0:00:00.349)       0:00:15.867 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with usb_boot Disabled] ***************************************
Monday 08 June 2026  15:01:38 +0000 (0:00:00.027)       0:00:15.895 ***********
ok: [instance]

TASK [Include bm_ensure_usb_boot without auto-enable (expect failure)] *********
Monday 08 June 2026  15:01:39 +0000 (0:00:00.306)       0:00:16.202 ***********

TASK [bm_sno : Check current GenericUsbBoot state] *****************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.049)       0:00:16.251 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/tasks/bm_check_usb_boot.yml for instance

TASK [bm_sno : Read GenericUsbBoot BIOS attribute] *****************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.027)       0:00:16.278 ***********
ok: [instance]

TASK [bm_sno : Set GenericUsbBoot status fact] *********************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.366)       0:00:16.645 ***********
ok: [instance]

TASK [bm_sno : Fail if GenericUsbBoot is disabled] *****************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.040)       0:00:16.685 ***********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}

TASK [bm_sno : Fail if auto-enable is off] *************************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.039)       0:00:16.725 ***********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443. Set cifmw_bm_agent_enable_usb_boot: true in vars.yaml to allow this playbook to enable it automatically (requires a reboot cycle)."}

TASK [Assert failure mentions GenericUsbBoot or auto-enable] *******************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.035)       0:00:16.760 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Test bm_eject_vmedia] ****************************************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.030)       0:00:16.791 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_eject_vmedia.yml for instance

TASK [Reset mock with VirtualMedia inserted] ***********************************
Monday 08 June 2026  15:01:39 +0000 (0:00:00.047)       0:00:16.839 ***********
ok: [instance]

TASK [Include bm_eject_vmedia] *************************************************
Monday 08 June 2026  15:01:40 +0000 (0:00:00.344)       0:00:17.184 ***********

TASK [bm_sno : Eject VirtualMedia] *********************************************
Monday 08 June 2026  15:01:40 +0000 (0:00:00.038)       0:00:17.222 ***********
ok: [instance]

TASK [bm_sno : Wait for VirtualMedia eject to settle] **************************
Monday 08 June 2026  15:01:40 +0000 (0:00:00.350)       0:00:17.573 ***********
Pausing for 5 seconds
ok: [instance]

TASK [bm_sno : Check Remote File Share status] *********************************
Monday 08 June 2026  15:01:45 +0000 (0:00:05.039)       0:00:22.612 ***********
ok: [instance]

TASK [bm_sno : Show Remote File Share status before reset] *********************
Monday 08 June 2026  15:01:45 +0000 (0:00:00.380)       0:00:22.992 ***********
ok: [instance] => {
    "msg": ""
}

TASK [bm_sno : Run racadm racreset] ********************************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.051)       0:00:23.044 ***********
skipping: [instance]

TASK [bm_sno : Wait for iDRAC SSH to come back up] *****************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.043)       0:00:23.088 ***********
skipping: [instance]

TASK [Query mock state after eject] ********************************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.029)       0:00:23.117 ***********
ok: [instance]

TASK [Assert VirtualMedia is ejected] ******************************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.331)       0:00:23.448 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with VirtualMedia not inserted] *******************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.033)       0:00:23.482 ***********
ok: [instance]

TASK [Include bm_eject_vmedia (idempotent)] ************************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.358)       0:00:23.841 ***********

TASK [bm_sno : Eject VirtualMedia] *********************************************
Monday 08 June 2026  15:01:46 +0000 (0:00:00.030)       0:00:23.872 ***********
ok: [instance]

TASK [bm_sno : Wait for VirtualMedia eject to settle] **************************
Monday 08 June 2026  15:01:47 +0000 (0:00:00.363)       0:00:24.235 ***********
Pausing for 5 seconds
ok: [instance]

TASK [bm_sno : Check Remote File Share status] *********************************
Monday 08 June 2026  15:01:52 +0000 (0:00:05.029)       0:00:29.264 ***********
ok: [instance]

TASK [bm_sno : Show Remote File Share status before reset] *********************
Monday 08 June 2026  15:01:52 +0000 (0:00:00.201)       0:00:29.466 ***********
ok: [instance] => {
    "msg": ""
}

TASK [bm_sno : Run racadm racreset] ********************************************
Monday 08 June 2026  15:01:52 +0000 (0:00:00.056)       0:00:29.522 ***********
skipping: [instance]

TASK [bm_sno : Wait for iDRAC SSH to come back up] *****************************
Monday 08 June 2026  15:01:52 +0000 (0:00:00.044)       0:00:29.567 ***********
skipping: [instance]

TASK [Query mock state] ********************************************************
Monday 08 June 2026  15:01:52 +0000 (0:00:00.049)       0:00:29.617 ***********
ok: [instance]

TASK [Assert VirtualMedia is still not inserted] *******************************
Monday 08 June 2026  15:01:52 +0000 (0:00:00.354)       0:00:29.972 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Test bm_discover_vmedia_target] ******************************************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.036)       0:00:30.008 ***********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/bm_sno/molecule/bm_redfish/tasks/test_discover_vmedia.yml for instance

TASK [Clear vmedia path from previous tests] ***********************************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.069)       0:00:30.078 ***********
ok: [instance]

TASK [Reset mock with VirtualMedia inserted] ***********************************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.019)       0:00:30.098 ***********
ok: [instance]

TASK [Include bm_discover_vmedia_target (auto-discover)] ***********************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.329)       0:00:30.427 ***********

TASK [bm_sno : Fetch UEFI boot option IDs] *************************************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.045)       0:00:30.473 ***********
ok: [instance]

TASK [bm_sno : Fetch each UEFI boot option detail] *****************************
Monday 08 June 2026  15:01:53 +0000 (0:00:00.343)       0:00:30.816 ***********
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance]

TASK [bm_sno : Build list of known UEFI device paths] **************************
Monday 08 June 2026  15:01:55 +0000 (0:00:01.794)       0:00:32.610 ***********
ok: [instance]

TASK [bm_sno : Show all UEFI boot options] *************************************
Monday 08 June 2026  15:01:55 +0000 (0:00:00.045)       0:00:32.656 ***********
ok: [instance] => {
    "msg": [
        "PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA)",
        "Virtual Floppy Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x1)",
        "Virtual Optical Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)",
        "Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi",
        "Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
    ]
}

TASK [bm_sno : Validate user-provided VirtualMedia UEFI path] ******************
Monday 08 June 2026  15:01:55 +0000 (0:00:00.060)       0:00:32.716 ***********
skipping: [instance]

TASK [bm_sno : Find Virtual Optical Drive boot path] ***************************
Monday 08 June 2026  15:01:55 +0000 (0:00:00.033)       0:00:32.750 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
ok: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)

TASK [bm_sno : Derive from Virtual Floppy Drive path (Unit 1 -> Unit 0)] *******
Monday 08 June 2026  15:01:55 +0000 (0:00:00.078)       0:00:32.828 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fall back to Generic USB Boot path] *****************************
Monday 08 June 2026  15:01:55 +0000 (0:00:00.081)       0:00:32.910 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fail if no VirtualMedia boot path found] ************************
Monday 08 June 2026  15:01:56 +0000 (0:00:00.105)       0:00:33.015 ***********
skipping: [instance]

TASK [bm_sno : Show VirtualMedia UEFI boot target] *****************************
Monday 08 June 2026  15:01:56 +0000 (0:00:00.033)       0:00:33.049 ***********
ok: [instance] => {
    "msg": "VirtualMedia UEFI path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Clear pending iDRAC config jobs that block boot override] *******
Monday 08 June 2026  15:01:56 +0000 (0:00:00.024)       0:00:33.073 ***********
ok: [instance]

TASK [bm_sno : Wait for iDRAC to settle after clearing jobs] *******************
Monday 08 June 2026  15:01:56 +0000 (0:00:00.376)       0:00:33.450 ***********
Pausing for 10 seconds
ok: [instance]

TASK [bm_sno : Set one-time boot from Virtual Optical Drive] *******************
Monday 08 June 2026  15:02:06 +0000 (0:00:10.042)       0:00:43.493 ***********
ok: [instance]

TASK [bm_sno : Verify boot override was applied] *******************************
Monday 08 June 2026  15:02:06 +0000 (0:00:00.369)       0:00:43.862 ***********
ok: [instance]

TASK [bm_sno : Assert boot override is set] ************************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.351)       0:00:44.214 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [bm_sno : Show resolved boot path] ****************************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.035)       0:00:44.250 ***********
ok: [instance] => {
    "msg": "Resolved boot path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Verify VirtualMedia is still inserted] **************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.021)       0:00:44.271 ***********
ok: [instance]

TASK [bm_sno : Assert VirtualMedia ISO is mounted] *****************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.359)       0:00:44.631 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Assert discovered path is Virtual Optical Drive] *************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.041)       0:00:44.673 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Query mock state after discover] *****************************************
Monday 08 June 2026  15:02:07 +0000 (0:00:00.050)       0:00:44.723 ***********
ok: [instance]

TASK [Assert boot override was set] ********************************************
Monday 08 June 2026  15:02:08 +0000 (0:00:00.379)       0:00:45.102 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with VirtualMedia inserted] ***********************************
Monday 08 June 2026  15:02:08 +0000 (0:00:00.050)       0:00:45.152 ***********
ok: [instance]

TASK [Include bm_discover_vmedia_target with valid explicit path] **************
Monday 08 June 2026  15:02:08 +0000 (0:00:00.443)       0:00:45.595 ***********

TASK [bm_sno : Fetch UEFI boot option IDs] *************************************
Monday 08 June 2026  15:02:08 +0000 (0:00:00.058)       0:00:45.654 ***********
ok: [instance]

TASK [bm_sno : Fetch each UEFI boot option detail] *****************************
Monday 08 June 2026  15:02:09 +0000 (0:00:00.357)       0:00:46.012 ***********
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance]

TASK [bm_sno : Build list of known UEFI device paths] **************************
Monday 08 June 2026  15:02:11 +0000 (0:00:02.118)       0:00:48.131 ***********
ok: [instance]

TASK [bm_sno : Show all UEFI boot options] *************************************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.046)       0:00:48.177 ***********
ok: [instance] => {
    "msg": [
        "PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA)",
        "Virtual Floppy Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x1)",
        "Virtual Optical Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)",
        "Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi",
        "Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
    ]
}

TASK [bm_sno : Validate user-provided VirtualMedia UEFI path] ******************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.046)       0:00:48.224 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [bm_sno : Find Virtual Optical Drive boot path] ***************************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.047)       0:00:48.271 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Derive from Virtual Floppy Drive path (Unit 1 -> Unit 0)] *******
Monday 08 June 2026  15:02:11 +0000 (0:00:00.076)       0:00:48.348 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fall back to Generic USB Boot path] *****************************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.063)       0:00:48.412 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Virtual Optical Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fail if no VirtualMedia boot path found] ************************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.102)       0:00:48.514 ***********
skipping: [instance]

TASK [bm_sno : Show VirtualMedia UEFI boot target] *****************************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.039)       0:00:48.553 ***********
ok: [instance] => {
    "msg": "VirtualMedia UEFI path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Clear pending iDRAC config jobs that block boot override] *******
Monday 08 June 2026  15:02:11 +0000 (0:00:00.032)       0:00:48.586 ***********
ok: [instance]

TASK [bm_sno : Wait for iDRAC to settle after clearing jobs] *******************
Monday 08 June 2026  15:02:11 +0000 (0:00:00.391)       0:00:48.977 ***********
Pausing for 10 seconds
ok: [instance]

TASK [bm_sno : Set one-time boot from Virtual Optical Drive] *******************
Monday 08 June 2026  15:02:22 +0000 (0:00:10.029)       0:00:59.006 ***********
ok: [instance]

TASK [bm_sno : Verify boot override was applied] *******************************
Monday 08 June 2026  15:02:22 +0000 (0:00:00.322)       0:00:59.329 ***********
ok: [instance]

TASK [bm_sno : Assert boot override is set] ************************************
Monday 08 June 2026  15:02:22 +0000 (0:00:00.381)       0:00:59.711 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [bm_sno : Show resolved boot path] ****************************************
Monday 08 June 2026  15:02:22 +0000 (0:00:00.043)       0:00:59.754 ***********
ok: [instance] => {
    "msg": "Resolved boot path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Verify VirtualMedia is still inserted] **************************
Monday 08 June 2026  15:02:22 +0000 (0:00:00.028)       0:00:59.783 ***********
ok: [instance]

TASK [bm_sno : Assert VirtualMedia ISO is mounted] *****************************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.366)       0:01:00.150 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Query mock state] ********************************************************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.065)       0:01:00.215 ***********
ok: [instance]

TASK [Assert boot override set with user-provided path] ************************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.318)       0:01:00.533 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Clear vmedia path from previous tests] ***********************************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.032)       0:01:00.566 ***********
ok: [instance]

TASK [Reset mock without Virtual Optical Drive] ********************************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.016)       0:01:00.583 ***********
ok: [instance]

TASK [Include bm_discover_vmedia_target (floppy fallback)] *********************
Monday 08 June 2026  15:02:23 +0000 (0:00:00.364)       0:01:00.948 ***********

TASK [bm_sno : Fetch UEFI boot option IDs] *************************************
Monday 08 June 2026  15:02:24 +0000 (0:00:00.064)       0:01:01.012 ***********
ok: [instance]

TASK [bm_sno : Fetch each UEFI boot option detail] *****************************
Monday 08 June 2026  15:02:24 +0000 (0:00:00.381)       0:01:01.393 ***********
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance]

TASK [bm_sno : Build list of known UEFI device paths] **************************
Monday 08 June 2026  15:02:25 +0000 (0:00:01.395)       0:01:02.789 ***********
ok: [instance]

TASK [bm_sno : Show all UEFI boot options] *************************************
Monday 08 June 2026  15:02:25 +0000 (0:00:00.052)       0:01:02.841 ***********
ok: [instance] => {
    "msg": [
        "PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA)",
        "Virtual Floppy Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x1)",
        "Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi",
        "Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
    ]
}

TASK [bm_sno : Validate user-provided VirtualMedia UEFI path] ******************
Monday 08 June 2026  15:02:25 +0000 (0:00:00.049)       0:01:02.890 ***********
skipping: [instance]

TASK [bm_sno : Find Virtual Optical Drive boot path] ***************************
Monday 08 June 2026  15:02:25 +0000 (0:00:00.040)       0:01:02.930 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Derive from Virtual Floppy Drive path (Unit 1 -> Unit 0)] *******
Monday 08 June 2026  15:02:26 +0000 (0:00:00.085)       0:01:03.016 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
ok: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)

TASK [bm_sno : Fall back to Generic USB Boot path] *****************************
Monday 08 June 2026  15:02:26 +0000 (0:00:00.067)       0:01:03.083 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Virtual Floppy Drive)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fail if no VirtualMedia boot path found] ************************
Monday 08 June 2026  15:02:26 +0000 (0:00:00.071)       0:01:03.155 ***********
skipping: [instance]

TASK [bm_sno : Show VirtualMedia UEFI boot target] *****************************
Monday 08 June 2026  15:02:26 +0000 (0:00:00.035)       0:01:03.191 ***********
ok: [instance] => {
    "msg": "VirtualMedia UEFI path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Clear pending iDRAC config jobs that block boot override] *******
Monday 08 June 2026  15:02:26 +0000 (0:00:00.023)       0:01:03.214 ***********
ok: [instance]

TASK [bm_sno : Wait for iDRAC to settle after clearing jobs] *******************
Monday 08 June 2026  15:02:26 +0000 (0:00:00.396)       0:01:03.611 ***********
Pausing for 10 seconds
ok: [instance]

TASK [bm_sno : Set one-time boot from Virtual Optical Drive] *******************
Monday 08 June 2026  15:02:36 +0000 (0:00:10.039)       0:01:13.650 ***********
ok: [instance]

TASK [bm_sno : Verify boot override was applied] *******************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.379)       0:01:14.030 ***********
ok: [instance]

TASK [bm_sno : Assert boot override is set] ************************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.361)       0:01:14.391 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [bm_sno : Show resolved boot path] ****************************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.048)       0:01:14.439 ***********
ok: [instance] => {
    "msg": "Resolved boot path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}

TASK [bm_sno : Verify VirtualMedia is still inserted] **************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.022)       0:01:14.462 ***********
ok: [instance]

TASK [bm_sno : Assert VirtualMedia ISO is mounted] *****************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.406)       0:01:14.869 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Assert derived path uses Unit(0x0) from Floppy Unit(0x1)] ****************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.062)       0:01:14.931 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Query mock state after floppy fallback] **********************************
Monday 08 June 2026  15:02:37 +0000 (0:00:00.047)       0:01:14.979 ***********
ok: [instance]

TASK [Assert boot override was set] ********************************************
Monday 08 June 2026  15:02:38 +0000 (0:00:00.365)       0:01:15.344 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Clear vmedia path from previous tests] ***********************************
Monday 08 June 2026  15:02:38 +0000 (0:00:00.034)       0:01:15.378 ***********
ok: [instance]

TASK [Reset mock without Virtual Optical or Floppy Drive] **********************
Monday 08 June 2026  15:02:38 +0000 (0:00:00.027)       0:01:15.405 ***********
ok: [instance]

TASK [Include bm_discover_vmedia_target (generic usb fallback)] ****************
Monday 08 June 2026  15:02:38 +0000 (0:00:00.374)       0:01:15.779 ***********

TASK [bm_sno : Fetch UEFI boot option IDs] *************************************
Monday 08 June 2026  15:02:38 +0000 (0:00:00.057)       0:01:15.837 ***********
ok: [instance]

TASK [bm_sno : Fetch each UEFI boot option detail] *****************************
Monday 08 June 2026  15:02:39 +0000 (0:00:00.345)       0:01:16.182 ***********
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance]

TASK [bm_sno : Build list of known UEFI device paths] **************************
Monday 08 June 2026  15:02:40 +0000 (0:00:01.031)       0:01:17.214 ***********
ok: [instance]

TASK [bm_sno : Show all UEFI boot options] *************************************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.068)       0:01:17.282 ***********
ok: [instance] => {
    "msg": [
        "PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA)",
        "Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi",
        "Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
    ]
}

TASK [bm_sno : Validate user-provided VirtualMedia UEFI path] ******************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.037)       0:01:17.320 ***********
skipping: [instance]

TASK [bm_sno : Find Virtual Optical Drive boot path] ***************************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.035)       0:01:17.355 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Derive from Virtual Floppy Drive path (Unit 1 -> Unit 0)] *******
Monday 08 June 2026  15:02:40 +0000 (0:00:00.057)       0:01:17.412 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
skipping: [instance] => (item=Generic USB Boot)
skipping: [instance]

TASK [bm_sno : Fall back to Generic USB Boot path] *****************************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.062)       0:01:17.475 ***********
skipping: [instance] => (item=PXE Device 1: Embedded NIC 1 Port 1 Partition 1)
skipping: [instance] => (item=Integrated RAID Controller 1: Red Hat Enterprise Linux)
ok: [instance] => (item=Generic USB Boot)

TASK [bm_sno : Fail if no VirtualMedia boot path found] ************************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.058)       0:01:17.533 ***********
skipping: [instance]

TASK [bm_sno : Show VirtualMedia UEFI boot target] *****************************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.030)       0:01:17.564 ***********
ok: [instance] => {
    "msg": "VirtualMedia UEFI path: VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
}

TASK [bm_sno : Clear pending iDRAC config jobs that block boot override] *******
Monday 08 June 2026  15:02:40 +0000 (0:00:00.022)       0:01:17.586 ***********
ok: [instance]

TASK [bm_sno : Wait for iDRAC to settle after clearing jobs] *******************
Monday 08 June 2026  15:02:40 +0000 (0:00:00.368)       0:01:17.954 ***********
Pausing for 10 seconds
ok: [instance]

TASK [bm_sno : Set one-time boot from Virtual Optical Drive] *******************
Monday 08 June 2026  15:02:50 +0000 (0:00:10.037)       0:01:27.991 ***********
ok: [instance]

TASK [bm_sno : Verify boot override was applied] *******************************
Monday 08 June 2026  15:02:51 +0000 (0:00:00.387)       0:01:28.378 ***********
ok: [instance]

TASK [bm_sno : Assert boot override is set] ************************************
Monday 08 June 2026  15:02:51 +0000 (0:00:00.338)       0:01:28.717 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [bm_sno : Show resolved boot path] ****************************************
Monday 08 June 2026  15:02:51 +0000 (0:00:00.049)       0:01:28.766 ***********
ok: [instance] => {
    "msg": "Resolved boot path: VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
}

TASK [bm_sno : Verify VirtualMedia is still inserted] **************************
Monday 08 June 2026  15:02:51 +0000 (0:00:00.031)       0:01:28.798 ***********
ok: [instance]

TASK [bm_sno : Assert VirtualMedia ISO is mounted] *****************************
Monday 08 June 2026  15:02:52 +0000 (0:00:00.341)       0:01:29.139 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Assert Generic USB Boot path was used] ***********************************
Monday 08 June 2026  15:02:52 +0000 (0:00:00.037)       0:01:29.177 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Query mock state after generic usb fallback] *****************************
Monday 08 June 2026  15:02:52 +0000 (0:00:00.050)       0:01:29.227 ***********
ok: [instance]

TASK [Assert boot override was set] ********************************************
Monday 08 June 2026  15:02:52 +0000 (0:00:00.379)       0:01:29.606 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

TASK [Reset mock with VirtualMedia inserted] ***********************************
Monday 08 June 2026  15:02:52 +0000 (0:00:00.023)       0:01:29.630 ***********
ok: [instance]

TASK [Include bm_discover_vmedia_target with invalid path (expect failure)] ****
Monday 08 June 2026  15:02:52 +0000 (0:00:00.354)       0:01:29.984 ***********

TASK [bm_sno : Fetch UEFI boot option IDs] *************************************
Monday 08 June 2026  15:02:53 +0000 (0:00:00.054)       0:01:30.039 ***********
ok: [instance]

TASK [bm_sno : Fetch each UEFI boot option detail] *****************************
Monday 08 June 2026  15:02:53 +0000 (0:00:00.372)       0:01:30.411 ***********
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance] => (item=None)
ok: [instance]

TASK [bm_sno : Build list of known UEFI device paths] **************************
Monday 08 June 2026  15:02:55 +0000 (0:00:01.595)       0:01:32.007 ***********
ok: [instance]

TASK [bm_sno : Show all UEFI boot options] *************************************
Monday 08 June 2026  15:02:55 +0000 (0:00:00.042)       0:01:32.049 ***********
ok: [instance] => {
    "msg": [
        "PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA)",
        "Virtual Floppy Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x1)",
        "Virtual Optical Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)",
        "Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi",
        "Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
    ]
}

TASK [bm_sno : Validate user-provided VirtualMedia UEFI path] ******************
Monday 08 June 2026  15:02:55 +0000 (0:00:00.041)       0:01:32.091 ***********
fatal: [instance]: FAILED! => {
    "assertion": "cifmw_bm_agent_vmedia_uefi_path in _known_uefi_paths",
    "changed": false,
    "evaluated_to": false,
    "msg": "cifmw_bm_agent_vmedia_uefi_path 'PciRoot(0x0)/INVALID/PATH' is not listed in UEFI boot options. Available paths: PXE Device 1: Embedded NIC 1 Port 1 Partition 1 -> VenHw(3A191845-5F86-4E78-8FCE-C4CFF59F9DAA), Virtual Floppy Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x1), Virtual Optical Drive -> PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0), Integrated RAID Controller 1: Red Hat Enterprise Linux -> HD(2,GPT,FF726BC2-263F-EE4A-BAE7-7CACE574EBD8,0x1000,0x3F800)/\\EFI\\redhat\\shimx64.efi, Generic USB Boot -> VenHw(0C8CB6CC-13AE-45F4-BBCD-6A25E98AC250)"
}

TASK [Assert failure was about UEFI path] **************************************
Monday 08 June 2026  15:02:55 +0000 (0:00:00.078)       0:01:32.169 ***********
ok: [instance] => {
    "changed": false,
    "msg": "All assertions passed"
}

PLAY RECAP *********************************************************************
instance                   : ok=168  changed=0    unreachable=0    failed=0    skipped=28   rescued=5    ignored=0

Monday 08 June 2026  15:02:55 +0000 (0:00:00.057)       0:01:32.227 ***********
===============================================================================
bm_sno : Wait for iDRAC to settle after clearing jobs ------------------ 10.04s
bm_sno : Wait for iDRAC to settle after clearing jobs ------------------ 10.04s
bm_sno : Wait for iDRAC to settle after clearing jobs ------------------ 10.04s
bm_sno : Wait for iDRAC to settle after clearing jobs ------------------ 10.03s
bm_sno : Wait for VirtualMedia eject to settle -------------------------- 5.04s
bm_sno : Wait for VirtualMedia eject to settle -------------------------- 5.03s
bm_sno : Fetch each UEFI boot option detail ----------------------------- 2.12s
bm_sno : Fetch each UEFI boot option detail ----------------------------- 1.79s
bm_sno : Fetch each UEFI boot option detail ----------------------------- 1.60s
bm_sno : Fetch each UEFI boot option detail ----------------------------- 1.40s
bm_sno : Fetch each UEFI boot option detail ----------------------------- 1.03s
Reset mock to power Off ------------------------------------------------- 0.46s
Reset mock with VirtualMedia inserted ----------------------------------- 0.44s
bm_sno : Wait for host POST to complete --------------------------------- 0.42s
bm_sno : Wait for host POST to complete --------------------------------- 0.41s
bm_sno : Verify VirtualMedia is still inserted -------------------------- 0.41s
bm_sno : Query current power state -------------------------------------- 0.40s
bm_sno : Clear pending iDRAC config jobs that block boot override ------- 0.40s
bm_sno : Clear pending iDRAC config jobs that block boot override ------- 0.39s
bm_sno : Set one-time boot from Virtual Optical Drive ------------------- 0.39s

PLAY [Cleanup mock iDRAC server] ***********************************************

TASK [Stop mock iDRAC server] **************************************************
Monday 08 June 2026  15:02:55 +0000 (0:00:00.033)       0:00:00.033 ***********
ok: [instance]

TASK [Show mock server log] ****************************************************
Monday 08 June 2026  15:02:56 +0000 (0:00:00.331)       0:00:00.365 ***********
ok: [instance]

TASK [Print mock server log] ***************************************************
Monday 08 June 2026  15:02:56 +0000 (0:00:00.240)       0:00:00.606 ***********
ok: [instance] => {
    "_mock_log.stdout_lines": [
        "Mock iDRAC listening on https://0.0.0.0:8443",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Systems/System.Embedded.1/Actions/ComputerSystem.Reset HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Systems/System.Embedded.1/Actions/ComputerSystem.Reset HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Systems/System.Embedded.1/Actions/ComputerSystem.Reset HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/Bios HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/Bios HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/Bios HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/Bios HTTP/1.1\" 200 -",
        "[mock-idrac] \"PATCH /redfish/v1/Systems/System.Embedded.1/Bios/Settings HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Managers/iDRAC.Embedded.1/Jobs HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Systems/System.Embedded.1/Actions/ComputerSystem.Reset HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Systems/System.Embedded.1/Actions/ComputerSystem.Reset HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/Bios HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD/Actions/VirtualMedia.EjectMedia HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD/Actions/VirtualMedia.EjectMedia HTTP/1.1\" 204 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0001 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0003 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0004 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0005 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0006 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Dell/Managers/iDRAC.Embedded.1/DellJobService/Actions/DellJobService.DeleteJobQueue HTTP/1.1\" 200 -",
        "[mock-idrac] \"PATCH /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0001 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0003 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0004 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0005 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0006 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Dell/Managers/iDRAC.Embedded.1/DellJobService/Actions/DellJobService.DeleteJobQueue HTTP/1.1\" 200 -",
        "[mock-idrac] \"PATCH /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0001 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0003 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0005 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0006 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Dell/Managers/iDRAC.Embedded.1/DellJobService/Actions/DellJobService.DeleteJobQueue HTTP/1.1\" 200 -",
        "[mock-idrac] \"PATCH /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0001 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0005 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0006 HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /redfish/v1/Dell/Managers/iDRAC.Embedded.1/DellJobService/Actions/DellJobService.DeleteJobQueue HTTP/1.1\" 200 -",
        "[mock-idrac] \"PATCH /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Managers/iDRAC.Embedded.1/VirtualMedia/CD HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /test/state HTTP/1.1\" 200 -",
        "[mock-idrac] \"POST /test/reset HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0001 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0003 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0004 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0005 HTTP/1.1\" 200 -",
        "[mock-idrac] \"GET /redfish/v1/Systems/System.Embedded.1/BootOptions/Boot0006 HTTP/1.1\" 200 -"
    ]
}

PLAY RECAP *********************************************************************
instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0

Monday 08 June 2026  15:02:56 +0000 (0:00:00.043)       0:00:00.649 ***********
===============================================================================
Stop mock iDRAC server -------------------------------------------------- 0.33s
Show mock server log ---------------------------------------------------- 0.24s
Print mock server log --------------------------------------------------- 0.04s