127.0.0.1 2017-06-23 10:28:25.862 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
**.*.**.*** 2017-06-23 10:28:25.996 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DA8000 flush 2F81/E7D90000 apply 2F81/E7D8FBA0
**.*.**.*** 2017-06-23 10:28:25.996 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DA8000 flush 2F81/E7DA8000 apply 2F81/E7D8FBA0
sent 16492 bytes received 24607 bytes 82198.00 bytes/sec
total size is 16777216 speedup is 408.21
2017-06-23 10:28:26.014 BST 24752 594bdf95.60b0 00000 DEBUG: archived transaction log file "0000000100002F81000000E5"
2017-06-23 10:28:26.018 BST 24752 594bdf95.60b0 00000 DEBUG: executing archive command "rsync -e ssh -arv /wal/pg_xlog/0000000100002F81000000E6 postgres@<hostname removed>:/wal/pg_xlog"
127.0.0.1 2017-06-23 10:28:26.101 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - index_beginscan_internal scan: 630163208
127.0.0.1 2017-06-23 10:28:26.101 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
sending incremental file list
sent 69 bytes received 12 bytes 162.00 bytes/sec
total size is 16777216 speedup is 207126.12
2017-06-23 10:28:26.200 BST 24752 594bdf95.60b0 00000 DEBUG: archived transaction log file "0000000100002F81000000E6"
2017-06-23 10:28:26.201 BST 24752 594bdf95.60b0 00000 DEBUG: executing archive command "rsync -e ssh -arv /wal/pg_xlog/0000000100002F81000000E7 postgres@<hostname removed>:/wal/pg_xlog"
**.*.**.*** 2017-06-23 10:28:26.203 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DC8000 flush 2F81/E7DA8000 apply 2F81/E7DA7FC8
**.*.**.*** 2017-06-23 10:28:26.203 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DC8000 flush 2F81/E7DC8000 apply 2F81/E7DA7FC8
**.*.**.*** 2017-06-23 10:28:26.204 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DE8000 flush 2F81/E7DC8000 apply 2F81/E7DB1838
**.*.**.*** 2017-06-23 10:28:26.205 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7DE8000 flush 2F81/E7DE8000 apply 2F81/E7DB4A60
**.*.**.*** 2017-06-23 10:28:26.206 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7E08000 flush 2F81/E7DE8000 apply 2F81/E7DC4F08
**.*.**.*** 2017-06-23 10:28:26.207 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E7E08000 flush 2F81/E7E08000 apply 2F81/E7DC8178
127.0.0.1 2017-06-23 10:28:26.212 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Calling toast_insert_or_update from rewriteheap.c raw_heap_insert
127.0.0.1 2017-06-23 10:28:26.212 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.212 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - INSERT - new tuple is 8001, table is: 2345707504
127.0.0.1 2017-06-23 10:28:26.212 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - INSERT - new tuple is 43493696, table is: 2345707504
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - called toast_fetch_datum from heap_tuple_fetch_attr
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Has failed is 0 before loop
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - About to start loop grabbing chunks.
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Calling systable_beginscan_ordered with toastrel: 2345700592 and toastidx: 2345702000.
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - index_beginscan_internal scan: 630163208
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Called systable_beginscan_ordered. Returned data is: 630163352.
127.0.0.1 2017-06-23 10:28:26.213 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
**.*.**.*** 2017-06-23 10:28:26.225 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E8000000 flush 2F81/E7E08000 apply 2F81/E7E079C0
**.*.**.*** 2017-06-23 10:28:26.227 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E8000000 flush 2F81/E8000000 apply 2F81/E7E079C0
sending incremental file list
sent 69 bytes received 12 bytes 162.00 bytes/sec
total size is 16777216 speedup is 207126.12
2017-06-23 10:28:26.380 BST 24752 594bdf95.60b0 00000 DEBUG: archived transaction log file "0000000100002F81000000E7"
2017-06-23 10:28:26.440 BST 24749 594bdf95.60ad 00000 DEBUG: snapshot of 1+0 running transaction ids (lsn 2F81/E871D980 oldest xid 73603616 latest complete 73603732 next xid 73603733)
**.*.**.*** 2017-06-23 10:28:26.502 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E871C000 flush 2F81/E8000000 apply 2F81/E7FFFB70
**.*.**.*** 2017-06-23 10:28:26.509 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E871C000 flush 2F81/E871C000 apply 2F81/E7FFFB70
**.*.**.*** 2017-06-23 10:28:26.625 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E8722000 flush 2F81/E871C000 apply 2F81/E856BE20
**.*.**.*** 2017-06-23 10:28:26.625 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E8722000 flush 2F81/E8722000 apply 2F81/E856DFB8
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: unexpected chunk number 2287 (expected 3) for toast value 192719354 in pg_toast_192719329
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Current chunk number is 2287, expecting 3.
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 DEBUG: HARRYAMBROSE - Has failed is 1 after loop
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] 00000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] XX000 ERROR: unexpected chunk number exception, please see DEBUG.
127.0.0.1 2017-06-23 10:28:26.669 BST 1219 594cd5ba.4c3 postgres [unknown] XX000 STATEMENT: VACUUM (FULL, ANALYZE) ctab
**.*.**.*** 2017-06-23 10:28:26.672 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E872C000 flush 2F81/E8722000 apply 2F81/E8722000
**.*.**.*** 2017-06-23 10:28:26.672 BST 24765 594bdf98.60bd postgres walreceiver 00000 DEBUG: write 2F81/E872C000 flush 2F81/E872C000 apply 2F81/E8722000