Created
April 25, 2016 21:47
-
-
Save arschles/dd6a2411cddd26982a39b78e59f23235 to your computer and use it in GitHub Desktop.
Storage Logs
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| ENG000656:workflow-manager-api aaronschlesinger$ kd logs -f deis-database-sr5al | |
| The files belonging to this database system will be owned by user "postgres". | |
| This user must also own the server process. | |
| The database cluster will be initialized with locale "en_US.utf8". | |
| The default database encoding has accordingly been set to "UTF8". | |
| The default text search configuration will be set to "english". | |
| Data page checksums are disabled. | |
| fixing permissions on existing directory /var/lib/postgresql/data ... ok | |
| creating subdirectories ... ok | |
| selecting default max_connections ... 100 | |
| selecting default shared_buffers ... 128MB | |
| selecting dynamic shared memory implementation ... posix | |
| creating configuration files ... ok | |
| creating template1 database in /var/lib/postgresql/data/base/1 ... ok | |
| initializing pg_authid ... ok | |
| initializing dependencies ... ok | |
| creating system views ... ok | |
| loading system objects' descriptions ... ok | |
| creating collations ... ok | |
| creating conversions ... ok | |
| creating dictionaries ... ok | |
| setting privileges on built-in objects ... ok | |
| creating information schema ... ok | |
| loading PL/pgSQL server-side language ... ok | |
| vacuuming database template1 ... ok | |
| copying template1 to template0 ... ok | |
| copying template1 to postgres ... ok | |
| syncing data to disk ... ok | |
| WARNING: enabling "trust" authentication for local connections | |
| You can change this by editing pg_hba.conf or using the option -A, or | |
| --auth-local and --auth-host, the next time you run initdb. | |
| Success. You can now start the database server using: | |
| postgres -D /var/lib/postgresql/data | |
| or | |
| pg_ctl -D /var/lib/postgresql/data -l logfile start | |
| waiting for server to start....LOG: database system was shut down at 2016-04-25 21:36:42 UTC | |
| LOG: MultiXact member wraparound protections are now enabled | |
| LOG: database system is ready to accept connections | |
| LOG: autovacuum launcher started | |
| done | |
| server started | |
| CREATE DATABASE | |
| CREATE ROLE | |
| /docker-entrypoint.sh: running /docker-entrypoint-initdb.d/001_setup_envdir.sh | |
| /docker-entrypoint.sh: running /docker-entrypoint-initdb.d/002_create_bucket.sh | |
| /docker-entrypoint.sh: running /docker-entrypoint-initdb.d/003_restore_from_backup.sh | |
| Rebooting postgres to enable archive mode | |
| LOG: received smart shutdown request | |
| LOG: autovacuum launcher shutting down | |
| LOG: shutting down | |
| waiting for server to shut down....LOG: database system is shut down | |
| done | |
| server stopped | |
| waiting for server to start....LOG: database system was shut down at 2016-04-25 21:37:00 UTC | |
| LOG: MultiXact member wraparound protections are now enabled | |
| LOG: database system is ready to accept connections | |
| LOG: autovacuum launcher started | |
| done | |
| server started | |
| Found backups. Restoring from backup... | |
| LOG: received smart shutdown request | |
| LOG: autovacuum launcher shutting down | |
| LOG: shutting down | |
| waiting for server to shut down....LOG: database system is shut down | |
| done | |
| server stopped | |
| wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "backup-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:03.984379-00 pid=100 | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| wal_e.worker.gs.gs_worker INFO MSG: beginning partition download | |
| DETAIL: The partition being downloaded is part_00000000.tar.lzo. | |
| HINT: The absolute GCS key is basebackups_005/base_000000010000000000000002_00000040/tar_partitions/part_00000000.tar.lzo. | |
| STRUCTURED: time=2016-04-25T21:37:05.313949-00 pid=100 | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| waiting for server to start....LOG: database system was interrupted; last known up at 2016-04-15 17:27:01 UTC | |
| LOG: creating missing WAL directory "pg_xlog/archive_status" | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .LOG: starting archive recovery | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:24.050108-00 pid=151 | |
| wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:37:24.144911-00 pid=151 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000002.lzo prefix= seg=000000010000000000000002 state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils INFO MSG: completed download and decompression | |
| DETAIL: Downloaded and decompressed "gs://aaron-outside-storage-test/wal_005/000000010000000000000002.lzo" to "pg_xlog/RECOVERYXLOG" | |
| STRUCTURED: time=2016-04-25T21:37:25.716216-00 pid=151 | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:37:25.716652-00 pid=151 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000002.lzo prefix= seg=000000010000000000000002 state=complete | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000002" from archive | |
| LOG: redo starts at 0/2000090 | |
| LOG: consistent recovery state reached at 0/20000B8 | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:29.192987-00 pid=188 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:37:30.382228-00 pid=188 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000003.lzo prefix= seg=000000010000000000000003 state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils INFO MSG: completed download and decompression | |
| DETAIL: Downloaded and decompressed "gs://aaron-outside-storage-test/wal_005/000000010000000000000003.lzo" to "pg_xlog/RECOVERYXLOG" | |
| STRUCTURED: time=2016-04-25T21:37:31.884790-00 pid=188 | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:37:31.885248-00 pid=188 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000003.lzo prefix= seg=000000010000000000000003 state=complete | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000003" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:35.694535-00 pid=212 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:37:36.585463-00 pid=212 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000004.lzo prefix= seg=000000010000000000000004 state=begin | |
| FATAL: the database system is starting up | |
| .oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils INFO MSG: completed download and decompression | |
| DETAIL: Downloaded and decompressed "gs://aaron-outside-storage-test/wal_005/000000010000000000000004.lzo" to "pg_xlog/RECOVERYXLOG" | |
| STRUCTURED: time=2016-04-25T21:37:38.417957-00 pid=212 | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:37:38.418365-00 pid=212 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000004.lzo prefix= seg=000000010000000000000004 state=complete | |
| LOG: restored log file "000000010000000000000004" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:42.572431-00 pid=230 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:44.175945-00 pid=230 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000005.lzo prefix= seg=000000010000000000000005 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000005" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:46.330263-00 pid=292 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:46.705061-00 pid=292 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000006.lzo prefix= seg=000000010000000000000006 | |
| LOG: restored log file "000000010000000000000006" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:48.308376-00 pid=306 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:48.630845-00 pid=306 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000007.lzo prefix= seg=000000010000000000000007 | |
| LOG: restored log file "000000010000000000000007" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:50.293749-00 pid=319 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:50.682923-00 pid=319 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000008.lzo prefix= seg=000000010000000000000008 | |
| LOG: restored log file "000000010000000000000008" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:52.455634-00 pid=333 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:52.966342-00 pid=333 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000009.lzo prefix= seg=000000010000000000000009 | |
| LOG: restored log file "000000010000000000000009" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:54.732521-00 pid=356 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:55.150451-00 pid=356 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000A.lzo prefix= seg=00000001000000000000000A | |
| LOG: restored log file "00000001000000000000000A" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:57.153223-00 pid=368 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:37:57.588609-00 pid=368 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000B.lzo prefix= seg=00000001000000000000000B | |
| LOG: restored log file "00000001000000000000000B" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:37:59.655688-00 pid=385 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:00.095582-00 pid=385 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000C.lzo prefix= seg=00000001000000000000000C | |
| LOG: restored log file "00000001000000000000000C" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:02.205378-00 pid=400 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:02.634182-00 pid=400 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000D.lzo prefix= seg=00000001000000000000000D | |
| LOG: restored log file "00000001000000000000000D" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:04.572760-00 pid=423 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:04.995517-00 pid=423 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000E.lzo prefix= seg=00000001000000000000000E | |
| LOG: restored log file "00000001000000000000000E" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:06.942063-00 pid=438 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:07.382665-00 pid=438 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000000F.lzo prefix= seg=00000001000000000000000F | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "00000001000000000000000F" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:09.340038-00 pid=454 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:09.790192-00 pid=454 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000010.lzo prefix= seg=000000010000000000000010 | |
| LOG: restored log file "000000010000000000000010" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:11.799206-00 pid=469 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:12.294496-00 pid=469 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000011.lzo prefix= seg=000000010000000000000011 | |
| LOG: restored log file "000000010000000000000011" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:14.294645-00 pid=491 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:14.710484-00 pid=491 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000012.lzo prefix= seg=000000010000000000000012 | |
| LOG: restored log file "000000010000000000000012" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:16.576874-00 pid=508 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:17.038670-00 pid=508 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000013.lzo prefix= seg=000000010000000000000013 | |
| LOG: restored log file "000000010000000000000013" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:19.036947-00 pid=523 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:19.506875-00 pid=523 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000014.lzo prefix= seg=000000010000000000000014 | |
| LOG: restored log file "000000010000000000000014" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:21.606876-00 pid=539 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:22.079398-00 pid=539 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000015.lzo prefix= seg=000000010000000000000015 | |
| LOG: restored log file "000000010000000000000015" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:24.042267-00 pid=560 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:24.483503-00 pid=560 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000016.lzo prefix= seg=000000010000000000000016 | |
| LOG: restored log file "000000010000000000000016" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:26.354190-00 pid=576 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:26.814243-00 pid=576 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000017.lzo prefix= seg=000000010000000000000017 | |
| LOG: restored log file "000000010000000000000017" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:28.806905-00 pid=592 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:29.262401-00 pid=592 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000018.lzo prefix= seg=000000010000000000000018 | |
| LOG: restored log file "000000010000000000000018" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:31.095652-00 pid=607 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:31.670020-00 pid=607 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000019.lzo prefix= seg=000000010000000000000019 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000019" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:33.645156-00 pid=623 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:34.099537-00 pid=623 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001A.lzo prefix= seg=00000001000000000000001A | |
| LOG: restored log file "00000001000000000000001A" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:35.930866-00 pid=645 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:36.330208-00 pid=645 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001B.lzo prefix= seg=00000001000000000000001B | |
| LOG: restored log file "00000001000000000000001B" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:38.277204-00 pid=660 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:38.741924-00 pid=660 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001C.lzo prefix= seg=00000001000000000000001C | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "00000001000000000000001C" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:40.680192-00 pid=676 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:41.198373-00 pid=676 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001D.lzo prefix= seg=00000001000000000000001D | |
| LOG: restored log file "00000001000000000000001D" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:43.363318-00 pid=691 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:43.786631-00 pid=691 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001E.lzo prefix= seg=00000001000000000000001E | |
| LOG: restored log file "00000001000000000000001E" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:45.707403-00 pid=714 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:46.139496-00 pid=714 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000001F.lzo prefix= seg=00000001000000000000001F | |
| LOG: restored log file "00000001000000000000001F" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:48.113630-00 pid=729 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:48.598451-00 pid=729 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000020.lzo prefix= seg=000000010000000000000020 | |
| LOG: restored log file "000000010000000000000020" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:50.550040-00 pid=744 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:50.970452-00 pid=744 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000021.lzo prefix= seg=000000010000000000000021 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000021" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:53.353563-00 pid=760 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:53.888112-00 pid=760 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000022.lzo prefix= seg=000000010000000000000022 | |
| LOG: restored log file "000000010000000000000022" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:55.810611-00 pid=784 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:56.334672-00 pid=784 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000023.lzo prefix= seg=000000010000000000000023 | |
| LOG: restored log file "000000010000000000000023" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:38:58.336186-00 pid=799 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:38:58.759769-00 pid=799 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000024.lzo prefix= seg=000000010000000000000024 | |
| LOG: restored log file "000000010000000000000024" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:00.659889-00 pid=815 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:01.082695-00 pid=815 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000025.lzo prefix= seg=000000010000000000000025 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000025" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:03.233129-00 pid=832 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:03.683123-00 pid=832 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000026.lzo prefix= seg=000000010000000000000026 | |
| LOG: restored log file "000000010000000000000026" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:05.681248-00 pid=853 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:06.132453-00 pid=853 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000027.lzo prefix= seg=000000010000000000000027 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000027" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:08.202246-00 pid=869 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:08.658468-00 pid=869 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000028.lzo prefix= seg=000000010000000000000028 | |
| LOG: restored log file "000000010000000000000028" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:10.489115-00 pid=885 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:10.926584-00 pid=885 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000029.lzo prefix= seg=000000010000000000000029 | |
| LOG: restored log file "000000010000000000000029" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:12.848661-00 pid=900 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:13.305828-00 pid=900 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002A.lzo prefix= seg=00000001000000000000002A | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "00000001000000000000002A" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:15.208392-00 pid=923 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:15.616395-00 pid=923 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002B.lzo prefix= seg=00000001000000000000002B | |
| LOG: restored log file "00000001000000000000002B" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:17.650973-00 pid=939 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:18.122407-00 pid=939 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002C.lzo prefix= seg=00000001000000000000002C | |
| LOG: restored log file "00000001000000000000002C" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:20.080380-00 pid=954 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:20.503400-00 pid=954 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002D.lzo prefix= seg=00000001000000000000002D | |
| LOG: restored log file "00000001000000000000002D" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:22.587851-00 pid=970 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:22.998511-00 pid=970 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002E.lzo prefix= seg=00000001000000000000002E | |
| LOG: restored log file "00000001000000000000002E" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:25.237086-00 pid=992 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:25.678250-00 pid=992 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000002F.lzo prefix= seg=00000001000000000000002F | |
| LOG: restored log file "00000001000000000000002F" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:27.582952-00 pid=1008 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:27.991941-00 pid=1008 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000030.lzo prefix= seg=000000010000000000000030 | |
| LOG: restored log file "000000010000000000000030" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:29.938701-00 pid=1023 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:30.378776-00 pid=1023 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000031.lzo prefix= seg=000000010000000000000031 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000031" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:32.344904-00 pid=1039 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:32.866056-00 pid=1039 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000032.lzo prefix= seg=000000010000000000000032 | |
| LOG: restored log file "000000010000000000000032" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:34.883626-00 pid=1060 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:35.319549-00 pid=1060 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000033.lzo prefix= seg=000000010000000000000033 | |
| LOG: restored log file "000000010000000000000033" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:37.198114-00 pid=1075 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:37.631992-00 pid=1075 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000034.lzo prefix= seg=000000010000000000000034 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000034" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:39.621644-00 pid=1091 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:40.046955-00 pid=1091 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000035.lzo prefix= seg=000000010000000000000035 | |
| LOG: restored log file "000000010000000000000035" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:41.928363-00 pid=1107 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:42.439113-00 pid=1107 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000036.lzo prefix= seg=000000010000000000000036 | |
| LOG: restored log file "000000010000000000000036" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:44.575920-00 pid=1130 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:45.039719-00 pid=1130 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000037.lzo prefix= seg=000000010000000000000037 | |
| LOG: restored log file "000000010000000000000037" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:47.128788-00 pid=1145 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:47.583982-00 pid=1145 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000038.lzo prefix= seg=000000010000000000000038 | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "000000010000000000000038" from archive | |
| FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:49.443142-00 pid=1162 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:49.866239-00 pid=1162 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/000000010000000000000039.lzo prefix= seg=000000010000000000000039 | |
| LOG: restored log file "000000010000000000000039" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:51.803886-00 pid=1177 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:52.270413-00 pid=1177 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003A.lzo prefix= seg=00000001000000000000003A | |
| LOG: restored log file "00000001000000000000003A" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:54.432155-00 pid=1199 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:54.880065-00 pid=1199 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003B.lzo prefix= seg=00000001000000000000003B | |
| LOG: restored log file "00000001000000000000003B" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:39:57.168123-00 pid=1218 | |
| wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:39:57.703105-00 pid=1218 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003C.lzo prefix= seg=00000001000000000000003C | |
| FATAL: the database system is starting up | |
| .LOG: restored log file "00000001000000000000003C" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:40:00.311725-00 pid=1237 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: promoted prefetched wal segment | |
| STRUCTURED: time=2016-04-25T21:40:00.880869-00 pid=1237 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003D.lzo prefix= seg=00000001000000000000003D | |
| LOG: restored log file "00000001000000000000003D" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:40:04.527419-00 pid=1256 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:40:05.204255-00 pid=1256 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003E.lzo prefix= seg=00000001000000000000003E state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils WARNING MSG: could no longer locate object while performing wal restore | |
| DETAIL: The absolute URI that could not be located is gs://aaron-outside-storage-test/wal_005/00000001000000000000003E.lzo. | |
| HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration. | |
| STRUCTURED: time=2016-04-25T21:40:06.036532-00 pid=1256 | |
| lzop: <stdin>: not a lzop file | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:40:06.137768-00 pid=1256 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003E.lzo prefix= seg=00000001000000000000003E state=complete | |
| LOG: redo done at 0/3D000090 | |
| LOG: last completed transaction was at log time 2016-04-15 18:32:13.04333+00 | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:40:09.286078-00 pid=1285 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:40:10.075856-00 pid=1285 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003D.lzo prefix= seg=00000001000000000000003D state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils INFO MSG: completed download and decompression | |
| DETAIL: Downloaded and decompressed "gs://aaron-outside-storage-test/wal_005/00000001000000000000003D.lzo" to "pg_xlog/RECOVERYXLOG" | |
| STRUCTURED: time=2016-04-25T21:40:11.362885-00 pid=1285 | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:40:11.363255-00 pid=1285 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001000000000000003D.lzo prefix= seg=00000001000000000000003D state=complete | |
| LOG: restored log file "00000001000000000000003D" from archive | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:40:14.758937-00 pid=1320 | |
| FATAL: the database system is starting up | |
| .wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:40:15.550262-00 pid=1320 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000002.history.lzo prefix= seg=00000002.history state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| FATAL: the database system is starting up | |
| .wal_e.blobstore.gs.utils WARNING MSG: could no longer locate object while performing wal restore | |
| DETAIL: The absolute URI that could not be located is gs://aaron-outside-storage-test/wal_005/00000002.history.lzo. | |
| HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration. | |
| STRUCTURED: time=2016-04-25T21:40:16.234239-00 pid=1320 | |
| lzop: <stdin>: not a lzop file | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:40:16.335353-00 pid=1320 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000002.history.lzo prefix= seg=00000002.history state=complete | |
| LOG: selected new timeline ID: 2 | |
| FATAL: the database system is starting up | |
| .FATAL: the database system is starting up | |
| .wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-fetch". | |
| STRUCTURED: time=2016-04-25T21:40:18.532611-00 pid=1354 | |
| wal_e.operator.backup INFO MSG: begin wal restore | |
| STRUCTURED: time=2016-04-25T21:40:19.015499-00 pid=1354 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001.history.lzo prefix= seg=00000001.history state=begin | |
| FATAL: the database system is starting up | |
| .oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| wal_e.blobstore.gs.utils WARNING MSG: could no longer locate object while performing wal restore | |
| DETAIL: The absolute URI that could not be located is gs://aaron-outside-storage-test/wal_005/00000001.history.lzo. | |
| HINT: This can be normal when Postgres is trying to detect what timelines are available during restoration. | |
| STRUCTURED: time=2016-04-25T21:40:19.553332-00 pid=1354 | |
| lzop: <stdin>: not a lzop file | |
| wal_e.operator.backup INFO MSG: complete wal restore | |
| STRUCTURED: time=2016-04-25T21:40:19.663391-00 pid=1354 action=wal-fetch key=gs://aaron-outside-storage-test/wal_005/00000001.history.lzo prefix= seg=00000001.history state=complete | |
| LOG: archive recovery complete | |
| FATAL: the database system is starting up | |
| .LOG: MultiXact member wraparound protections are now enabled | |
| LOG: database system is ready to accept connections | |
| LOG: autovacuum launcher started | |
| done | |
| server started | |
| /docker-entrypoint.sh: running /docker-entrypoint-initdb.d/004_setup_backup_restore.sh | |
| /docker-entrypoint.sh: running /docker-entrypoint-initdb.d/005_run_backups.sh | |
| LOG: received fast shutdown request | |
| LOG: aborting any active transactions | |
| LOG: autovacuum launcher shutting down | |
| LOG: shutting down | |
| waiting for server to shut down....LOG: database system is shut down | |
| done | |
| server stopped | |
| PostgreSQL init process complete; ready for start up. | |
| LOG: database system was shut down at 2016-04-25 21:40:21 UTC | |
| LOG: MultiXact member wraparound protections are now enabled | |
| LOG: database system is ready to accept connections | |
| LOG: autovacuum launcher started | |
| FATAL: password authentication failed for user "Yu6GlueTlivYC1dKSuRQkhRwXKYV6N2D" | |
| DETAIL: Connection matched pg_hba.conf line 8: "host all all 0.0.0.0/0 md5" | |
| wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-push". | |
| STRUCTURED: time=2016-04-25T21:41:23.170203-00 pid=1479 | |
| wal_e.worker.upload INFO MSG: begin archiving a file | |
| DETAIL: Uploading "pg_xlog/00000002000000000000003E" to "gs://aaron-outside-storage-test/wal_005/00000002000000000000003E.lzo". | |
| STRUCTURED: time=2016-04-25T21:41:23.253441-00 pid=1479 action=push-wal key=gs://aaron-outside-storage-test/wal_005/00000002000000000000003E.lzo prefix= seg=00000002000000000000003E state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| wal_e.worker.upload INFO MSG: completed archiving to a file | |
| DETAIL: Archiving to "gs://aaron-outside-storage-test/wal_005/00000002000000000000003E.lzo" complete at 251.596KiB/s. | |
| STRUCTURED: time=2016-04-25T21:41:23.748712-00 pid=1479 action=push-wal key=gs://aaron-outside-storage-test/wal_005/00000002000000000000003E.lzo prefix= rate=251.596 seg=00000002000000000000003E state=complete | |
| FATAL: password authentication failed for user "Yu6GlueTlivYC1dKSuRQkhRwXKYV6N2D" | |
| DETAIL: Connection matched pg_hba.conf line 8: "host all all 0.0.0.0/0 md5" | |
| wal_e.main INFO MSG: starting WAL-E | |
| DETAIL: The subcommand is "wal-push". | |
| STRUCTURED: time=2016-04-25T21:45:23.628196-00 pid=1680 | |
| wal_e.worker.upload INFO MSG: begin archiving a file | |
| DETAIL: Uploading "pg_xlog/00000002000000000000003F" to "gs://aaron-outside-storage-test/wal_005/00000002000000000000003F.lzo". | |
| STRUCTURED: time=2016-04-25T21:45:23.744947-00 pid=1680 action=push-wal key=gs://aaron-outside-storage-test/wal_005/00000002000000000000003F.lzo prefix= seg=00000002000000000000003F state=begin | |
| oauth2client.client INFO Attempting refresh to obtain initial access_token | |
| oauth2client.client INFO Refreshing access_token | |
| wal_e.worker.upload INFO MSG: completed archiving to a file | |
| DETAIL: Archiving to "gs://aaron-outside-storage-test/wal_005/00000002000000000000003F.lzo" complete at 307.292KiB/s. | |
| STRUCTURED: time=2016-04-25T21:45:24.177272-00 pid=1680 action=push-wal key=gs://aaron-outside-storage-test/wal_005/00000002000000000000003F.lzo prefix= rate=307.292 seg=00000002000000000000003F state=complete |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment