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

WriteConflict in dropIndexes on WT

    • Type: Icon: Bug Bug
    • Resolution: Duplicate
    • Priority: Icon: Critical - P2 Critical - P2
    • None
    • Affects Version/s: 3.0.0-rc6
    • Component/s: WiredTiger
    • ALL
    • Hide

      benchrun.py --testFilter "daily" -m 8

      Show
      benchrun.py --testFilter "daily" -m 8

      Problem

      During a mongo-perf run I see the following reported. This passes on MMAPv1

      Insert.EmptyCapped
      1       8747.676335975568
      2       13728.890263868827
      Error running test [object Object]: drop failed: {
      "ns" : "test5.Insert_EmptyCapped0",
      "nIndexesWas" : 1,
      "ok" : 0,
      "errmsg" : "drop: dropIndexes for collection failed. cause: WriteConflict. See http://dochub.mongodb.org/core/data-recovery",
      "code" : 1
      }
      

      The logs seem to indicate the the drop was executed, but then the following insert (in the timeline) tripped this

      2015-01-25T10:25:59.859-0800 I COMMAND  [conn3] CMD: drop test0.Insert_EmptyCapped0
      2015-01-25T10:25:59.862-0800 I COMMAND  [conn3] CMD: drop test1.Insert_EmptyCapped0
      2015-01-25T10:25:59.865-0800 I COMMAND  [conn3] CMD: drop test2.Insert_EmptyCapped0
      2015-01-25T10:25:59.867-0800 I COMMAND  [conn3] CMD: drop test3.Insert_EmptyCapped0
      2015-01-25T10:25:59.869-0800 I COMMAND  [conn3] CMD: drop test4.Insert_EmptyCapped0
      2015-01-25T10:25:59.871-0800 I COMMAND  [conn3] CMD: drop test5.Insert_EmptyCapped0
      2015-01-25T10:25:59.874-0800 I COMMAND  [conn3] CMD: drop test6.Insert_EmptyCapped0
      2015-01-25T10:25:59.876-0800 I COMMAND  [conn3] CMD: drop test7.Insert_EmptyCapped0
      2015-01-25T10:25:59.878-0800 I COMMAND  [conn3] CMD: drop test0.Insert_EmptyCapped0
      2015-01-25T10:25:59.878-0800 I COMMAND  [conn3] CMD: drop test1.Insert_EmptyCapped0
      2015-01-25T10:25:59.878-0800 I COMMAND  [conn3] CMD: drop test2.Insert_EmptyCapped0
      2015-01-25T10:25:59.879-0800 I COMMAND  [conn3] CMD: drop test3.Insert_EmptyCapped0
      2015-01-25T10:25:59.879-0800 I COMMAND  [conn3] CMD: drop test4.Insert_EmptyCapped0
      2015-01-25T10:25:59.879-0800 I COMMAND  [conn3] CMD: drop test5.Insert_EmptyCapped0
      2015-01-25T10:25:59.879-0800 I COMMAND  [conn3] CMD: drop test6.Insert_EmptyCapped0
      2015-01-25T10:25:59.880-0800 I COMMAND  [conn3] CMD: drop test7.Insert_EmptyCapped0
      2015-01-25T10:25:59.880-0800 I COMMAND  [conn3] CMD: drop test0.Insert_EmptyCapped0
      2015-01-25T10:25:59.882-0800 I COMMAND  [conn3] CMD: drop test1.Insert_EmptyCapped0
      2015-01-25T10:25:59.884-0800 I COMMAND  [conn3] CMD: drop test2.Insert_EmptyCapped0
      2015-01-25T10:25:59.885-0800 I COMMAND  [conn3] CMD: drop test3.Insert_EmptyCapped0
      2015-01-25T10:25:59.887-0800 I COMMAND  [conn3] CMD: drop test4.Insert_EmptyCapped0
      2015-01-25T10:25:59.889-0800 I COMMAND  [conn3] CMD: drop test5.Insert_EmptyCapped0
      2015-01-25T10:25:59.891-0800 I COMMAND  [conn3] CMD: drop test6.Insert_EmptyCapped0
      2015-01-25T10:25:59.892-0800 I COMMAND  [conn3] CMD: drop test7.Insert_EmptyCapped0
      2015-01-25T10:25:59.895-0800 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:46742 #5936 (2 connections now open)
      2015-01-25T10:25:59.895-0800 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:46743 #5937 (3 connections now open)
      2015-01-25T10:25:59.895-0800 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:46744 #5938 (4 connections now open)
      2015-01-25T10:25:59.895-0800 I NETWORK  [conn5936] end connection 127.0.0.1:46742 (3 connections now open)
      2015-01-25T10:26:02.562-0800 I WRITE    [conn5938] insert test1.Insert_EmptyCapped0 ninserted:1 keyUpdates:0 writeConflicts:0 numYields:0 111ms
      2015-01-25T10:26:02.562-0800 I COMMAND  [conn5938] command test1.$cmd command: insert { insert: "Insert_EmptyCapped0", documents: [ {} ] } ntoreturn:1 keyUpdates:0 writeConflicts:0 numYields:0 reslen:40 111ms
      2015-01-25T10:26:04.896-0800 I NETWORK  [conn5937] end connection 127.0.0.1:46743 (2 connections now open)
      2015-01-25T10:26:04.896-0800 I NETWORK  [conn5938] end connection 127.0.0.1:46744 (1 connection now open)
      2015-01-25T10:26:04.896-0800 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:46745 #5939 (2 connections now open)
      2015-01-25T10:26:04.896-0800 I NETWORK  [conn5939] end connection 127.0.0.1:46745 (1 connection now open)
      2015-01-25T10:26:04.897-0800 I COMMAND  [conn3] CMD: drop test0.Insert_EmptyCapped0
      2015-01-25T10:26:04.902-0800 I COMMAND  [conn3] CMD: drop test1.Insert_EmptyCapped0
      2015-01-25T10:26:04.906-0800 I COMMAND  [conn3] CMD: drop test2.Insert_EmptyCapped0
      2015-01-25T10:26:04.910-0800 I COMMAND  [conn3] CMD: drop test3.Insert_EmptyCapped0
      2015-01-25T10:26:04.913-0800 I COMMAND  [conn3] CMD: drop test4.Insert_EmptyCapped0
      2015-01-25T10:26:04.917-0800 I COMMAND  [conn3] CMD: drop test5.Insert_EmptyCapped0
      2015-01-25T10:26:44.455-0800 W STORAGE  [conn3] drop: dropIndexes for collection failed. cause: WriteConflict. See http://dochub.mongodb.org/core/data-recovery
      2015-01-25T10:26:44.455-0800 I COMMAND  [conn3] command test5.$cmd command: drop { drop: "Insert_EmptyCapped0" } keyUpdates:0 writeConflicts:0 numYields:0 reslen:222 39538ms
      2015-01-25T10:26:44.461-0800 I NETWORK  [conn3] end connection 127.0.0.1:40736 (0 connections now open)
      

            Assignee:
            schwerin@mongodb.com Andy Schwerin
            Reporter:
            alvin Alvin Richards (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            7 Start watching this issue

              Created:
              Updated:
              Resolved: