postgresql-2012-03-08_012605.log
text/x-log
Filename: postgresql-2012-03-08_012605.log
Type: text/x-log
Part: 0
DEBUG: sendtime 2012-03-08 01:26:04.978106+00 receipttime 2012-03-08 01:26:04.978106+00 replication apply delay 0 ms transfer latency 165 ms DEBUG: sendtime 2012-03-08 01:26:04.979274+00 receipttime 2012-03-08 01:26:04.979274+00 replication apply delay 0 ms transfer latency 164 ms DEBUG: sendtime 2012-03-08 01:26:04.979379+00 receipttime 2012-03-08 01:26:04.979379+00 replication apply delay 0 ms transfer latency 164 ms DEBUG: sendtime 2012-03-08 01:26:04.979467+00 receipttime 2012-03-08 01:26:04.979467+00 replication apply delay 0 ms transfer latency 164 ms DEBUG: sendtime 2012-03-08 01:26:04.979555+00 receipttime 2012-03-08 01:26:04.979555+00 replication apply delay 0 ms transfer latency 164 ms DEBUG: sendtime 2012-03-08 01:26:04.979661+00 receipttime 2012-03-08 01:26:04.979661+00 replication apply delay 0 ms transfer latency 164 ms DEBUG: sendtime 2012-03-08 01:26:04.979728+00 receipttime 2012-03-08 01:26:04.979728+00 replication apply delay 0 ms transfer latency 165 ms DEBUG: sendtime 2012-03-08 01:26:05.019428+00 receipttime 2012-03-08 01:26:05.019428+00 replication apply delay 0 ms transfer latency 125 ms DEBUG: sendtime 2012-03-08 01:26:05.019611+00 receipttime 2012-03-08 01:26:05.019611+00 replication apply delay 0 ms transfer latency 125 ms DEBUG: sendtime 2012-03-08 01:26:05.019779+00 receipttime 2012-03-08 01:26:05.019779+00 replication apply delay 0 ms transfer latency 125 ms DEBUG: sendtime 2012-03-08 01:26:05.05939+00 receipttime 2012-03-08 01:26:05.05939+00 replication apply delay 0 ms transfer latency 86 ms DEBUG: sendtime 2012-03-08 01:26:05.059565+00 receipttime 2012-03-08 01:26:05.059565+00 replication apply delay 0 ms transfer latency 86 ms DEBUG: sendtime 2012-03-08 01:26:05.099384+00 receipttime 2012-03-08 01:26:05.099384+00 replication apply delay 0 ms transfer latency 46 ms DEBUG: sendtime 2012-03-08 01:26:05.099567+00 receipttime 2012-03-08 01:26:05.099567+00 replication apply delay 0 ms transfer latency 46 ms DEBUG: sendtime 2012-03-08 01:26:05.099734+00 receipttime 2012-03-08 01:26:05.099734+00 replication apply delay 0 ms transfer latency 46 ms DEBUG: sendtime 2012-03-08 01:26:05.13939+00 receipttime 2012-03-08 01:26:05.13939+00 replication apply delay 0 ms transfer latency 8 ms DEBUG: sendtime 2012-03-08 01:26:05.139563+00 receipttime 2012-03-08 01:26:05.139563+00 replication apply delay 0 ms transfer latency 8 ms DEBUG: sendtime 2012-03-08 01:26:05.139732+00 receipttime 2012-03-08 01:26:05.139732+00 replication apply delay 0 ms transfer latency 8 ms DEBUG: sendtime 2012-03-08 01:26:05.140823+00 receipttime 2012-03-08 01:26:05.140823+00 replication apply delay 0 ms transfer latency 7 ms DEBUG: sendtime 2012-03-08 01:26:05.141023+00 receipttime 2012-03-08 01:26:05.141023+00 replication apply delay 0 ms transfer latency 7 ms DEBUG: sendtime 2012-03-08 01:26:05.141208+00 receipttime 2012-03-08 01:26:05.141208+00 replication apply delay 0 ms transfer latency 7 ms DEBUG: sendtime 2012-03-08 01:26:05.143619+00 receipttime 2012-03-08 01:26:05.143619+00 replication apply delay 0 ms transfer latency 5 ms DEBUG: sendtime 2012-03-08 01:26:05.14381+00 receipttime 2012-03-08 01:26:05.14381+00 replication apply delay 0 ms transfer latency 5 ms DEBUG: sendtime 2012-03-08 01:26:05.143986+00 receipttime 2012-03-08 01:26:05.143986+00 replication apply delay 0 ms transfer latency 6 ms DEBUG: sendtime 2012-03-08 01:26:05.144157+00 receipttime 2012-03-08 01:26:05.144157+00 replication apply delay 0 ms transfer latency 6 ms DEBUG: sendtime 2012-03-08 01:26:05.147327+00 receipttime 2012-03-08 01:26:05.147327+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.147588+00 receipttime 2012-03-08 01:26:05.147588+00 replication apply delay 0 ms transfer latency 4 ms DEBUG: sendtime 2012-03-08 01:26:05.147786+00 receipttime 2012-03-08 01:26:05.147786+00 replication apply delay 0 ms transfer latency 4 ms DEBUG: sendtime 2012-03-08 01:26:05.147979+00 receipttime 2012-03-08 01:26:05.147979+00 replication apply delay 0 ms transfer latency 4 ms DEBUG: sendtime 2012-03-08 01:26:05.148173+00 receipttime 2012-03-08 01:26:05.148173+00 replication apply delay 0 ms transfer latency 5 ms DEBUG: sendtime 2012-03-08 01:26:05.14836+00 receipttime 2012-03-08 01:26:05.14836+00 replication apply delay 0 ms transfer latency 5 ms DEBUG: sendtime 2012-03-08 01:26:05.148537+00 receipttime 2012-03-08 01:26:05.148537+00 replication apply delay 0 ms transfer latency 5 ms DEBUG: sendtime 2012-03-08 01:26:05.14871+00 receipttime 2012-03-08 01:26:05.14871+00 replication apply delay 0 ms transfer latency 6 ms DEBUG: sendtime 2012-03-08 01:26:05.151479+00 receipttime 2012-03-08 01:26:05.151479+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.15175+00 receipttime 2012-03-08 01:26:05.15175+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.151943+00 receipttime 2012-03-08 01:26:05.151943+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.152121+00 receipttime 2012-03-08 01:26:05.152121+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.152288+00 receipttime 2012-03-08 01:26:05.152288+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.152456+00 receipttime 2012-03-08 01:26:05.152456+00 replication apply delay 0 ms transfer latency 3 ms DEBUG: sendtime 2012-03-08 01:26:05.154828+00 receipttime 2012-03-08 01:26:05.154828+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.155102+00 receipttime 2012-03-08 01:26:05.155102+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.155329+00 receipttime 2012-03-08 01:26:05.155329+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.155531+00 receipttime 2012-03-08 01:26:05.155531+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.155721+00 receipttime 2012-03-08 01:26:05.155721+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.155895+00 receipttime 2012-03-08 01:26:05.155895+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.156068+00 receipttime 2012-03-08 01:26:05.156068+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.156245+00 receipttime 2012-03-08 01:26:05.156245+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.156718+00 receipttime 2012-03-08 01:26:05.156718+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.156959+00 receipttime 2012-03-08 01:26:05.156959+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.157155+00 receipttime 2012-03-08 01:26:05.157155+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.157352+00 receipttime 2012-03-08 01:26:05.157352+00 replication apply delay 0 ms transfer latency 2 ms DEBUG: sendtime 2012-03-08 01:26:05.157554+00 receipttime 2012-03-08 01:26:05.157554+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.157743+00 receipttime 2012-03-08 01:26:05.157743+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.157916+00 receipttime 2012-03-08 01:26:05.157916+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.158113+00 receipttime 2012-03-08 01:26:05.158113+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.158346+00 receipttime 2012-03-08 01:26:05.158346+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.158908+00 receipttime 2012-03-08 01:26:05.158908+00 replication apply delay 0 ms transfer latency 1 ms DEBUG: sendtime 2012-03-08 01:26:05.159224+00 receipttime 2012-03-08 01:26:05.159224+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159369+00 receipttime 2012-03-08 01:26:05.159369+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159422+00 receipttime 2012-03-08 01:26:05.159422+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159471+00 receipttime 2012-03-08 01:26:05.159471+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159525+00 receipttime 2012-03-08 01:26:05.159525+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159594+00 receipttime 2012-03-08 01:26:05.159594+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159758+00 receipttime 2012-03-08 01:26:05.159758+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.15985+00 receipttime 2012-03-08 01:26:05.15985+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.159906+00 receipttime 2012-03-08 01:26:05.159906+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.15997+00 receipttime 2012-03-08 01:26:05.15997+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160033+00 receipttime 2012-03-08 01:26:05.160033+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160098+00 receipttime 2012-03-08 01:26:05.160098+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160159+00 receipttime 2012-03-08 01:26:05.160159+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160219+00 receipttime 2012-03-08 01:26:05.160219+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160262+00 receipttime 2012-03-08 01:26:05.160262+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sendtime 2012-03-08 01:26:05.160265+00 receipttime 2012-03-08 01:26:05.160265+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending write 0/6F819F8 flush 0/66A0000 apply 0/6F819F8 DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:05.160942+00 receipttime 2012-03-08 01:26:05.160942+00 replication apply delay 0 ms transfer latency 209 ms DEBUG: sendtime 2012-03-08 01:26:05.370094+00 receipttime 2012-03-08 01:26:05.370094+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: forked new backend, pid=28994 socket=9 DEBUG: postgres child[28994]: starting with ( DEBUG: postgres DEBUG: DEBUG: ) DEBUG: InitPostgres DEBUG: my backend ID is 2 DEBUG: StartTransaction DEBUG: name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children: DEBUG: CommitTransaction DEBUG: name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children: DEBUG: received replication command: IDENTIFY_SYSTEM DEBUG: received replication command: BASE_BACKUP LABEL 'pg_basebackup base backup' PROGRESS NOWAIT DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: forked new backend, pid=28995 socket=9 DEBUG: postgres child[28995]: starting with ( DEBUG: postgres DEBUG: DEBUG: ) DEBUG: InitPostgres DEBUG: my backend ID is 3 DEBUG: StartTransaction DEBUG: name: unnamed; blockState: DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children: DEBUG: CommitTransaction DEBUG: name: unnamed; blockState: STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children: DEBUG: received replication command: IDENTIFY_SYSTEM DEBUG: received replication command: START_REPLICATION 0/6000000 DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: standby "pg_basebackup" has now caught up with primary DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:26:11.377168+00 receipttime 2012-03-08 01:26:11.377168+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:15.383397+00 receipttime 2012-03-08 01:26:15.383397+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:26:21.38845+00 receipttime 2012-03-08 01:26:21.38845+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:25.394822+00 receipttime 2012-03-08 01:26:25.394822+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:26:31.401934+00 receipttime 2012-03-08 01:26:31.401934+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:35.407989+00 receipttime 2012-03-08 01:26:35.407989+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:26:41.412964+00 receipttime 2012-03-08 01:26:41.412964+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:45.41902+00 receipttime 2012-03-08 01:26:45.41902+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:26:51.426129+00 receipttime 2012-03-08 01:26:51.426129+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:26:55.432016+00 receipttime 2012-03-08 01:26:55.432016+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:01.439157+00 receipttime 2012-03-08 01:27:01.439157+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:05.445304+00 receipttime 2012-03-08 01:27:05.445304+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:11.451609+00 receipttime 2012-03-08 01:27:11.451609+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:15.457635+00 receipttime 2012-03-08 01:27:15.457635+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:21.464756+00 receipttime 2012-03-08 01:27:21.464756+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:25.471037+00 receipttime 2012-03-08 01:27:25.471037+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:31.478138+00 receipttime 2012-03-08 01:27:31.478138+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:35.484273+00 receipttime 2012-03-08 01:27:35.484273+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:41.491375+00 receipttime 2012-03-08 01:27:41.491375+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:45.497383+00 receipttime 2012-03-08 01:27:45.497383+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:27:51.502086+00 receipttime 2012-03-08 01:27:51.502086+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:27:55.508179+00 receipttime 2012-03-08 01:27:55.508179+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:28:01.515283+00 receipttime 2012-03-08 01:28:01.515283+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:28:05.521329+00 receipttime 2012-03-08 01:28:05.521329+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:28:11.528437+00 receipttime 2012-03-08 01:28:11.528437+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:28:15.534588+00 receipttime 2012-03-08 01:28:15.534588+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:28:21.541657+00 receipttime 2012-03-08 01:28:21.541657+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0 DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:28:25.547936+00 receipttime 2012-03-08 01:28:25.547936+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: write 0/0 flush 0/0 apply 0/0 DEBUG: sending replication keepalive DEBUG: sendtime 2012-03-08 01:28:31.55295+00 receipttime 2012-03-08 01:28:31.55295+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: sending replication keepalive DEBUG: sending write 0/6F819F8 flush 0/6F819F8 apply 0/6F819F8 DEBUG: sendtime 2012-03-08 01:28:35.559123+00 receipttime 2012-03-08 01:28:35.559123+00 replication apply delay 0 ms transfer latency 0 ms DEBUG: skipping restartpoint, already performed at 0/0