如何在PG的function里面记录每一步骤的时间呢？

Question

发现了PG的一个问题，就是当我在一个function中记录不同的now()的时候，实际上这些now()是同一个值。案例如下：

--创建日志表
create table dw_sp_log
(
    log_no serial,
    log_time varchar(60),
    schema_nm varchar(60),
    proc_nm varchar(60),
    log_level varchar(60),
    log_msg text
);
--创建日志存储过程
create or replace function SP_CM_GEN_LOG(
in vc_log_time varchar,
in vc_schema varchar,
in vc_proc varchar,
in vc_level varchar,
in vc_msg text
)
returns void as $$
    declare

    begin
        insert into dw_sp_log(log_time,schema_nm, proc_nm, log_level, log_msg) values(vc_log_time,vc_schema,vc_proc,vc_level,vc_msg);
    end;
$$  language plpgsql;
--在存储过程中记录日志
create or replace function RUN_SP_CM_GEN_LOG(
)
returns void as $$
    declare

    begin
        perform SP_CM_GEN_LOG(now()::text,'1','1','1','1');
        perform pg_sleep(5);
        perform SP_CM_GEN_LOG(now()::text,'2','2','2','2');
    end;
$$  language plpgsql;

执行select RUN_SP_CM_GEN_LOG()会发现里面记录的log_time是相同的。但是实际上应该差5秒才对，请问如何实现呢？谢谢大家

Answer 1

now()（=transaction_timestamp()）返回的是在事务中第一次被调用的时间。因为函数执行过程是包裹在一个事务中的，因此函数中所有的now()返回的都和第一次相同。

记录日志时间的话，用statement_timestamp()或者clock_timestamp()吧。

文档参考