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

inserts take long time after several hours insert operation

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Blocker - P1 Blocker - P1
    • None
    • Affects Version/s: 2.0.2
    • Component/s: None
    • Environment:
      windows 2008
      Intel Xeon E5504 @2GHz
      4G RAM
    • Windows

      My application is write intensive. The op type is only insert. During the begin hours every thing is ok, but after that the insert op may takes a very very long time. I have no idea of why that happen. The log:

      Mon Dec 19 12:57:36 [conn10] insert agis.test.tp 234ms
      Mon Dec 19 13:00:03 [clientcursormon] mem (MB) res:3806 virt:65558 mapped:32705
      Mon Dec 19 13:02:38 [conn34] insert agis.test.tp 390ms
      Mon Dec 19 13:05:04 [clientcursormon] mem (MB) res:3809 virt:65558 mapped:32705
      Mon Dec 19 13:06:19 [conn34] insert agis.test.tp 109ms
      Mon Dec 19 13:06:45 [conn12] insert agis.test._records 390ms
      Mon Dec 19 13:06:51 [journal] old journal file will be removed: data/journal/j._9
      Mon Dec 19 13:07:46 [conn33] insert agis.test._records 109ms
      Mon Dec 19 13:07:58 [conn34] insert agis.test.tp 577ms
      Mon Dec 19 13:08:37 [conn34] insert agis.test._records 124ms
      Mon Dec 19 13:08:38 [conn34] insert agis.test.tp 265ms
      Mon Dec 19 13:10:05 [clientcursormon] mem (MB) res:3788 virt:69652 mapped:34752
      Mon Dec 19 13:10:39 [conn32] insert agis.test._records 280ms
      Mon Dec 19 13:10:45 [conn16] insert agis.test._records 795ms
      Mon Dec 19 13:11:04 [conn10] insert agis.test._records 109ms
      Mon Dec 19 13:11:11 [conn28] insert agis.test._records 124ms
      Mon Dec 19 16:12:23 [conn34] insert agis.test.tp 10828681ms      ****** look at this line *******
      Mon Dec 19 16:12:25 [conn33] insert agis.test._records 1544ms
      Mon Dec 19 16:12:25 [conn33] command agis.$cmd command: { getlasterror: 1, w: 1, wtimeout: 0 } ntoreturn:1 reslen:78 280ms
      Mon Dec 19 16:12:25 [conn28] command agis.$cmd command: { getlasterror: 1, w: 1, wtimeout: 0 } ntoreturn:1 reslen:78 234ms
      Mon Dec 19 16:12:25 [conn34] command agis.$cmd command: { getlasterror: 1, w: 1, wtimeout: 0 } ntoreturn:1 reslen:78 280ms
      

      currentOp show that one insert operation is processing while others are waiting for lock.

      The data size is about 12G. The index data is small against RAM.

      After restart(I must kill mongod process) it will run normally hours again, but hang again at last.

        1. vmmap.JPG
          vmmap.JPG
          296 kB
        2. status2.jpg
          status2.jpg
          132 kB
        3. status1.jpg
          status1.jpg
          120 kB
        4. dbstats.jpg
          dbstats.jpg
          119 kB

            Assignee:
            tad Tad Marshall
            Reporter:
            kobevaliant kobevaliant
            Votes:
            0 Vote for this issue
            Watchers:
            6 Start watching this issue

              Created:
              Updated:
              Resolved: