Uploaded image for project: 'Core Server'
  1. Core Server
  2. SERVER-10093

initial_sync4.js failing on Windows 32-bit nightly

    XMLWordPrintable

Details

    • Bug
    • Status: Closed
    • Critical - P2
    • Resolution: Gone away
    • None
    • None
    • Testing Infrastructure
    • buildbot: Windows 32-bit
    • Windows

    Description

      Failing differently than SERVER-9419?

      Nightly Windows 32-bit Build #1320 on Wed Jul 3

      It appears that something similar has been occurring since Build 1312 on Sun Jun 30 (rev 665e6ec21) but not before Build 1308 on Wed Jun 26 (rev 3d6e8936938e)

      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

      Attachments

        Activity

          People

            matt.dannenberg Matt Dannenberg
            matt.kangas Matt Kangas
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: