Horton

2pc_master_logs.log

application/octet-stream

Download
Filename: 2pc_master_logs.log
Type: application/octet-stream
Part: 0
Message: Re: Speedup twophase transactions 
2016-04-12 18:14:07 JST [6110]: [1-1] db=,user=,app=,client= LOG:  database system was interrupted while in recovery at log time 2016-04-12 18:12:27 JST
2016-04-12 18:14:07 JST [6110]: [2-1] db=,user=,app=,client= HINT:  If this has occurred more than once some data might be corrupted and you might need to choose an earlier recovery target.
cp: /Users/ioltas/archive/5432/00000002.history: No such file or directory
2016-04-12 18:14:07 JST [6110]: [3-1] db=,user=,app=,client= LOG:  entering standby mode
2016-04-12 18:14:07 JST [6110]: [4-1] db=,user=,app=,client= LOG:  restored log file "000000010000000000000002" from archive
2016-04-12 18:14:07 JST [6110]: [5-1] db=,user=,app=,client= DEBUG:  got WAL segment from archive
2016-04-12 18:14:07 JST [6110]: [6-1] db=,user=,app=,client= DEBUG:  checkpoint record is at 0/2000060
2016-04-12 18:14:07 JST [6110]: [7-1] db=,user=,app=,client= DEBUG:  redo record is at 0/2000028; shutdown FALSE
2016-04-12 18:14:07 JST [6110]: [8-1] db=,user=,app=,client= DEBUG:  next transaction ID: 0:866; next OID: 12670
2016-04-12 18:14:07 JST [6110]: [9-1] db=,user=,app=,client= DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0
2016-04-12 18:14:07 JST [6110]: [10-1] db=,user=,app=,client= DEBUG:  oldest unfrozen transaction ID: 858, in database 1
2016-04-12 18:14:07 JST [6110]: [11-1] db=,user=,app=,client= DEBUG:  oldest MultiXactId: 1, in database 1
2016-04-12 18:14:07 JST [6110]: [12-1] db=,user=,app=,client= DEBUG:  commit timestamp Xid oldest/newest: 0/0
2016-04-12 18:14:07 JST [6110]: [13-1] db=,user=,app=,client= DEBUG:  transaction ID wrap limit is 2147484505, limited by database with OID 1
2016-04-12 18:14:07 JST [6110]: [14-1] db=,user=,app=,client= DEBUG:  MultiXactId wrap limit is 2147483648, limited by database with OID 1
2016-04-12 18:14:07 JST [6110]: [15-1] db=,user=,app=,client= DEBUG:  starting up replication slots
2016-04-12 18:14:07 JST [6110]: [16-1] db=,user=,app=,client= DEBUG:  resetting unlogged relations: cleanup 1 init 0
2016-04-12 18:14:07 JST [6110]: [17-1] db=,user=,app=,client= DEBUG:  initializing for hot standby
2016-04-12 18:14:07 JST [6110]: [18-1] db=,user=,app=,client= LOG:  redo starts at 0/2000028
2016-04-12 18:14:07 JST [6110]: [19-1] db=,user=,app=,client= DEBUG:  recovery snapshots are now enabled
2016-04-12 18:14:07 JST [6110]: [20-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/2000028 for Standby/RUNNING_XACTS: nextXid 866 latestCompletedXid 865 oldestRunningXid 866
cp: /Users/ioltas/archive/5432/000000010000000000000003: No such file or directory
2016-04-12 18:14:07 JST [6110]: [21-1] db=,user=,app=,client= LOG:  consistent recovery state reached at 0/3000850
2016-04-12 18:14:07 JST [6101]: [3-1] db=,user=,app=,client= LOG:  database system is ready to accept read only connections
2016-04-12 18:14:07 JST [6110]: [22-1] db=,user=,app=,client= LOG:  LockAcquire: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [23-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165B0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [24-1] db=,user=,app=,client= LOG:  LockAcquire: new: lock(0x1165ade48) id(16384,16385,0,0,0,1) grantMask(0) req(0,0,0,0,0,0,0)=0 grant(0,0,0,0,0,0,0)=0 wait(0) type(AccessExclusiveLock)
2016-04-12 18:14:07 JST [6110]: [25-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165B0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [26-1] db=,user=,app=,client= LOG:  LockAcquire: new: proclock(0x116674380) lock(0x1165ade48) method(1) proc(0x116932a40) hold(0)
2016-04-12 18:14:07 JST [6110]: [27-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165B0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [28-1] db=,user=,app=,client= LOG:  LockCheckConflicts: no conflict: proclock(0x116674380) lock(0x1165ade48) method(1) proc(0x116932a40) hold(0)
2016-04-12 18:14:07 JST [6110]: [29-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165B0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [30-1] db=,user=,app=,client= LOG:  GrantLock: lock(0x1165ade48) id(16384,16385,0,0,0,1) grantMask(100) req(0,0,0,0,0,0,0)=1 grant(0,0,0,0,0,0,0)=1 wait(0) type(AccessExclusiveLock)
2016-04-12 18:14:07 JST [6110]: [31-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165B0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [32-1] db=,user=,app=,client= LOG:  LockAcquire: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [33-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30165E0 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [34-1] db=,user=,app=,client= LOG:  LockAcquire: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [35-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/3016978 for Standby/LOCK: xid 867 db 16384 rel 16385 
2016-04-12 18:14:07 JST [6110]: [36-1] db=,user=,app=,client= LOG:  invalid record length at 0/30169E0: wanted 24, got 0
2016-04-12 18:14:07 JST [6124]: [1-1] db=,user=,app=,client= LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2016-04-12 18:14:07 JST [6110]: [37-1] db=,user=,app=,client= LOG:  LockRelease: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [38-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [39-1] db=,user=,app=,client= LOG:  LockRelease: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [40-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [41-1] db=,user=,app=,client= LOG:  LockRelease: lock [16384,16385] AccessExclusiveLock
2016-04-12 18:14:07 JST [6110]: [42-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [43-1] db=,user=,app=,client= LOG:  LockRelease: found: lock(0x1165ade48) id(16384,16385,0,0,0,1) grantMask(100) req(0,0,0,0,0,0,0)=1 grant(0,0,0,0,0,0,0)=1 wait(0) type(AccessExclusiveLock)
2016-04-12 18:14:07 JST [6110]: [44-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [45-1] db=,user=,app=,client= LOG:  LockRelease: found: proclock(0x116674380) lock(0x1165ade48) method(1) proc(0x116932a40) hold(100)
2016-04-12 18:14:07 JST [6110]: [46-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [47-1] db=,user=,app=,client= LOG:  UnGrantLock: updated: lock(0x1165ade48) id(16384,16385,0,0,0,1) grantMask(0) req(0,0,0,0,0,0,0)=0 grant(0,0,0,0,0,0,0)=0 wait(0) type(AccessExclusiveLock)
2016-04-12 18:14:07 JST [6110]: [48-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [49-1] db=,user=,app=,client= LOG:  UnGrantLock: updated: proclock(0x116674380) lock(0x1165ade48) method(1) proc(0x116932a40) hold(0)
2016-04-12 18:14:07 JST [6110]: [50-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [51-1] db=,user=,app=,client= LOG:  CleanUpLock: deleting: proclock(0x116674380) lock(0x1165ade48) method(1) proc(0x116932a40) hold(0)
2016-04-12 18:14:07 JST [6110]: [52-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:07 JST [6110]: [53-1] db=,user=,app=,client= LOG:  CleanUpLock: deleting: lock(0x1165ade48) id(16384,16385,0,0,0,1) grantMask(0) req(0,0,0,0,0,0,0)=0 grant(0,0,0,0,0,0,0)=0 wait(0) type(INVALID)
2016-04-12 18:14:07 JST [6110]: [54-1] db=,user=,app=,client= CONTEXT:  xlog redo at 0/30169E0 for Transaction/COMMIT_PREPARED: 867: 2016-04-12 18:14:01.923756+09; inval msgs: catcache 63 catcache 62 catcache 63 catcache 62 catcache 45 catcache 44 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 catcache 7 catcache 6 snapshot 2608 relcache 16385
2016-04-12 18:14:20 JST [6147]: [1-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll: lockmethod=1
2016-04-12 18:14:20 JST [6147]: [2-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll done
2016-04-12 18:14:20 JST [6147]: [3-1] db=ioltas,user=ioltas,app=psql,client=[local] DEBUG:  unexpected EOF on client connection
2016-04-12 18:14:22 JST [6165]: [1-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll: lockmethod=1
2016-04-12 18:14:22 JST [6165]: [2-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll done
2016-04-12 18:14:23 JST [6165]: [3-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  statement: SELECT n.nspname as "Schema",
	  c.relname as "Name",
	  CASE c.relkind WHEN 'r' THEN 'table' WHEN 'v' THEN 'view' WHEN 'm' THEN 'materialized view' WHEN 'i' THEN 'index' WHEN 'S' THEN 'sequence' WHEN 's' THEN 'special' WHEN 'f' THEN 'foreign table' END as "Type",
	  pg_catalog.pg_get_userbyid(c.relowner) as "Owner"
	FROM pg_catalog.pg_class c
	     LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace
	WHERE c.relkind IN ('r','v','m','S','f','')
	      AND n.nspname <> 'pg_catalog'
	      AND n.nspname <> 'information_schema'
	      AND n.nspname !~ '^pg_toast'
	  AND pg_catalog.pg_table_is_visible(c.oid)
	ORDER BY 1,2;
2016-04-12 18:14:23 JST [6165]: [4-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll: lockmethod=1
2016-04-12 18:14:23 JST [6165]: [5-1] db=ioltas,user=ioltas,app=psql,client=[local] STATEMENT:  SELECT n.nspname as "Schema",
	  c.relname as "Name",
	  CASE c.relkind WHEN 'r' THEN 'table' WHEN 'v' THEN 'view' WHEN 'm' THEN 'materialized view' WHEN 'i' THEN 'index' WHEN 'S' THEN 'sequence' WHEN 's' THEN 'special' WHEN 'f' THEN 'foreign table' END as "Type",
	  pg_catalog.pg_get_userbyid(c.relowner) as "Owner"
	FROM pg_catalog.pg_class c
	     LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace
	WHERE c.relkind IN ('r','v','m','S','f','')
	      AND n.nspname <> 'pg_catalog'
	      AND n.nspname <> 'information_schema'
	      AND n.nspname !~ '^pg_toast'
	  AND pg_catalog.pg_table_is_visible(c.oid)
	ORDER BY 1,2;
2016-04-12 18:14:23 JST [6165]: [6-1] db=ioltas,user=ioltas,app=psql,client=[local] LOG:  LockReleaseAll done
2016-04-12 18:14:23 JST [6165]: [7-1] db=ioltas,user=ioltas,app=psql,client=[local] STATEMENT:  SELECT n.nspname as "Schema",
	  c.relname as "Name",
	  CASE c.relkind WHEN 'r' THEN 'table' WHEN 'v' THEN 'view' WHEN 'm' THEN 'materialized view' WHEN 'i' THEN 'index' WHEN 'S' THEN 'sequence' WHEN 's' THEN 'special' WHEN 'f' THEN 'foreign table' END as "Type",
	  pg_catalog.pg_get_userbyid(c.relowner) as "Owner"
	FROM pg_catalog.pg_class c
	     LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace
	WHERE c.relkind IN ('r','v','m','S','f','')
	      AND n.nspname <> 'pg_catalog'
	      AND n.nspname <> 'information_schema'
	      AND n.nspname !~ '^pg_toast'
	  AND pg_catalog.pg_table_is_visible(c.oid)
	ORDER BY 1,2;