WT-9607 changed the behaviour of the compact progress message to base the message period off of the last message time. This caused the progress message to incorrectly print the time since the last message was printed rather than the total time compact has been working for.
Example
{"t":{"$date":"2024-01-17T08:04:57.419+00:00"},"s":"I", "c":"WTCMPCT", "id":22430, "svc":"-", "ctx":"thread14","msg":"WiredTiger message","attr":{"message":{"ts_sec":1705478697,"ts_usec":419680,"thread":"2999:0xffff89bd8d90","session_dhandle_name":"file:collection-46-13606165324402966069.wt","session_name":"WT_SESSION.compact","category":"WT_VERB_COMPACT_PROGRESS","category_id":10,"verbose_level":"INFO","verbose_level_id":0,"msg":"compacting collection-46-13606165324402966069.wt for 138 seconds; reviewed 1433538 pages, rewritten 192017 pages (204MB), approx. 100% done"}}} {"t":{"$date":"2024-01-17T08:05:47.960+00:00"},"s":"I", "c":"WTCMPCT", "id":22430, "svc":"-", "ctx":"thread14","msg":"WiredTiger message","attr":{"message":{"ts_sec":1705478747,"ts_usec":960069,"thread":"2999:0xffff89bd8d90","session_dhandle_name":"file:collection-46-13606165324402966069.wt","session_name":"WT_SESSION.compact","category":"WT_VERB_COMPACT_PROGRESS","category_id":10,"verbose_level":"INFO","verbose_level_id":0,"msg":"compacting collection-46-13606165324402966069.wt for 50 seconds; reviewed 1498440 pages, rewritten 195931 pages (204MB), approx. 100% done"}}}
- is caused by
-
WT-9607 Fix timing of compact progress messages
- Closed