Warning "Unrecognized command key" in TransferComplete message

Oliver Kraitschy oliver at okraits.de
Mon Aug 6 04:13:41 EDT 2018


On Thu, Aug 02, 2018 at 10:59:11AM -0700, Zaid Abdulla wrote:

> Look for timeout errors in logs. Otherwise check the debug log and make
> sure the command key in the Download and TransferComplete requests
> match.

2018-08-06T07:43:30.407Z [INFO] 127.0.0.1 VR101841: ACS request; acsRequestId="1650e30e5e00000" acsRequestName="Download" acsRequestCommandKey="1650e30e5e00000"
2018-08-06T07:43:30.535Z [INFO] 127.0.0.1 VR101841: ACS request; acsRequestId="1650e30e5e00100" acsRequestName="Download" acsRequestCommandKey="1650e30e5e00100"
2018-08-06T07:43:48.944Z [INFO] 127.0.0.1 VR101841: Inform; cpeRequestId="1" informEvent="2 PERIODIC,7 TRANSFER COMPLETE,M Download,M Download" informRetryCount=0
2018-08-06T07:43:49.064Z [INFO] 127.0.0.1 VR101841: CPE request; cpeRequestId="2" cpeRequestName="TransferComplete" cpeRequestCommandKey="1650e30e5e00000"
2018-08-06T07:43:49.065Z [WARN] 127.0.0.1 VR101841: Unrecognized command key; cpeRequestId="2" cpeRequestName="TransferComplete" cpeRequestCommandKey="1650e30e5e00000"
2018-08-06T07:43:49.181Z [INFO] 127.0.0.1 VR101841: CPE request; cpeRequestId="3" cpeRequestName="TransferComplete" cpeRequestCommandKey="1650e30e5e00100"
2018-08-06T07:43:49.181Z [WARN] 127.0.0.1 VR101841: Unrecognized command key; cpeRequestId="3" cpeRequestName="TransferComplete" cpeRequestCommandKey="1650e30e5e00100"
2018-08-06T07:43:49.296Z [INFO] 127.0.0.1 VR101841: CPE request; cpeRequestId="4" cpeRequestName="GetRPCMethods"
2018-08-06T07:43:49.418Z [INFO] 127.0.0.1 VR101841: ACS request; acsRequestId="1650e312ec70000" acsRequestName="Download" acsRequestCommandKey="1650e312ec70000"
2018-08-06T07:43:49.544Z [INFO] 127.0.0.1 VR101841: ACS request; acsRequestId="1650e312ec70100" acsRequestName="Download" acsRequestCommandKey="1650e312ec70100"

If you look at the log, you see that the command keys in the ACS and CPE
requests match (1650e30e5e00000 and 1650e30e5e00100).

So it's purely a timing issue? The client should wait longer before
sending the TransferComplete requests? How long should it wait?

Or how can this be patched in GenieACS?

Greetings,
Oliver


More information about the Users mailing list