ORM高级特性-日志输出

ORM日志的输出，是在提交底层数据库driver之前，ORM将链式操作或者SQL构成的模板与执行参数进行格式化打印展示，供人工阅读调试。由于底层driver可能会对提交参数进行二次的转换，所以这里的日志输出仅供参考，并不完全是底层真正执行的SQL语句。

日志输出往往是打印一些调试或者SQL语句，日志对象可以通过SetLogger/GetLogger方法来设置，也可以通过配置文件来做配置，日志的配置请查看ORM的 ORM使用配置章节。以下是一个开启了日志输出的配置示例：

database:
  logger:
  - path:   "/var/log/gf-app/sql"
    level:  "all"
    stdout: true
  default:
  - link:  "mysql:root:12345678@tcp(127.0.0.1:3306)/user"
    debug: true

需要注意这里使用关键字logger作为ORM的日志配置项名称，因此您无法使用该名字作为数据库配置分组。

ORM组件输出的日志相当详尽，我们来看一个示例：

2021-05-22 21:12:10.776 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  4 ms] [default] [rows:0  ] [txid:1] BEGIN
2021-05-22 21:12:10.776 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  0 ms] [default] [rows:0  ] [txid:1] SAVEPOINT `transaction0`
2021-05-22 21:12:10.789 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [ 13 ms] [default] [rows:8  ] [txid:1] SHOW FULL COLUMNS FROM `user`
2021-05-22 21:12:10.790 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  1 ms] [default] [rows:1  ] [txid:1] INSERT INTO `user`(`id`,`name`) VALUES(1,'john') 
2021-05-22 21:12:10.791 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  1 ms] [default] [rows:0  ] [txid:1] ROLLBACK TO SAVEPOINT `transaction0`
2021-05-22 21:12:10.791 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  0 ms] [default] [rows:1  ] [txid:1] INSERT INTO `user`(`id`,`name`) VALUES(2,'smith') 
2021-05-22 21:12:10.792 [DEBU] {38d45cbf2743db16f1062074f7473e5c} [  1 ms] [default] [rows:0  ] [txid:1] COMMIT

可以看到，日志包含以下几部分信息：

日期及时间，精确到毫秒。
日志级别。因为SQL日志主要用于功能调试/问题排查，生产环境往往需要关闭掉，因此日志级别固定为DEBUG级别。
当前SQL执行耗时。从客户端发起请求到接收到数据的时间，单位为毫秒。当执行时间不足1毫秒时，展示为0毫秒。
当前SQL所处的数据库配置分组，默认为default。关于配置分组的介绍具体请参考章节：ORM使用配置。
当前SQL所属的事务ID。如果当前SQL不属于事务操作时，不存在该字段。关于事务ID的介绍请参考章节：ORM事务处理。
具体执行的SQL语句。需要注意的是，由于底层使用的是SQL预处理，这里的SQL语句是通过组件自动拼接的结果，仅供参考。

Space shortcuts

Page tree

1 Comment

wwmin