Skip to content

Instantly share code, notes, and snippets.

@eduherraiz
Created October 28, 2014 10:12
Show Gist options
  • Save eduherraiz/feee12bc2e76a2c0e095 to your computer and use it in GitHub Desktop.
Save eduherraiz/feee12bc2e76a2c0e095 to your computer and use it in GitHub Desktop.
2014-10-28 10:56:17 CET LOG: database system was interrupted; last known up at 2014-10-28 10:33:22 CET
2014-10-28 10:56:17 CET LOG: starting archive recovery
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:17.253425-00 pid=22302 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000020.lzo prefix=test-edu/ seg=000000010000000000000020 state=begin
2014-10-28 10:56:17 CET LOG: incomplete startup packet
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000020.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:18.061708-00 pid=22302
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:18.064113-00 pid=22302 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000020.lzo prefix=test-edu/ seg=000000010000000000000020 state=complete
2014-10-28 10:56:18 CET LOG: restored log file "000000010000000000000020" from archive
2014-10-28 10:56:18 CET LOG: redo starts at 0/20000028
2014-10-28 10:56:18 CET FATAL: the database system is starting up
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:18.186707-00 pid=22314 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000021.lzo prefix=test-edu/ seg=000000010000000000000021 state=begin
2014-10-28 10:56:18 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000021.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:19.028058-00 pid=22314
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:19.032057-00 pid=22314 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000021.lzo prefix=test-edu/ seg=000000010000000000000021 state=complete
2014-10-28 10:56:19 CET LOG: restored log file "000000010000000000000021" from archive
2014-10-28 10:56:19 CET LOG: consistent recovery state reached at 0/21000710
2014-10-28 10:56:19 CET FATAL: the database system is starting up
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:19.143011-00 pid=22329 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000022.lzo prefix=test-edu/ seg=000000010000000000000022 state=begin
2014-10-28 10:56:19 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000022.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:19.813306-00 pid=22329
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:19.814646-00 pid=22329 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000022.lzo prefix=test-edu/ seg=000000010000000000000022 state=complete
2014-10-28 10:56:19 CET LOG: restored log file "000000010000000000000022" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:19.914991-00 pid=22342 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000023.lzo prefix=test-edu/ seg=000000010000000000000023 state=begin
2014-10-28 10:56:20 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000023.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:20.504523-00 pid=22342
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:20.506366-00 pid=22342 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000023.lzo prefix=test-edu/ seg=000000010000000000000023 state=complete
2014-10-28 10:56:20 CET LOG: restored log file "000000010000000000000023" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:20.602892-00 pid=22354 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000024.lzo prefix=test-edu/ seg=000000010000000000000024 state=begin
2014-10-28 10:56:20 CET FATAL: the database system is starting up
2014-10-28 10:56:21 CET FATAL: the database system is starting up
2014-10-28 10:56:21 CET FATAL: the database system is starting up
2014-10-28 10:56:22 CET FATAL: the database system is starting up
2014-10-28 10:56:22 CET FATAL: the database system is starting up
2014-10-28 10:56:23 CET FATAL: the database system is starting up
2014-10-28 10:56:23 CET LOG: incomplete startup packet
lzop: Inappropriate ioctl for device: <stdin>
wal_e.blobstore.s3.s3_util INFO MSG: Retrying fetch because of a socket error
DETAIL: The socket error's message is 'The read operation timed out'. There have been 1 attempts to fetch wal file s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000024.lzo so far.
STRUCTURED: time=2014-10-28T09:56:36.668667-00 pid=22354
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000024.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:41.063618-00 pid=22354
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:41.084442-00 pid=22354 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000024.lzo prefix=test-edu/ seg=000000010000000000000024 state=complete
2014-10-28 10:56:41 CET LOG: restored log file "000000010000000000000024" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:41.179597-00 pid=22440 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000025.lzo prefix=test-edu/ seg=000000010000000000000025 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000025.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:41.920809-00 pid=22440
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:41.924854-00 pid=22440 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000025.lzo prefix=test-edu/ seg=000000010000000000000025 state=complete
2014-10-28 10:56:41 CET LOG: restored log file "000000010000000000000025" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:42.021318-00 pid=22449 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000026.lzo prefix=test-edu/ seg=000000010000000000000026 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000026.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:42.665110-00 pid=22449
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:42.668330-00 pid=22449 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000026.lzo prefix=test-edu/ seg=000000010000000000000026 state=complete
2014-10-28 10:56:42 CET LOG: restored log file "000000010000000000000026" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:42.770222-00 pid=22458 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000027.lzo prefix=test-edu/ seg=000000010000000000000027 state=begin
2014-10-28 10:56:43 CET FATAL: the database system is starting up
2014-10-28 10:56:45 CET FATAL: the database system is starting up
2014-10-28 10:56:45 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000027.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:48.860144-00 pid=22458
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:48.862722-00 pid=22458 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000027.lzo prefix=test-edu/ seg=000000010000000000000027 state=complete
2014-10-28 10:56:48 CET LOG: restored log file "000000010000000000000027" from archive
2014-10-28 10:56:48 CET FATAL: the database system is starting up
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:48.963080-00 pid=22519 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000028.lzo prefix=test-edu/ seg=000000010000000000000028 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000028.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:49.551410-00 pid=22519
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:49.552655-00 pid=22519 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000028.lzo prefix=test-edu/ seg=000000010000000000000028 state=complete
2014-10-28 10:56:49 CET LOG: restored log file "000000010000000000000028" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:49.641169-00 pid=22539 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000029.lzo prefix=test-edu/ seg=000000010000000000000029 state=begin
2014-10-28 10:56:49 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000029.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:50.235397-00 pid=22539
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:50.236618-00 pid=22539 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000029.lzo prefix=test-edu/ seg=000000010000000000000029 state=complete
2014-10-28 10:56:50 CET LOG: restored log file "000000010000000000000029" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:50.331547-00 pid=22560 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002A.lzo prefix=test-edu/ seg=00000001000000000000002A state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002A.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:50.953007-00 pid=22560
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:50.957012-00 pid=22560 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002A.lzo prefix=test-edu/ seg=00000001000000000000002A state=complete
2014-10-28 10:56:50 CET LOG: restored log file "00000001000000000000002A" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:51.056788-00 pid=22570 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002B.lzo prefix=test-edu/ seg=00000001000000000000002B state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002B.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:58.609643-00 pid=22570
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:58.612038-00 pid=22570 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002B.lzo prefix=test-edu/ seg=00000001000000000000002B state=complete
2014-10-28 10:56:58 CET LOG: restored log file "00000001000000000000002B" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:58.716367-00 pid=22594 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002C.lzo prefix=test-edu/ seg=00000001000000000000002C state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002C.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:56:59.367228-00 pid=22594
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:56:59.368448-00 pid=22594 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002C.lzo prefix=test-edu/ seg=00000001000000000000002C state=complete
2014-10-28 10:56:59 CET LOG: restored log file "00000001000000000000002C" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:56:59.467666-00 pid=22603 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002D.lzo prefix=test-edu/ seg=00000001000000000000002D state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002D.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:00.071457-00 pid=22603
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:00.072703-00 pid=22603 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002D.lzo prefix=test-edu/ seg=00000001000000000000002D state=complete
2014-10-28 10:57:00 CET LOG: restored log file "00000001000000000000002D" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:00.166818-00 pid=22610 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002E.lzo prefix=test-edu/ seg=00000001000000000000002E state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002E.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:00.851509-00 pid=22610
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:00.855482-00 pid=22610 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002E.lzo prefix=test-edu/ seg=00000001000000000000002E state=complete
2014-10-28 10:57:00 CET LOG: restored log file "00000001000000000000002E" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:00.948324-00 pid=22619 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002F.lzo prefix=test-edu/ seg=00000001000000000000002F state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002F.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:01.546938-00 pid=22619
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:01.549399-00 pid=22619 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000002F.lzo prefix=test-edu/ seg=00000001000000000000002F state=complete
2014-10-28 10:57:01 CET LOG: restored log file "00000001000000000000002F" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:01.650058-00 pid=22628 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000030.lzo prefix=test-edu/ seg=000000010000000000000030 state=begin
2014-10-28 10:57:02 CET FATAL: the database system is starting up
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000030.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:04.878550-00 pid=22628
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:04.879774-00 pid=22628 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000030.lzo prefix=test-edu/ seg=000000010000000000000030 state=complete
2014-10-28 10:57:04 CET LOG: restored log file "000000010000000000000030" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:04.968435-00 pid=22663 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000031.lzo prefix=test-edu/ seg=000000010000000000000031 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000031.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:05.581179-00 pid=22663
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:05.582557-00 pid=22663 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000031.lzo prefix=test-edu/ seg=000000010000000000000031 state=complete
2014-10-28 10:57:05 CET LOG: restored log file "000000010000000000000031" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:05.681388-00 pid=22676 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000032.lzo prefix=test-edu/ seg=000000010000000000000032 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000032.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:06.399639-00 pid=22676
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:06.464339-00 pid=22676 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000032.lzo prefix=test-edu/ seg=000000010000000000000032 state=complete
2014-10-28 10:57:06 CET LOG: restored log file "000000010000000000000032" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:06.553671-00 pid=22690 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000033.lzo prefix=test-edu/ seg=000000010000000000000033 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000033.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:07.189859-00 pid=22690
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:07.191068-00 pid=22690 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000033.lzo prefix=test-edu/ seg=000000010000000000000033 state=complete
2014-10-28 10:57:07 CET LOG: restored log file "000000010000000000000033" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:07.292987-00 pid=22697 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000034.lzo prefix=test-edu/ seg=000000010000000000000034 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000034.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:07.881758-00 pid=22697
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:07.882967-00 pid=22697 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000034.lzo prefix=test-edu/ seg=000000010000000000000034 state=complete
2014-10-28 10:57:07 CET LOG: restored log file "000000010000000000000034" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:07.973402-00 pid=22706 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000035.lzo prefix=test-edu/ seg=000000010000000000000035 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000035.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:08.626776-00 pid=22706
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:08.627999-00 pid=22706 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000035.lzo prefix=test-edu/ seg=000000010000000000000035 state=complete
2014-10-28 10:57:08 CET LOG: restored log file "000000010000000000000035" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:08.722363-00 pid=22715 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000036.lzo prefix=test-edu/ seg=000000010000000000000036 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000036.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:14.538908-00 pid=22715
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:14.542647-00 pid=22715 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000036.lzo prefix=test-edu/ seg=000000010000000000000036 state=complete
2014-10-28 10:57:14 CET LOG: restored log file "000000010000000000000036" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:14.636819-00 pid=22738 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000037.lzo prefix=test-edu/ seg=000000010000000000000037 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000037.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:17.035596-00 pid=22738
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:17.036842-00 pid=22738 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000037.lzo prefix=test-edu/ seg=000000010000000000000037 state=complete
2014-10-28 10:57:17 CET LOG: restored log file "000000010000000000000037" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:17.137367-00 pid=22749 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000038.lzo prefix=test-edu/ seg=000000010000000000000038 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000038.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:17.882563-00 pid=22749
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:17.886536-00 pid=22749 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000038.lzo prefix=test-edu/ seg=000000010000000000000038 state=complete
2014-10-28 10:57:17 CET LOG: restored log file "000000010000000000000038" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:17.984479-00 pid=22760 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000039.lzo prefix=test-edu/ seg=000000010000000000000039 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000039.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:18.577172-00 pid=22760
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:18.581250-00 pid=22760 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000039.lzo prefix=test-edu/ seg=000000010000000000000039 state=complete
2014-10-28 10:57:18 CET LOG: restored log file "000000010000000000000039" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:18.680889-00 pid=22769 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003A.lzo prefix=test-edu/ seg=00000001000000000000003A state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003A.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:19.284740-00 pid=22769
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:19.286083-00 pid=22769 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003A.lzo prefix=test-edu/ seg=00000001000000000000003A state=complete
2014-10-28 10:57:19 CET LOG: restored log file "00000001000000000000003A" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:19.375501-00 pid=22780 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003B.lzo prefix=test-edu/ seg=00000001000000000000003B state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003B.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:20.199978-00 pid=22780
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:20.203779-00 pid=22780 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003B.lzo prefix=test-edu/ seg=00000001000000000000003B state=complete
2014-10-28 10:57:20 CET LOG: restored log file "00000001000000000000003B" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:20.306991-00 pid=22790 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003C.lzo prefix=test-edu/ seg=00000001000000000000003C state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003C.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:21.219930-00 pid=22790
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:21.221144-00 pid=22790 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003C.lzo prefix=test-edu/ seg=00000001000000000000003C state=complete
2014-10-28 10:57:21 CET LOG: restored log file "00000001000000000000003C" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:21.316960-00 pid=22800 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003D.lzo prefix=test-edu/ seg=00000001000000000000003D state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003D.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:21.921197-00 pid=22800
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:21.923285-00 pid=22800 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003D.lzo prefix=test-edu/ seg=00000001000000000000003D state=complete
2014-10-28 10:57:21 CET LOG: restored log file "00000001000000000000003D" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:22.026261-00 pid=22807 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003E.lzo prefix=test-edu/ seg=00000001000000000000003E state=begin
lzop: Inappropriate ioctl for device: <stdin>
wal_e.blobstore.s3.s3_util INFO MSG: Retrying fetch because of a socket error
DETAIL: The socket error's message is 'The read operation timed out'. There have been 1 attempts to fetch wal file s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003E.lzo so far.
STRUCTURED: time=2014-10-28T09:57:37.600943-00 pid=22807
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003E.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:40.695967-00 pid=22807
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:40.721509-00 pid=22807 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003E.lzo prefix=test-edu/ seg=00000001000000000000003E state=complete
2014-10-28 10:57:40 CET LOG: restored log file "00000001000000000000003E" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:40.821537-00 pid=22868 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003F.lzo prefix=test-edu/ seg=00000001000000000000003F state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003F.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:41.500046-00 pid=22868
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:41.501355-00 pid=22868 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000003F.lzo prefix=test-edu/ seg=00000001000000000000003F state=complete
2014-10-28 10:57:41 CET LOG: restored log file "00000001000000000000003F" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:41.596726-00 pid=22877 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000040.lzo prefix=test-edu/ seg=000000010000000000000040 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000040.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:42.360087-00 pid=22877
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:42.363662-00 pid=22877 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000040.lzo prefix=test-edu/ seg=000000010000000000000040 state=complete
2014-10-28 10:57:42 CET LOG: restored log file "000000010000000000000040" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:42.455784-00 pid=22886 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000041.lzo prefix=test-edu/ seg=000000010000000000000041 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000041.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:43.288949-00 pid=22886
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:43.292906-00 pid=22886 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000041.lzo prefix=test-edu/ seg=000000010000000000000041 state=complete
2014-10-28 10:57:43 CET LOG: restored log file "000000010000000000000041" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:43.397999-00 pid=22895 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000042.lzo prefix=test-edu/ seg=000000010000000000000042 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000042.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:43.995659-00 pid=22895
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:43.997792-00 pid=22895 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000042.lzo prefix=test-edu/ seg=000000010000000000000042 state=complete
2014-10-28 10:57:44 CET LOG: restored log file "000000010000000000000042" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:44.094417-00 pid=22907 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000043.lzo prefix=test-edu/ seg=000000010000000000000043 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000043.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:44.854479-00 pid=22907
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:44.858598-00 pid=22907 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000043.lzo prefix=test-edu/ seg=000000010000000000000043 state=complete
2014-10-28 10:57:44 CET LOG: restored log file "000000010000000000000043" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:44.953057-00 pid=22917 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000044.lzo prefix=test-edu/ seg=000000010000000000000044 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000044.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:45.606049-00 pid=22917
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:45.607287-00 pid=22917 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000044.lzo prefix=test-edu/ seg=000000010000000000000044 state=complete
2014-10-28 10:57:45 CET LOG: restored log file "000000010000000000000044" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:45.706282-00 pid=22927 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000045.lzo prefix=test-edu/ seg=000000010000000000000045 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000045.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:46.358726-00 pid=22927
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:46.359941-00 pid=22927 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000045.lzo prefix=test-edu/ seg=000000010000000000000045 state=complete
2014-10-28 10:57:46 CET LOG: restored log file "000000010000000000000045" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:46.452116-00 pid=22936 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000046.lzo prefix=test-edu/ seg=000000010000000000000046 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000046.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:47.069088-00 pid=22936
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:47.070308-00 pid=22936 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000046.lzo prefix=test-edu/ seg=000000010000000000000046 state=complete
2014-10-28 10:57:47 CET LOG: restored log file "000000010000000000000046" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:47.165735-00 pid=22944 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000047.lzo prefix=test-edu/ seg=000000010000000000000047 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000047.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:50.076967-00 pid=22944
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:50.078354-00 pid=22944 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000047.lzo prefix=test-edu/ seg=000000010000000000000047 state=complete
2014-10-28 10:57:50 CET LOG: restored log file "000000010000000000000047" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:50.171614-00 pid=22973 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000048.lzo prefix=test-edu/ seg=000000010000000000000048 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000048.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:50.828869-00 pid=22973
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:50.832997-00 pid=22973 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000048.lzo prefix=test-edu/ seg=000000010000000000000048 state=complete
2014-10-28 10:57:50 CET LOG: restored log file "000000010000000000000048" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:50.934513-00 pid=22980 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000049.lzo prefix=test-edu/ seg=000000010000000000000049 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000049.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:51.618311-00 pid=22980
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:51.622510-00 pid=22980 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000049.lzo prefix=test-edu/ seg=000000010000000000000049 state=complete
2014-10-28 10:57:51 CET LOG: restored log file "000000010000000000000049" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:51.728360-00 pid=22989 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004A.lzo prefix=test-edu/ seg=00000001000000000000004A state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004A.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:52.355052-00 pid=22989
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:52.357520-00 pid=22989 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004A.lzo prefix=test-edu/ seg=00000001000000000000004A state=complete
2014-10-28 10:57:52 CET LOG: restored log file "00000001000000000000004A" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:52.458175-00 pid=22998 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004B.lzo prefix=test-edu/ seg=00000001000000000000004B state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004B.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:53.180025-00 pid=22998
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:53.181290-00 pid=22998 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004B.lzo prefix=test-edu/ seg=00000001000000000000004B state=complete
2014-10-28 10:57:53 CET LOG: restored log file "00000001000000000000004B" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:53.272104-00 pid=23008 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004C.lzo prefix=test-edu/ seg=00000001000000000000004C state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004C.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:53.873321-00 pid=23008
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:53.877360-00 pid=23008 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004C.lzo prefix=test-edu/ seg=00000001000000000000004C state=complete
2014-10-28 10:57:53 CET LOG: restored log file "00000001000000000000004C" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:53.986927-00 pid=23015 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004D.lzo prefix=test-edu/ seg=00000001000000000000004D state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004D.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:54.629597-00 pid=23015
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:54.633733-00 pid=23015 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004D.lzo prefix=test-edu/ seg=00000001000000000000004D state=complete
2014-10-28 10:57:54 CET LOG: restored log file "00000001000000000000004D" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:54.734632-00 pid=23024 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004E.lzo prefix=test-edu/ seg=00000001000000000000004E state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004E.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:55.487600-00 pid=23024
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:55.488869-00 pid=23024 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004E.lzo prefix=test-edu/ seg=00000001000000000000004E state=complete
2014-10-28 10:57:55 CET LOG: restored log file "00000001000000000000004E" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:55.580547-00 pid=23033 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004F.lzo prefix=test-edu/ seg=00000001000000000000004F state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004F.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:57:59.443560-00 pid=23033
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:57:59.444812-00 pid=23033 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001000000000000004F.lzo prefix=test-edu/ seg=00000001000000000000004F state=complete
2014-10-28 10:57:59 CET LOG: restored log file "00000001000000000000004F" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:57:59.538364-00 pid=23049 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo prefix=test-edu/ seg=000000010000000000000050 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:58:00.141153-00 pid=23049
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:58:00.145135-00 pid=23049 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo prefix=test-edu/ seg=000000010000000000000050 state=complete
2014-10-28 10:58:00 CET LOG: restored log file "000000010000000000000050" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:58:00.243648-00 pid=23056 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000051.lzo prefix=test-edu/ seg=000000010000000000000051 state=begin
lzop: <stdin>: not a lzop file
wal_e.blobstore.s3.s3_util WARNING MSG: could no longer locate object while performing wal restore
DETAIL: The absolute URI that could not be located is s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000051.lzo.
HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration.
STRUCTURED: time=2014-10-28T09:58:00.598399-00 pid=23056
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:58:00.601625-00 pid=23056 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000051.lzo prefix=test-edu/ seg=000000010000000000000051 state=complete
2014-10-28 10:58:00 CET LOG: redo done at 0/50002F38
2014-10-28 10:58:00 CET LOG: last completed transaction was at log time 2014-10-28 10:57:32.503358+01
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:58:00.699819-00 pid=23065 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo prefix=test-edu/ seg=000000010000000000000050 state=begin
wal_e.blobstore.s3.s3_util INFO MSG: completed download and decompression
DETAIL: Downloaded and decompressed "s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo" to "pg_xlog/RECOVERYXLOG"
STRUCTURED: time=2014-10-28T09:58:01.274021-00 pid=23065
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:58:01.276933-00 pid=23065 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/000000010000000000000050.lzo prefix=test-edu/ seg=000000010000000000000050 state=complete
2014-10-28 10:58:01 CET LOG: restored log file "000000010000000000000050" from archive
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:58:01.375958-00 pid=23075 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000002.history.lzo prefix=test-edu/ seg=00000002.history state=begin
lzop: <stdin>: not a lzop file
wal_e.blobstore.s3.s3_util WARNING MSG: could no longer locate object while performing wal restore
DETAIL: The absolute URI that could not be located is s3://cursopostgres-wale/test-edu/wal_005/00000002.history.lzo.
HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration.
STRUCTURED: time=2014-10-28T09:58:01.701260-00 pid=23075
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:58:01.702901-00 pid=23075 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000002.history.lzo prefix=test-edu/ seg=00000002.history state=complete
2014-10-28 10:58:01 CET LOG: selected new timeline ID: 2
wal_e.operator.backup INFO MSG: begin wal restore
STRUCTURED: time=2014-10-28T09:58:01.804439-00 pid=23082 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001.history.lzo prefix=test-edu/ seg=00000001.history state=begin
lzop: <stdin>: not a lzop file
wal_e.blobstore.s3.s3_util WARNING MSG: could no longer locate object while performing wal restore
DETAIL: The absolute URI that could not be located is s3://cursopostgres-wale/test-edu/wal_005/00000001.history.lzo.
HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration.
STRUCTURED: time=2014-10-28T09:58:02.133495-00 pid=23082
wal_e.operator.backup INFO MSG: complete wal restore
STRUCTURED: time=2014-10-28T09:58:02.136621-00 pid=23082 action=wal-fetch key=s3://cursopostgres-wale/test-edu/wal_005/00000001.history.lzo prefix=test-edu/ seg=00000001.history state=complete
2014-10-28 10:58:02 CET LOG: archive recovery complete
2014-10-28 10:58:02 CET LOG: autovacuum launcher started
2014-10-28 10:58:02 CET LOG: database system is ready to accept connections
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment