6. Start updating documents on primary
2013-07-03 11:50:58 EDT	
 m31000| Wed Jul 03 15:50:46.984 [conn5] end connection 10.151.17.233:51215 (4 connections now open)
 m31002| Wed Jul 03 15:50:46.984 [rsSync] oplog sync 3 of 3
 m31000| Wed Jul 03 15:50:47.280 [initandlisten] connection accepted from 10.151.17.233:51221 #7 (5 connections now open)
 m31000| Wed Jul 03 15:50:47.733 [conn1] update d.c query: { _id: 48492.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:203732 203ms
 m31000| Wed Jul 03 15:50:47.967 [conn7] command admin.$cmd command: { replSetUpdatePosition: 1, handshake: { handshake: ObjectId('51d448522784a9e70e6b06b7'), member: 2, config: { _id: 2, host: "EC2AMAZ-EXWX6MN:31002" } } } ntoreturn:1 keyUpdates:0  reslen:37 233ms
 m31000| Wed Jul 03 15:50:47.967 [conn7] end connection 10.151.17.233:51221 (4 connections now open)
 m31000| Wed Jul 03 15:50:48.169 [conn1] update d.c query: { _id: 47989.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:203201 203ms
 m31000| Wed Jul 03 15:50:48.388 [initandlisten] connection accepted from 10.151.17.233:51222 #8 (5 connections now open)
 m31000| Wed Jul 03 15:50:48.591 [conn1] update d.c query: { _id: 47518.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:406588 203ms
 m31000| Wed Jul 03 15:50:49.012 [conn1] update d.c query: { _id: 47047.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:203186 203ms
 m31000| Wed Jul 03 15:50:49.402 [slaveTracking] build index local.slaves { _id: 1 }
 m31000| Wed Jul 03 15:50:49.402 [slaveTracking] build index done.  scanned 0 total records. 0 secs
 m31000| Wed Jul 03 15:50:49.854 [conn1] update d.c query: { _id: 46109.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203266 203ms
 m31000| Wed Jul 03 15:50:50.275 [conn1] update d.c query: { _id: 45642.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:203320 203ms
 m31000| Wed Jul 03 15:50:50.712 [conn1] update d.c query: { _id: 45141.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203354 203ms
 m31000| Wed Jul 03 15:50:51.134 [conn1] update d.c query: { _id: 44679.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:406513 203ms
 m31000| Wed Jul 03 15:50:51.555 [conn1] update d.c query: { _id: 44220.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203298 203ms
 m31000| Wed Jul 03 15:50:52.397 [conn1] update d.c query: { _id: 43293.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:155 203ms
 m31000| Wed Jul 03 15:50:52.818 [conn1] update d.c query: { _id: 42829.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203346 203ms
 m31000| Wed Jul 03 15:50:54.098 [conn1] update d.c query: { _id: 41411.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203364 203ms
 m31000| Wed Jul 03 15:50:54.347 [conn3] end connection 10.151.17.233:51196 (4 connections now open)
 m31000| Wed Jul 03 15:50:54.519 [conn1] update d.c query: { _id: 40946.0 } update: { $set: { a: 1.0 } } idhack:1 nupdated:1 keyUpdates:0 locks(micros) w:203298 203ms
 m31000| Wed Jul 03 15:50:54.737 [initandlisten] connection accepted from 10.151.17.233:51223 #9 (5 connections now open)
 m31000| Wed Jul 03 15:50:54.940 [conn1] update d.c query: { _id: 40479.0 } update: { $set: { a.b: 1.0 } } idhack:1 nmoved:1 nupdated:1 keyUpdates:0 locks(micros) w:203591 203ms
 m31000| Wed Jul 03 15:50:55.377 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:203145 203ms
 m31000| Wed Jul 03 15:50:55.829 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:405998 203ms
 m31000| Wed Jul 03 15:50:56.266 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:406482 203ms
 m31000| Wed Jul 03 15:50:57.140 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:203336 203ms
2013-07-03 11:50:59 EDT	
 m31000| Wed Jul 03 15:50:58.481 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:406111 203ms
 m31000| Wed Jul 03 15:50:58.934 [conn1] insert d.c ninserted:1 keyUpdates:0 locks(micros) w:406407 203ms
 m31000| Wed Jul 03 15:50:59.776 [rsHealthPoll] DBClientCursor::init call() failed
 m31000| Wed Jul 03 15:50:59.776 [rsHealthPoll] replSet info EC2AMAZ-EXWX6MN:31002 is down (or slow to respond): 
 m31000| Wed Jul 03 15:50:59.776 [rsHealthPoll] replSet member EC2AMAZ-EXWX6MN:31002 is now in state DOWN
 m31000| Wed Jul 03 15:50:59.776 [rsMgr] can't see a majority of the set, relinquishing primary
 m31000| Wed Jul 03 15:50:59.807 [rsMgr] replSet relinquishing primary state
 m31000| Wed Jul 03 15:50:59.807 [rsMgr] replSet SECONDARY
 m31000| Wed Jul 03 15:50:59.807 [rsMgr] replSet closing client sockets after relinquishing primary
 m31000| Wed Jul 03 15:50:59.807 [conn4] end connection 10.151.17.233:51214 (4 connections now open)
 m31000| Wed Jul 03 15:50:59.823 [conn8] end connection 10.151.17.233:51222 (4 connections now open)
 m31002| Wed Jul 03 15:50:59.823 [rsBackgroundSync] DBClientCursor::init call() failed
 m31002| Wed Jul 03 15:50:59.823 [conn2] command admin.$cmd command: { replSetHeartbeat: "jstests_initsync4", v: 2, pv: 1, checkEmpty: false, from: "EC2AMAZ-EXWX6MN:31000" } ntoreturn:1 keyUpdates:0  reslen:203 10580ms
 m31000| Wed Jul 03 15:50:59.823 [conn1] end connection 127.0.0.1:51180 (4 connections now open)
Wed Jul 03 15:50:59.823 Socket say send() errno:10054 An existing connection was forcibly closed by the remote host. 127.0.0.1:31000
Wed Jul 03 15:50:59.823 Error: socket exception [SEND_ERROR] for 127.0.0.1:31000 at src/mongo/shell/collection.js:203
failed to load: D:\slave\Windows_32bit_Nightly\mongo\jstests\replsets\initial_sync4.js