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

replication oplog stream goes back in time under load

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

      Doesn't happen in 2.6.

      Upstream version:

      commit 0e9cb3b20498b9f167afaff7a5c4a4d1da7e06a2
      Author: Dan Pasette <dan@10gen.com>
      Date:   Wed Oct 22 14:10:37 2014 -0400
      
          BUMP 2.7.8
      

      Relevant bits in the log:

      $ tail -n 20 repl2.txt.2014-10-28T23-17-32 
      2014-10-28T23:17:31.731+0000 I STORAGE  [FileAllocator] done allocating datafile /home/ec2-user//mongo/repl2/thisdb10.0, size: 64MB,  took 0.002 secs
      2014-10-28T23:17:31.733+0000 I INDEXING [rsSync] build index on: thisdb10.mycoll10 properties: { v: 1, key: { _id: 1 }, name: "_id_", ns: "thisdb10.mycoll10" }
      2014-10-28T23:17:31.734+0000 I INDEXING [rsSync]         building index using bulk method
      2014-10-28T23:17:31.734+0000 I INDEXING [rsSync] build index done.  scanned 11 total records. 0 secs
      2014-10-28T23:17:31.734+0000 I REPLSETS [rsSync] initial sync cloning db: thisdb15
      2014-10-28T23:17:31.735+0000 I INDEXING [rsSync] allocating new ns file /home/ec2-user//mongo/repl2/thisdb15.ns, filling with zeroes...
      2014-10-28T23:17:31.901+0000 I STORAGE  [FileAllocator] allocating new datafile /home/ec2-user//mongo/repl2/thisdb15.0, filling with zeroes...
      2014-10-28T23:17:31.906+0000 I STORAGE  [FileAllocator] done allocating datafile /home/ec2-user//mongo/repl2/thisdb15.0, size: 64MB,  took 0.005 secs
      2014-10-28T23:17:31.910+0000 I INDEXING [rsSync] build index on: thisdb15.mycoll15 properties: { v: 1, key: { _id: 1 }, name: "_id_", ns: "thisdb15.mycoll15" }
      2014-10-28T23:17:31.910+0000 I INDEXING [rsSync]         building index using bulk method
      2014-10-28T23:17:31.910+0000 I INDEXING [rsSync] build index done.  scanned 79 total records. 0 secs
      2014-10-28T23:17:31.910+0000 I REPLSETS [rsSync] initial sync data copy, starting syncup
      2014-10-28T23:17:31.910+0000 I REPLSETS [rsSync] oplog sync 1 of 3
      2014-10-28T23:17:32.373+0000 F REPLSETS [rsSync] replication oplog stream went back in time. previous timestamp: 54502408:4765 newest timestamp: 545023e6:10eb
      2014-10-28T23:17:32.373+0000 I -        [rsSync] Fatal Assertion 18905
      2014-10-28T23:17:32.373+0000 I -        [rsSync] 
      
      ***aborting after fassert() failure
      

            Assignee:
            scotthernandez Scott Hernandez (Inactive)
            Reporter:
            davide.italiano Davide Italiano
            Votes:
            0 Vote for this issue
            Watchers:
            8 Start watching this issue

              Created:
              Updated:
              Resolved: