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

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

    1. database:
    2. logger:
    3. - path: "/var/log/gf-app/sql"
    4. level: "all"
    5. stdout: true
    6. default:
    7. - link: "mysql:root:12345678@tcp(127.0.0.1:3306)/user"
    8. debug: true

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

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

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

    可以看到,日志包含以下几部分信息:

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