Can someone help explain what's going on from the attached logs?

From: Samuel Hwang <samuel(at)replicon(dot)com>
To: pgsql-general(at)postgresql(dot)org
Subject: Can someone help explain what's going on from the attached logs?
Date: 2011-10-18 17:39:32
Message-ID: CAOPCsHGc4Z9cgfQ_o7r_95OFcSLJCL5A-MtHMbfT5+ZM3TAH8Q@mail.gmail.com
Views: Raw Message | Whole Thread | Download mbox | Resend email
Thread:
Lists: 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

Responses

Browse pgsql-general by date

  From Date Subject
Next Message Alban Hertroys 2011-10-18 18:16:45 Re: Use true and false when bulk-exporting boolean data
Previous Message Deshpande, Yogesh Sadashiv (STSD-Openview) 2011-10-18 17:38:19 Re: Postgre Performance