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

test fails when shell kills mongod after waiting 5 secs for file allocation to wrap up, resulting in bad lock file

    • Type: Icon: Bug Bug
    • Resolution: Incomplete
    • Priority: Icon: Trivial - P5 Trivial - P5
    • None
    • Affects Version/s: None
    • Component/s: Tools
    • None
    • None
    • 0
    • None
    • None
    • None
    • None
    • None
    • None

      <http://buildbot.mongodb.org/builders/FreeBSD%208.0%2064-bit/builds/65/steps/test/logs/stdio>

      m35000| Tue Feb 2 20:51:56 allocating new datafile /data/db/jstests_tool_tool1/jstests_tool_tool1.1, filling with zeroes...
      shell: started mongo program /usr/home/10gen/buildslaves/mongo/FreeBSD_80_64bit/mongo/mongorestore --host 127.0.0.1:35000 --dir /data/db/jstests_tool_tool1_external/
      m35000| Tue Feb 2 20:51:56 connection accepted from 127.0.0.1:33264 #3
      sh18479| connected to: 127.0.0.1:35000
      sh18479| /data/db/jstests_tool_tool1_external/jstests_tool_tool1/jstests_tool_tool1.bson
      m35000| Tue Feb 2 20:51:56 building new index on

      { _id: 1 }

      for jstests_tool_tool1.jstests_tool_tool1...
      m35000| Tue Feb 2 20:51:56 Buildindex jstests_tool_tool1.jstests_tool_tool1 idxNo:0 { name: "id", ns: "jstests_tool_tool1.jstests_tool_tool1", key:

      { _id: 1 }

      }
      m35000| Tue Feb 2 20:51:56 done for 0 records 0secs
      m35000| Tue Feb 2 20:51:56 end connection 127.0.0.1:33264
      sh18479| going into namespace [jstests_tool_tool1.jstests_tool_tool1]
      sh18479| 1 objects
      sh18479| /data/db/jstests_tool_tool1_external/jstests_tool_tool1/system.indexes.bson
      sh18479| going into namespace [jstests_tool_tool1.system.indexes]
      sh18479| 1 objects
      sh18479| Tue Feb 2 20:51:56 dbexit:
      sh18479| Tue Feb 2 20:51:56 shutdown: going to close listening sockets...
      sh18479| Tue Feb 2 20:51:56 shutdown: going to flush oplog...
      sh18479| Tue Feb 2 20:51:56 shutdown: going to close sockets...
      sh18479| Tue Feb 2 20:51:56 shutdown: waiting for fs preallocator...
      sh18479| Tue Feb 2 20:51:56 shutdown: closing all files...
      sh18479| Tue Feb 2 20:51:56 closeAllFiles() finished
      sh18479| Tue Feb 2 20:51:56 dbexit: really exiting now
      shell: started mongo program /usr/home/10gen/buildslaves/mongo/FreeBSD_80_64bit/mongo/mongoexport --host 127.0.0.1:35000 -d jstests_tool_tool1 -c jstests_tool_tool1 --out /data/db/jstests_tool_tool1_external/export.json
      m35000| Tue Feb 2 20:51:56 connection accepted from 127.0.0.1:16708 #4
      sh18480| connected to: 127.0.0.1:35000
      sh18480| exported 1 records
      sh18480| Tue Feb 2 20:51:56 dbexit:
      sh18480| Tue Feb 2 20:51:56 shutdown: going to close listening sockets...
      m35000| Tue Feb 2 20:51:56 end connection 127.0.0.1:16708
      m35000| Tue Feb 2 20:51:56 CMD: drop jstests_tool_tool1.jstests_tool_tool1
      sh18480| Tue Feb 2 20:51:56 shutdown: going to flush oplog...
      sh18480| Tue Feb 2 20:51:56 shutdown: going to close sockets...
      sh18480| Tue Feb 2 20:51:56 shutdown: waiting for fs preallocator...
      sh18480| Tue Feb 2 20:51:56 shutdown: closing all files...
      sh18480| Tue Feb 2 20:51:56 closeAllFiles() finished
      sh18480| Tue Feb 2 20:51:56 dbexit: really exiting now
      shell: started mongo program /usr/home/10gen/buildslaves/mongo/FreeBSD_80_64bit/mongo/mongoimport --host 127.0.0.1:35000 -d jstests_tool_tool1 -c jstests_tool_tool1 --file /data/db/jstests_tool_tool1_external/export.json
      m35000| Tue Feb 2 20:51:56 connection accepted from 127.0.0.1:61767 #5
      sh18481| connected to: 127.0.0.1:35000
      sh18481| imported 1 objects
      sh18481| Tue Feb 2 20:51:56 dbexit:
      sh18481| Tue Feb 2 20:51:56 shutdown: going to close listening sockets...
      sh18481| Tue Feb 2 20:51:56 shutdown: going to flush oplog...
      sh18481| Tue Feb 2 20:51:56 shutdown: going to close sockets...
      sh18481| Tue Feb 2 20:51:56 shutdown: waiting for fs preallocator...
      sh18481| Tue Feb 2 20:51:56 shutdown: closing all files...
      sh18481| Tue Feb 2 20:51:56 closeAllFiles() finished
      sh18481| Tue Feb 2 20:51:56 dbexit: really exiting now
      m35000| Tue Feb 2 20:51:56 building new index on

      { _id: 1 }

      for jstests_tool_tool1.jstests_tool_tool1...
      m35000| Tue Feb 2 20:51:56 Buildindex jstests_tool_tool1.jstests_tool_tool1 idxNo:0 { name: "id", ns: "jstests_tool_tool1.jstests_tool_tool1", key:

      { _id: 1 }

      }
      m35000| Tue Feb 2 20:51:56 done for 0 records 0secs
      m35000| Tue Feb 2 20:51:56 end connection 127.0.0.1:61767
      m35000| Tue Feb 2 20:51:56 got kill or ctrl c signal 15 (Terminated: 15), will terminate after current cmd ends
      m35000| Tue Feb 2 20:51:56 now exiting
      m35000| Tue Feb 2 20:51:56 dbexit:
      m35000| Tue Feb 2 20:51:56 shutdown: going to close listening sockets...
      m35000| Tue Feb 2 20:51:56 going to close listening socket: 36
      m35000| Tue Feb 2 20:51:56 shutdown: going to flush oplog...
      m35000| Tue Feb 2 20:51:56 shutdown: going to close sockets...
      m35000| Tue Feb 2 20:51:56 shutdown: waiting for fs preallocator...
      Tue Feb 2 20:52:01 process on port 35000, with pid 18477 not terminated, sending sigkill
      shell: stopped mongo program on port 35000
      shell: started mongo program /usr/home/10gen/buildslaves/mongo/FreeBSD_80_64bit/mongo/mongodump --dbpath /data/db/jstests_tool_tool1/ --out /data/db/jstests_tool_tool1_external/
      sh18482| **************
      sh18482| old lock file: /data/db/jstests_tool_tool1/mongod.lock. probably means unclean shutdown
      sh18482| reccomend removing file and running --repair
      sh18482| see: http://dochub.mongodb.org/core/repair for more information
      sh18482| *************
      sh18482| Tue Feb 2 20:52:06 User Exception 12596:old lock file
      sh18482| terminate called after throwing an instance of 'mongo::UserException'
      sh18482| what(): old lock file

            Assignee:
            aaron Aaron Staple (Inactive)
            Reporter:
            aaron Aaron Staple (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            0 Start watching this issue

              Created:
              Updated:
              Resolved: