From 77c166ba6cf6fe8f7e9737b7fe1793d886dd5cf8 Mon Sep 17 00:00:00 2001 From: Tom Lane Date: Thu, 27 Sep 2007 18:15:36 +0000 Subject: [PATCH] Add virtual transaction IDs to CSVLOG output, so that messages coming from the same transaction can be identified even when no regular XID was assigned. This seems essential after addition of the lazy-XID patch. Also some minor code cleanup in write_csvlog(). --- doc/src/sgml/config.sgml | 16 ++++++------- src/backend/utils/error/elog.c | 43 ++++++++++++++-------------------- 2 files changed, 25 insertions(+), 34 deletions(-) diff --git a/doc/src/sgml/config.sgml b/doc/src/sgml/config.sgml index 3d7dddc1ac..c146357f89 100644 --- a/doc/src/sgml/config.sgml +++ b/doc/src/sgml/config.sgml @@ -1,4 +1,4 @@ - + Server Configuration @@ -3123,8 +3123,8 @@ SELECT * FROM parent WHERE key = 2400; This option emits log lines in comma-separated-value format, with these columns: timestamp with milliseconds, username, database name, session id, host:port number, process id, per-process line - number, command tag, session start time, transaction id, error - severity, SQL state code, statement/error message. + number, command tag, session start time, virtual transaction id, + regular transaction id, error severity, SQL state code, error message. Here is a sample table definition for storing CSV-format log output: @@ -3132,23 +3132,23 @@ SELECT * FROM parent WHERE key = 2400; CREATE TABLE postgres_log ( log_time timestamp with time zone, - username text, + user_name text, database_name text, - sessionid text, + session_id text, connection_from text, process_id integer, process_line_num bigint, command_tag text, session_start_time timestamp with time zone, + virtual_transaction_id text, transaction_id bigint, error_severity text, sql_state_code text, - statement text, - PRIMARY KEY (sessionid, process_line_num) + message text, + PRIMARY KEY (session_id, process_line_num) ); - To import a log file into this table, use the COPY FROM command: diff --git a/src/backend/utils/error/elog.c b/src/backend/utils/error/elog.c index e8a3ed3db0..c8ba87efab 100644 --- a/src/backend/utils/error/elog.c +++ b/src/backend/utils/error/elog.c @@ -42,7 +42,7 @@ * * * IDENTIFICATION - * $PostgreSQL: pgsql/src/backend/utils/error/elog.c,v 1.196 2007/09/05 18:10:48 tgl Exp $ + * $PostgreSQL: pgsql/src/backend/utils/error/elog.c,v 1.197 2007/09/27 18:15:36 tgl Exp $ * *------------------------------------------------------------------------- */ @@ -1636,10 +1636,9 @@ appendCSVLiteral(StringInfo buf, const char* data) /* * Constructs the error message, depending on the Errordata it gets, - * in CSV (comma seperated values) format. The COPY command + * in CSV (comma separated values) format. The COPY command * can then be used to load the messages into a table. */ - static void write_csvlog(ErrorData *edata) { @@ -1672,8 +1671,8 @@ write_csvlog(ErrorData *edata) * The format of the log output in CSV format: * timestamp with milliseconds, username, databasename, session id, * host and port number, process id, process line number, command tag, - * session start time, transaction id, error severity, sql state code, - * statement or error message. + * session start time, virtual transaction id, regular transaction id, + * error severity, sql state code, error message. */ /* timestamp_with_milliseconds */ @@ -1737,29 +1736,25 @@ write_csvlog(ErrorData *edata) appendStringInfoChar(&buf, ','); /* session id */ - appendStringInfo(&buf, "%lx.%x", - (long) MyStartTime, MyProcPid); + appendStringInfo(&buf, "%lx.%x", (long) MyStartTime, MyProcPid); appendStringInfoChar(&buf, ','); - /* Remote host and port */ + /* Remote host and port (is it safe to not quote this?) */ if (MyProcPort && MyProcPort->remote_host) { appendStringInfo(&buf, "%s", MyProcPort->remote_host); if (MyProcPort->remote_port && MyProcPort->remote_port[0] != '\0') appendStringInfo(&buf, ":%s", MyProcPort->remote_port); } - appendStringInfoChar(&buf, ','); /* Process id */ if (MyProcPid != 0) appendStringInfo(&buf, "%d", MyProcPid); - appendStringInfoChar(&buf, ','); /* Line number */ appendStringInfo(&buf, "%ld", log_line_number); - appendStringInfoChar(&buf, ','); /* PS display */ @@ -1773,16 +1768,13 @@ write_csvlog(ErrorData *edata) appendCSVLiteral(&buf, msgbuf.data); resetStringInfo(&msgbuf); } - appendStringInfoChar(&buf, ','); /* session start timestamp */ if (formatted_start_time[0] == '\0') { pg_time_t stamp_time = (pg_time_t) MyStartTime; - pg_tz *tz; - - tz = log_timezone ? log_timezone : gmt_timezone; + pg_tz *tz = log_timezone ? log_timezone : gmt_timezone; pg_strftime(formatted_start_time, FORMATTED_TS_LEN, "%Y-%m-%d %H:%M:%S %Z", @@ -1791,22 +1783,21 @@ write_csvlog(ErrorData *edata) appendStringInfoString(&buf, formatted_start_time); appendStringInfoChar(&buf, ','); + /* Virtual transaction id */ + /* keep VXID format in sync with lockfuncs.c */ + if (MyProc != NULL) + appendStringInfo(&buf, "%d/%u", MyProc->backendId, MyProc->lxid); + appendStringInfoChar(&buf, ','); + /* Transaction id */ appendStringInfo(&buf, "%u", GetTopTransactionIdIfAny()); - appendStringInfoChar(&buf, ','); /* Error severity */ - if (error_severity(edata->elevel) != NULL) - appendStringInfo(&buf, "%s,", error_severity(edata->elevel)); - else - appendStringInfoString(&buf, ","); - + appendStringInfo(&buf, "%s,", error_severity(edata->elevel)); + /* SQL state code */ - if (Log_error_verbosity >= PGERROR_VERBOSE) - appendStringInfo(&buf, "%s", - unpack_sql_state(edata->sqlerrcode)); - appendStringInfoChar(&buf, ','); + appendStringInfo(&buf, "%s,", unpack_sql_state(edata->sqlerrcode)); /* Error message and cursor position if any */ get_csv_error_message(&buf, edata); @@ -1830,7 +1821,7 @@ write_csvlog(ErrorData *edata) static void get_csv_error_message(StringInfo buf, ErrorData *edata) { - char *msg = edata->message ? edata-> message : _("missing error text"); + char *msg = edata->message ? edata->message : _("missing error text"); char c; appendStringInfoCharMacro(buf, '"');