从Mars的Xlog组件学习日志系统

看完了微信团队对Xlog的整体介绍,迫不及待开始了研究,理论部分我是完全参考微信终端跨平台组件 mars 系列(一) - 高性能日志模块xlog 这篇文章。
看完这篇理论,文章通过各个点来阐述如何创造这样的高性能、可扩展的日志系统的想法和设计,于是我结合源码配合一起梳理一下Xlog的几个部分,无论以后自己造轮子作为借鉴或者使用这个轮子,都有很多价值。

方案:
使用流式压缩方式对单行日志进行压缩,压缩加密后写进作为 log 中间 buffer的 mmap 中,当 mmap 中的数据到达一定大小后再写进磁盘文件中

从github上的官方文档来看,Xlog的初始化涉及三个语句:

  1. xlogger_SetLevel(kLevelDebug);
  2. appender_set_console_log(true);
  3. appender_open(kAppednerAsync, [logPath UTF8String], "Test");

头两句第一个是设置log级别,第二句是控制台是否打印语句的开关。
最重要的是第三句话 appender_open
Appender模块是是整个日志系统负责写日志的模块。这里直接从顶部文章搬运一下结构图片。这里appender_open是打开log目录下的日志文件,进行一些初始化操作。

E6D06D6B-F811-404D-8B98-634FA8B06C19.png
void appender_open(TAppenderMode _mode, const char* _dir, const char* _nameprefix) {
    xlogger_SetAppender(&xlogger_appender);  //设置xlogger的appender
    
    //创建路径文件夹
    boost::filesystem::create_directories(_dir);
    //tickcount_t用于计算每个步骤执行时间。
    tickcount_t tick;
    tick.gettickcount();
    //第一步作用是每次启动的时候会清理日志,防止占用太多用户磁盘空间
    __del_timeout_file(_dir);
    tickcountdiff_t del_timeout_file_time = tickcount_t().gettickcount() - tick;
    tick.gettickcount();
   //设置mmap文件的路径
    char mmap_file_path[512] = {0};
    snprintf(mmap_file_path, sizeof(mmap_file_path), "%s/%s.mmap2", sg_cache_logdir.empty()?_dir:sg_cache_logdir.c_str(), _nameprefix);
    //检查是否mmap文件中有数据,如果没有直接退出,如果有,清除日志缓存,同时构造LogBuffer对象。
    bool use_mmap = false;
    if (OpenMmapFile(mmap_file_path, kBufferBlockLength, sg_mmmap_file))  {
        sg_log_buff = new LogBuffer(sg_mmmap_file.data(), kBufferBlockLength, true);
        use_mmap = true;
    } else {
        char* buffer = new char[kBufferBlockLength];
        sg_log_buff = new LogBuffer(buffer, kBufferBlockLength, true);
        use_mmap = false;
    }

    if (NULL == sg_log_buff->GetData().Ptr()) {
        if (use_mmap && sg_mmmap_file.is_open())  CloseMmapFile(sg_mmmap_file);
        return;
    }
    AutoBuffer buffer;
    sg_log_buff->Flush(buffer);
    ...
    BOOT_RUN_EXIT(appender_close);
}

日志目录下的log文件为每天的日志文件,而mmap是日志缓存文件,因此会有如下策略:
这部分可以概括为:
**1. ** 首先日志系统有一个过期的设置,日志log文件过期就自动删除
**2. ** 每次启动清空 mmap 的日志缓存
**3. ** 设置日志系统的Appender模块,应该可以通过自己重写appender函数,实现自己的一套方案,也即是顶部文章所说的热插拔。

题外话:

  1. 刚开始使用时发现main.mm中 setxattr(...);这个方法,以为是 mars 提供的初始化方法,后来才知道这个方法是系统库提供,为了防止该路径下的日志文件被 iCloud 同步。可以参阅官方文档: https://developer.apple.com/library/content/qa/qa1719/_index.html

关于mmap

上文中关于 appender_open 方法中有这么一句 OpenMmapFile(mmap_file_path, kBufferBlockLength, sg_mmmap_file),这个方法的实现就是使用了mmap方法

mmap的介绍
mmap是一种内存映射文件的方法,将一个文件或者其它对象映射到进程的地址空间,实现文件磁盘地址和进程虚拟地址空间中的映射关系。维持关系之后,进程就可以采用指针的方式读写操作这一段内存,而系统会自动回写脏页面到对应的文件磁盘上,即完成了对文件的操作而不必再调用read,write等系统调用函数。但内核空间对这段区域的修改也直接反映用户空间,从而可以实现不同进程间的文件共享。

A2179609-D37C-45D7-A181-1C3E225A69BB.png

文件的一般读写操作为了提高读写效率和保护磁盘,使用了页缓存机制。这样造成读文件时需要先将文件页从磁盘拷贝到页缓存中,由于页缓存处在内核空间,不能被用户进程直接寻址,所以还需要将页缓存中数据页再次拷贝到内存对应的用户空间中。这样,通过了两次数据拷贝过程,才能完成进程对文件内容的获取任务。写操作也是一样,待写入的buffer在内核空间不能直接访问,必须要先拷贝至内核空间对应的主存,再写回磁盘中(延迟写回),也是需要两次数据拷贝。

总而言之,常规文件操作需要从磁盘到页缓存再到用户主存的两次数据拷贝。而mmap操控文件,只需要从磁盘到用户主存的一次数据拷贝过程。说白了,mmap的关键点是实现了用户空间和内核空间的数据直接交互而省去了空间不同数据不通的繁琐过程。因此mmap效率更高。

而之后我们可以通过使用mmap返回的指针 bufferPtr ,然后使用指针直接写入数据。

Log接口

首先来看一下 Mars.framework 提供的log接口。

xlogger_IsEnabledFor(_level)    
xlogger_AssertP(...)            
xlogger_Assert(...)         
xlogger_VPrint(...)         
xlogger_Print(...)              
xlogger_Write(...)              

如果你已经使用过 Xlogger
从iOS的案例项目中可以看到LOG_INFO,这本身就是平台适配层对Mars框架的log接口进行的一层封装,其实是调用了xlogger_Write方法,看一下具体实现。

void __xlogger_Write_impl(const XLoggerInfo* _info, const char* _log) {
    
    if (!gs_appender) return;
    ...
    if (NULL == _log) {
        if (_info) {
            XLoggerInfo* info = (XLoggerInfo*)_info;
            info->level = kLevelFatal;
        }
        gs_appender(_info, "NULL == _log");
    } else {
        gs_appender(_info, _log);
    }
}

而gs_appender就是上面所说appender函数,appender就是负责单行日志的写入。

static void __appender_async(const XLoggerInfo* _info, const char* _log) {
    ScopedLock lock(sg_mutex_buffer_async);
    if (NULL == sg_log_buff) return;

    char temp[16*1024] = {0};       //tell perry,ray if you want modify size.
    PtrBuffer log_buff(temp, 0, sizeof(temp));
    log_formater(_info, _log, log_buff);

    if (!sg_log_buff->Write(log_buff.Ptr(), (unsigned int)log_buff.Length())) return;

    if (sg_log_buff->GetData().Length() >= kBufferBlockLength*1/3 || (NULL!=_info && kLevelFatal == _info->level)) {
       sg_cond_buffer_async.notifyAll();
    }

}

异步的appender方法可以归纳为:

  1. 对日志进行formater
  2. 写入日志
  3. 发送通知。

而同步的Write的方法没有最后发送通知的部分,在写入的日志的部分也稍有不同。
然后看一下LogBuffer::Write方法,根据同步模式和异步模式,Write 方法也分为了同步和异步,这里进一步查看一下异步模式的 Write 方法:

bool LogBuffer::Write(const void* _data, size_t _length) {
    size_t before_len = buff_.Length();
    size_t write_len = _length;
    //1. 先进行流式压缩
    if (is_compress_) {
        cstream_.avail_in = (uInt)_length;
        cstream_.next_in = (Bytef*)_data;

        uInt avail_out = (uInt)(buff_.MaxLength() - buff_.Length());
        cstream_.next_out = (Bytef*)buff_.PosPtr();
        cstream_.avail_out = avail_out;

        if (Z_OK != deflate(&cstream_, Z_SYNC_FLUSH)) {
            return false;
        }

        write_len = avail_out - cstream_.avail_out;
    } else {
        buff_.Write(_data, _length);
    }
    

    char crypt_buffer[4096] = {0};
    size_t crypt_buffer_len = sizeof(crypt_buffer);
    
    // 2. 在进行加密
    s_log_crypt->CryptAsyncLog((char*)buff_.Ptr() + before_len, write_len, crypt_buffer, crypt_buffer_len);
    // 3. 把压缩加密的日志写入mmap中
    buff_.Write(crypt_buffer, crypt_buffer_len, before_len);
    buff_.Length(before_len + crypt_buffer_len, before_len + crypt_buffer_len);
   
    s_log_crypt->UpdateLogLen((char*)buff_.Ptr(), (uint32_t)crypt_buffer_len);

    return true;
}

可以看到步骤这个异步LogBuffer中Write方法几乎是重中之重,涉及到了数据流的压缩、加密和写入。

关于流式压缩

看到了 Write 方法中出现这里的压缩部分:

 if (Z_OK != deflate(&cstream_, Z_SYNC_FLUSH)) {
            return false;
  }

这里deflate是压缩数据流的算法. 任何需要流式压缩的地方都可以用。它是由Huffman 编码 和 LZ77压缩 两个算法组成。

  1. **Huffman **编码:huffman编码是一种可变长编码( VLC:variable length coding))方式,于1952年由huffman提出。依据字符在需要编码文件中出现的概率提供对字符的唯一编码,并且保证了可变编码的平均编码最短,被称为最优二叉树,有时又称为最佳编码。

  2. ** LZ77**:LZ77压缩算法靠查找重复的序列. 这里使用术语:”滑动窗口”, 它的意思是:在任何的数据点上, 都记录了在此之前的字符. 32k的滑动窗口表示压缩器(解压器)能记录前32768个字符. 当下一个需要压缩的字符序列能够在滑动窗口中找到, 这个序列会被两个数字代替: 一个是距离,表示这个序列在窗口中的起始位置离窗口的距离, 一个是长度, 字符串的长度.

举例 : 将字符串“Blah blah b ”压缩为“Blah b[D=5,L=5] ”

根据顶部文章的介绍,如之上的代码所示,的确是单行日志压缩,作为日志系统,应该避免造成CPU负担,因此避免了多个日志同时压缩的方式。

关于加密

这次开源没有提供具体的加密算法,但是对单行日志进行了编码。
先来说一下解码的部分,刚开始使用 Mars 的 Xlogger 组件,看着输出的xlog格式的文件却不知道如何解码,github也没介绍怎么解码,后来看了一下源码,在 mars/log/crypt/ 目录下有一个
decode_mars_log_file 的python文件,把Xlog格式的加密文件,放到该目录下,跑一下脚本,就会生成解码后的log文件。

至于加密的部分,如下图所示,加密部分的代码会将其日志变为如下格式,拥有日志头信息和尾部,然后再变为二进制格式,然后最终写入到xlog格式的文件中。


6E0EE9E2-7896-449F-AFD9-60341BC892FD.png

下面以同步模式的日志加密为例子:

void LogCrypt::CryptSyncLog(const char* const _log_data, size_t _input_len, char* _output, size_t& _output_len) {
    uint16_t seq = __GetSeq(false);
    uint32_t len = std::min(_input_len, _output_len - GetHeaderLen() - GetTailerLen());
    
    memcpy(_output + GetHeaderLen(), _log_data, len);
    _output[GetHeaderLen() + len] = kMagicEnd;
    _output[0] = kMagicSyncStart;
    
    memcpy(_output + 1, &seq, sizeof(seq));
    
    struct timeval tv;
    gettimeofday(&tv, 0);
    time_t sec = tv.tv_sec;
    tm tm_tmp = *localtime((const time_t*)&sec);
    
    char hour = (char)tm_tmp.tm_hour;
    
    memcpy(_output+3, &hour, sizeof(hour));
    memcpy(_output+4, &hour, sizeof(hour));
    memcpy(_output+5, &len, sizeof(len));
    
    _output_len = GetHeaderLen() + GetTailerLen() + len;
}

于是把xlog对单行日志从压缩到加密后写进作为 log 中间 buffer的 mmap 的部分,大致梳理了一下,源码还有很多细枝末节的地方,主体算是梳理完毕。

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 206,126评论 6 481
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 88,254评论 2 382
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 152,445评论 0 341
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 55,185评论 1 278
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 64,178评论 5 371
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 48,970评论 1 284
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 38,276评论 3 399
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 36,927评论 0 259
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 43,400评论 1 300
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,883评论 2 323
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 37,997评论 1 333
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,646评论 4 322
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 39,213评论 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 30,204评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,423评论 1 260
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 45,423评论 2 352
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,722评论 2 345

推荐阅读更多精彩内容

  • 在应用程序中添加日志记录总的来说基于三个目的:监视代码中变量的变化情况,周期性的记录到文件中供其他应用进行统计分析...
    时待吾阅读 4,948评论 1 13
  • 在应用程序中添加日志记录总的来说基于三个目的:监视代码中变量的变化情况,周期性的记录到文件中供其他应用进行统计分析...
    时待吾阅读 4,961评论 0 6
  • 单机存储引擎就是哈希表、B树等数据结构在机械磁盘、SSD等持久化介质上的实现。单机存储系统是单机存储引擎的一种封装...
    olostin阅读 2,392评论 0 5
  • log4j 1.1 简介 Log4j是一个由Java编写可靠、灵活的日志框架,是Apache旗下的一个开源项目;现...
    贾博岩阅读 7,888评论 1 32
  • 喣风十里,柳堤一路,湘南苏仙桃源处。古樟千年再逢春,车马相继乡人驻。 炮竹冲天,福联盈户,红泥小炉亲人簇。 嘘寒问...
    从八到九阅读 149评论 0 0