Add message process duration to logs

[Bill Erickson]

In cases where local syslog writes with 1-second granularity, it would be good to log SIP message processing duration with millisecond granularity for improved diagnostics. This is especially useful for diagnosing SIP speed issues with automated materials handling, where small changes in speed can have a big impact.

Wee patch en route.

[Bill Erickson (berick)]

http://git.evergreen-ils.org/?p=working/SIPServer.git;a=shortlog;h=refs/heads/user/berick/lp1425135-sip-message-time-log

[Galen Charlton (gmc)]

Looks OK, but I've got a couple quibbles:

- Does the log level need to be info rather than debug?
- Does the entire request, which may contain personally identifying information, need to be included in the log message? Note that I'm less concerned on this point if the log level is changed to debug.

[Bill Erickson (berick)]

Thanks, Galen. Agreed DEBUG makes more sense, but beware there are other INFO lines logging the full message.

[Bill Erickson (berick)]

Force-pushed change to DEBUG for the new log line. I can push a follow-up patch to change the others if we're agreed on that.

[Galen Charlton (gmc)]

Thanks, Bill. I've pushed the revised patch to master. I suggest opening a new bug for adjusting the existing log messages that include the full request.