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

max_time_ms_sharded.js failing in "sharding" suite across multiple builders

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Major - P3 Major - P3
    • 2.5.4
    • Affects Version/s: None
    • Component/s: None
    • Labels:
    • ALL

      http://mci.10gen.com/ui/task/mongodb_mongo_master_linux_64_59c349c00ecd5de1adb18f398a63917c36c8334b_13_10_31_10_24_06_sharding_linux_6

      its failing on all the linux-64 and linux-32 bit variants.

      The failing test is "max_time_ms_sharded.js"
      Commit:
      https://github.com/mongodb/mongo/commit/59c349c00ecd5de1adb18f398a63917c36c8334b

      A windows example:
      http://mci.10gen.com/ui/task/mongodb_mongo_master_windows_64_59c349c00ecd5de1adb18f398a63917c36c8334b_13_10_31_10_24_06_sharding_windows_64

      Log snippet:

      [migrateThread] migrate commit succeeded flushing to secondaries for 'foo.bar' { _id: 0.0 } -> { _id: MaxKey }
      
       m30001| 2013-10-31T15:56:19.933+0000 [migrateThread] migrate commit flushed to journal for 'foo.bar' { _id: 0.0 } -> { _id: MaxKey }
      
       m30001| 2013-10-31T15:56:19.933+0000 [migrateThread] about to log metadata event: { _id: "build16.nj1.10gen.cc-2013-10-31T15:56:19-52727da36cd5b111bd10fcc9", server: "build16.nj1.10gen.cc", clientAddr: ":27017", time: new Date(1383234979933), what: "moveChunk.to", ns: "foo.bar", details: { min: { _id: 0.0 }, max: { _id: MaxKey }, step 1 of 5: 10, step 2 of 5: 0, step 3 of 5: 0, step 4 of 5: 0, step 5 of 5: 12 } }
      
       m30000| 2013-10-31T15:56:19.934+0000 [conn7] moveChunk migrate commit accepted by TO-shard: { active: false, ns: "foo.bar", from: "localhost:30000", min: { _id: 0.0 }, max: { _id: MaxKey }, shardKeyPattern: { _id: 1.0 }, state: "done", counts: { cloned: 0, clonedBytes: 0, catchup: 0, steady: 0 }, ok: 1.0 }
      
       m30000| 2013-10-31T15:56:19.934+0000 [conn7] moveChunk updating self version to: 2|1||52727da3e408d01ca947ccd4 through { _id: MinKey } -> { _id: 0.0 } for collection 'foo.bar'
      
       m30000| 2013-10-31T15:56:19.935+0000 [initandlisten] connection accepted from 127.0.0.1:41050 #12 (12 connections now open)
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] about to log metadata event: { _id: "build16.nj1.10gen.cc-2013-10-31T15:56:19-52727da3d51f594ef09a80f7", server: "build16.nj1.10gen.cc", clientAddr: "127.0.0.1:41040", time: new Date(1383234979936), what: "moveChunk.commit", ns: "foo.bar", details: { min: { _id: 0.0 }, max: { _id: MaxKey }, from: "shard0000", to: "shard0001", cloned: 0, clonedBytes: 0, catchup: 0, steady: 0 } }
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] MigrateFromStatus::done About to acquire global write lock to exit critical section
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] MigrateFromStatus::done Global lock acquired
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] forking for cleanup of chunk data
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] MigrateFromStatus::done About to acquire global write lock to exit critical section
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] MigrateFromStatus::done Global lock acquired
      
       m30000| 2013-10-31T15:56:19.936+0000 [RangeDeleter] Deleter starting delete for: foo.bar from { _id: 0.0 } -> { _id: MaxKey }, with opId: 233
      
       m30000| 2013-10-31T15:56:19.936+0000 [RangeDeleter] rangeDeleter deleted 0 documents for foo.bar from { _id: 0.0 } -> { _id: MaxKey }
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] distributed lock 'foo.bar/build16.nj1.10gen.cc:30000:1383234979:223234912' unlocked. 
      
       m30000| 2013-10-31T15:56:19.936+0000 [conn7] about to log metadata event: { _id: "build16.nj1.10gen.cc-2013-10-31T15:56:19-52727da3d51f594ef09a80f8", server: "build16.nj1.10gen.cc", clientAddr: "127.0.0.1:41040", time: new Date(1383234979936), what: "moveChunk.from", ns: "foo.bar", details: { min: { _id: 0.0 }, max: { _id: MaxKey }, step 1 of 6: 0, step 2 of 6: 3, step 3 of 6: 2, step 4 of 6: 16, step 5 of 6: 9, step 6 of 6: 0 } }
      
       m30999| 2013-10-31T15:56:19.937+0000 [conn1] ChunkManager: time to load chunks for foo.bar: 0ms sequenceNumber: 4 version: 2|1||52727da3e408d01ca947ccd4 based on: 1|2||52727da3e408d01ca947ccd4
      
       m30001| 2013-10-31T15:56:19.942+0000 [conn3] remotely refreshing metadata for foo.bar with requested shard version 2|0||52727da3e408d01ca947ccd4 based on current shard version 0|0||52727da3e408d01ca947ccd4
      
       m30001| 2013-10-31T15:56:19.943+0000 [conn3] loaded newer metadata for foo.bar (loaded version : 2|0||52727da3e408d01ca947ccd4, stored version : 0|0||52727da3e408d01ca947ccd4, took 0ms)
      
      assert: [50] != [0] are not equal : undefined
      
      Error: Printing Stack Trace
      
          at printStackTrace (src/mongo/shell/utils.js:38:15)
      
          at doassert (src/mongo/shell/assert.js:6:5)
      
          at Function.assert.eq (src/mongo/shell/assert.js:32:5)
      
          at /srv/10gen/mci-exec/mci/git@github.commongodb/mongo.git/master/jstests/sharding/max_time_ms_sharded.js:55:8
      
      2013-10-31T15:56:19.947+0000 [50] != [0] are not equal : undefined at src/mongo/shell/assert.js:7
      
      failed to load: /srv/10gen/mci-exec/mci/git@github.commongodb/mongo.git/master/jstests/sharding/max_time_ms_sharded.js
      
      

            Assignee:
            rassi J Rassi
            Reporter:
            mikeo@mongodb.com Michael O'Brien
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: