Nanolog起步笔记-9-log解压过程-3-寻找meta续
- 当前的目标
- 新的改变
- decompressNextLogStatement
- metadata
- 查看业务面的log语句
- 注释掉 runBenchmark();
- 改过之后,2条记录之后,这里就直接返回了
- 小结
当前的目标
没有办法,还要继续。
当前的目标,是找到解压时, meta 的信息。
注意,先备份一下/tmp/logFile到其它的位置。
因为clean,clean-all时会删除这个文件。
除非sample下和runtime下的两个GNUmakefile都改过。
如果没有备份,可能对分析有点影响。
新的改变
1。 launch.json
改为:decompressUnordered
{"name": "C++ Launch decompressor","type": "cppdbg","request": "launch","program": "${workspaceFolder}/sample/decompressor","args": ["decompressUnordered","/tmp/logFile"],"environment": [{ "name": "config", "value": "Debug" }],"cwd": "${workspaceFolder}/sample","setupCommands": [{"description": "Enable pretty-printing for gdb","text": "-enable-pretty-printing","ignoreFailures": true}]}
因为这样能路过许多不需要关注的代码。
我们目前也不需要sort。
decompressNextLogStatement
bool
Log::Decoder::BufferFragment::decompressNextLogStatement(FILE *outputFd,uint64_t &logMsgsProcessed,LogMessage &logArgs,const Checkpoint &checkpoint,std::vector<void*>& fmtId2metadata,long aggregationFilterId,void (*aggregationFn)(const char*, ...))
{// Output the contextif (outputFd) {fprintf(outputFd,"%s.%09.0lf %s:%u %s[%u]: ", timeString, nanos, filename, metadata->lineNumber, logLevel, runtimeId);fflush(outputFd); //haoyujie}
如下图:
说明:outputFd=stdout
在这里也卡了一小会,因为terminal中,没有输出。我想了半天,
问了下电脑,才发现自己大脑快down机,只是没有flush…
好吧,我加上了一句。为了调试。
这样我们第一次看到一个log的输出所在的位置。
之后我们可以向前研究,这个结果是如何得到的。
2024-12-10 16:23:46.000853307 main.cc:59 NOTICE[0]:
我们看到,这些信息,都能对应上,
而且只打印了前半部分。
这是后面我们要分析的,
timeString , nanos, filename , metadata->lineNumber , logLevel , runtimeId
这些record头部的信息,值从何得来。
目前我们看到几个问题:
1。 信息只有前半部分,时间
2。文件名中的路径被删除了。只留下main.c。这是前述“压缩”的重要组成部分之一。
3。 runtimeId,这个可能就是线程号。但是要注意的是nanolog的线程id,并不是线程ID。是nanolog自己维护的一个entry id。每开一个线程,就多一个。
metadata
然后我们看到了metadata
// Print out the actual log message, piece by piecePrintFragment *pf = reinterpret_cast<PrintFragment*>(reinterpret_cast<char*>(metadata)+ sizeof(FormatMetadata)+ metadata->filenameLength);
其实前面,我们已经看到过:
也就是意味着,我们在这里分析时,meta信息,已加载了。
没有关系,一会我们再分析一遍。
metadata->filenameLength=8
正好是main.cc加一个’\0’。
查看业务面的log语句
NANO_LOG(NOTICE, "A string, pointer, number, and float: '%s', %p, %d, %f",randomString,&randomString,512,3.14159);
可以看到,共4个参数。
其中3个数字,1个字符串。
后面的代码:
metadata->numPrintFragments=4 //argc: 4 parameters
然后,在printSingleArg
执行这之后:
2024-12-10 16:23:46.000853307 main.cc:59 NOTICE[0]: A string, pointer, number, and float: 'Hello World
即已打印出第一个参数。
向前步进:
第二个参数%p
以此类推,直到最后一个参数完成。
团成员到主循环,准备解压下一条
## 然后,发现这里前面我的一个误解
2024-12-10 16:23:46.000856704 main.cc:88 NOTICE[0]: Simple log message with 0 parameters
是真的有这个数据。
否则不会指出是哪个文件的哪一行,这里是我疏忽了。
也给了我们进一步简化这个程序的可能性。
注释掉 runBenchmark();
注掉main()中的runBenchmark();,之后,程序的运行轨迹简化了许多。
不过,要注意的是,logFile需要删除重做。如果不删除,文件会被重新打开,从头开始重写。旧的内容还在。
改过之后,2条记录之后,这里就直接返回了
小结
基本准备好一套,可以进一步分析代码。