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
语句是通过组件自动拼接的结果,仅供参考。