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

WriteConflict in dropIndexes on WT

    XMLWordPrintable

Details

    • Bug
    • Status: Closed
    • Critical - P2
    • Resolution: Duplicate
    • 3.0.0-rc6
    • None
    • WiredTiger
    • ALL
    • Hide

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

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

    Description

      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)

      Attachments

        Issue Links

          Activity

            People

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

              Dates

                Created:
                Updated:
                Resolved: