前提:
debug需要,在一段被频繁调用的代码块中加入了大量的日志输出,同时 也问题复现也对系统有一定的性能要求,但是加上日志后发现该部分性能显著下降,于是开始尝试解决该问题
首先换了tmfs文件的日志输出,发现性能仍然辣鸡
然后perf 查看进程的开销,发现vfprintf 开销明显异常,一开始以为是直接在主线程write导致(有部分原因但不是主因)
后来发现sprintf也会调用该函数,之后又怀疑是sprintf太多导致了主线程速度变慢 恰好在调试write的时候也把日志fmt输出的vsprintf关闭过,但是性能仍然无明显提升。
此时重新打开了perf 发现native_queued_spin_lock 开销也是明显异常,终于怀疑是锁导致的问题,后来发现前人在写日志系统时为了线程安全调用了localtime_r 的函数获取时间, 直接导致多线程狂打日志时的争锁开销无限放大
后续解决办法:
使用thread_local实现了一个自己的线程安全的localtime_r 函数,然后使用tmfs日志作为输出对象,发现性能明显好转,问题解决



