Can someone help explain what's going on from the attached logs? - Mailing list pgsql-general

From Samuel Hwang
Subject Can someone help explain what's going on from the attached logs?
Date
Msg-id CAOPCsHGc4Z9cgfQ_o7r_95OFcSLJCL5A-MtHMbfT5+ZM3TAH8Q@mail.gmail.com
Whole thread Raw
Responses Re: Can someone help explain what's going on from the attached logs?  (Craig Ringer <ringerc@ringerc.id.au>)
Re: Can someone help explain what's going on from the attached logs?  (Simon Riggs <simon@2ndQuadrant.com>)
List pgsql-general
The log is getting from PostgreSQL 9.0.4
Basically we set up streaming replication hot-standby slave while master is under heavy load
The slave started but not accepting read-only queries,
every request will trigger the "FATAL:  the database system is starting up" error.

The slave will eventually be able to accept read-only queries after the load on master is removed.
We don't see the same problem if we set up hot-standby slave while master is not under heavy load.

the logs follow:

2011-10-18 10:34:06 MDT [17570]: [1-1] LOG:  database system was interrupted; last known up at 2011-10-18 10:33:57 MDT
2011-10-18 10:34:06 MDT [17570]: [2-1] LOG:  creating missing WAL directory "pg_xlog/archive_status"
2011-10-18 10:34:06 MDT [17570]: [3-1] LOG:  entering standby mode
2011-10-18 10:34:06 MDT [17573]: [1-1] LOG:  streaming replication successfully connected to primary
2011-10-18 10:34:06 MDT [17570]: [4-1] DEBUG:  checkpoint record is at 3/6F1CBD68
2011-10-18 10:34:06 MDT [17570]: [5-1] DEBUG:  redo record is at 3/6F0000E8; shutdown FALSE
2011-10-18 10:34:06 MDT [17570]: [6-1] DEBUG:  next transaction ID: 0/5245499; next OID: 1470356
2011-10-18 10:34:06 MDT [17570]: [7-1] DEBUG:  next MultiXactId: 703; next MultiXactOffset: 1413
2011-10-18 10:34:06 MDT [17570]: [8-1] DEBUG:  oldest unfrozen transaction ID: 654, in database 1
2011-10-18 10:34:06 MDT [17570]: [9-1] DEBUG:  transaction ID wrap limit is 2147484301, limited by database with OID 1
2011-10-18 10:34:06 MDT [17570]: [10-1] DEBUG:  initializing for hot standby
2011-10-18 10:34:06 MDT [17570]: [11-1] LOG:  consistent recovery state reached at 3/6F1CBDC0
2011-10-18 10:34:06 MDT [17570]: [12-1] LOG:  redo starts at 3/6F0000E8
2011-10-18 10:34:06 MDT [17570]: [13-1] LOG:  consistent state delayed because recovery snapshot incomplete
2011-10-18 10:34:06 MDT [17570]: [14-1] CONTEXT:  xlog redo  running xacts: nextXid 5245499 latestCompletedXid 5245497 oldestRunningXid 5244950; 300 xacts: 5245124 5245125 5245126 5245127 5245128 5245129 5245130 5245131 5245132 5245133 5245134 5245135 5245136 5245137 5245138 5245139 5245140 5245141 5245142 5245143 5245144 5245145 5245146 5245147 5245148 5245149 5245150 5245151 5245152 5245153 5245154 5245155 5245156 5245157 5245426 5245427 5245428 5245429 5245430 5245431 5245432 5245433 5245434 5245435 5245436 5245437 5245438 5245439 5245440 5245441 5245442 5245443 5245444 5245445 5245446 5245447 5245448 5245449 5245450 5245451 5245452 5245453 5245454 5245455 5245456 5245498 5244950 5244951 5244952 5244953 5244954 5244955 5244956 5244957 5244958 5244959 5244960 5244961 5244962 5244963 5244964 5244965 5244966 5244967 5244968 5244969 5244970 5244971 5244972 5244973 5244974 5244975 5244976 5244977 5244978 5244979 5244980 5244981 5244982 5244983 5245159 5245160 5245161 5245162 5245163 5245164 5245165 5245166 5245167 5245168 5245169 5245170 5245171 5245172 5245173 5245174 5245175 5245176 5245177 5245178 5245179 5245180 5245181 5245182 5245183 5245184 5245185 5245186 5245187 5245188 5245189 5245190 5245191 5245192 5245193 5245194 5245195 5245196 5245197 5245198 5245199 5245200 5245201 5245202 5245203 5245204 5245205 5245206 5245207 5245208 5245209 5245210 5245211 5245212 5245213 5245214 5245215 5245216 5245217 5245218 5245219 5245222 5245223 5245224 5245225 5244987 5244988 5244989 5244990 5244991 5244992 5244993 5244994 5244995 5244996 5244997 5244998 5244999 5245000 5245001 5245002 5245003 5245004 5245005 5245006 5245007 5245008 5245009 5245010 5245011 5245012 5245013 5245014 5245015 5245016 5245017 5245018 5245019 5245020 5245307 5245308 5245309 5245310 5245311 5245312 5245313 5245314 5245315 5245316 5245317 5245318 5245319 5245320 5245321 5245322 5245323 5245324 5245325 5245326 5245327 5245328 5245329 5245330 5245331 5245332 5245333 5245334 5245335 5245336 5245337 5245242 5245243 5245244 5245245 5245246 5245247 5245248 5245249 5245250 5245251 5245252 5245253 5245254 5245255 5245256 5245257 5245258 5245259 5245260 5245261 5245262 5245263 5245264 5245265 5245266 5245267 5245268 5245269 5245270 5245271 5245272 5245273 5245274 5245275 5245276 5245277 5245062 5245063 5245064 5245065 5245066 5245067 5245068 5245069 5245070 5245071 5245072 5245073 5245074 5245075 5245076 5245077 5245078 5245079 5245080 5245081 5245082 5245083 5245084 5245085 5245086 5245087 5245088 5245089 5245090 5245091 5245092 5245093 5245094 5245095; subxid ovf
2011-10-18 10:34:07 MDT [17593]: [1-1] FATAL:  the database system is starting up
2011-10-18 10:34:08 MDT [17633]: [1-1] FATAL:  the database system is starting up
2011-10-18 10:34:09 MDT [17669]: [1-1] FATAL:  the database system is starting up

Samuel

pgsql-general by date:

Previous
From: "Joshua D. Drake"
Date:
Subject: Re: Postgre Performance
Next
From: "Deshpande, Yogesh Sadashiv (STSD-Openview)"
Date:
Subject: Re: Postgre Performance