Add log_duration to GUC/postgresql.conf.
authorBruce Momjian
Sun, 1 Sep 2002 23:26:06 +0000 (23:26 +0000)
committerBruce Momjian
Sun, 1 Sep 2002 23:26:06 +0000 (23:26 +0000)
Rename debug_print_query to log_statement and rename show_query_stats to
show_statement_stats.

doc/src/sgml/func.sgml
doc/src/sgml/runtime.sgml
src/backend/tcop/postgres.c
src/backend/utils/misc/guc.c
src/backend/utils/misc/postgresql.conf.sample
src/bin/psql/tab-complete.c
src/include/utils/guc.h

index e954f8e7bf9878d98c15a94cfcad9a0d8f08f39e..ee8cc7d3f600e49bb4b3a1b94171409387b19425 100644 (file)
@@ -1,5 +1,5 @@
 
 
@@ -5241,7 +5241,7 @@ select current_setting('DateStyle');
     false instead. It is the equivalent to the SQL
     SET command. For example:
 
-select set_config('show_query_stats','off','f');
+select set_config('show_statement_stats','off','f');
  set_config
 ------------
  off
index fbbe6274dc091287864d57238fc2e5d9a929cd27..42677b20e14ccb90a9f43c685f7bfe782ca5a8d6 100644 (file)
@@ -1,5 +1,5 @@
 
 
 
@@ -928,7 +928,6 @@ env PGOPTIONS='-c geqo=off' psql
      
 
      
-      DEBUG_PRINT_QUERY (boolean)
       DEBUG_PRINT_PARSE (boolean)
       DEBUG_PRINT_REWRITTEN (boolean)
       DEBUG_PRINT_PLAN (boolean)
@@ -992,6 +991,26 @@ env PGOPTIONS='-c geqo=off' psql
       
      
 
+     
+      LOG_STATEMENT (boolean)
+      
+       
+        Prints each query received.
+       
+      
+     
+
+     
+      LOG_DURATION (boolean)
+      
+       
+        Prints the duration of every completed query.  To use this option, 
+        enable LOG_STATEMENT and LOG_PID so you can link the original query
+        to the duration using the process id.
+       
+      
+     
+
      
       LOG_TIMESTAMP (boolean)
       
@@ -1003,7 +1022,7 @@ env PGOPTIONS='-c geqo=off' psql
      
 
      
-      SHOW_QUERY_STATS (boolean)
+      SHOW_STATEMENT_STATS (boolean)
       SHOW_PARSER_STATS (boolean)
       SHOW_PLANNER_STATS (boolean)
       SHOW_EXECUTOR_STATS (boolean)
@@ -2072,7 +2091,7 @@ dynamic_library_path = '/usr/local/lib/postgresql:/home/my_project/lib:$libdir'
        
        
         
-        show_query_stats = on
+        show_statement_stats = on
         *
        
        
index 3947755a2e321ed0dd30d383e96b00d0c5b9e485..282cc7ded7cd0e6042366a73200cf296312e7ec1 100644 (file)
@@ -8,7 +8,7 @@
  *
  *
  * IDENTIFICATION
- *   $Header: /cvsroot/pgsql/src/backend/tcop/postgres.c,v 1.287 2002/08/30 22:18:06 tgl Exp $
+ *   $Header: /cvsroot/pgsql/src/backend/tcop/postgres.c,v 1.288 2002/09/01 23:26:06 momjian Exp $
  *
  * NOTES
  *   this is the "main" module of the postgres backend and
@@ -220,7 +220,7 @@ InteractiveBackend(StringInfo inBuf)
     * if the query echo flag was given, print the query..
     */
    if (EchoQuery)
-       printf("query: %s\n", inBuf->data);
+       printf("statement: %s\n", inBuf->data);
    fflush(stdout);
 
    return 'Q';
@@ -372,7 +372,7 @@ pg_parse_query(StringInfo query_string, Oid *typev, int nargs)
 {
    List       *raw_parsetree_list;
 
-   if (Debug_print_query)
+   if (Log_statement)
        elog(LOG, "query: %s", query_string->data);
 
    if (Show_parser_stats)
@@ -561,9 +561,19 @@ pg_exec_query_string(StringInfo query_string,      /* string to execute */
    MemoryContext oldcontext;
    List       *parsetree_list,
               *parsetree_item;
-
+   struct timezone tz;
+   struct timeval start_t, stop_t;
+   bool        save_Log_duration = Log_duration;
+   
    debug_query_string = query_string->data;    /* used by pgmonitor */
 
+   /*
+    *  We use save_Log_duration so setting Log_duration to true doesn't
+    *  report incorrect time because gettimeofday() wasn't called.
+    */
+   if (save_Log_duration)
+       gettimeofday(&start_t, &tz);
+
    /*
     * Start up a transaction command.  All queries generated by the
     * query_string will be in this same command block, *unless* we find a
@@ -850,6 +860,19 @@ pg_exec_query_string(StringInfo query_string,      /* string to execute */
    if (xact_started)
        finish_xact_command();
 
+   if (save_Log_duration)
+   {
+       gettimeofday(&stop_t, &tz);
+       if (stop_t.tv_usec < start_t.tv_usec)
+       {
+           stop_t.tv_sec--;
+           stop_t.tv_usec += 1000000;
+       }
+       elog(LOG, "duration: %ld.%06ld sec",
+           (long int) stop_t.tv_sec - start_t.tv_sec,
+           (long int) stop_t.tv_usec - start_t.tv_usec);
+   }
+       
    debug_query_string = NULL;  /* used by pgmonitor */
 }
 
@@ -1234,7 +1257,7 @@ PostgresMain(int argc, char *argv[], const char *username)
                        if (atoi(optarg) >= 1)
                            SetConfigOption("log_connections", "true", ctx, gucsource);
                        if (atoi(optarg) >= 2)
-                           SetConfigOption("debug_print_query", "true", ctx, gucsource);
+                           SetConfigOption("log_statement", "true", ctx, gucsource);
                        if (atoi(optarg) >= 3)
                            SetConfigOption("debug_print_parse", "true", ctx, gucsource);
                        if (atoi(optarg) >= 4)
@@ -1377,7 +1400,7 @@ PostgresMain(int argc, char *argv[], const char *username)
                /*
                 * s - report usage statistics (timings) after each query
                 */
-               SetConfigOption("show_query_stats", "true", ctx, gucsource);
+               SetConfigOption("show_statement_stats", "true", ctx, gucsource);
                break;
 
            case 't':
@@ -1489,11 +1512,11 @@ PostgresMain(int argc, char *argv[], const char *username)
    /*
     * Post-processing for command line options.
     */
-   if (Show_query_stats &&
+   if (Show_statement_stats &&
        (Show_parser_stats || Show_planner_stats || Show_executor_stats))
    {
        elog(WARNING, "Query statistics are disabled because parser, planner, or executor statistics are on.");
-       SetConfigOption("show_query_stats", "false", ctx, gucsource);
+       SetConfigOption("show_statement_stats", "false", ctx, gucsource);
    }
 
    if (!IsUnderPostmaster)
@@ -1664,7 +1687,7 @@ PostgresMain(int argc, char *argv[], const char *username)
    if (!IsUnderPostmaster)
    {
        puts("\nPOSTGRES backend interactive interface ");
-       puts("$Revision: 1.287 $ $Date: 2002/08/30 22:18:06 $\n");
+       puts("$Revision: 1.288 $ $Date: 2002/09/01 23:26:06 $\n");
    }
 
    /*
@@ -1887,7 +1910,7 @@ PostgresMain(int argc, char *argv[], const char *username)
                     * Note: transaction command start/end is now done within
                     * pg_exec_query_string(), not here.
                     */
-                   if (Show_query_stats)
+                   if (Show_statement_stats)
                        ResetUsage();
 
                    pgstat_report_activity(parser_input->data);
@@ -1896,7 +1919,7 @@ PostgresMain(int argc, char *argv[], const char *username)
                                         whereToSendOutput,
                                         QueryContext);
 
-                   if (Show_query_stats)
+                   if (Show_statement_stats)
                        ShowUsage("QUERY STATISTICS");
                }
                break;
index 6000493a85357dc154005dc6cc69af98cfdf8bf5..e07421a87cfa97b844c4b80b6b1ae228bc033ae7 100644 (file)
@@ -5,7 +5,7 @@
  * command, configuration file, and command line options.
  * See src/backend/utils/misc/README for more information.
  *
- * $Header: /cvsroot/pgsql/src/backend/utils/misc/guc.c,v 1.89 2002/08/30 22:18:07 tgl Exp $
+ * $Header: /cvsroot/pgsql/src/backend/utils/misc/guc.c,v 1.90 2002/09/01 23:26:06 momjian Exp $
  *
  * Copyright 2000 by PostgreSQL Global Development Group
  * Written by Peter Eisentraut .
@@ -77,7 +77,8 @@ static const char *assign_facility(const char *facility,
 #ifdef USE_ASSERT_CHECKING
 bool       assert_enabled = true;
 #endif
-bool       Debug_print_query = false;
+bool       Log_statement = false;
+bool       Log_duration = false;
 bool       Debug_print_plan = false;
 bool       Debug_print_parse = false;
 bool       Debug_print_rewritten = false;
@@ -86,7 +87,7 @@ bool      Debug_pretty_print = false;
 bool       Show_parser_stats = false;
 bool       Show_planner_stats = false;
 bool       Show_executor_stats = false;
-bool       Show_query_stats = false;   /* this is sort of all three above
+bool       Show_statement_stats = false;   /* this is sort of all three above
                                         * together */
 bool       Show_btree_build_stats = false;
 
@@ -362,7 +363,11 @@ static struct config_bool
 #endif
 
    {
-       { "debug_print_query", PGC_USERSET }, &Debug_print_query,
+       { "log_statement", PGC_USERSET }, &Log_statement,
+       false, NULL, NULL
+   },
+   {
+       { "log_duration", PGC_USERSET }, &Log_duration,
        false, NULL, NULL
    },
    {
@@ -395,7 +400,7 @@ static struct config_bool
        false, NULL, NULL
    },
    {
-       { "show_query_stats", PGC_USERSET }, &Show_query_stats,
+       { "show_statement_stats", PGC_USERSET }, &Show_statement_stats,
        false, NULL, NULL
    },
 #ifdef BTREE_BUILD_STATS
index 1141e3c6042eb98ece730fc8f16ca283bf928c4b..3bcf77c52ce09ac47926628c6446c4f84343dc92 100644 (file)
 #geqo = true
 #geqo_selection_bias = 2.0 # range 1.5-2.0
 #geqo_threshold = 11
-#geqo_pool_size = 0        # default based on tables in query
+#geqo_pool_size = 0        # default based on tables in statement
                # range 128-1024
 #geqo_effort = 1
 #geqo_generations = 0
 #silent_mode = false
 
 #log_connections = false
-#log_timestamp = false
 #log_pid = false
+#log_statement = false
+#log_duration = false
+#log_timestamp = false
 
-#debug_print_query = false
 #debug_print_parse = false
 #debug_print_rewritten = false
 #debug_print_plan = false
 #show_parser_stats = false
 #show_planner_stats = false
 #show_executor_stats = false
-#show_query_stats = false
+#show_statement_stats = false
 
 # requires BTREE_BUILD_STATS
 #show_btree_build_stats = false
index 0ff42b5ee3e86dfcdbadc2b28d9537a492918e5c..3611eaec442cce674f0adaa34ae2444585a9ad37 100644 (file)
@@ -3,7 +3,7 @@
  *
  * Copyright 2000-2002 by PostgreSQL Global Development Group
  *
- * $Header: /cvsroot/pgsql/src/bin/psql/tab-complete.c,v 1.60 2002/08/30 22:18:07 tgl Exp $
+ * $Header: /cvsroot/pgsql/src/bin/psql/tab-complete.c,v 1.61 2002/09/01 23:26:06 momjian Exp $
  */
 
 /*----------------------------------------------------------------------
@@ -231,7 +231,8 @@ psql_completion(char *text, int start, int end)
        "server_min_messages",
        "client_min_messages",
        "debug_assertions",
-       "debug_print_query",
+       "log_statement",
+       "log_duration",
        "debug_print_parse",
        "debug_print_rewritten",
        "debug_print_plan",
@@ -239,7 +240,7 @@ psql_completion(char *text, int start, int end)
        "show_parser_stats",
        "show_planner_stats",
        "show_executor_stats",
-       "show_query_stats",
+       "show_statement_stats",
        "trace_notify",
        "explain_pretty_print",
        "sql_inheritance",
index b6d1421ec49a48cf4eb935433b78146544c8016d..ad511f50a6fbc391c2e01e62e6fee22af4d14210 100644 (file)
@@ -4,7 +4,7 @@
  * External declarations pertaining to backend/utils/misc/guc.c and
  * backend/utils/misc/guc-file.l
  *
- * $Id: guc.h,v 1.20 2002/07/30 16:20:03 momjian Exp $
+ * $Id: guc.h,v 1.21 2002/09/01 23:26:06 momjian Exp $
  */
 #ifndef GUC_H
 #define GUC_H
@@ -100,7 +100,8 @@ extern void ProcessGUCArray(ArrayType *array, GucSource source);
 extern ArrayType *GUCArrayAdd(ArrayType *array, const char *name, const char *value);
 extern ArrayType *GUCArrayDelete(ArrayType *array, const char *name);
 
-extern bool Debug_print_query;
+extern bool Log_statement;
+extern bool Log_duration;
 extern bool Debug_print_plan;
 extern bool Debug_print_parse;
 extern bool Debug_print_rewritten;
@@ -109,7 +110,7 @@ extern bool Debug_pretty_print;
 extern bool Show_parser_stats;
 extern bool Show_planner_stats;
 extern bool Show_executor_stats;
-extern bool Show_query_stats;
+extern bool Show_statement_stats;
 extern bool Show_btree_build_stats;
 
 extern bool Explain_pretty_print;