Обсуждение: [ADMIN] Repeating autovacuum

Поиск
Список
Период
Сортировка

[ADMIN] Repeating autovacuum

От
Ertan Küçükoğlu
Дата:
Hello,

I have a PostgreSQL 9.6.2 running on Windows 10 EnterpriseDB binaries. This
is a software development desktop computer having i7 entry level CPU and
Sandisk Extreme SSD and 8GB RAM.

While trying to figure some login related stuff, I realize that autovacuum
is repeating non-stop. Below is about 15-20 minute debug2 level log entries
after restarting the server.

I have 2 databases which have only table definitions in them. Only one of
them named "tracker" have about 15 records in it. Basically they are empty.

Memory related parameters in my config file are not modified, just removed
remarks on below items:
shared_buffers = 128M
work_mem = 4MB
maintenance_work_mem = 64MB
autovacuum_work_mem = -1

No autovacuum parameters are changed or used. They are all remarked as in
default config file.

I wonder if that is a normal behavior as system is idle.
-----
LOG:  database system was shut down at 2017-04-08 01:04:50 +03
DEBUG:  checkpoint record is at 0/17662D8
DEBUG:  redo record is at 0/17662D8; shutdown TRUE
DEBUG:  next transaction ID: 0:697; next OID: 16888
DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0
DEBUG:  oldest unfrozen transaction ID: 536, in database 12401
DEBUG:  oldest MultiXactId: 1, in database 16526
DEBUG:  commit timestamp Xid oldest/newest: 0/0
DEBUG:  transaction ID wrap limit is 2147484183, limited by database with
OID 12401
DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID
16526
DEBUG:  starting up replication slots
DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID
16526
LOG:  MultiXact member wraparound protections are now enabled
DEBUG:  MultiXact member stop limit is now 4294914944 based on MultiXact 1
LOG:  database system is ready to accept connections
DEBUG:  checkpointer updated shared memory configuration values
LOG:  autovacuum launcher started
DEBUG:  removing permanent stats file "pg_stat/db_12401.stat"
DEBUG:  removing permanent stats file "pg_stat/db_1.stat"
DEBUG:  removing permanent stats file "pg_stat/db_16526.stat"
DEBUG:  removing permanent stats file "pg_stat/db_16461.stat"
DEBUG:  removing permanent stats file "pg_stat/db_0.stat"
DEBUG:  removing permanent stats file "pg_stat/global.stat"
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  forked new backend, pid=8716 socket=5016
LOG:  connection received: host=::1 port=60268
LOG:  connection authorized: user=DESKTOP-L63HSP2$ database=postgres
DEBUG:  server process (PID 8716) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "tracker"
DEBUG:  received inquiry for database 16526
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 14664) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "spanc"
DEBUG:  received inquiry for database 16461
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 16176) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "template1"
DEBUG:  received inquiry for database 1
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 16220) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "postgres"
DEBUG:  received inquiry for database 12401
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 8568) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "tracker"
DEBUG:  received inquiry for database 16526
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 16156) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "spanc"
DEBUG:  received inquiry for database 16461
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 14892) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "template1"
DEBUG:  received inquiry for database 1
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 17756) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "postgres"
DEBUG:  received inquiry for database 12401
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 18316) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "tracker"
DEBUG:  received inquiry for database 16526
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 14204) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "spanc"
DEBUG:  received inquiry for database 16461
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 5668) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "template1"
DEBUG:  received inquiry for database 1
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 15884) exited with exit code 0
DEBUG:  received inquiry for database 0
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  autovacuum: processing database "postgres"
DEBUG:  received inquiry for database 12401
DEBUG:  writing stats file "pg_stat_tmp/global.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
DEBUG:  server process (PID 5188) exited with exit code 0
-----





Re: [ADMIN] Repeating autovacuum

От
"David G. Johnston"
Дата:
On Fri, Apr 7, 2017 at 3:35 PM, Ertan Küçükoğlu <ertan.kucukoglu@1nar.com.tr> wrote:
No autovacuum parameters are changed or used. They are all remarked as in
default config file.

I wonder if that is a normal behavior as system is idle.

​See "autovacuum_naptime (integer)"


Its "auto" vacuum which means it runs (nearly) continuously looking for things to do.

I suppose that in theory it could operate in a kind of "wait/notify" mode where every DDL against the database wakes the daemon otherwise it stays asleep.  I recall some work being done a number of years back on making the system more energy efficient during no-activity periods - but there are trade-offs to consider here for which I am not up-to-speed on the specifics.

It would be interesting to see the timestamps on those log entries...

David J.

Re: [ADMIN] Repeating autovacuum

От
Ertan Küçükoğlu
Дата:


On 8 Apr 2017, at 01:53, David G. Johnston <david.g.johnston@gmail.com> wrote:

On Fri, Apr 7, 2017 at 3:35 PM, Ertan Küçükoğlu <ertan.kucukoglu@1nar.com.tr> wrote:
No autovacuum parameters are changed or used. They are all remarked as in
default config file.

I wonder if that is a normal behavior as system is idle.

​See "autovacuum_naptime (integer)"


Its "auto" vacuum which means it runs (nearly) continuously looking for things to do.

I suppose that in theory it could operate in a kind of "wait/notify" mode where every DDL against the database wakes the daemon otherwise it stays asleep.  I recall some work being done a number of years back on making the system more energy efficient during no-activity periods - but there are trade-offs to consider here for which I am not up-to-speed on the specifics.

It would be interesting to see the timestamps on those log entries...

David J.

I don't see any timestamp in the log. I myself wanted that, too. Do I need to turn some switch on?

Ertan Küçükoğlu

Re: [ADMIN] Repeating autovacuum

От
Keith
Дата:


On Fri, Apr 7, 2017 at 7:33 PM, Ertan Küçükoğlu <ertan.kucukoglu@1nar.com.tr> wrote:


On 8 Apr 2017, at 01:53, David G. Johnston <david.g.johnston@gmail.com> wrote:

On Fri, Apr 7, 2017 at 3:35 PM, Ertan Küçükoğlu <ertan.kucukoglu@1nar.com.tr> wrote:
No autovacuum parameters are changed or used. They are all remarked as in
default config file.

I wonder if that is a normal behavior as system is idle.

​See "autovacuum_naptime (integer)"


Its "auto" vacuum which means it runs (nearly) continuously looking for things to do.

I suppose that in theory it could operate in a kind of "wait/notify" mode where every DDL against the database wakes the daemon otherwise it stays asleep.  I recall some work being done a number of years back on making the system more energy efficient during no-activity periods - but there are trade-offs to consider here for which I am not up-to-speed on the specifics.

It would be interesting to see the timestamps on those log entries...

David J.

I don't see any timestamp in the log. I myself wanted that, too. Do I need to turn some switch on?

Ertan Küçükoğlu

Look at log_line_prefix for postgresql.conf. There's a whole set of macros you can use to add logging information, including timestamps.

https://www.postgresql.org/docs/9.6/static/runtime-config-logging.html#GUC-LOG-LINE-PREFIX

Keith

Re: [ADMIN] Repeating autovacuum

От
Ertan Küçükoğlu
Дата:
> From: pgsql-admin-owner@postgresql.org [mailto:pgsql-admin-owner@postgresql.org] On Behalf Of David G. Johnston
> Sent: Saturday, April 8, 2017 1:53 AM
> To: Ertan Küçükoğlu <ertan.kucukoglu@1nar.com.tr>
> Cc: pgsql-admin@postgresql.org
> Subject: Re: [ADMIN] Repeating autovacuum
>
>
> It would be interesting to see the timestamps on those log entries...
>
> David J.


Below is a cropped log with timestamps

-----
2017-04-08 15:52:34 +03LOG:  database system was shut down at 2017-04-08 15:52:32 +03
2017-04-08 15:52:34 +03DEBUG:  checkpoint record is at 0/176E540
2017-04-08 15:52:34 +03DEBUG:  redo record is at 0/176E540; shutdown TRUE
2017-04-08 15:52:34 +03DEBUG:  next transaction ID: 0:702; next OID: 16888
2017-04-08 15:52:34 +03DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0
2017-04-08 15:52:34 +03DEBUG:  oldest unfrozen transaction ID: 536, in database 12401
2017-04-08 15:52:34 +03DEBUG:  oldest MultiXactId: 1, in database 16526
2017-04-08 15:52:34 +03DEBUG:  commit timestamp Xid oldest/newest: 0/0
2017-04-08 15:52:34 +03DEBUG:  transaction ID wrap limit is 2147484183, limited by database with OID 12401
2017-04-08 15:52:34 +03DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 16526
2017-04-08 15:52:34 +03DEBUG:  starting up replication slots
2017-04-08 15:52:34 +03DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 16526
2017-04-08 15:52:34 +03LOG:  MultiXact member wraparound protections are now enabled
2017-04-08 15:52:34 +03DEBUG:  MultiXact member stop limit is now 4294914944 based on MultiXact 1
2017-04-08 15:52:34 +03LOG:  database system is ready to accept connections
2017-04-08 15:52:34 +03DEBUG:  checkpointer updated shared memory configuration values
2017-04-08 15:52:34 +03LOG:  autovacuum launcher started
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/db_12401.stat"
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/db_1.stat"
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/db_16526.stat"
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/db_16461.stat"
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/db_0.stat"
2017-04-08 15:52:34 +03DEBUG:  removing permanent stats file "pg_stat/global.stat"
2017-04-08 15:52:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:52:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:52:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:52:34 +03DEBUG:  forked new backend, pid=7048 socket=5072
2017-04-08 15:52:34 +03LOG:  connection received: host=::1 port=58851
2017-04-08 15:52:34 +03LOG:  connection authorized: user=DESKTOP-L63HSP2$ database=postgres
2017-04-08 15:52:35 +03DEBUG:  server process (PID 7048) exited with exit code 0
2017-04-08 15:52:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:52:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:52:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:52:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:52:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:52:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:52:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:52:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:52:49 +03DEBUG:  server process (PID 2724) exited with exit code 0
2017-04-08 15:53:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:53:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:53:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:53:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:53:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:04 +03DEBUG:  server process (PID 18440) exited with exit code 0
2017-04-08 15:53:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:53:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:53:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:53:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:53:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:19 +03DEBUG:  server process (PID 12944) exited with exit code 0
2017-04-08 15:53:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:53:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:53:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:53:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:53:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:34 +03DEBUG:  server process (PID 18672) exited with exit code 0
2017-04-08 15:53:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:53:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:53:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:53:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:53:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:53:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:53:49 +03DEBUG:  server process (PID 8352) exited with exit code 0
2017-04-08 15:54:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:54:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:54:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:54:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:54:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:04 +03DEBUG:  server process (PID 18548) exited with exit code 0
2017-04-08 15:54:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:54:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:54:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:54:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:54:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:19 +03DEBUG:  server process (PID 14840) exited with exit code 0
2017-04-08 15:54:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:54:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:54:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:54:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:54:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:34 +03DEBUG:  server process (PID 19172) exited with exit code 0
2017-04-08 15:54:44 +03DEBUG:  forked new backend, pid=19448 socket=5024
2017-04-08 15:54:44 +03LOG:  connection received: host=::1 port=58984
2017-04-08 15:54:44 +03LOG:  connection authorized: user=postgres database=tracker
2017-04-08 15:54:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:54:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:54:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:54:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:54:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:54:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:54:49 +03DEBUG:  server process (PID 12448) exited with exit code 0
2017-04-08 15:54:54 +03DEBUG:  forked new backend, pid=18744 socket=5032
2017-04-08 15:54:54 +03LOG:  connection received: host=::1 port=58994
2017-04-08 15:54:54 +03LOG:  connection authorized: user=postgres database=tracker
2017-04-08 15:55:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:55:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:55:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:55:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:55:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:04 +03DEBUG:  server process (PID 4500) exited with exit code 0
2017-04-08 15:55:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:55:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:55:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:55:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:55:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:19 +03DEBUG:  server process (PID 11156) exited with exit code 0
2017-04-08 15:55:19 +03DEBUG:  server process (PID 18744) exited with exit code 0
2017-04-08 15:55:27 +03DEBUG:  forked new backend, pid=14356 socket=5032
2017-04-08 15:55:27 +03LOG:  connection received: host=::1 port=59027
2017-04-08 15:55:27 +03LOG:  connection authorized: user=postgres database=tracker
2017-04-08 15:55:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:55:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:55:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:55:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:55:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:34 +03DEBUG:  server process (PID 11412) exited with exit code 0
2017-04-08 15:55:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:55:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:55:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:55:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:55:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:55:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:55:49 +03DEBUG:  server process (PID 13888) exited with exit code 0
2017-04-08 15:56:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:56:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:56:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:56:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:56:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:04 +03DEBUG:  server process (PID 10268) exited with exit code 0
2017-04-08 15:56:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:56:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:56:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:56:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:56:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:19 +03DEBUG:  server process (PID 7676) exited with exit code 0
2017-04-08 15:56:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:56:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:56:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:56:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:56:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:34 +03DEBUG:  server process (PID 18516) exited with exit code 0
2017-04-08 15:56:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:56:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:56:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:56:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:56:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:56:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:56:49 +03DEBUG:  server process (PID 10112) exited with exit code 0
2017-04-08 15:57:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:57:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:57:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:57:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:57:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:04 +03DEBUG:  server process (PID 12396) exited with exit code 0
2017-04-08 15:57:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:57:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:57:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:57:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:57:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:19 +03DEBUG:  server process (PID 12048) exited with exit code 0
2017-04-08 15:57:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:57:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:57:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:57:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:57:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:34 +03DEBUG:  server process (PID 2188) exited with exit code 0
2017-04-08 15:57:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:57:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:57:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:57:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:57:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:57:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:57:49 +03DEBUG:  server process (PID 9560) exited with exit code 0
2017-04-08 15:58:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:58:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:58:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:58:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:58:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:04 +03DEBUG:  server process (PID 8704) exited with exit code 0
2017-04-08 15:58:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:58:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:58:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:58:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:58:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:19 +03DEBUG:  server process (PID 10236) exited with exit code 0
2017-04-08 15:58:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:58:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:58:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:58:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:58:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:34 +03DEBUG:  server process (PID 12848) exited with exit code 0
2017-04-08 15:58:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:58:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:58:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:58:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:58:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:58:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:58:49 +03DEBUG:  server process (PID 2484) exited with exit code 0
2017-04-08 15:59:04 +03DEBUG:  received inquiry for database 0
2017-04-08 15:59:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:04 +03DEBUG:  autovacuum: processing database "spanc"
2017-04-08 15:59:04 +03DEBUG:  received inquiry for database 16461
2017-04-08 15:59:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_16461.stat"
2017-04-08 15:59:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:04 +03DEBUG:  server process (PID 1168) exited with exit code 0
2017-04-08 15:59:19 +03DEBUG:  received inquiry for database 0
2017-04-08 15:59:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:19 +03DEBUG:  autovacuum: processing database "template1"
2017-04-08 15:59:19 +03DEBUG:  received inquiry for database 1
2017-04-08 15:59:19 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_1.stat"
2017-04-08 15:59:19 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:19 +03DEBUG:  server process (PID 19000) exited with exit code 0
2017-04-08 15:59:34 +03DEBUG:  received inquiry for database 0
2017-04-08 15:59:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:34 +03DEBUG:  autovacuum: processing database "postgres"
2017-04-08 15:59:34 +03DEBUG:  received inquiry for database 12401
2017-04-08 15:59:34 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_12401.stat"
2017-04-08 15:59:34 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:34 +03DEBUG:  server process (PID 572) exited with exit code 0
2017-04-08 15:59:40 +03DEBUG:  server process (PID 14356) exited with exit code 0
2017-04-08 15:59:40 +03DEBUG:  server process (PID 19448) exited with exit code 0
2017-04-08 15:59:49 +03DEBUG:  received inquiry for database 0
2017-04-08 15:59:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:49 +03DEBUG:  autovacuum: processing database "tracker"
2017-04-08 15:59:49 +03DEBUG:  received inquiry for database 16526
2017-04-08 15:59:49 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 15:59:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_16526.stat"
2017-04-08 15:59:49 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
2017-04-08 15:59:49 +03DEBUG:  server process (PID 2172) exited with exit code 0
2017-04-08 16:00:04 +03DEBUG:  received inquiry for database 0
2017-04-08 16:00:04 +03DEBUG:  writing stats file "pg_stat_tmp/global.stat"
2017-04-08 16:00:04 +03DEBUG:  writing stats file "pg_stat_tmp/db_0.stat"
-----