-
Type: Bug
-
Resolution: Fixed
-
Priority: Major - P3
-
Affects Version/s: None
-
Component/s: Testing Infrastructure
-
Fully Compatible
-
ALL
-
STM 2021-03-08, STM 2021-03-22
-
2
Log lines (particularly when run through resmoke) have been and are getting longer. The new information we want to add is useful (e.g: TIG-1791), but it comes at a cost. This ticket is to start a conversation to explore what can be done to reduce the need to scroll to the right.
The current log lines outputs the following information (as an example, not all log lines are the same):
a fixture, a job number, node identification, a timestamp, log level, loggger name, thread name, log message
[ReplicaSetFixture:job9:primary] 2019-07-02T02:09:03.027+0000 I INDEX [conn98] validated collection config.transactions (UUID: f6c6dde1-fc12-4608-b439-c09508cfee9e)
One workflow I have when debugging a failure in the server (as opposed to an unexpected response):
- First find the failure (requires looking at the contents of the log message).
- Look at which job/node the failure came from
- Add a filter based on job/node identification
- Filter out unrelated spammy loggers (for execution related failures that's often all of replication, and service arch logs)
- Scan the log statements
Information like the job and loggers are necessary for filtering, but not looking at. The fixture name and log level are always irrelevant to me. The thread name, log message and at times, the timestamp (more for temporal locality or cross referencing) are most important. Node identification is sometimes only needed for filtering, and sometimes needed because I need to look at messages from multiple nodes simultaneously. Lobster coloring is often a sufficient for distinguishing between nodes in this case.
Common refrains in execution standups when going over new BFs that have come in (where the BB has already done an excellent job picking out the relevant log lines) are: "Scroll to the right [please]" and "keep going..."
- causes
-
SERVER-81595 resmoke incorrectly reports datetimes using 'Z' suffix even when local timepoint is not from UTC
- Closed
- is duplicated by
-
SERVER-48177 Prefix-less resmoke console logger
- Closed
- is related to
-
SERVER-50085 Make it easier to correlate mongo process names, ports, PIDs in logs of fixtures started by resmoke
- Closed
- related to
-
SERVER-62935 Add timestamp with Failed to Load message in jstests
- Closed