postgresql-2011-10-31_152925.log

application/octet-stream

Filename: postgresql-2011-10-31_152925.log
Type: application/octet-stream
Part: 1
Message: Re: Hot Standby startup with overflowed snapshots
2011-10-31 15:29:25.422 MDT [30424]: [1-1] LOG:  database system was interrupted; last known up at 2011-10-31 15:29:13 MDT
2011-10-31 15:29:25.422 MDT [30424]: [2-1] LOG:  creating missing WAL directory "pg_xlog/archive_status"
2011-10-31 15:29:25.538 MDT [30424]: [3-1] LOG:  entering standby mode
DEBUG:  received replication command: IDENTIFY_SYSTEM
DEBUG:  received replication command: START_REPLICATION 10/B3000000
2011-10-31 15:29:25.543 MDT [30425]: [1-1] LOG:  streaming replication successfully connected to primary
2011-10-31 15:29:25.644 MDT [30424]: [4-1] DEBUG:  checkpoint record is at 10/B3440C80
2011-10-31 15:29:25.644 MDT [30424]: [5-1] DEBUG:  redo record is at 10/B3000020; shutdown FALSE
2011-10-31 15:29:25.644 MDT [30424]: [6-1] DEBUG:  next transaction ID: 0/25705268; next OID: 7307230
2011-10-31 15:29:25.644 MDT [30424]: [7-1] DEBUG:  next MultiXactId: 4004; next MultiXactOffset: 8059
2011-10-31 15:29:25.644 MDT [30424]: [8-1] DEBUG:  oldest unfrozen transaction ID: 1669, in database 1
2011-10-31 15:29:25.644 MDT [30424]: [9-1] DEBUG:  transaction ID wrap limit is 2147485316, limited by database with OID 1
2011-10-31 15:29:25.734 MDT [30424]: [10-1] DEBUG:  resetting unlogged relations: cleanup 1 init 0
2011-10-31 15:29:25.735 MDT [30444]: [1-1] FATAL:  the database system is starting up
2011-10-31 15:29:25.741 MDT [30424]: [11-1] DEBUG:  initializing for hot standby
2011-10-31 15:29:25.742 MDT [30424]: [12-1] LOG:  consistent recovery state reached at 10/B3440CD8
2011-10-31 15:29:25.742 MDT [30424]: [13-1] LOG:  redo starts at 10/B3000020
2011-10-31 15:29:25.801 MDT [30424]: [14-1] LOG:  recovery snapshot waiting for non-overflowed snapshot or until oldest active xid on standby is at least 25705267 (now 25704895)
2011-10-31 15:29:25.801 MDT [30424]: [15-1] CONTEXT:  xlog redo  running xacts: nextXid 25705268 latestCompletedXid 25705267 oldestRunningXid 25704895; 130 xacts: 25704895 25704896 25704897 25704898 25704899 25704900 25704901 25704902 25704903 25704904 25704905 25704906 25704907 25704908 25704909 25704910 25704911 25704912 25704913 25704914 25704915 25704916 25704917 25704918 25704919 25704920 25704921 25704922 25704923 25704924 25704925 25704926 25704927 25704928 25705049 25705050 25705051 25705052 25705053 25705054 25705055 25705056 25705057 25705058 25705059 25705060 25705061 25705062 25705063 25705064 25705065 25705066 25705067 25705068 25705069 25705070 25705071 25705072 25705073 25705074 25705075 25705076 25705077 25705078 25705079 25705103 25705104 25705105 25705106 25705107 25705108 25705109 25705110 25705111 25705112 25705113 25705114 25705115 25705116 25705117 25705118 25705119 25705120 25705121 25705122 25705123 25705124 25705125 25705126 25705127 25705128 25705129 25705130 25705131 25705132 25705133 25705134 25705135 25705136 25705137 25705138 25705139 25705140 25705141 25705142 25705143 25705144 25705145 25705146 25705147 25705148 25705149 25705150 25705151 25705152 25705153 25705154 25705155 25705156 25705157 25705158 25705159 25705160 25705161 25705162 25705169 25705172 25705173 25705174 25705175; subxid ovf
DEBUG:  standby "othernode_slave" has now caught up with primary
2011-10-31 15:29:25.932 MDT [30424]: [16-1] LOG:  102 KnownAssignedXids (num=102 tail=0 head=111) [0]=25718823 [1]=25718824 [2]=25718825 [3]=25718826 [4]=25718827 [5]=25718828 [6]=25718829 [7]=25718830 [8]=25718831 [9]=25718832 [10]=25718833 [11]=25718834 [12]=25718835 [13]=25718836 [14]=25718837 [15]=25718838 [16]=25718839 [17]=25718840 [18]=25718841 [19]=25718842 [20]=25718843 [21]=25718844 [22]=25718845 [23]=25718846 [24]=25718847 [25]=25718848 [26]=25718849 [27]=25718850 [28]=25718851 [29]=25718852 [30]=25718853 [31]=25718854 [32]=25718855 [33]=25718856 [38]=25718861 [39]=25718862 [40]=25718863 [41]=25718864 [42]=25718865 [43]=25718866 [44]=25718867 [45]=25718868 [46]=25718869 [47]=25718870 [48]=25718871 [49]=25718872 [50]=25718873 [51]=25718874 [52]=25718875 [53]=25718876 [54]=25718877 [55]=25718878 [56]=25718879 [57]=25718880 [58]=25718881 [59]=25718882 [60]=25718883 [61]=25718884 [62]=25718885 [63]=25718886 [64]=25718887 [65]=25718888 [66]=25718889 [67]=25718890 [68]=25718891 [69]=25718892 [70]=25718893 [71]=25718894 [72]=25718895 [73]=25718896 [74]=25718897 [75]=25718898 [76]=25718899 [77]=25718900 [78]=25718901 [79]=25718902 [80]=25718903 [81]=25718904 [82]=25718905 [83]=25718906 [84]=25718907 [85]=25718908 [86]=25718909 [87]=25718910 [88]=25718911 [89]=25718912 [90]=25718913 [91]=25718914 [92]=25718915 [93]=25718916 [94]=25718917 [95]=25718918 [96]=25718919 [97]=25718920 [98]=25718921 [99]=25718922 [100]=25718923 [101]=25718924 [102]=25718925 [103]=25718926 [104]=25718927 [105]=25718928 
2011-10-31 15:29:25.932 MDT [30424]: [17-1] CONTEXT:  xlog redo  running xacts: nextXid 25718935 latestCompletedXid 25718933 oldestRunningXid 25718823; 103 xacts: 25718934 25718895 25718896 25718897 25718898 25718899 25718900 25718901 25718902 25718903 25718904 25718905 25718906 25718907 25718908 25718909 25718910 25718911 25718912 25718913 25718914 25718915 25718916 25718917 25718918 25718919 25718920 25718921 25718922 25718923 25718924 25718925 25718926 25718927 25718928 25718861 25718862 25718863 25718864 25718865 25718866 25718867 25718868 25718869 25718870 25718871 25718872 25718873 25718874 25718875 25718876 25718877 25718878 25718879 25718880 25718881 25718882 25718883 25718884 25718885 25718886 25718887 25718888 25718889 25718890 25718891 25718892 25718893 25718894 25718823 25718824 25718825 25718826 25718827 25718828 25718829 25718830 25718831 25718832 25718833 25718834 25718835 25718836 25718837 25718838 25718839 25718840 25718841 25718842 25718843 25718844 25718845 25718846 25718847 25718848 25718849 25718850 25718851 25718852 25718853 25718854 25718855 25718856
2011-10-31 15:29:25.932 MDT [30424]: [18-1] FATAL:  out-of-order XID insertion in KnownAssignedXids
2011-10-31 15:29:25.932 MDT [30424]: [19-1] CONTEXT:  xlog redo  running xacts: nextXid 25718935 latestCompletedXid 25718933 oldestRunningXid 25718823; 103 xacts: 25718934 25718895 25718896 25718897 25718898 25718899 25718900 25718901 25718902 25718903 25718904 25718905 25718906 25718907 25718908 25718909 25718910 25718911 25718912 25718913 25718914 25718915 25718916 25718917 25718918 25718919 25718920 25718921 25718922 25718923 25718924 25718925 25718926 25718927 25718928 25718861 25718862 25718863 25718864 25718865 25718866 25718867 25718868 25718869 25718870 25718871 25718872 25718873 25718874 25718875 25718876 25718877 25718878 25718879 25718880 25718881 25718882 25718883 25718884 25718885 25718886 25718887 25718888 25718889 25718890 25718891 25718892 25718893 25718894 25718823 25718824 25718825 25718826 25718827 25718828 25718829 25718830 25718831 25718832 25718833 25718834 25718835 25718836 25718837 25718838 25718839 25718840 25718841 25718842 25718843 25718844 25718845 25718846 25718847 25718848 25718849 25718850 25718851 25718852 25718853 25718854 25718855 25718856
2011-10-31 15:29:25.937 MDT [30371]: [2-1] LOG:  startup process (PID 30424) exited with exit code 1
2011-10-31 15:29:25.937 MDT [30371]: [3-1] LOG:  terminating any other active server processes
2011-10-31 15:29:25.994 MDT [30423]: [1-1] DEBUG:  logger shutting down