PLAY [Prepare mock iDRAC server] ***********************************************
TASK [Create mock server directory] ********************************************
Friday 13 March 2026 10:15:50 +0000 (0:00:00.025) 0:00:00.025 **********
changed: [instance]
TASK [Generate self-signed TLS certificate] ************************************
Friday 13 March 2026 10:15:50 +0000 (0:00:00.348) 0:00:00.374 **********
changed: [instance]
TASK [Copy mock iDRAC server script] *******************************************
Friday 13 March 2026 10:15:50 +0000 (0:00:00.337) 0:00:00.711 **********
changed: [instance]
TASK [Start mock iDRAC server] *************************************************
Friday 13 March 2026 10:15:51 +0000 (0:00:00.514) 0:00:01.226 **********
changed: [instance]
TASK [Record mock server PID] **************************************************
Friday 13 March 2026 10:15:51 +0000 (0:00:00.181) 0:00:01.408 **********
ok: [instance]
TASK [Wait for mock iDRAC to respond] ******************************************
Friday 13 March 2026 10:15:51 +0000 (0:00:00.209) 0:00:01.617 **********
ok: [instance]
PLAY RECAP *********************************************************************
instance : ok=6 changed=4 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0
Friday 13 March 2026 10:15:52 +0000 (0:00:00.462) 0:00:02.080 **********
===============================================================================
Copy mock iDRAC server script ------------------------------------------- 0.51s
Wait for mock iDRAC to respond ------------------------------------------ 0.46s
Create mock server directory -------------------------------------------- 0.35s
Generate self-signed TLS certificate ------------------------------------ 0.34s
Record mock server PID -------------------------------------------------- 0.21s
Start mock iDRAC server ------------------------------------------------- 0.18s
PLAY [Converge -- test bm_* Redfish tasks against mock iDRAC] ******************
TASK [Test bm_power_off] *******************************************************
Friday 13 March 2026 10:15:52 +0000 (0:00:00.054) 0:00:00.054 **********
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:15:52 +0000 (0:00:00.024) 0:00:00.079 **********
ok: [instance]
TASK [Include bm_power_off] ****************************************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.404) 0:00:00.483 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.031) 0:00:00.515 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.324) 0:00:00.839 **********
skipping: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.021) 0:00:00.860 **********
skipping: [instance]
TASK [Query mock state after power_off (already off)] **************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.023) 0:00:00.884 **********
ok: [instance]
TASK [Assert host is still Off] ************************************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.291) 0:00:01.176 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock to power On] **************************************************
Friday 13 March 2026 10:15:53 +0000 (0:00:00.023) 0:00:01.200 **********
ok: [instance]
TASK [Include bm_power_off] ****************************************************
Friday 13 March 2026 10:15:54 +0000 (0:00:00.289) 0:00:01.489 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:15:54 +0000 (0:00:00.028) 0:00:01.517 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:15:54 +0000 (0:00:00.309) 0:00:01.827 **********
ok: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:15:54 +0000 (0:00:00.295) 0:00:02.122 **********
ok: [instance]
TASK [Query mock state after power_off (was on)] *******************************
Friday 13 March 2026 10:15:54 +0000 (0:00:00.299) 0:00:02.422 **********
ok: [instance]
TASK [Assert host is now Off] **************************************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.287) 0:00:02.710 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_power_on] ********************************************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.025) 0:00:02.736 **********
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:15:55 +0000 (0:00:00.024) 0:00:02.760 **********
ok: [instance]
TASK [Include bm_power_on] *****************************************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.313) 0:00:03.073 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.025) 0:00:03.099 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.290) 0:00:03.390 **********
skipping: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:15:55 +0000 (0:00:00.018) 0:00:03.408 **********
ok: [instance]
TASK [Query mock state after power_on (already on)] ****************************
Friday 13 March 2026 10:15:56 +0000 (0:00:00.298) 0:00:03.707 **********
ok: [instance]
TASK [Assert host is still On] *************************************************
Friday 13 March 2026 10:15:56 +0000 (0:00:00.313) 0:00:04.021 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock to power Off] *************************************************
Friday 13 March 2026 10:15:56 +0000 (0:00:00.027) 0:00:04.049 **********
ok: [instance]
TASK [Include bm_power_on] *****************************************************
Friday 13 March 2026 10:15:56 +0000 (0:00:00.309) 0:00:04.358 **********
TASK [reproducer : Query current power state] **********************************
Friday 13 March 2026 10:15:56 +0000 (0:00:00.031) 0:00:04.389 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:15:57 +0000 (0:00:00.291) 0:00:04.681 **********
ok: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:15:57 +0000 (0:00:00.290) 0:00:04.972 **********
ok: [instance]
TASK [Query mock state after power_on (was off)] *******************************
Friday 13 March 2026 10:15:57 +0000 (0:00:00.306) 0:00:05.278 **********
ok: [instance]
TASK [Assert host is now On] ***************************************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.285) 0:00:05.564 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_check_usb_boot] **************************************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.022) 0:00:05.586 **********
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:15:58 +0000 (0:00:00.025) 0:00:05.611 **********
ok: [instance]
TASK [Include bm_check_usb_boot] ***********************************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.289) 0:00:05.901 **********
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.028) 0:00:05.929 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.314) 0:00:06.243 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.038) 0:00:06.282 **********
skipping: [instance]
TASK [Assert _usb_boot_enabled is true] ****************************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.031) 0:00:06.314 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled] ***************************************
Friday 13 March 2026 10:15:58 +0000 (0:00:00.029) 0:00:06.343 **********
ok: [instance]
TASK [Include bm_check_usb_boot (expect failure)] ******************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.319) 0:00:06.662 **********
TASK [reproducer : Read GenericUsbBoot BIOS attribute] *************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.029) 0:00:06.692 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.294) 0:00:06.986 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.031) 0:00:07.018 **********
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:15:59 +0000 (0:00:00.037) 0:00:07.055 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_ensure_usb_boot] *************************************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.024) 0:00:07.079 **********
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:15:59 +0000 (0:00:00.031) 0:00:07.111 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot] **********************************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.289) 0:00:07.401 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:15:59 +0000 (0:00:00.033) 0:00:07.434 **********
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:16:00 +0000 (0:00:00.023) 0:00:07.457 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.284) 0:00:07.741 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.030) 0:00:07.772 **********
skipping: [instance]
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.035) 0:00:07.807 **********
ok: [instance]
TASK [Assert host stayed Off (no power cycle needed)] **************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.298) 0:00:08.106 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled and host Off] **************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.022) 0:00:08.128 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot with auto-enable] *****************************
Friday 13 March 2026 10:16:00 +0000 (0:00:00.290) 0:00:08.418 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:16:01 +0000 (0:00:00.029) 0:00:08.448 **********
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:16:01 +0000 (0:00:00.023) 0:00:08.472 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:16:01 +0000 (0:00:00.281) 0:00:08.753 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:16:01 +0000 (0:00:00.032) 0:00:08.786 **********
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:16:01 +0000 (0:00:00.037) 0:00:08.824 **********
skipping: [instance]
TASK [reproducer : Set GenericUsbBoot BIOS attribute] **************************
Friday 13 March 2026 10:16:01 +0000 (0:00:00.034) 0:00:08.858 **********
ok: [instance]
TASK [reproducer : Create BIOS config job to schedule the change] **************
Friday 13 March 2026 10:16:01 +0000 (0:00:00.308) 0:00:09.167 **********
ok: [instance]
TASK [reproducer : Power off before applying BIOS change] **********************
Friday 13 March 2026 10:16:02 +0000 (0:00:00.289) 0:00:09.456 **********
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:16:02 +0000 (0:00:00.025) 0:00:09.482 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:16:02 +0000 (0:00:00.308) 0:00:09.791 **********
skipping: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:16:02 +0000 (0:00:00.023) 0:00:09.814 **********
skipping: [instance]
TASK [reproducer : Power on to apply BIOS config job during POST] **************
Friday 13 March 2026 10:16:02 +0000 (0:00:00.018) 0:00:09.832 **********
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:16:02 +0000 (0:00:00.026) 0:00:09.859 **********
ok: [instance]
TASK [reproducer : Power on bare metal host] ***********************************
Friday 13 March 2026 10:16:02 +0000 (0:00:00.314) 0:00:10.174 **********
ok: [instance]
TASK [reproducer : Wait for host POST to complete] *****************************
Friday 13 March 2026 10:16:03 +0000 (0:00:00.303) 0:00:10.477 **********
ok: [instance]
TASK [reproducer : Power off after BIOS change applied] ************************
Friday 13 March 2026 10:16:03 +0000 (0:00:00.309) 0:00:10.787 **********
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:16:03 +0000 (0:00:00.029) 0:00:10.816 **********
ok: [instance]
TASK [reproducer : Force power off via Redfish] ********************************
Friday 13 March 2026 10:16:03 +0000 (0:00:00.296) 0:00:11.112 **********
ok: [instance]
TASK [reproducer : Wait for host to power off] *********************************
Friday 13 March 2026 10:16:03 +0000 (0:00:00.287) 0:00:11.400 **********
ok: [instance]
TASK [Query mock state after auto-enable] **************************************
Friday 13 March 2026 10:16:04 +0000 (0:00:00.309) 0:00:11.710 **********
ok: [instance]
TASK [Assert BIOS updated and host left Off] ***********************************
Friday 13 March 2026 10:16:04 +0000 (0:00:00.289) 0:00:12.000 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with usb_boot Disabled] ***************************************
Friday 13 March 2026 10:16:04 +0000 (0:00:00.027) 0:00:12.028 **********
ok: [instance]
TASK [Include bm_ensure_usb_boot without auto-enable (expect failure)] *********
Friday 13 March 2026 10:16:04 +0000 (0:00:00.294) 0:00:12.322 **********
TASK [reproducer : Check current GenericUsbBoot state] *************************
Friday 13 March 2026 10:16:04 +0000 (0:00:00.033) 0:00:12.356 **********
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:16:04 +0000 (0:00:00.022) 0:00:12.378 **********
ok: [instance]
TASK [reproducer : Set GenericUsbBoot status fact] *****************************
Friday 13 March 2026 10:16:05 +0000 (0:00:00.307) 0:00:12.685 **********
ok: [instance]
TASK [reproducer : Fail if GenericUsbBoot is disabled] *************************
Friday 13 March 2026 10:16:05 +0000 (0:00:00.039) 0:00:12.725 **********
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:16:05 +0000 (0:00:00.040) 0:00:12.766 **********
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:16:05 +0000 (0:00:00.039) 0:00:12.806 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_eject_vmedia] ****************************************************
Friday 13 March 2026 10:16:05 +0000 (0:00:00.022) 0:00:12.829 **********
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:16:05 +0000 (0:00:00.030) 0:00:12.859 **********
ok: [instance]
TASK [Include bm_eject_vmedia] *************************************************
Friday 13 March 2026 10:16:05 +0000 (0:00:00.304) 0:00:13.163 **********
TASK [reproducer : Eject VirtualMedia] *****************************************
Friday 13 March 2026 10:16:05 +0000 (0:00:00.027) 0:00:13.191 **********
ok: [instance]
TASK [reproducer : Wait for VirtualMedia eject to settle] **********************
Friday 13 March 2026 10:16:06 +0000 (0:00:00.299) 0:00:13.491 **********
Pausing for 5 seconds
ok: [instance]
TASK [Query mock state after eject] ********************************************
Friday 13 March 2026 10:16:11 +0000 (0:00:05.029) 0:00:18.521 **********
ok: [instance]
TASK [Assert VirtualMedia is ejected] ******************************************
Friday 13 March 2026 10:16:11 +0000 (0:00:00.298) 0:00:18.819 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia not inserted] *******************************
Friday 13 March 2026 10:16:11 +0000 (0:00:00.029) 0:00:18.848 **********
ok: [instance]
TASK [Include bm_eject_vmedia (idempotent)] ************************************
Friday 13 March 2026 10:16:11 +0000 (0:00:00.280) 0:00:19.129 **********
TASK [reproducer : Eject VirtualMedia] *****************************************
Friday 13 March 2026 10:16:11 +0000 (0:00:00.024) 0:00:19.153 **********
ok: [instance]
TASK [reproducer : Wait for VirtualMedia eject to settle] **********************
Friday 13 March 2026 10:16:11 +0000 (0:00:00.283) 0:00:19.437 **********
Pausing for 5 seconds
ok: [instance]
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:16:17 +0000 (0:00:05.031) 0:00:24.469 **********
ok: [instance]
TASK [Assert VirtualMedia is still not inserted] *******************************
Friday 13 March 2026 10:16:17 +0000 (0:00:00.287) 0:00:24.756 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Test bm_discover_vmedia_target] ******************************************
Friday 13 March 2026 10:16:17 +0000 (0:00:00.029) 0:00:24.786 **********
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:16:17 +0000 (0:00:00.035) 0:00:24.821 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target (auto-discover)] ***********************
Friday 13 March 2026 10:16:17 +0000 (0:00:00.271) 0:00:25.092 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:16:17 +0000 (0:00:00.036) 0:00:25.129 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:16:17 +0000 (0:00:00.279) 0:00:25.408 **********
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:16:19 +0000 (0:00:01.390) 0:00:26.798 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:16:19 +0000 (0:00:00.038) 0:00:26.836 **********
skipping: [instance]
TASK [reproducer : Find Virtual Optical Drive boot path] ***********************
Friday 13 March 2026 10:16:19 +0000 (0:00:00.023) 0:00:26.860 **********
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:16:19 +0000 (0:00:00.065) 0:00:26.926 **********
skipping: [instance]
TASK [reproducer : Show VirtualMedia UEFI boot target] *************************
Friday 13 March 2026 10:16:19 +0000 (0:00:00.023) 0:00:26.950 **********
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:16:19 +0000 (0:00:00.020) 0:00:26.970 **********
ok: [instance]
TASK [reproducer : Wait for iDRAC to settle after clearing jobs] ***************
Friday 13 March 2026 10:16:19 +0000 (0:00:00.294) 0:00:27.265 **********
Pausing for 10 seconds
ok: [instance]
TASK [reproducer : Set one-time boot from Virtual Optical Drive] ***************
Friday 13 March 2026 10:16:29 +0000 (0:00:10.025) 0:00:37.290 **********
ok: [instance]
TASK [reproducer : Verify boot override was applied] ***************************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.309) 0:00:37.600 **********
ok: [instance]
TASK [reproducer : Assert boot override is set correctly] **********************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.289) 0:00:37.889 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Verify VirtualMedia is still inserted] **********************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.030) 0:00:37.920 **********
ok: [instance]
TASK [reproducer : Assert VirtualMedia ISO is mounted] *************************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.295) 0:00:38.215 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Assert discovered path is Virtual Optical Drive] *************************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.046) 0:00:38.262 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Query mock state after discover] *****************************************
Friday 13 March 2026 10:16:30 +0000 (0:00:00.047) 0:00:38.309 **********
ok: [instance]
TASK [Assert boot override was set] ********************************************
Friday 13 March 2026 10:16:31 +0000 (0:00:00.316) 0:00:38.626 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:16:31 +0000 (0:00:00.029) 0:00:38.655 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target with valid explicit path] **************
Friday 13 March 2026 10:16:31 +0000 (0:00:00.284) 0:00:38.940 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:16:31 +0000 (0:00:00.037) 0:00:38.977 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:16:31 +0000 (0:00:00.297) 0:00:39.274 **********
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:16:33 +0000 (0:00:01.379) 0:00:40.654 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:16:33 +0000 (0:00:00.039) 0:00:40.694 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Find Virtual Optical Drive boot path] ***********************
Friday 13 March 2026 10:16:33 +0000 (0:00:00.041) 0:00:40.735 **********
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:16:33 +0000 (0:00:00.054) 0:00:40.789 **********
skipping: [instance]
TASK [reproducer : Show VirtualMedia UEFI boot target] *************************
Friday 13 March 2026 10:16:33 +0000 (0:00:00.022) 0:00:40.812 **********
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:16:33 +0000 (0:00:00.020) 0:00:40.832 **********
ok: [instance]
TASK [reproducer : Wait for iDRAC to settle after clearing jobs] ***************
Friday 13 March 2026 10:16:33 +0000 (0:00:00.299) 0:00:41.132 **********
Pausing for 10 seconds
ok: [instance]
TASK [reproducer : Set one-time boot from Virtual Optical Drive] ***************
Friday 13 March 2026 10:16:43 +0000 (0:00:10.034) 0:00:51.166 **********
ok: [instance]
TASK [reproducer : Verify boot override was applied] ***************************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.280) 0:00:51.446 **********
ok: [instance]
TASK [reproducer : Assert boot override is set correctly] **********************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.276) 0:00:51.723 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [reproducer : Verify VirtualMedia is still inserted] **********************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.029) 0:00:51.753 **********
ok: [instance]
TASK [reproducer : Assert VirtualMedia ISO is mounted] *************************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.275) 0:00:52.029 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Query mock state] ********************************************************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.041) 0:00:52.070 **********
ok: [instance]
TASK [Assert boot override set with user-provided path] ************************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.275) 0:00:52.346 **********
ok: [instance] => {
"changed": false,
"msg": "All assertions passed"
}
TASK [Reset mock with VirtualMedia inserted] ***********************************
Friday 13 March 2026 10:16:44 +0000 (0:00:00.026) 0:00:52.373 **********
ok: [instance]
TASK [Include bm_discover_vmedia_target with invalid path (expect failure)] ****
Friday 13 March 2026 10:16:45 +0000 (0:00:00.275) 0:00:52.648 **********
TASK [reproducer : Fetch UEFI boot option IDs] *********************************
Friday 13 March 2026 10:16:45 +0000 (0:00:00.036) 0:00:52.684 **********
ok: [instance]
TASK [reproducer : Fetch each UEFI boot option detail] *************************
Friday 13 March 2026 10:16:45 +0000 (0:00:00.313) 0:00:52.998 **********
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:16:46 +0000 (0:00:01.347) 0:00:54.346 **********
ok: [instance]
TASK [reproducer : Validate user-provided VirtualMedia UEFI path] **************
Friday 13 March 2026 10:16:46 +0000 (0:00:00.046) 0:00:54.392 **********
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:16:46 +0000 (0:00:00.045) 0:00:54.438 **********
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:16:47 +0000 (0:00:00.045) 0:00:54.484 **********
===============================================================================
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.39s
reproducer : Fetch each UEFI boot option detail ------------------------- 1.38s
reproducer : Fetch each UEFI boot option detail ------------------------- 1.35s
Reset mock to power Off ------------------------------------------------- 0.40s
reproducer : Query current power state ---------------------------------- 0.32s
Reset mock with usb_boot Disabled --------------------------------------- 0.32s
Query mock state after discover ----------------------------------------- 0.32s
reproducer : Query current power state ---------------------------------- 0.31s
reproducer : Read GenericUsbBoot BIOS attribute ------------------------- 0.31s
Query mock state after power_on (already on) ---------------------------- 0.31s
reproducer : Fetch UEFI boot option IDs --------------------------------- 0.31s
Reset mock to power On -------------------------------------------------- 0.31s
reproducer : Set one-time boot from Virtual Optical Drive --------------- 0.31s
reproducer : Wait for host POST to complete ----------------------------- 0.31s
Reset mock to power Off ------------------------------------------------- 0.31s
reproducer : Wait for host to power off --------------------------------- 0.31s
PLAY [Cleanup mock iDRAC server] ***********************************************
TASK [Stop mock iDRAC server] **************************************************
Friday 13 March 2026 10:16:47 +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.015413", "end": "2026-03-13 10:16:47.799255", "msg": "non-zero return code", "rc": -15, "start": "2026-03-13 10:16:47.783842", "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:16:47 +0000 (0:00:00.286) 0:00:00.308 **********
===============================================================================
Stop mock iDRAC server -------------------------------------------------- 0.29s
PLAY [Cleanup mock iDRAC server] ***********************************************
TASK [Stop mock iDRAC server] **************************************************
Friday 13 March 2026 10:16:48 +0000 (0:00:00.029) 0:00:00.029 **********
fatal: [instance]: FAILED! => {"changed": false, "cmd": "pkill -f 'mock_idrac.py.*--port 8443' || true", "delta": "0:00:00.014946", "end": "2026-03-13 10:16:48.542674", "msg": "non-zero return code", "rc": -15, "start": "2026-03-13 10:16:48.527728", "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:16:48 +0000 (0:00:00.277) 0:00:00.306 **********
===============================================================================
Stop mock iDRAC server -------------------------------------------------- 0.28s