:::success 本文描述的是我修改过的 zlog 的使用手册, 有一小部分有别于官方版本.
我修改过的代码见: github.com/zzqcn/zlog, zzqcn分支 :::

原手册地址: http://hardysimpson.github.io/zlog/UsersGuide-CN.html
本人非常尊重并感谢 zlog 原作者, 以及所有代码贡献者.

我的修改说明

我主要是修改了日志级别相关代码, 使它符合 syslog 级别定义和我的使用习惯. syslog 中, 日志级别是越严重数值越小, 见:

  1. // syslog.h
  2. #define LOG_EMERG 0 /* system is unusable */
  3. #define LOG_ALERT 1 /* action must be taken immediately */
  4. #define LOG_CRIT 2 /* critical conditions */
  5. #define LOG_ERR 3 /* error conditions */
  6. #define LOG_WARNING 4 /* warning conditions */
  7. #define LOG_NOTICE 5 /* normal but significant condition */
  8. #define LOG_INFO 6 /* informational */
  9. #define LOG_DEBUG 7 /* debug-level messages */

而 zlog 原版代码中正好相反, 越严重数值越大:

  1. // zlog.h
  2. typedef enum {
  3. ZLOG_LEVEL_DEBUG = 20,
  4. ZLOG_LEVEL_INFO = 40,
  5. ZLOG_LEVEL_NOTICE = 60,
  6. ZLOG_LEVEL_WARN = 80,
  7. ZLOG_LEVEL_ERROR = 100,
  8. ZLOG_LEVEL_FATAL = 120
  9. } zlog_level;

虽然有日志级别自定义等, 对我来说也不需要, 反而是代码等复杂.
我修改了代码, 并通过条件编译宏 ZZQ_LEVEL来控制是用原版代码还是我修改过的代码. 我还引入了 meson 编译系统, 默认加上 ZZQ_LEVEL编译选项.

修改后的日志级别定义为:

  1. // zlog.h
  2. #ifndef ZZQ_LEVEL
  3. typedef enum {
  4. ZLOG_LEVEL_DEBUG = 20,
  5. ZLOG_LEVEL_INFO = 40,
  6. ZLOG_LEVEL_NOTICE = 60,
  7. ZLOG_LEVEL_WARN = 80,
  8. ZLOG_LEVEL_ERROR = 100,
  9. ZLOG_LEVEL_FATAL = 120
  10. } zlog_level;
  11. #else
  12. #define ZLOG_LEVEL_EMERG 0U
  13. #define ZLOG_LEVEL_FATAL 0U
  14. #define ZLOG_LEVEL_ALERT 1U
  15. #define ZLOG_LEVEL_CRIT 2U
  16. #define ZLOG_LEVEL_ERR 3U
  17. #define ZLOG_LEVEL_WARNING 4U
  18. #define ZLOG_LEVEL_NOTICE 5U
  19. #define ZLOG_LEVEL_INFO 6U
  20. #define ZLOG_LEVEL_DEBUG 7U
  21. #endif

1 zlog是什么?

zlog是一个高可靠性、高性能、线程安全、灵活、概念清晰的纯C日志函数库。

事实上,在C的世界里面没有特别好的日志函数库(就像JAVA里面的的log4j,或者C++的log4cxx)。C程序员都喜欢用自己的轮子。printf就是个挺好的轮子,但没办法通过配置改变日志的格式或者输出文件。syslog是个系统级别的轮子,不过速度慢,而且功能比较单调。

所以我写了zlog。

zlog在效率、功能、安全性上大大超过了log4c,并且是用c写成的,具有比较好的通用性。

zlog有这些特性:

  • syslog分类模型,比log4j模型更加直接了当
  • 日志格式定制,类似于log4j的pattern layout
  • 多种输出,包括动态文件、静态文件、stdout、stderr、syslog、用户自定义输出函数
  • 运行时手动、自动刷新配置文件(同时保证安全)
  • 高性能,在我的笔记本上达到25万条日志每秒, 大概是syslog(3)配合rsyslogd的1000倍速度
  • 用户自定义等级
  • 多线程和多进程环境下保证安全转档
  • 精确到微秒
  • 简单调用包装dzlog(一个程序默认只用一个分类)
  • MDC,线程键-值对的表,可以扩展用户自定义的字段
  • 自诊断,可以在运行时输出zlog自己的日志和配置状态
  • 不依赖其他库,只要是个POSIX系统就成(当然还要一个C99兼容的vsnprintf)

相关链接:
主页:http://hardysimpson.github.com/zlog/
下载:https://github.com/HardySimpson/zlog/releases
邮箱:HardySimpson1984@gmail.com

1.1 兼容性说明

  1. zlog是基于POSIX的。目前我手上有的环境只有AIX和linux。在其他的系统下(FreeBSD, NetBSD, OpenBSD, OpenSolaris, Mac OS X…)估计也能行,有问题欢迎探讨。
  2. zlog使用了一个C99兼容的vsnprintf。也就是说如果缓存大小不足,vsnprintf将会返回目标字符串应有的长度(不包括’\0’)。如果在你的系统上vsnprintf不是这么运作的,zlog就不知道怎么扩大缓存。如果在目标缓存不够的时候vsnprintf返回-1,zlog就会认为这次写入失败。幸运的是目前大多数c标准库符合C99标准。glibc 2.1, libc on AIX, libc on freebsd…都是好的,不过glibc2.0不是。在这种情况下,用户需要自己来装一个C99兼容的vsnprintf,来crack这个函数库。我推荐ctrio, 或者C99-snprintf。只要改buf.c就行,祝好运!
  3. 有网友提供了如下版本,方便其他平台上安装编译,非常感谢!

auto tools版本: https://github.com/bmanojlovic/zlog
cmake版本: https://github.com/lisongmin/zlog
windows版本: https://github.com/lopsd07/WinZlog

1.2 zlog 1.2 发布说明

  1. zlog 1.2 新增了这些功能
    • 对管道的支持,从此zlog可以外接cronolog这样的日志过滤程序来输出
    • 全面的日志转档支持,详见5.6
    • 其他兼容性的代码改动
  2. zlog 1.2 在库方面是和zlog 1.0/1.1二进制兼容的,区别在于:
    • 所有的宏改为小写,ZLOG_INFO->zlog_info,方便开发者手工输入。这是一个巨大的改变,如果zlog1.1/1.0的用户要用zlog 1.2的话,需要写一个脚本,把源代码中的大写批量替换为小写,然后重新编译你的程序。我提供了一个脚本:
      sed -i -e ’s/\b\w*ZLOG\w*\b/\L&\E/g’ aa.c
    • 取消了auto tools的使用,也就是说,不论你在任何平台,都需要gcc和gnu make才能编译安装zlog。主流的操作系统(Aix, OpenSolaris..)都能安装gcc和gnu make。当然也可以自行修改makefile来完成编译,对于平台稍有经验的Geek都可以自行完成!

      2 zlog不是什么?

      zlog的目标是成为一个简而精的日志函数库,不会直接支持网络输出或者写入数据库,不会直接支持日志内容的过滤和解析。

原因很明显,日志库是被应用程序调用的,所有花在日志库上的时间都是应用程序运行时间的一部分,而上面说的这些操作都很费时间,会拖慢应用程序的速度。这些事儿应该在别的进程或者别的机器上做。

如果你需要这些特性,我建议使用rsyslog、zLogFabric、Logstash,这些日志搜集、过滤、存储软件,当然这是单独的进程,不是应用程序的一部分。

目前zlog已经支持7.4,可以自己实现一个输出函数,自由的把日志输出到其他进程或者其他机器。而把日志的分类匹配、日志格式成型的工作交给zlog。

目前我的想法是实现一个zlog-redis客户端,用自定义输出功能,把日志存储到本机或者远程的redis服务器内,然后用其他进程(也使用zlog库)来把日志写到文件里面,不知大家以为这个想法如何?欢迎和我联系探讨。

3 Hello World

3.1 编译和安装zlog

下载zlog-latest-stable.tar.gz

  1. $ tar -zxvf zlog-latest-stable.tar.gz
  2. $ cd zlog-latest-stable/
  3. $ make
  4. $ sudo make install
  5. # or
  6. $ sudo make PREFIX=/usr/local/ install

PREFIX指明了安装的路径,安转完之后为了让你的程序能找到zlog动态库

  1. $ sudo vi /etc/ld.so.conf
  2. /usr/local/lib
  3. $ sudo ldconfig

在你的程序运行之前,保证libzlog.so在系统的动态链接库加载器可以找到的目录下。上面的命令适用于linux,别的系统自己想办法。

除了一般的make以外,还可以

  1. $ make 32bit # 32bit version on 64bit machine, libc6-dev-i386 is needed
  2. $ make noopt # without gcc optimization
  3. $ make doc # lyx and hevea is needed
  4. $ make test # test code, which is also good example for zlog

makefile是用GNU make的格式写的,所以在你的平台上需要预装gnu make和gcc。或者,手工修改一个自己平台的makefile也行。

3.2 应用程序调用和链接zlog

应用程序使用zlog很简单,只要在C文件里面加一行。
#include "zlog.h"

链接zlog需要pthread库,命令是:

  1. $ cc -c -o app.o app.c -I/usr/local/include
  2. # -I[where zlog.h is put]
  3. $ cc -o app app.o -L/usr/local/lib -lzlog -lpthread
  4. # -L[where libzlog.so is put]

3.3 Hello World 代码

这些代码在$(top_builddir)/test/test_hello.c, test_hello.conf

  1. 写一个C文件: ```c // test_hello.c

    include

    include “zlog.h”

int main(int argc, char* argv) { int rc; zlog_category_t c;

  1. rc = zlog_init("test_hello.conf");
  2. if (rc) {
  3. printf("init failed\n");
  4. return -1;
  5. }
  6. c = zlog_get_category("my_cat");
  7. if (!c) {
  8. printf("get cat fail\n");
  9. zlog_fini();
  10. return -2;
  11. }
  12. zlog_info(c, "hello, zlog");
  13. zlog_fini();
  14. return 0;

}

  1. 2. 写一个配置文件,放在和test_hello.c同样的目录下:
  2. ```properties
  3. # test_hello.conf
  4. [formats]
  5. simple = "%m%n"
  6. [rules]
  7. my_cat.DEBUG >stdout; simple
  1. 编译、然后运行!
    1. $ cc -c -o test_hello.o test_hello.c -I/usr/local/include
    2. $ cc -o test_hello test_hello.o -L/usr/local/lib -lzlog
    3. $ ./test_hello
    4. hello, zlog

    3.4 更简单的Hello World

    这个例子在$(top_builddir)/test/test_default.c, test_default.conf. 源代码是: ```c // test_default.c

    include

    include “zlog.h”

int main(int argc, char** argv) { int rc;

  1. rc = dzlog_init("test_default.conf", "my_cat");
  2. if (rc) {
  3. printf("init failed\n");
  4. return -1;
  5. }
  6. dzlog_info("hello, zlog");
  7. zlog_fini();
  8. return 0;

}

  1. 配置文件是test_default.conf,和test_hello.conf一模一样,最后执行程序的输出也一样。区别在于这里用了dzlog API,内含一个默认的zlog_category_t。详见6.5
  2. <a name="EXDTd"></a>
  3. # 4 Syslog 模型
  4. <a name="VolKh"></a>
  5. ## 4.1 分类(Category)、规则(Rule)和格式(Format)
  6. zlog3个重要的概念:分类(Category)、规则(Rule)和格式(Format)。
  7. - **分类(Category)** 用于区分不同的输入。代码中的分类变量的名字是一个字符串,在一个程序里面可以通过获取不同的分类名的category用来后面输出不同分类的日志,用于不同的目的。
  8. - **格式(Format)** 是用来描述输出日志的格式,比如是否有带有时间戳,是否包含文件位置信息等,上面的例子里面的格式simple就是简单的用户输入的信息+换行符。
  9. - **规则(Rule) **则是把分类、级别、输出文件、格式组合起来,决定一条代码中的日志是否输出,输出到哪里,以什么格式输出。
  10. 所以,当程序执行下面的语句的时候
  11. ```c
  12. zlog_category_t *c;
  13. c = zlog_get_category("my_cat");
  14. zlog_info(c, "hello, zlog");

zlog会找到c的名字是”my_cat”,对应的配置文件中的规则是

  1. [rules]
  2. my_cat.DEBUG >stdout; simple

然后库会检查,目前这条日志的级别是否符合规则中的级别来决定是否输出。因为INFO>=DEBUG,所以这条日志会被输出。并且根据这条规则,会被输出到stdout(标准输出) ,输出的格式是simple,在配置文件中定义是

  1. [formats]
  2. simple = "%m%n"

最后在屏幕上打印

hello, zlog

这就是整个过程。用户要做就是写自己的信息。日志往哪里输出,以什么格式输出,都是库和配置文件来完成的。

4.2 syslog模型和log4j模型的区别

好,那么目前这个模型和syslog有什么关系呢?至今为止,这个模型还是比较像log4j。log4j的模型里面有logger, appender和layout。区别在于,在log4j里面,代码中的logger和配置中的logger是一一对应的,并且一个logger有唯一的级别。一对一关系是log4j, log4cxx, log4cpp, log4cplus, log4net的唯一选择。

但这种模型是不灵活的,他们发明了过滤器(filters)来弥补,但这只能把事情弄得更加混乱。所以让我们把目光转回syslog的模型,这是一个设计的很简易正确的模型。

继续上一节的例子,如果在zlog的配置文件中有这么2行规则:

  1. [rules]
  2. my_cat.DEBUG >stdout; simple
  3. my_cat.INFO >stdout;

然后,一行代码会产生两行输出:

hello, zlog 2012-05-29 10:41:36 INFO [11288:test_hello.c:41] hello, zlog

现在一个代码中的分类对应配置文件中的两条规则。log4j的用户可能会说:”这很好,但是只要在log4j里面放两个appender也能做的一样。”所以继续看下一个例子:

  1. [rules]
  2. my_cat.WARN "/var/log/aa.log"
  3. my_cat.DEBUG "/var/log/bb.log"

代码是:

  1. zlog_info(c, "info, zlog");
  2. zlog_debug(c, "debug, zlog");

最后,在aa.log中只有一条日志

2012-05-29 10:41:36 INFO [11288:test_hello.c:41] info, zlog

但在bb.log里面有两条

2012-05-29 10:41:36 INFO [11288:test_hello.c:41] info, zlog 2012-05-29 10:41:36 DEBUG [11288:test_hello.c:42] debug, zlog

从这个例子能看出来区别。log4j无法轻易的做到这一点。在zlog里面,一个分类可以对应多个规则,每个规则有自己的级别、输出和格式。这就让用户能按照需求过滤、多渠道输出自己的所有日志。

4.3 扩展syslog模型

所以到现在你能看出来zlog的模型更像syslog的模型。不幸的是,在syslog里面,设施(facility)是个int型,而且必须从系统定义的那几种里面选择。zlog走的远一点,用一个字符串来标识分类。

syslog有一个通配符”“,匹配所有的设施(facility)。zlog里面也一样,”“匹配所有分类。这提供了一个很方便的办法来重定向你的系统中各个组件的错误。只要这么写:

  1. [rules]
  2. *.error "/var/log/error.log"

zlog强大而独有的特性是上下级分类匹配。如果你的分类是这样的:

  1. c = zlog_get_category("my_cat");

然后配置文件是这样

  1. [rules]
  2. my_cat.* "/var/log/my_cat.log"
  3. my_.NOTICE "/var/log/my.log"

这两条规则都匹配c分类”mycat”。通配符”“ 表示上级分类。 “my_”是”my_cat”和”my_dog”的上级分类。还有一个通配符是”!”,详见5.5.2.

5 配置文件

大部分的zlog的行为取决于配置文件:把日志打到哪里去,用什么格式,怎么转档。配置文件是zlog的黑话,我尽量把这个黑话设计的简单明了。这是个配置文件例子:

  1. # comments
  2. [global]
  3. strict init = true
  4. buffer min = 1024
  5. buffer max = 2MB
  6. rotate lock file = /tmp/zlog.lock
  7. default format = "%d.%us %-6V (%c:%F:%L) - %m%n"
  8. file perms = 600
  9. [levels]
  10. TRACE = 10
  11. CRIT = 130, LOG_CRIT
  12. [formats]
  13. simple = "%m%n"
  14. normal = "%d %m%n"
  15. [rules]
  16. default.* >stdout; simple
  17. *.* "%12.2E(HOME)/log/%c.log", 1MB*12; simple
  18. my_.INFO >stderr;
  19. my_cat.!ERROR "/var/log/aa.log"
  20. my_dog.=DEBUG >syslog, LOG_LOCAL0; simple
  21. my_mice.* $user_define;

有关单位:当设置内存大小或者大数字时,可以设置1k 5GB 4M这样的单位:

  1. # 1k => 1000 bytes
  2. # 1kb => 1024 bytes
  3. # 1m => 1000000 bytes
  4. # 1mb => 1024*1024 bytes
  5. # 1g => 1000000000 bytes
  6. # 1gb => 1024*1024*1024 byte

单位是大小写不敏感的,所以1GB 1Gb 1gB是等效的。

5.1 全局参数

全局参数以[global]开头。[]代表一个节的开始,四个小节的顺序不能变,依次为global-levels-formats-rules。这一节可以忽略不写。语法为
(key) = (value).

  • strict init

如果”strict init”是true,zlog_init()将会严格检查所有的格式和规则,任何错误都会导致zlog_init() 失败并且返回-1。当”strict init”是false的时候,zlog_init()会忽略错误的格式和规则。 这个参数默认为true。

  • reload conf period

这个选项让zlog能在一段时间间隔后自动重载配置文件。重载的间隔以每进程写日志的次数来定义。当写日志次数到了一定值后,内部将会调用zlog_reload()进行重载。每次zlog_reload()或者zlog_init()之后重新计数累加。因为zlog_reload()是原子性的,重载失败继续用当前的配置信息,所以自动重载是安全的。默认值是0,自动重载是关闭的。

  • buffer min
  • buffer max

zlog在堆上为每个线程申请缓存。”buffer min”是单个缓存的最小值,zlog_init()的时候申请这个长度的内存。写日志的时候,如果单条日志长度大于缓存,缓存会自动扩充,直到到”buffer max”。 单条日志再长超过”buffer max”就会被截断。如果 “buffer max” 是 0,意味着不限制缓存,每次扩充为原先的2倍,直到这个进程用完所有内存为止。缓存大小可以加上 KB, MB 或 GB这些单位。默认来说”buffer min”是 1K , “buffer max” 是2MB。

  • rotate lock file

这个选项指定了一个锁文件,用来保证多进程情况下日志安全转档。zlog会在zlog_init()时候以读写权限打开这个文件。确认你执行程序的用户有权限创建和读写这个文件。转档日志的伪代码是:

  1. write(log_file, a_log)
  2. if (log_file > 1M)
  3. if (pthread_mutex_lock succ && fcntl_lock(lock_file) succ)
  4. if (log_file > 1M) rotate(log_file);
  5. fcntl_unlock(lock_file);
  6. pthread_mutex_unlock;

mutex_lock用于多线程, fcntl_lock用于多进程。fcntl_lock是POSIX建议锁。详见man 3 fcntl。这个锁是全系统有效的。在某个进程意外死亡后,操作系统会释放此进程持有的锁。这就是我为什么用fcntl锁来保证安全转档。进程需要对锁文件有读写权限。

默认来说,rotate lock file = self。在这种情况下,zlog不会创建任何锁文件,用配置文件作为锁文件。fcntl是建议锁,所以用户可以自由的修改存储他们的配置文件。一般来说,单个日志文件不会被不同操作系统用户的进程转档,所以用配置文件作为锁文件是安全的。

如果你设置其他路径作为锁文件,例如/tmp/zlog.lock,zlog会在zlog_init()的时候创建这个文件。如果有多个操作系统用户的进程需要转档同一个日志文件,确认这个锁文件对于多个用户都可读写。默认值是/tmp/zlog.lock。

  • default format

这个参数是缺省的日志格式,默认值为:
"%d %V [%p:%F:%L] %m%n"
这种格式产生的输出类似这样:
2012-02-14 17:03:12 INFO [3758:test_hello.c:39] hello, zlog

  • file perms

这个指定了创建日志文件的缺省访问权限。必须注意的是最后的产生的日志文件的权限为”file perms”& ~umask。默认为600,只允许当前用户读写。

  • fsync period

在每条规则写了一定次数的日志到文件后,zlog会调用fsync(3)来让操作系统马上把数据写到硬盘。次数是每条规则单独统计的,并且在zlog_reload()后会被清0。必须指出的是,在日志文件名是动态生成或者被转档的情况下,zlog不能保证把所有文件都搞定,zlog只fsync()那个时候刚刚write()的文件描述符。这提供了写日志速度和数据安全性之间的平衡。例子:

  1. $ time ./test_press_zlog 1 10 100000
  2. real 0m1.806s
  3. user 0m3.060s
  4. sys 0m0.270s
  5. $ wc -l press.log
  6. 1000000 press.log
  7. $ time ./test_press_zlog 1 10 100000 #fsync period = 1K
  8. real 0m41.995s
  9. user 0m7.920s
  10. sys 0m0.990s
  11. $ time ./test_press_zlog 1 10 100000 #fsync period = 10K
  12. real 0m6.856s
  13. user 0m4.360s
  14. sys 0m0.550s

如果你极度在乎安全而不是速度的话,用同步IO文件,见5.5.3。默认值是0,由操作系统来决定什么时候刷缓存到文件。

5.2 日志等级自定义

这一节以[levels]开始。用于定义用户自己的日志等级,建议和用户自定义的日志记录宏一起使用。这一节可以忽略不写。语法为:
(level string) = (level int), (syslog level, optional)
(level int)必须在[1,253]这个范围内,越大越重要。(syslog level)是可选的,如果不设默认为LOG_DEBUG。

详见7.3。

5.3 格式(Formats)

这一节以[formats]开始。用来定义日志的格式。语法为:
(name) = "(actual formats)"
很好理解,(name)被后面的规则使用。(name)必须由数字和字母组成,下划线”_”也算字母。(actual format)前后需要有双引号。 (actual formats)可以由转换字符组成,见下一节。

5.4 转换格式串

转换格式串的设计是从C的printf函数里面抄来的。一个转换格式串由文本字符和转换说明组成。
转换格式串用在规则的日志文件路径和输出格式(format)中。你可以把任意的文本字符放到转换格式串里面。

每个转换说明都是以百分号(%)打头的,后面跟可选的宽度修饰符,最后以转换字符结尾。转换字符决定了输出什么数据,例如分类名、级别、时间日期、进程号等等。宽度修饰符控制了这个字段的最大最小宽度、左右对齐。下面是简单的例子。

如果转换格式串是:
"%d(%m-%d %T) %-5V [%p:%F:%L] %m%n".
源代码中的写日志语句是:
zlog_info(c, "hello, zlog");
将会输出:

02-14 17:17:42 INFO [4935:test_hello.c:39] hello, zlog

可以注意到,在文本字符和转换说明之间没有显式的分隔符。zlog解析的时候知道哪里是转换说明的开头和结尾。在这个例子里面%-5p这个转换说明决定了日志级别要被左对齐,占5个字符宽。

5.4.1 转换字符

可以被辨认的转换字符是

字符 效果 例子
%c 分类名 aa_bb
%d() 打日志的时间。这个后面要跟一对小括号()内含说明具体的日期格式。就像%d(%F)或者%d(%m-%d %T)。如果不跟小括号,默认是%d(%F %T)。括号内的格式和 strftime(2)的格式一致。详见5.4.3 %d(%F) 2011-12-01
%d(%m-%d %T) 12-01 17:17:42
%d(%T) 17:17:42.035
%d 2012-02-14 17:03:12
%d()
%E() 获取环境变量的值 %E(USER) simpson
%ms 毫秒,3位数字字符串
取自gettimeofday(2)
013
%us 微秒,6位数字字符串
取自gettimeofday(2)
002323
%F 源代码文件名,来源于FILE宏。在某些编译器下 FILE是绝对路径。用%f来去掉目录只保留文件名,或者编译器有选项可以调节 test_hello.c
或者在某些编译器下
/home/zlog/src/test/test_hello.c
%f 源代码文件名,输出$F最后一个’/’后面的部分。当然这会有一定的性能损失 test_hello.c
%H 主机名,来源于 gethostname(2) zlog-dev
%L 源代码行数,来源于LINE 135
%m 用户日志,用户从zlog函数输入的日志。 hello, zlog
%M MDC (mapped diagnostic context),每个线程一张键值对表,输出键相对应的值。后面必需跟跟一对小括号()内含键。例如 %M(clientNumber) ,clientNumbe是键。 详见 7.1 %M(clientNumber) 12345
%n 换行符,目前还不支持windows换行符 \n
%p 进程ID,来源于getpid() 2134
%U 调用函数名,来自于func(C99)或者FUNCTION(gcc),如果编译器支持的话。 main
%V 日志级别,大写 INFO
%v 日志级别,小写 info
%t 16进制表示的线程ID,来源于pthread_self()
“0x%x”,(unsigned int) pthread_t
0xba01e700
%T 相当于%t,不过是以长整型表示的
“%lu”, (unsigned long) pthread_t
140633234859776
%% 一个百分号 %
%[其他字符] 解析为错误,zlog_init()将会失败

5.4.2 宽度修饰符

一般来说数据按原样输出。不过,有了宽度修饰符,就能够控制最小字段宽度、最大字段宽度和左右对齐。当然这要付出一定的性能代价。
可选的宽度修饰符放在百分号和转换字符之间。

第一个可选的宽度修饰符是左对齐标识,减号(-)。然后是可选的最小字段宽度,这是一个十进制数字常量,表示最少有几个字符会被输出。如果数据本来没有那么多字符,将会填充空格(左对齐或者右对齐)直到最小字段宽度为止。默认是填充在左边也就是右对齐。当然你也可以使用左对齐标志,指定为填充在右边来左对齐。填充字符为空格(space)。如果数据的宽度超过最小字段宽度,则按照数据的宽度输出,永远不会截断数据。

这种行为可以用最大字段宽度来改变。最大字段宽度是放在一个句点号(.)后面的十进制数字常量。如果数据的宽度超过了最大字段宽度,则尾部多余的字符(超过最大字段宽度的部分)将会被截去。 最大字段宽度是8,数据的宽度是10,则最后两个字符会被丢弃。这种行为和C的printf是一样的,把后面的部分截断。

下面是各种宽度修饰符和分类转换字符配合一起用的例子。

宽度修饰符 左对齐 最小字段宽度 最大字段宽度 附注
%20c 20 左补充空格,如果分类名小于20个字符长。
%-20c 20 右补充空格,如果分类名小于20个字符长。
%.30c 30 如果分类名大于30个字符长,取前30个字符,去掉后面的。
%20.30c 20 30 如果分类名小于20个字符长,左补充空格。如果在20-30之间,按照原样输出。如果大于30个字符长,取前30个字符,去掉后面的。
%-20.30c 20 30 如果分类名小于20个字符长,右补充空格。如果在20-30之间,按照原样输出。如果大于30个字符长,取前30个字符,去掉后面的。

5.4.3 时间字符

这里是转换字符d支持的时间字符。

所有字符都是由strftime(2)生成的,在我的linux操作系统上支持的是:

字符 效果 例子
%a 一星期中各天的缩写名,根据locale显示 Wed
%A 一星期中各天的全名,根据locale显示 Wednesday
%b 缩写的月份名,根据locale显示 Mar
%B 月份全名,根据locale显示 March
%c 当地时间和日期的全表示, 根据locale显示 Thu Feb 16 14:16:35 2012
%C 世纪 (年/100),2位的数字(SU) 20
%d 一个月中的某一天 (01-31) 06
%D 相当于%m/%d/%y. (呃,美国人专用,美国人要知道在别的国家%d/%m/%y 才是主流。也就是说在国际环境下这个格式容易造成误解,要少用) (SU) 02/16/12
%e 就像%d,一个月中的某一天,但是头上的0被替换成空格(SU) 6
%F 相当于%Y-%m-%d (ISO 8601日期格式)(C99) 2012-02-16
%G The ISO 8601 week-based year (see NOTES) with century as a decimal number. The 4-digit year corre‐ sponding to the ISO week number (see %V). This has the same format and value as %Y, except that if the ISO week number belongs to the previous or next year, that year is used instead. (TZ)
大意是采用%V定义的年,如果那年的前几天不算新年的第一周,就算上一年
2012
%g 相当于%G,就是不带世纪 (00-99). (TZ) 12
%h 相当于%b(SU) Feb
%H 小时,24小时表示(00-23) 14
%I 小时,12小时表示(01-12) 02
%j 一年中的各天(001-366) 047
%k 小时,24小时表示( 0-23); 一位的前面为空格 (可和%H比较) (TZ) 15
%l 小时,12小时表示( 0-12); 一位的前面为空格 (可和%比较)(TZ) 3
%m 月份(01-12) 02
%M 分钟(00-59) 11
%n 换行符 (SU) \n
%p “AM” 或 “PM”,根据当时的时间,根据locale显示相应的值,例如”上午”、”下午” 。 中午是”PM”,凌晨是”AM” PM
%P 相当于%p不过是小写,根据locale显示相应的值 (GNU) pm
%r 时间+后缀AM或PM。在POSIX locale下相当于%I:%M:%S %p. (SU) 03:11:54 PM
%R 小时(24小时制):分钟 (%H:%M) (SU) 如果要带秒的,见%T 15:11
%s Epoch以来的秒数,也就是从1970-01-01 00:00:00 UTC. (TZ) 1329376487
%S 秒(00-60). (允许60是为了闰秒) 54
%t 制表符tab(SU)
%T 小时(24小时制):分钟:秒 (%H:%M:%S) (SU) 15:14:47
%u 一周的天序号(1-7),周一是1,另见%w (SU) 4
%U 一年中的星期序号(00-53),周日是一周的开始,一年中第一个周日所在的周是第01周。另见%V和%W 07
%V ISO 8601星期序号(01-53),01周是第一个至少有4天在新年的周。另见%U 和%W(SU) 07
%w 一周的天序号(0-6),周日是0。另见%u 4
%W 一年中的星期序号(00-53),周一是一周的开始,一年中第一个周一所在的周是第01周。另见%V和%W 07
%x 当前locale下的偏好日期 02/16/12
%X 当前locale下的偏好时间 15:14:47
%y 不带世纪数目的年份(00-99) 12
%Y 带世纪数目的年份 2012
%z 当前时区相对于GMT时间的偏移量。采用RFC 822-conformant来计算(话说我也不知道是啥) (using “%a, %d %b %Y %H:%M:%S %z”). (GNU) +0800
%Z 时区名(如果有的话) CST
%% 一个百分号 %

5.5 规则(Rules)

这一节以[rules]开头。这个描述了日志是怎么被过滤、格式化以及被输出的。这节可以忽略不写,不过这样就没有日志输出了,嘿嘿。语法是:
(category).(level) (output), (options, optional); (format name, optional)

当zlog_init()被调用的时候,所有规则都会被读到内存中。当zlog_get_category()被调用,规则就被被分配给分类(5.5.2)。在实际写日志的时候,例如zlog_info()被调用的时候,就会比较这个INFO和各条规则的等级,来决定这条日志会不会通过这条规则输出。当zlog_reload()被调用的时候,配置文件会被重新读入,包括所有的规则,并且重新计算分类对应的规则。

5.5.1 级别匹配

zlog有6个默认的级别:”DEBUG”, “INFO”, “NOTICE”, “WARN”, “ERROR”和”FATAL”。就像其他的日志函数库那样, aa.DEBUG意味着任何大于等于DEBUG级别的日志会被输出。当然还有其他的表达式。配置文件中的级别是大小写不敏感的。

表达式 含义
* 所有等级
aa.debug 代码内等级>=debug
aa.=debug 代码内等级==debug
aa.!debug 代码内等级!=debug

用户可以自定义等级,详见7.3。

5.5.2 分类匹配

分类必须由数字和字母组成,下划线”_”也算字母。

总结 配置文件规则分类 匹配的代码分类 不匹配的代码分类
*匹配所有 . aa, aa_bb, aa_cc, xx, yy … NONE
以_结尾的分类匹配本级及下级分类 aa_.* aa, aa_bb, aa_cc, aa_bb_cc xx, yy
不以_结尾的精确匹配分类名 aa.* aa aa_bb, aa_cc, aa_bb_cc
!匹配那些没有找到规则的分类 !.* xx aa(as it matches rules above)

5.5.3 输出动作

目前zlog支持若干种输出,语法是:
[输出], [附加选项, 可选]; [format(格式)名, 可选]

动作 输出字段 附加选项
标准输出 >stdout 无意义
标准错误输出 >stderr 无意义
输出到syslog >syslog syslog设施(facilitiy):
LOG_USER(default), LOG_LOCAL[0-7]
必填
管道输出 |cat 无意义
文件 “文件路径” 文件转档,详见5.6
10M * 3 ~ “press.#r.log”
同步IO文件 -“文件路径”
用户自定义输出 $name “path” 动态或者静态的用于record输出
  • stdout, stderr, syslog

如表格描述,其中只有sylog的附加选项是有意义并必须写的。

值得注意的是,zlog在写日志的时候会用这样的语句

write(STDOUT_FILENO, zlog_buf_str(a_thread->msg_buf), zlog_buf_len(a_thread->msg_buf))
而如果你的程序是个守护进程,在启动的时候把STDOUT_FILENO,也就是1的文件描述符关掉的话,会发生什么结果呢?

日志会被写到新的1的文件描述符所代表的文件里面!我收到过邮件,说zlog把日志写到自己的配置文件里面去了!

所以,千万不要在守护进程的规则里面加上>stdout或>stderr。这会产生不可预料的结果……如果一定要输出到终端,用”/dev/tty”代替。

  • 管道输出

*.* | /usr/bin/cronolog /www/logs/example_%Y%m%d.log ; normal
这是一个将zlog的输出到管道后接cronolog的例子。实现的原理很简单,在zloginit的时候调用popen(“/usr/bin/cronolog /www/logs/example%Y%m%d.log”, “w”),后面往这个文件描述符里面写指定格式的日志。使用cronolog来生成按天分割的日志效率比zlog自己的动态路径的效率要高,因为通过管道,无须每次打开关闭动态路径的文件描述符。

  1. [rules]
  2. *.* "press%d(%Y%m%d).log"
  3. $ time ./test_press_zlog 1 10 100000
  4. real 0m4.240s
  5. user 0m2.500s
  6. sys 0m5.460s
  7. [rules]
  8. *.* | /usr/bin/cronolog press%Y%m%d.log
  9. $ time ./test_press_zlog 1 10 100000
  10. real 0m1.911s
  11. user 0m1.980s
  12. sys 0m1.470s

不过,使用管道也是有限制的:

  • POSIX.1-2001保证读写不大于PIPE_BUF大小的内容是原子的。linux上PIPE_BUF为4096。
  • 单条日志的长度超过PIPE_BUF的时候并且有多个有父子关系的进程写通过zlog写同一个管道,也就是在zlog_init之后fork多个子进程,此时只有一个cronolog的进程监听一个管道描述符,日志内容可能会交错。
  • 多个进程分别zlog_init,启动多个cronolog进程,写拥有同一个文件路径的日志文件,即使单条日志长度不超过PIPE_BUF,也有可能导致日志交错,因为cronolog读到的文件流是连续的,它不知道单条日志的边界在哪里。

所以,总结一下,使用管道来输出到单个日志文件的情况是:

  • 单进程写,单条日志长度不限制。单进程内内的多线程写日志的原子性已经由zlog保证了。
  • 有父子关系的多进程,单条日志长度不能超过PIPE_BUF(4096)
  • 无父子关系的多进程使用管道同时写一个日志,无论单条日志长度是多少,都有可能导致日志交错。

zlog本身的直接文件输出能保证即使是多进程,同时调用zlog写一个日志文件也不会产生交错,见下。

  • 文件
    • 文件路径

可以是相对路径或者绝对路径,被双引号”包含。转换格式串可以用在文件路径上。例如文件路径是 “%E(HOME)/log/out.log”,环境变量$HOME是/home/harry,那最后的输出文件是/home/harry/log/output.log。转换格式串详见 5.4。

zlog的文件功能极为强大,例如:
输出到命名管道(FIFO),必须在调用前由mkfifo(1)创建
*.* "/tmp/pipefile"
输出到NULL,也就是不输出
*.* "/dev/null"
在任何情况下输出到终端
*.* "/dev/tty"
每线程一个日志,在程序运行的目录下
*.* "%T.log"
输出到有进程号区分的日志,每天,在$HOME/log目录,每1GB转档一次,保持5个日志文件。
*.* "%E(HOME)/log/aa.%p.%d(%F).log",1GB * 5
aa及下级分类,每个分类一个日志
`aa
.* “/var/log/%c.log”`

  • 文件转档

控制文件的大小和个数。zlog根据这个字段来转档,当日志文件太大的时候。例如
"%E(HOME)/log/out.log", 1M * 3 ~ "%E(HOME)/log/out.log.#r"
这三个参数都不是必填项,zlog的转档功能详见5.6

  • 同步IO文件

在文件路径前加上一个”-“就打开了同步IO选项。在打开文件(open)的时候,会以O_SYNC选项打开,这时候每次写日志操作都会等操作系统把数据写到硬盘后才返回。这个选项极为耗时:

$ time ./test_press_zlog 100 1000
real 0m0.732s
user 0m1.030s
sys 0m1.080s

$ time ./test_press_zlog 100 1000 # synchronous I/O open
real 0m20.646s
user 0m2.570s
sys 0m6.950s

  • 格式名

是可选的,如果不写,用全局配置里面的默认格式:

  1. [global]
  2. default format = "%d(%F %T) %V [%p:%F:%L] %m%n"
  • 用户自定义输出详见7.4

    5.6 文件转档

    为什么需要将日志文件转档?我已经在实际的运行环境中不止一次的看到过,因为日志文件过大,导致系统硬盘被撑爆,或者单个日志文件过大而即使用grep也要花费很多时间来寻找匹配的日志。对于日志转档,我总结了如下几种范式:
  1. 按固定时间段来切分日志。

例如,每天生成一个日志
aa.2012-08-02.log
aa.2012-08-03.log
aa.2012-08-04.log

这种日志适合的场景是,管理员大概知道每天生成的日志量,然后希望在n个月之后能精确的找出某天的所有日志。这种日志切分最好由日志库来完成,其次的方法是用cronosplit这种软件来分析日志内容的时间字符串来进行后期的切分,较差的办法是用crontab+logrotate或mv来定期移动(但这并不精确,会造成若干条当天的日志被放到上一天的文件里面去)。

在zlog里面,这种需求不需要用日志转档功能来完成,简单的在日志文件名里面设置时间日期字符串就能解决问题:
*.* "aa.%d(%F).log"
或者用cronolog来完成,速度会更快一点
*.* | cronolog aa.%F.log

  1. 按照日志大小切分

多用于开发环境,适合的场景是,程序在短时间内生成大量的日志,而用编辑器vi,ue等能快速打开的日志大小是有限的,或者大的日志打开来极慢。同样的,这种日志的切分可以在事后用split等工具来完成,但对于开发而言会增加步骤,所以最好也是由日志库来完成。值得一提的是存档有两种模式,nlog里面称之为Sequence和Rolling,在Sequence情况下:
aa.log (new)
aa.log.2 (less new)
aa.log.1
aa.log.0 (old)

而在Rolling的情况下:
aa.log (new)
aa.log.0 (less new)
aa.log.1
aa.log.2 (old)

很难说哪种更加符合人的直觉。
如果只有若干个最新的文件是有意义的,需要日志库来做主动的删除旧的工作。由外部程序是很难判定哪些日志是旧的。
最简单的zlog的转档配置为
*.* "aa.log", 10MB
这个配置是Rolling的情况,每次aa.log超过10MB的时候,会做这样的重命名
aa.log.2 -> aa.log.3
aa.log.1 -> aa.log.2
aa.log.0 -> aa.log.1
aa.log -> aa.log.0

上面的配置可以写的更加罗嗦一点
*.* "aa.log", 10MB * 0 ~ "aa.log.#r"
逗号后第一个参数表示文件达到多大后开始进行转档。
第二个参数表示保留多少个存档文件(0代表不删除任何存档文件)。
第三个参数表示转档的文件名,其中#r表示存档文件的序号,r是rolling的缩写。还可以放#s,是sequence的缩写。转档文件名必须包含#r或者#s。

  1. 按照日志大小切分,但同时加上时间标签

aa.log
aa.log-20070305.00.log
aa.log-20070501.00.log
aa.log-20070501.01.log
aa.log-20071008.00.log

这种情况适合于程序本身的日志一般不是很受关注,但是又在某一天想要找出来看的情况。当然,在这种情况下,万一在20070501这一天日志的量超过了指定值,例如100MB,就又要退回到第二种状态,在文件名中加后缀。

zlog对应的配置是
*.* "aa.log", 100MB ~ "aa-%d(%Y%m%d).#2s.log"

每到100MB的时候转档,转档文件名也支持转换字符,可以把转档当时的时间串作为转档文件名的一部分。#2s的意思是序号的长度最少为2位,从00开始编号,Sequence转档。这是zlog对转档最复杂的支持了!

  1. 压缩、移动、删除旧的日志

首先,压缩不应该由日志库来完成,因为压缩消耗时间和CPU。日志库的任务是配合压缩。

对于第一种和第三种,管理较为简单,只要符合某些文件名规则或修改日期的,可以用shell脚本+crontab轻易的压缩、移动和删除。
对于第二种,其实不是非常需要压缩,只需要删除就可以了。

如果一定需要转档的同时进行压缩,只有logrotate能干这活儿,毕竟他是独立的程序,能在转档同时搞压缩,不会有混淆的问题。

  1. zlog对外部转档工具,例如logrotate的支持

zlog的转档功能已经极为强大,当然也有几种情况是zlog无法处理的,例如按时间条件进行转档,转档前后调用一些自制的shell脚本……这会把zlog的配置和表达弄得过于复杂而缺乏美感。

这时候你也许喜欢用一些外部转档工具,例如logrotate来完成工作。问题是,在linux操作系统下,转档工具重命名日志文件名后,应用进程还是往原来的文件描述符写日志,没办法重新打开日志文件写新的日志。标准的做法是给应用程序一个信号,让他重新打开日志文件,对于syslogd是
kill -SIGHUP ‘cat /var/run/syslogd.pid‘
对于zlog,因为是个函数库,不适合接受信号。zlog提供了函数接口zlog_reload(),这个函数会重载配置文件,重新打开所有的日志文件。应用程序在logrotate的信号,或者其他途径,例如客户端的命令后,可以调用这个函数,来重新打开所有的日志文件。

5.7 配置文件工具

  1. $ zlog-chk-conf -h
  2. Useage: zlog-chk-conf [conf files]...
  3. -q, suppress non-error message
  4. -h, show help message

zlog-chk-conf尝试读取配置文件,检查语法,然后往屏幕上输出这些配置文件是否正确。我建议每次创建或者改动一个配置文件之后都用一下这个工具。输出可能是这样:

  1. $ ./zlog-chk-conf zlog.conf
  2. 03-08 15:35:44 ERROR (10595:rule.c:391) sscanf [aaa] fail, category or level is null
  3. 03-08 15:35:44 ERROR (10595:conf.c:155) zlog_rule_new fail [aaa]
  4. 03-08 15:35:44 ERROR (10595:conf.c:258) parse configure file[zlog.conf] line[126] fail
  5. 03-08 15:35:44 ERROR (10595:conf.c:306) zlog_conf_read_config fail
  6. 03-08 15:35:44 ERROR (10595:conf.c:366) zlog_conf_build fail
  7. 03-08 15:35:44 ERROR (10595:zlog.c:66) conf_file[zlog.conf], init conf fail
  8. 03-08 15:35:44 ERROR (10595:zlog.c:131) zlog_init_inner[zlog.conf] fail
  9. ---[zlog.conf] syntax error, see error message above

这个告诉你配置文件zlog.conf的126行,是错的。第一行进一步告诉你[aaa]不是一条正确的规则。
zlog-chk-conf可以同时分析多个配置文件,举例:

  1. $ zlog-chk-conf zlog.conf ylog.conf
  2. --[zlog.conf] syntax right
  3. --[ylog.conf] syntax right

6 zlog接口(API)

zlog的所有函数都是线程安全的,使用的时候只需要#include "zlog.h".

6.1 初始化和清理

总览

  1. int zlog_init(const char *confpath);
  2. int zlog_reload(const char *confpath);
  3. void zlog_fini(void);

描述
zlog_init()从配置文件confpath中读取配置信息到内存。如果confpath为NULL,会寻找环境变量ZLOG_CONF_PATH的值作为配置文件名。如果环境变量ZLOG_CONF_PATH也没有,所有日志以内置格式写到标准输出上。每个进程只有第一次调用zlog_init()是有效的,后面的多余调用都会失败并不做任何事情。

zlog_reload()从confpath重载配置,并根据这个配置文件来重计算内部的分类规则匹配、重建每个线程的缓存、并设置原有的用户自定义输出函数。可以在配置文件发生改变后调用这个函数。这个函数使用次数不限。如果confpath为NULL,会重载上一次zlog_init()或者zlog_reload()使用的配置文件。如果zlog_reload()失败,上一次的配置依然有效。所以zlog_reload()具有原子性。

zlog_fini()清理所有zlog API申请的内存,关闭它们打开的文件。使用次数不限。

返回值
如果成功,zlog_init()和zlog_reload()返回0。失败的话,zlog_init()和zlog_reload()返回-1。详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.2 分类(Category)操作

总览

  1. typedef struct zlog_category_s zlog_category_t;
  2. zlog_category_t *zlog_get_category(const char *cname);

描述
zlog_get_category()从zlog的全局分类表里面找到分类,用于以后输出日志。如果没有的话,就建一个。然后它会遍历所有的规则,寻找和cname匹配的规则并绑定。

配置文件规则中的分类名匹配cname的规律描述如下:

    • 匹配任意cname。
  • 以下划线结尾的分类名同时匹配本级分类和下级分类。例如aa匹配aa, aa_, aa_bb, aa_bb_cc这几个cname。
  • 不以下划线_结尾的分类名精确匹配cname。例如aa_bb匹配aa_bb这个cname。
  • ! 匹配目前还没有规则的cname。

每个zlog_category_t *对应的规则,在zlog_reload()的时候会被自动重新计算。不用担心内存释放,zlog_fini() 最后会清理一切。

返回值
如果成功,返回zlog_category_t的指针。如果失败,返回NULL。详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.3 写日志函数及宏

总览

  1. void zlog(zlog_category_t * category,
  2. const char *file, size_t filelen,
  3. const char *func, size_t funclen,
  4. long line, int level,
  5. const char *format, ...);
  6. void vzlog(zlog_category_t * category,
  7. const char *file, size_t filelen,
  8. const char *func, size_t funclen,
  9. long line, int level,
  10. const char *format, va_list args);
  11. void hzlog(zlog_category_t * category,
  12. const char *file, size_t filelen,
  13. const char *func, size_t funclen,
  14. long line, int level,
  15. const void *buf, size_t buflen);

描述
这3个函数是实际写日志的函数,输入的数据对应于配置文件中的%m。category来自于调用zlog_get_category()。

zlog()和vzlog()根据format输出,就像printf(3)和vprintf(3)。

vzlog()相当于zlog(),只是它用一个va_list类型的参数args,而不是一堆类型不同的参数。vzlog() 内部使用了 va_copy 宏,args的内容在vzlog()后保持不变,可以参考stdarg(3)。

hzlog()有点不一样,它产生下面这样的输出,长度为buf_len的内存buf以16进制的形式表示出来。

  1. hex_buf_len=[5365]
  2. 0 1 2 3 4 5 6 7 8 9 A B C D E F 0123456789ABCDEF
  3. 0000000001 23 21 20 2f 62 69 6e 2f 62 61 73 68 0a 0a 23 20 #! /bin/bash..#
  4. 0000000002 74 65 73 74 5f 68 65 78 20 2d 20 74 65 6d 70 6f test_hex - tempo
  5. 0000000003 72 61 72 79 20 77 72 61 70 70 65 72 20 73 63 72 rary wrapper scr

参数file和line填写为FILELINE这两个宏。这两个宏标识日志是在哪里发生的。参数func 填写为func或者FUNCTION,如果编译器支持的话,如果不支持,就填写为”“。

level是一个整数,应该是在下面几个里面取值。

  1. typedef enum {
  2. ZLOG_LEVEL_DEBUG = 20,
  3. ZLOG_LEVEL_INFO = 40,
  4. ZLOG_LEVEL_NOTICE = 60,
  5. ZLOG_LEVEL_WARN = 80,
  6. ZLOG_LEVEL_ERROR = 100,
  7. ZLOG_LEVEL_FATAL = 120
  8. } zlog_level;

每个函数都有对应的宏,简单使用。例如:

  1. #define zlog_fatal(cat, format, args...) \
  2. zlog(cat, __FILE__, sizeof(__FILE__)-1, \
  3. __func__, sizeof(__func__)-1, __LINE__, \
  4. ZLOG_LEVEL_FATAL, format, ##args)

所有的宏列表:

  1. /* zlog macros */
  2. zlog_fatal(cat, format, ...)
  3. zlog_error(cat, format, ...)
  4. zlog_warn(cat, format, ...)
  5. zlog_notice(cat, format, ...)
  6. zlog_info(cat, format, ...)
  7. zlog_debug(cat, format, ...)
  8. /* vzlog macros */
  9. vzlog_fatal(cat, format, args)
  10. vzlog_error(cat, format, args)
  11. vzlog_warn(cat, format, args)
  12. vzlog_notice(cat, format, args)
  13. vzlog_info(cat, format, args)
  14. vzlog_debug(cat, format, args)
  15. /* hzlog macros */
  16. hzlog_fatal(cat, buf, buf_len)
  17. hzlog_error(cat, buf, buf_len)
  18. hzlog_warn(cat, buf, buf_len)
  19. hzlog_notice(cat, buf, buf_len)
  20. hzlog_info(cat, buf, buf_len)
  21. hzlog_debug(cat, buf, buf_len)

返回值
这些函数不返回。如果有错误发生,详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.4 MDC操作

总览

  1. int zlog_put_mdc(const char *key, const char *value);
  2. char *zlog_get_mdc(const char *key);
  3. void zlog_remove_mdc(const char *key);
  4. void zlog_clean_mdc(void);

描述
MDC(Mapped Diagnostic Context)是一个每线程拥有的键-值表,所以和分类没什么关系。
key和value是字符串,长度不能超过MAXLEN_PATH(1024)。如果超过MAXLEN_PATH(1024)的话,会被截断。
记住这个表是和线程绑定的,每个线程有自己的表,所以在一个线程内的调用不会影响其他线程。

返回值
zlog_put_mdc()成功返回0,失败返回-1。zlog_get_mdc()成功返回value的指针,失败或者没有相应的key返回NULL。如果有错误发生,详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.5 dzlog接口

总览

  1. int dzlog_init(const char *confpath, const char *cname);
  2. int dzlog_set_category(const char *cname);
  3. void dzlog(const char *file, size_t filelen,
  4. const char *func, size_t funclen,
  5. long line, int level,
  6. const char *format, ...);
  7. void vdzlog(const char *file, size_t filelen,
  8. const char *func, size_t funclen,
  9. long line, int level,
  10. const char *format, va_list args);
  11. void hdzlog(const char *file, size_t filelen,
  12. const char *func, size_t funclen,
  13. long line, int level,
  14. const void *buf, size_t buflen);

描述
dzlog是忽略分类(zlog_category_t)的一组简单zlog接口。它采用内置的一个默认分类,这个分类置于锁的保护下。这些接口也是线程安全的。忽略了分类,意味着用户不需要操心创建、存储、传输zlog_category_t类型的变量。当然也可以在用dzlog接口的同时用一般的zlog接口函数,这样会更爽。

dzlog_init()和zlog_init()一样做初始化,就是多需要一个默认分类名cname的参数。zlog_reload()、 zlog_fini() 可以和以前一样使用,用来刷新配置,或者清理。

dzlog_set_category()是用来改变默认分类用的。上一个分类会被替换成新的。同样不用担心内存释放的问题,zlog_fini()最后会清理。

dzlog的宏也定义在zlog.h里面。更简单的写法。

  1. dzlog_fatal(format, ...)
  2. dzlog_error(format, ...)
  3. dzlog_warn(format, ...)
  4. dzlog_notice(format, ...)
  5. dzlog_info(format, ...)
  6. dezlog_debug(format, ...)
  7. vdzlog_fatal(format, args)
  8. vdzlog_error(format, args)
  9. vdzlog_warn(format, args)
  10. vdzlog_notice(format, args)
  11. vdzlog_info(format, args)
  12. vdzlog_debug(format, args)
  13. hdzlog_fatal(buf, buf_len)
  14. hdzlog_error(buf, buf_len)
  15. hdzlog_warn(buf, buf_len)
  16. hdzlog_noticebuf, buf_len)
  17. hdzlog_info(buf, buf_len)
  18. hdzlog_debug(buf, buf_len)

返回值
成功情况下dzlog_init()和dzlog_set_category()返回0。失败情况下dzlog_init()和 dzlog_set_category()返回-1。详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.6 用户自定义输出

总览

  1. typedef struct zlog_msg_s {
  2. char *buf;
  3. size_t len;
  4. char *path;
  5. } zlog_msg_t;
  6. typedef int (*zlog_record_fn)(zlog_msg_t *msg);
  7. int zlog_set_record(const char *rname, zlog_record_fn record);

描述
zlog允许用户自定义输出函数。输出函数需要绑定到某条特殊的规则上。这种规则的例子是:
*.* $name, "record path %c %d"; simple

zlog_set_record()做绑定动作。规则中输出段有$name的,会被用来做用户自定义输出。输出函数为record。这个函数需要为zlog_record_fn的格式。

zlog_msg_t结构的各个成员描述如下:

  • path来自规则的逗号后的字符串,这个字符串会被动态的解析,输出当前的path,就像动态文件路径一样。
  • buf和len 是zlog格式化后的日志信息和长度。

所有zlog_set_record()做的绑定在zlog_reload()使用后继续有效。

返回值
成功情况下zlog_set_record()返回0。失败情况下zlog_set_record()返回-1。详细错误会被写在由环境变量ZLOG_PROFILE_ERROR指定的错误日志里面。

6.7 调试和诊断

总览

  1. void zlog_profile(void);

描述
环境变量ZLOG_PROFILE_ERROR指定zlog本身的错误日志。
环境变量ZLOG_PROFILE_DEBUG指定zlog本身的调试日志。

zlog_profile()打印所有内存中的配置信息到ZLOG_PROFILE_ERROR,在运行时。可以把这个和配置文件比较,看看有没有问题。

7 高阶使用

7.1 MDC

MDC是什么?在log4j里面解释为Mapped Diagnostic Context。听起来是个很复杂的技术,其实MDC就是一个键-值对表。一旦某次你设置了,后面库可以帮你自动打印出来,或者成为文件名的一部分。让我们看一个例子,来自于$(top_builddir)/test/test_mdc.c.

  1. // test_mdc.c
  2. #include <stdio.h>
  3. #include <stdlib.h>
  4. #include <sys/types.h>
  5. #include <unistd.h>
  6. #include <string.h>
  7. #include "zlog.h"
  8. int main(int argc, char** argv)
  9. {
  10. int rc;
  11. zlog_category_t *zc;
  12. rc = zlog_init("test_mdc.conf");
  13. if (rc) {
  14. printf("init failed\n");
  15. return -1;
  16. }
  17. zc = zlog_get_category("my_cat");
  18. if (!zc) {
  19. printf("get cat fail\n");
  20. zlog_fini();
  21. return -2;
  22. }
  23. zlog_info(zc, "1.hello, zlog");
  24. zlog_put_mdc("myname", "Zhang");
  25. zlog_info(zc, "2.hello, zlog");
  26. zlog_put_mdc("myname", "Li");
  27. zlog_info(zc, "3.hello, zlog");
  28. zlog_fini();
  29. return 0;
  30. }

配置文件

  1. # test_mdc.conf
  2. [formats]
  3. mdc_format= "%d(%F %X.%ms) %-6V (%c:%F:%L) [%M(myname)] - %m%n"
  4. [rules]
  5. *.* >stdout; mdc_format

输出

  1. $ ./test_mdc
  2. 2012-03-12 09:26:37.740 INFO (my_cat:test_mdc.c:47) [] - 1.hello, zlog
  3. 2012-03-12 09:26:37.740 INFO (my_cat:test_mdc.c:51) [Zhang] - 2.hello, zlog
  4. 2012-03-12 09:26:37.740 INFO (my_cat:test_mdc.c:55) [Li] - 3.hello, zlog

你可以看到zlog_put_mdc()在表里面设置键”myname”对应值”Zhang”,然后在配置文件里面%M(myname)指出了在日志的哪个位置需要把值打出来。第二次,键”myname”的值被覆盖写成”Li”,然后日志里面也有相应的变化。

MDC什么时候有用呢?往往在用户需要在同样的日志行为区分不同的业务数据的时候。比如说,c源代码是

  1. zlog_put_mdc("customer_name", get_customer_name_from_db() );
  2. zlog_info("get in");
  3. zlog_info("pick product");
  4. zlog_info("pay");
  5. zlog_info("get out");

在配置文件里面是
&format "%M(customer_name) %m%n"

当程序同时处理两个用户的时候,打出来的日志可能是

Zhang get in Li get in Zhang pick product Zhang pay Li pick product Li pay Zhang get out Li get out

这样,你就可以用grep命令把这两个用户的日志分开来了

  1. $ grep Zhang aa.log > Zhang.log
  2. $ grep Li aa.log >Li.log

或者,还有另外一条路,一开始在文件名里面做区分,看配置文件:
*.* "mdc_%M(customer_name).log";
这就会产生3个日志文件。

  • mdc_.log
  • mdc_Zhang.log
  • mdc_Li.log

这是一条近路,如果用户知道自己在干什么。

MDC是每个线程独有的,所以可以把一些线程专有的变量设置进去。如果单单为了区分线程,可以用转换字符里面的%t来搞定。

7.2 诊断zlog本身

OK,至今为止,我假定zlog库本身是不出毛病的。zlog帮助用户程序写日志,帮助程序员debug程序。但是如果zlog内部出错了呢?怎么知道错在哪里呢?其他的程序可以用日志库来debug,但日志库自己怎么debug?答案很简单,zlog有自己的日志——诊断日志。这个日志通常是关闭的,可以通过环境变量来打开。

  1. $ export ZLOG_PROFILE_DEBUG=/tmp/zlog.debug.log
  2. $ export ZLOG_PROFILE_ERROR=/tmp/zlog.error.log

诊断日志只有两个级别debug和error。设置好环境变量后. 再跑test_hello程序3.3,然后debug日志为

  1. $ more zlog.debug.log
  2. 03-13 09:46:56 DEBUG (7503:zlog.c:115) ------zlog_init start, compile time[Mar 13 2012 11:28:56]------
  3. 03-13 09:46:56 DEBUG (7503:spec.c:825) spec:[0x7fdf96b7c010][%d(%F %T)][%F %T 29][]
  4. 03-13 09:46:56 DEBUG (7503:spec.c:825) spec:[0x7fdf96b52010][ ][ 0][]
  5. ......
  6. 03-13 09:52:40 DEBUG (8139:zlog.c:291) ------zlog_fini end------

zlog.error.log日志没产生,因为没有错误发生。

你可以看出来,debug日志展示了zlog是怎么初始化还有清理的。不过在zlog_info()执行的时候没有日志打出来,这是为了效率。

如果zlog库有任何问题,都会打日志到ZLOG_PROFILE_ERROR所指向的错误日志。比如说,在zlog_info()上用一个错误的printf的语法:
zlog_info(zc, "%l", 1);
然后编译执行程序,ZLOG_PROFILE_ERROR的日志会是

  1. $ cat zlog.error.log
  2. 03-13 10:04:58 ERROR (10102:buf.c:189) vsnprintf fail, errno[0]
  3. 03-13 10:04:58 ERROR (10102:buf.c:191) nwrite[-1], size_left[1024], format[%l]
  4. 03-13 10:04:58 ERROR (10102:spec.c:329) zlog_buf_vprintf maybe fail or overflow
  5. 03-13 10:04:58 ERROR (10102:spec.c:467) a_spec->gen_buf fail
  6. 03-13 10:04:58 ERROR (10102:format.c:160) zlog_spec_gen_msg fail
  7. 03-13 10:04:58 ERROR (10102:rule.c:265) zlog_format_gen_msg fail
  8. 03-13 10:04:58 ERROR (10102:category.c:164) hzb_log_rule_output fail
  9. 03-13 10:04:58 ERROR (10102:zlog.c:632) zlog_output fail, srcfile[test_hello.c], srcline[41]

这样,用户就能知道为啥期待的输出没有产生,然后搞定这个问题。

运行时诊断会带来一定的性能损失。一般来说,我在生产环境把ZLOG_PROFILE_ERROR打开,ZLOG_PROFILE_DEBUG关闭。

还有另外一个办法来诊断zlog。我们都知道,zlog_init()会把配置信息读入内存。在整个写日志的过程中,这块内存保持不变。如果用户程序因为某种原因损坏了这块内存,那么就会造成问题。还有可能是内存中的信息和配置文件的信息不匹配。所以我设计了一个函数,把内存的信息展现到ZLOG_PROFILE_ERROR指向的错误日志。

代码见$(top_builddir)/test/test_profile.c

  1. #include <stdio.h>
  2. #include "zlog.h"
  3. int main(int argc, char** argv)
  4. {
  5. int rc;
  6. rc = dzlog_init("test_profile.conf", "my_cat");
  7. if (rc) {
  8. printf("init failed\n");
  9. return -1;
  10. }
  11. dzlog_info("hello, zlog");
  12. zlog_profile();
  13. zlog_fini();
  14. return 0;
  15. }

zlog_profile()就是这个函数。配置文件很简单。

  1. # test_profile.conf
  2. [formats]
  3. simple = "%m%n"
  4. [rules]
  5. my_cat.* >stdout; simple

然后zlog.error.log会是

  1. $ cat /tmp/zlog.error.log
  2. 06-01 11:21:26 WARN (7063:zlog.c:783) ------zlog_profile start------
  3. 06-01 11:21:26 WARN (7063:zlog.c:784) init_flag:[1]
  4. 06-01 11:21:26 WARN (7063:conf.c:75) -conf[0x2333010]-
  5. 06-01 11:21:26 WARN (7063:conf.c:76) --global--
  6. 06-01 11:21:26 WARN (7063:conf.c:77) ---file[test_profile.conf],mtime[2012-06-01 11:20:44]---
  7. 06-01 11:21:26 WARN (7063:conf.c:78) ---strict init[1]---
  8. 06-01 11:21:26 WARN (7063:conf.c:79) ---buffer min[1024]---
  9. 06-01 11:21:26 WARN (7063:conf.c:80) ---buffer max[2097152]---
  10. 06-01 11:21:26 WARN (7063:conf.c:82) ---default_format---
  11. 06-01 11:21:26 WARN (7063:format.c:48) ---format[0x235ef60][default = %d(%F %T) %V [%p:%F:%L] %m%n(0x233b810)]---
  12. 06-01 11:21:26 WARN (7063:conf.c:85) ---file perms[0600]---
  13. 06-01 11:21:26 WARN (7063:conf.c:87) ---rotate lock file[/tmp/zlog.lock]---
  14. 06-01 11:21:26 WARN (7063:rotater.c:48) --rotater[0x233b7d0][0x233b7d0,/tmp/zlog.lock,4]--
  15. 06-01 11:21:26 WARN (7063:level_list.c:37) --level_list[0x2335490]--
  16. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x23355c0][0,*,*,1,6]---
  17. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x23375e0][20,DEBUG,debug,5,7]---
  18. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x2339600][40,INFO,info,4,6]---
  19. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x233b830][60,NOTICE,notice,6,5]---
  20. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x233d850][80,WARN,warn,4,4]---
  21. 06-01 11:21:26 WARN (7063:level.c:37) ---level[0x233fc80][100,ERROR,error,5,3]---

7.3 用户自定义等级

这里我把用户自定义等级的几个步骤写下来。

  1. 在配置文件中定义新的等级 ```properties

    test_level.conf

    [global] default format = “%V %v %m%n”

[levels] TRACE = 30, LOG_DEBUG

[rules] my_cat.TRACE >stdout;

  1. 内置的默认等级是(这些不需要写在配置文件里面)
  2. ```latex
  3. DEBUG = 20, LOG_DEBUG
  4. INFO = 40, LOG_INFO
  5. NOTICE = 60, LOG_NOTICE
  6. WARN = 80, LOG_WARNING
  7. ERROR = 100, LOG_ERR
  8. FATAL = 120, LOG_ALERT
  9. UNKNOWN = 254, LOG_ERR

这样在zlog看来,一个整数(30)还有一个等级字符串(TRACE)代表了等级。这个整数必须位于[1,253]之间,其他数字是非法的。数字越大代表越重要。现在TRACE比DEBUG重要(30>20),比INFO等级低(30<40)。在这样的定义后,TRACE就可以在下面的配置文件里面用了。例如这句话:
my_cat.TRACE >stdout;
意味着等级>=TRACE的,包括INFO, NOTICE, WARN, ERROR, FATAL会被写到标准输出。

格式里面的转换字符%V会产生等级字符串的大写输出,%v会产生小写的等级字符串输出。

另外,在等级的定义里面,LOG_DEBUG是指当需要输出到syslog的时候,自定义的TRACE等级会以LOG_DEBUG输出到syslog。

  1. 在源代码里面直接用新的等级是这么搞的
    1. zlog(cat, __FILE__, sizeof(__FILE__)-1, \
    2. __func__, sizeof(__func__)-1,__LINE__, \
    3. 30, "test %d", 1);
    为了简单使用,创建一个.h头文件 ```c // test_level.h

    ifndef __test_level_h

    define __test_level_h

    include “zlog.h”

enum { ZLOG_LEVEL_TRACE = 30, / must equals conf file setting / };

define zlog_trace(cat, format, …) \

  1. zlog(cat, __FILE__, sizeof(__FILE__)-1, \
  2. __func__, sizeof(__func__)-1, __LINE__, \
  3. ZLOG_LEVEL_TRACE, format, ## __VA_ARGS__)

endif

  1. 3. 这样zlog_trace就能在.c文件里面用了
  2. ```c
  3. // test_level.c
  4. #include <stdio.h>
  5. #include "test_level.h"
  6. int main(int argc, char** argv)
  7. {
  8. int rc;
  9. zlog_category_t *zc;
  10. rc = zlog_init("test_level.conf");
  11. if (rc) {
  12. printf("init failed\n");
  13. return -1;
  14. }
  15. zc = zlog_get_category("my_cat");
  16. if (!zc) {
  17. printf("get cat fail\n");
  18. zlog_fini();
  19. return -2;
  20. }
  21. zlog_trace(zc, "hello, zlog - trace");
  22. zlog_debug(zc, "hello, zlog - debug");
  23. zlog_info(zc, "hello, zlog - info");
  24. zlog_fini();
  25. return 0;
  26. }
  1. 最后我们能看到输出

    1. $ ./test_level
    2. TRACE trace hello, zlog - trace
    3. INFO info hello, zlog - info

    正是我们所期待的,配置文件只允许>=TRACE等级的日志输出到屏幕上。%V和%v也显示了正确的结果。

    7.4 用户自定义输出

    用户自定义输出的意义是zlog放弃一些权力。zlog只负责动态生成单条日志和文件路径,但怎么输出、转档、清理等等工作由用户按照自己的需求自行写函数完成。写完函数只要绑定到某个规则就可以。这里我把用户自定义输出的几个步骤写下来。

  2. 在配置文件里面定义规则 ```properties

    test_record.conf

    [formats] simple = “%m%n”

[rules] my_cat.* $myoutput, “ mypath %c %d”;simple

  1. 2. 绑定一个函数到$myoutput,并使用之
  2. ```c
  3. #include <stdio.h>
  4. #include "zlog.h"
  5. int output(zlog_msg_t *msg)
  6. {
  7. printf("[mystd]:[%s][%s][%ld]\n", msg->path, msg->buf, (long)msg->len);
  8. return 0;
  9. }
  10. int main(int argc, char** argv)
  11. {
  12. int rc;
  13. zlog_category_t *zc;
  14. rc = zlog_init("test_record.conf");
  15. if (rc) {
  16. printf("init failed\n");
  17. return -1;
  18. }
  19. zlog_set_record("myoutput", output);
  20. zc = zlog_get_category("my_cat");
  21. if (!zc) {
  22. printf("get cat fail\n");
  23. zlog_fini();
  24. return -2;
  25. }
  26. zlog_info(zc, "hello, zlog");
  27. zlog_fini();
  28. return 0;
  29. }
  1. 最后我们发现用户自定义输出的函数能用了!

    1. $ ./test_record
    2. [mystd]:[ mypath my_cat 2012-07-19 12:23:08][hello, zlog][12]

    正如你所见,msglen是12,zlog生成的msg在最后有一个换行符。

  2. 用户自定义输出可以干很多神奇的事情,就像某个用户(flw@newsmth.net)的需求

    • 日志文件名为 foo.log
    • 如果 foo.log 超过 100M,则生成一个新文件,其中包含的就是 foo.log 目前的内容 而 foo.log 则变为空,重新开始增长
    • 如果距离上次生成文件时已经超过 5 分钟,则即使不到 100M,也应当生成一个新文件
    • 新文件名称可以自定义,比如加上设备名作为前缀、日期时间串作为后缀
    • 我希望这个新文件可以被压缩,以节省磁盘空间或者网络带宽。
    • 但愿他能顺利写出这个需求的代码!在多进程或者多线程的情况下!上帝保佑他!

8 尾声

好酒!所有人生问题的终极起源和终极答案。

—荷马.辛普森

有问题,在github上开个issue,或者写邮件到 HardySimpson1984@gmail.com

参考