Re:NanoLog – a nanosecond scale logging system for C++
by Matthias247 on Sunday September 01, 2019 @04:16PM
(#20854587)
> That's why you are supposed to do your formatting in the background thread :).
Which then has the drawback that every argument that gets transferred to the background thread must first be heap allocated or static - instead of only the final buffer being that. My gut feeling is that the additional allocation and memory management costs typically outweigh the cost of formatting in the current thread. Even if one pools all logging buffers less buffers are needed for transferring only formatted buffers.
Re:NanoLog – a nanosecond scale logging system for C++
by gpderetta on Sunday September 01, 2019 @06:08PM
(#20855316)
Normally you would copy the arguments to be formatted. This is usually less bytes than the formatted output.
Re:NanoLog – a nanosecond scale logging system for C++
by paulddraper on Sunday September 01, 2019 @06:51PM
(#20855541)
Or you'd copy them.
Unless you do something dumb (like copy a vector when you only need to print the size), copying is significantly faster than string formatting.
Re:NanoLog – a nanosecond scale logging system for C++
by dclusin on Sunday September 01, 2019 @07:05PM
(#20855588)
I think what OP is hinting at here is cache invalidation/eviction caused by the additional thread's processing and memory operations. If your requirements are down to nanosecond granularities then cache misses are probably being measured and noticeable. A third party logging thread doing memory copies and other log processing sounds like a fine way to unintentionally evict a bunch of cache entries. You might be able to mitigate this to some extent with CPU affinities for threads I suppose. Another option would be to move logging to the network and record packets in/out with an out of band monitoring solution.
Re:NanoLog – a nanosecond scale logging system for C++
by Rapzid on Sunday September 01, 2019 @07:48PM
(#20855723)
Logging the raw structured log entry and off-loading any string formatting to another system is a very interesting idea. Just push the whole "friendly message" stuff off to other logging infrastructure where the latencies matter less.
Re:NanoLog – a nanosecond scale logging system for C++
by paulddraper on Sunday September 01, 2019 @07:11PM
(#20855603)
Well yes, using another thread is a only good idea if you have an extra core available.
L1 cache is per-core anyway, and I doubt L2 is going to hurt much.
Re:NanoLog – a nanosecond scale logging system for C++
by jnordwick on Sunday September 01, 2019 @07:55PM
(#20855752)
At a few places I've worked, logging and util stuff had it's own core to prevent l1/l2 cache pollution (either using threads or shared memory just as long as you got it out of the hot path).
Re:NanoLog – a nanosecond scale logging system for C++
by Matthias247 on Sunday September 01, 2019 @10:41PM
(#20856402)
Yes, but you need to have a location where to copy them to. They can’t be on the stack, since the logging task runs asynchronously. So each captured argument must be in some form heap allocated. Eg passing 2 strings as arguments and one integer might require 3 additional heap allocation - where the malloc/free overhead might outweigh the string formatting costs. You can try to optimize here with specialized allocators (eg Arenas) or trying to generate dedicated structures for each logging callsite where all arguments can be carried inside a single heap allocated struct (instead of 3 here). But that might lead to other disadvantages - eg code bloat.
Re:NanoLog – a nanosecond scale logging system for C++
by gpderetta on Monday September 02, 2019 @03:48AM
(#20857441)
That would be a terrible implementation strategy. You would simply copy each argument into the communication ring buffer. You can also use specialised strategies for some types, for example string contents can be transmitted inline instead of copying the strings themselves.
Re:NanoLog – a nanosecond scale logging system for C++
by RafaGC on Monday September 02, 2019 @01:48AM
(#20857026)
On my logger the thread queue was(is) node based, so it was just a matter of making a bigger contiguous allocation and placing things there contiguously, the log entry and the data copies. 1 allocation.
Re:NanoLog – a nanosecond scale logging system for C++
by paulddraper on Sunday September 01, 2019 @11:43PM
(#20856629)
Good point
Re:NanoLog – a nanosecond scale logging system for C++
by cma on Sunday September 01, 2019 @06:30PM
(#20855443)
Shared memory to a background process is better, in that it can survive most classes of process crash.
Re:NanoLog – a nanosecond scale logging system for C++
by gpderetta on Sunday September 01, 2019 @06:45PM
(#20855508)
Yes it is significantly more robust, the downside is that you have to copy everything, including static vars and format strings. An option is to fork early on process startup and possibly share some memory.
We simply catch all signals and do a best effort attempt to flush the queue before aborting.
That's why you are supposed to do your formatting in the background thread :).
> doesn't matter how many millions of logs you can generate per second
Usually the issue is not generating millions of line of logs (in whitch case dispatching to a background thread just adds overhead), but being able to log with minimal overhead on the surrounding code.