[LU-5691] hsm: Cannot find running request for cookie 0x539b6fc2 on fid=[0x200000bd0:0x5d57:0x0]

• Type: Bug
• Resolution: Fixed
• Priority: Minor
• Fix Version/s: Lustre 2.7.0
• Affects Version/s: Lustre 2.6.0, Lustre 2.5.3
• Labels: patch
• Environment: Centos 6.5
• Severity: 3
• Assignee: James Nunez (Inactive)
• Reporter: Frank Zago (Inactive)

Description

After some error with the copy tool, the MDS will start logging the following in the system logs:

Jun 14 04:28:57 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:1464:mdt_hsm_update_request_state()) tas01-MDT0000: Cannot find running request for cookie 0x539b6fc2 on fid=[0x200000bd0:0x5d57:0x0]
Jun 14 04:28:57 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:1464:mdt_hsm_update_request_state()) Skipped 59 previous similar messages
Jun 14 04:28:57 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:338:mdt_coordinator_cb()) tas01-MDT0000: Cannot cleanup timeouted request: [0x200000bd0:0x5d57:0x0] for cookie 0x539b6fc2 action=CANCEL
Jun 14 04:28:57 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:338:mdt_coordinator_cb()) Skipped 59 previous similar messages
Jun 14 04:38:58 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:1464:mdt_hsm_update_request_state()) tas01-MDT0000: Cannot find running request for cookie 0x539b6fc2 on fid=[0x200000bd0:0x5d57:0x0]
Jun 14 04:38:58 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:1464:mdt_hsm_update_request_state()) Skipped 59 previous similar messages
Jun 14 04:38:58 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:338:mdt_coordinator_cb()) tas01-MDT0000: Cannot cleanup timeouted request: [0x200000bd0:0x5d57:0x0] for cookie 0x539b6fc2 action=CANCEL
Jun 14 04:38:58 tassrv01 kernel: LustreError: 1535:0:(mdt_coordinator.c:338:mdt_coordinator_cb()) Skipped 59 previous similar messages

This will go on forever, and actually log many many messages:

LustreError: 2028:0:(mdt_coordinator.c:1465:mdt_hsm_update_request_state()) tas01-MDT0000: Cannot find running request for cookie 0x54249515 on fid=[0x200000404:0x15caa:0x0]
LustreError: 2028:0:(mdt_coordinator.c:1465:mdt_hsm_update_request_state()) Skipped 15979999 previous similar messages
LustreError: 2028:0:(mdt_coordinator.c:339:mdt_coordinator_cb()) tas01-MDT0000: Cannot cleanup timeouted request: [0x200000404:0x15caa:0x0] for cookie 0x54249515 action=CANCEL
LustreError: 2028:0:(mdt_coordinator.c:339:mdt_coordinator_cb()) Skipped 15979999 previous similar messages

To get these messages, shutdown the copytool while it has some requests being processed, and restart it. That may need several such cycles.