PLAY [Prepare mock iDRAC server] ***********************************************
TASK [Create mock server directory] ********************************************
Friday 13 March 2026 10:10:46 +0000 (0:00:00.026) 0:00:00.026 **********
changed: [instance]
TASK [Generate self-signed TLS certificate] ************************************
Friday 13 March 2026 10:10:46 +0000 (0:00:00.321) 0:00:00.347 **********
changed: [instance]
TASK [Copy mock iDRAC server script] *******************************************
Friday 13 March 2026 10:10:47 +0000 (0:00:00.393) 0:00:00.741 **********
changed: [instance]
TASK [Start mock iDRAC server] *************************************************
Friday 13 March 2026 10:10:47 +0000 (0:00:00.492) 0:00:01.233 **********
changed: [instance]
TASK [Record mock server PID] **************************************************
Friday 13 March 2026 10:10:47 +0000 (0:00:00.174) 0:00:01.408 **********
ok: [instance]
TASK [Wait for mock iDRAC to respond] ******************************************
Friday 13 March 2026 10:10:47 +0000 (0:00:00.185) 0:00:01.594 **********
ok: [instance]
PLAY RECAP *********************************************************************
instance : ok=6 changed=4 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0
Friday 13 March 2026 10:10:48 +0000 (0:00:00.397) 0:00:01.991 **********
===============================================================================
Copy mock iDRAC server script ------------------------------------------- 0.49s
Wait for mock iDRAC to respond ------------------------------------------ 0.40s
Generate self-signed TLS certificate ------------------------------------ 0.39s
Create mock server directory -------------------------------------------- 0.32s
Record mock server PID -------------------------------------------------- 0.19s
Start mock iDRAC server ------------------------------------------------- 0.17s
PLAY [Converge -- test bm_* Redfish tasks against mock iDRAC] ******************
TASK [Test bm_power_off] *******************************************************
Friday 13 March 2026 10:10:48 +0000 (0:00:00.044) 0:00:00.044 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_power_off.yml for instance
TASK [Reset mock to power Off] *************************************************
Friday 13 March 2026 10:10:48 +0000 (0:00:00.020) 0:00:00.064 **********
ok: [instance]
TASK [Include bm_power_off] ****************************************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.390) 0:00:00.454 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.026) 0:00:00.481 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.271) 0:00:00.752 **********
skipping: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.015) 0:00:00.767 **********
skipping: [instance]
TASK [Query mock state after power_off (already off)] **************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.017) 0:00:00.785 **********
ok: [instance]
TASK [Assert host is still Off] ************************************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.269) 0:00:01.055 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock to power On] **************************************************
Friday 13 March 2026 10:10:49 +0000 (0:00:00.019) 0:00:01.074 **********
ok: [instance]
TASK [Include bm_power_off] ****************************************************
Friday 13 March 2026 10:10:50 +0000 (0:00:00.270) 0:00:01.345 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:50 +0000 (0:00:00.024) 0:00:01.369 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:10:50 +0000 (0:00:00.268) 0:00:01.637 **********
ok: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:10:50 +0000 (0:00:00.277) 0:00:01.915 **********
ok: [instance]
TASK [Query mock state after power_off (was on)] *******************************
Friday 13 March 2026 10:10:50 +0000 (0:00:00.281) 0:00:02.196 **********
ok: [instance]
TASK [Assert host is now Off] **************************************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.270) 0:00:02.467 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_power_on] ********************************************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.017) 0:00:02.485 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_power_on.yml for instance
TASK [Reset mock to power On] **************************************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.021) 0:00:02.506 **********
ok: [instance]
TASK [Include bm_power_on] *****************************************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.268) 0:00:02.774 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.024) 0:00:02.799 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.269) 0:00:03.068 **********
skipping: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:10:51 +0000 (0:00:00.015) 0:00:03.083 **********
ok: [instance]
TASK [Query mock state after power_on (already on)] ****************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.283) 0:00:03.366 **********
ok: [instance]
TASK [Assert host is still On] *************************************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.268) 0:00:03.635 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock to power Off] *************************************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.017) 0:00:03.653 **********
ok: [instance]
TASK [Include bm_power_on] *****************************************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.273) 0:00:03.927 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.024) 0:00:03.951 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:10:52 +0000 (0:00:00.278) 0:00:04.229 **********
ok: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:10:53 +0000 (0:00:00.276) 0:00:04.506 **********
ok: [instance]
TASK [Query mock state after power_on (was off)] *******************************
Friday 13 March 2026 10:10:53 +0000 (0:00:00.280) 0:00:04.786 **********
ok: [instance]
TASK [Assert host is now On] ***************************************************
Friday 13 March 2026 10:10:53 +0000 (0:00:00.269) 0:00:05.055 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_check_usb_boot] **************************************************
Friday 13 March 2026 10:10:53 +0000 (0:00:00.020) 0:00:05.076 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_check_usb_boot.yml for instance
TASK [Reset mock with usb_boot Enabled] ****************************************
Friday 13 March 2026 10:10:53 +0000 (0:00:00.023) 0:00:05.099 **********
ok: [instance]
TASK [Include bm_check_usb_boot] ***********************************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.267) 0:00:05.367 **********
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.024) 0:00:05.391 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.283) 0:00:05.675 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.031) 0:00:05.707 **********
skipping: [instance]
TASK [Assert _usb_boot_enabled is true] ****************************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.027) 0:00:05.734 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled] ***************************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.025) 0:00:05.760 **********
ok: [instance]
TASK [Include bm_check_usb_boot (expect failure)] ******************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.283) 0:00:06.043 **********
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:10:54 +0000 (0:00:00.024) 0:00:06.068 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.292) 0:00:06.361 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.029) 0:00:06.391 **********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}
TASK [Assert failure was about GenericUsbBoot] *********************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.029) 0:00:06.420 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_ensure_usb_boot] *************************************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.019) 0:00:06.440 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_ensure_usb_boot.yml for instance
TASK [Reset mock with usb_boot Enabled and host Off] ***************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.033) 0:00:06.473 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot] **********************************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.293) 0:00:06.767 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.031) 0:00:06.799 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_check_usb_boot.yml for instance
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.022) 0:00:06.821 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.287) 0:00:07.108 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.032) 0:00:07.141 **********
skipping: [instance]
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:10:55 +0000 (0:00:00.032) 0:00:07.173 **********
ok: [instance]
TASK [Assert host stayed Off (no power cycle needed)] **************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.286) 0:00:07.460 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled and host Off] **************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.021) 0:00:07.482 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot with auto-enable] *****************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.288) 0:00:07.770 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.032) 0:00:07.803 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_check_usb_boot.yml for instance
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.022) 0:00:07.825 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.285) 0:00:08.110 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.033) 0:00:08.143 **********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}
TASK [reproducer : Fail if auto-enable is off] *********************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.032) 0:00:08.176 **********
skipping: [instance]
TASK [reproducer : Set GenericUsbBoot BIOS attribute] **************************
Friday 13 March 2026 10:10:56 +0000 (0:00:00.029) 0:00:08.205 **********
ok: [instance]
TASK [reproducer : Create BIOS config job to schedule the change] **************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.281) 0:00:08.487 **********
ok: [instance]
TASK [reproducer : Power off before applying BIOS change] **********************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.278) 0:00:08.765 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_power_off.yml for instance
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.023) 0:00:08.788 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.277) 0:00:09.066 **********
skipping: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.018) 0:00:09.084 **********
skipping: [instance]
TASK [reproducer : Power on to apply BIOS config job during POST] **************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.017) 0:00:09.102 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_power_on.yml for instance
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:57 +0000 (0:00:00.025) 0:00:09.128 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:10:58 +0000 (0:00:00.285) 0:00:09.414 **********
ok: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:10:58 +0000 (0:00:00.277) 0:00:09.692 **********
ok: [instance]
TASK [reproducer : Power off after BIOS change applied] ************************
Friday 13 March 2026 10:10:58 +0000 (0:00:00.277) 0:00:09.969 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_power_off.yml for instance
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:10:58 +0000 (0:00:00.027) 0:00:09.996 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:10:58 +0000 (0:00:00.276) 0:00:10.273 **********
ok: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:10:59 +0000 (0:00:00.276) 0:00:10.549 **********
ok: [instance]
TASK [Query mock state after auto-enable] **************************************
Friday 13 March 2026 10:10:59 +0000 (0:00:00.276) 0:00:10.825 **********
ok: [instance]
TASK [Assert BIOS updated and host left Off] ***********************************
Friday 13 March 2026 10:10:59 +0000 (0:00:00.277) 0:00:11.103 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled] ***************************************
Friday 13 March 2026 10:10:59 +0000 (0:00:00.022) 0:00:11.126 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot without auto-enable (expect failure)] *********
Friday 13 March 2026 10:11:00 +0000 (0:00:00.275) 0:00:11.401 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.030) 0:00:11.432 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/tasks/bm_check_usb_boot.yml for instance
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.023) 0:00:11.455 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.283) 0:00:11.738 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.030) 0:00:11.769 **********
fatal: [instance]: FAILED! => {"changed": false, "msg": "GenericUsbBoot is Disabled in BIOS. VirtualMedia cannot boot host localhost:8443."}
TASK [reproducer : Fail if auto-enable is off] *********************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.062) 0:00:11.831 **********
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] *******************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.030) 0:00:11.862 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_eject_vmedia] ****************************************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.019) 0:00:11.882 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_eject_vmedia.yml for instance
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.029) 0:00:11.912 **********
ok: [instance]
TASK [Include bm_eject_vmedia] *************************************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.269) 0:00:12.181 **********
TASK [reproducer : Eject VirtualMedia] *****************************************
Friday 13 March 2026 10:11:00 +0000 (0:00:00.024) 0:00:12.205 **********
ok: [instance]
TASK [reproducer : Wait for VirtualMedia eject to settle] **********************
Friday 13 March 2026 10:11:01 +0000 (0:00:00.274) 0:00:12.480 **********
Pausing for 5 seconds
ok: [instance]
TASK [Query mock state after eject] ********************************************
Friday 13 March 2026 10:11:06 +0000 (0:00:05.026) 0:00:17.506 **********
ok: [instance]
TASK [Assert VirtualMedia is ejected] ******************************************
Friday 13 March 2026 10:11:06 +0000 (0:00:00.277) 0:00:17.784 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia not inserted] *******************************
Friday 13 March 2026 10:11:06 +0000 (0:00:00.028) 0:00:17.813 **********
ok: [instance]
TASK [Include bm_eject_vmedia (idempotent)] ************************************
Friday 13 March 2026 10:11:06 +0000 (0:00:00.282) 0:00:18.095 **********
TASK [reproducer : Eject VirtualMedia] *****************************************
Friday 13 March 2026 10:11:06 +0000 (0:00:00.024) 0:00:18.120 **********
ok: [instance]
TASK [reproducer : Wait for VirtualMedia eject to settle] **********************
Friday 13 March 2026 10:11:07 +0000 (0:00:00.274) 0:00:18.395 **********
Pausing for 5 seconds
ok: [instance]
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:11:12 +0000 (0:00:05.026) 0:00:23.421 **********
ok: [instance]
TASK [Assert VirtualMedia is still not inserted] *******************************
Friday 13 March 2026 10:11:12 +0000 (0:00:00.269) 0:00:23.691 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_discover_vmedia_target] ******************************************
Friday 13 March 2026 10:11:12 +0000 (0:00:00.029) 0:00:23.721 **********
included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/reproducer/molecule/bm_redfish/tasks/test_discover_vmedia.yml for instance
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:11:12 +0000 (0:00:00.034) 0:00:23.755 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target (auto-discover)] ***********************
Friday 13 March 2026 10:11:12 +0000 (0:00:00.282) 0:00:24.037 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:11:12 +0000 (0:00:00.034) 0:00:24.072 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:11:13 +0000 (0:00:00.280) 0:00:24.352 **********
ok: [instance] => (item=Boot0001)
ok: [instance] => (item=Boot0003)
ok: [instance] => (item=Boot0004)
ok: [instance] => (item=Boot0005)
ok: [instance] => (item=Boot0006)
TASK [reproducer : Build list of known UEFI device paths] **********************
Friday 13 March 2026 10:11:14 +0000 (0:00:01.347) 0:00:25.700 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:11:14 +0000 (0:00:00.037) 0:00:25.738 **********
skipping: [instance]
TASK [reproducer : Find Virtual Optical Drive boot path] ***********************
Friday 13 March 2026 10:11:14 +0000 (0:00:00.020) 0:00:25.758 **********
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 [reproducer : Fail if no Virtual Optical Drive found] *********************
Friday 13 March 2026 10:11:14 +0000 (0:00:00.063) 0:00:25.822 **********
skipping: [instance]
TASK [reproducer : Show VirtualMedia UEFI boot target] *************************
Friday 13 March 2026 10:11:14 +0000 (0:00:00.020) 0:00:25.843 **********
ok: [instance] => {
"msg": "VirtualMedia UEFI path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}
TASK [reproducer : Clear pending iDRAC config jobs that block boot override] ***
Friday 13 March 2026 10:11:14 +0000 (0:00:00.019) 0:00:25.863 **********
ok: [instance]
TASK [reproducer : Wait for iDRAC to settle after clearing jobs] ***************
Friday 13 March 2026 10:11:14 +0000 (0:00:00.283) 0:00:26.147 **********
Pausing for 10 seconds
ok: [instance]
TASK [reproducer : Set one-time boot from Virtual Optical Drive] ***************
Friday 13 March 2026 10:11:24 +0000 (0:00:10.030) 0:00:36.177 **********
ok: [instance]
TASK [reproducer : Verify boot override was applied] ***************************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.275) 0:00:36.453 **********
ok: [instance]
TASK [reproducer : Assert boot override is set correctly] **********************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.275) 0:00:36.728 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Verify VirtualMedia is still inserted] **********************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.023) 0:00:36.752 **********
ok: [instance]
TASK [reproducer : Assert VirtualMedia ISO is mounted] *************************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.280) 0:00:37.033 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Assert discovered path is Virtual Optical Drive] *************************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.035) 0:00:37.068 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Query mock state after discover] *****************************************
Friday 13 March 2026 10:11:25 +0000 (0:00:00.035) 0:00:37.104 **********
ok: [instance]
TASK [Assert boot override was set] ********************************************
Friday 13 March 2026 10:11:26 +0000 (0:00:00.273) 0:00:37.377 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:11:26 +0000 (0:00:00.026) 0:00:37.404 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target with valid explicit path] **************
Friday 13 March 2026 10:11:26 +0000 (0:00:00.281) 0:00:37.685 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:11:26 +0000 (0:00:00.031) 0:00:37.717 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:11:26 +0000 (0:00:00.269) 0:00:37.987 **********
ok: [instance] => (item=Boot0001)
ok: [instance] => (item=Boot0003)
ok: [instance] => (item=Boot0004)
ok: [instance] => (item=Boot0005)
ok: [instance] => (item=Boot0006)
TASK [reproducer : Build list of known UEFI device paths] **********************
Friday 13 March 2026 10:11:27 +0000 (0:00:01.308) 0:00:39.295 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:11:28 +0000 (0:00:00.036) 0:00:39.332 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Find Virtual Optical Drive boot path] ***********************
Friday 13 March 2026 10:11:28 +0000 (0:00:00.039) 0:00:39.371 **********
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 [reproducer : Fail if no Virtual Optical Drive found] *********************
Friday 13 March 2026 10:11:28 +0000 (0:00:00.052) 0:00:39.423 **********
skipping: [instance]
TASK [reproducer : Show VirtualMedia UEFI boot target] *************************
Friday 13 March 2026 10:11:28 +0000 (0:00:00.021) 0:00:39.445 **********
ok: [instance] => {
"msg": "VirtualMedia UEFI path: PciRoot(0x0)/Pci(0x14,0x0)/USB(0xD,0x0)/USB(0x0,0x0)/USB(0x2,0x0)/Unit(0x0)"
}
TASK [reproducer : Clear pending iDRAC config jobs that block boot override] ***
Friday 13 March 2026 10:11:28 +0000 (0:00:00.019) 0:00:39.464 **********
ok: [instance]
TASK [reproducer : Wait for iDRAC to settle after clearing jobs] ***************
Friday 13 March 2026 10:11:28 +0000 (0:00:00.283) 0:00:39.748 **********
Pausing for 10 seconds
ok: [instance]
TASK [reproducer : Set one-time boot from Virtual Optical Drive] ***************
Friday 13 March 2026 10:11:38 +0000 (0:00:10.028) 0:00:49.776 **********
ok: [instance]
TASK [reproducer : Verify boot override was applied] ***************************
Friday 13 March 2026 10:11:38 +0000 (0:00:00.289) 0:00:50.066 **********
ok: [instance]
TASK [reproducer : Assert boot override is set correctly] **********************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.270) 0:00:50.337 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Verify VirtualMedia is still inserted] **********************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.024) 0:00:50.361 **********
ok: [instance]
TASK [reproducer : Assert VirtualMedia ISO is mounted] *************************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.276) 0:00:50.637 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.033) 0:00:50.671 **********
ok: [instance]
TASK [Assert boot override set with user-provided path] ************************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.273) 0:00:50.944 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.020) 0:00:50.965 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target with invalid path (expect failure)] ****
Friday 13 March 2026 10:11:39 +0000 (0:00:00.269) 0:00:51.234 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:11:39 +0000 (0:00:00.031) 0:00:51.266 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:11:40 +0000 (0:00:00.271) 0:00:51.537 **********
ok: [instance] => (item=Boot0001)
ok: [instance] => (item=Boot0003)
ok: [instance] => (item=Boot0004)
ok: [instance] => (item=Boot0005)
ok: [instance] => (item=Boot0006)
TASK [reproducer : Build list of known UEFI device paths] **********************
Friday 13 March 2026 10:11:41 +0000 (0:00:01.291) 0:00:52.829 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:11:41 +0000 (0:00:00.036) 0:00:52.865 **********
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] **************************************
Friday 13 March 2026 10:11:41 +0000 (0:00:00.039) 0:00:52.905 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
PLAY RECAP *********************************************************************
instance : ok=110 changed=0 unreachable=0 failed=0 skipped=12 rescued=5 ignored=0
Friday 13 March 2026 10:11:41 +0000 (0:00:00.037) 0:00:52.942 **********
===============================================================================
reproducer : Wait for iDRAC to settle after clearing jobs -------------- 10.03s
reproducer : Wait for iDRAC to settle after clearing jobs -------------- 10.03s
reproducer : Wait for VirtualMedia eject to settle ---------------------- 5.03s
reproducer : Wait for VirtualMedia eject to settle ---------------------- 5.03s
reproducer : Fetch each UEFI boot option detail ------------------------- 1.35s
reproducer : Fetch each UEFI boot option detail ------------------------- 1.31s
reproducer : Fetch each UEFI boot option detail ------------------------- 1.29s
Reset mock to power Off ------------------------------------------------- 0.39s
Reset mock with usb_boot Enabled and host Off --------------------------- 0.29s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.29s
reproducer : Set one-time boot from Virtual Optical Drive --------------- 0.29s
Reset mock with usb_boot Disabled and host Off -------------------------- 0.29s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.29s
Query mock state -------------------------------------------------------- 0.29s
reproducer : Query current power state ---------------------------------- 0.29s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.29s
reproducer : Clear pending iDRAC config jobs that block boot override --- 0.28s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.28s
reproducer : Clear pending iDRAC config jobs that block boot override --- 0.28s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.28s
PLAY [Cleanup mock iDRAC server] ***********************************************
TASK [Stop mock iDRAC server] **************************************************
Friday 13 March 2026 10:11:42 +0000 (0:00:00.021) 0:00:00.021 **********
fatal: [instance]: FAILED! => {"changed": false, "cmd": "pkill -f 'mock_idrac.py.*--port 8443' || true", "delta": "0:00:00.013425", "end": "2026-03-13 10:11:42.319724", "msg": "non-zero return code", "rc": -15, "start": "2026-03-13 10:11:42.306299", "stderr": "", "stderr_lines": [], "stdout": "", "stdout_lines": []}
PLAY RECAP *********************************************************************
instance : ok=0 changed=0 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0
Friday 13 March 2026 10:11:42 +0000 (0:00:00.270) 0:00:00.292 **********
===============================================================================
Stop mock iDRAC server -------------------------------------------------- 0.27s
PLAY [Cleanup mock iDRAC server] ***********************************************
TASK [Stop mock iDRAC server] **************************************************
Friday 13 March 2026 10:11:42 +0000 (0:00:00.022) 0:00:00.022 **********
fatal: [instance]: FAILED! => {"changed": false, "cmd": "pkill -f 'mock_idrac.py.*--port 8443' || true", "delta": "0:00:00.013212", "end": "2026-03-13 10:11:43.038742", "msg": "non-zero return code", "rc": -15, "start": "2026-03-13 10:11:43.025530", "stderr": "", "stderr_lines": [], "stdout": "", "stdout_lines": []}
PLAY RECAP *********************************************************************
instance : ok=0 changed=0 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0
Friday 13 March 2026 10:11:43 +0000 (0:00:00.276) 0:00:00.298 **********
===============================================================================
Stop mock iDRAC server -------------------------------------------------- 0.28s