[NIFI-4221] Print app startup in human-readable time

• Type: Improvement
• Status: Resolved
• Priority: Minor
• Resolution: Fixed
• Affects Version/s: 1.3.0
• Component/s: Core Framework
• Labels: logs time
• Assignee: Pierre Villard
• Reporter: Andy LoPresto
• Created: 24/Jul/17 21:40
• Updated: 25/Jul/17 22:43
• Resolved: 25/Jul/17 22:42

Description

Currently the application startup time is printed in nanoseconds in the app log. While this is very precise, because of the scale of these values, it can require mental math to detect delays or variance from the standard/average timing. I think it would be helpful to print a "human-readable" (i.e. broken out into larger units) start time alongside the nanosecond precision time:

2017-07-24 14:00:14,159 INFO [main] org.apache.nifi.NiFi Controller initialization took 15127377569 nanoseconds.

to:

2017-07-24 14:00:14,159 INFO [main] org.apache.nifi.NiFi Controller initialization took 15127377569 nanoseconds (15 seconds).

While currently it's simply a matter of moving the decimal over the right number of places, some deployments on cloud systems (especially those with low entropy before NIFI-3313 was resolved) could take minutes to deploy. Being able to parse these values in meaningful dimensions at a glance is helpful.

Update: This is also the case with NAR unpacking time: 2017-06-05 19:39:50,095 INFO [main] org.apache.nifi.nar.NarUnpacker NAR loading process took 11056718205 nanoseconds.

Issue Links

• links to: GitHub Pull Request #2038