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

Very slow page faulting on windows on find() - performance regression

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed
    • Priority: Critical - P2
    • Resolution: Duplicate
    • Affects Version/s: 2.4.3
    • Fix Version/s: None
    • Component/s: None
    • Labels:
      None
    • Environment:
      Windows 2008 R2 SP1, 3.5GB RAM, 2 Cores, Azure
      2.2.4 2008+ vs 2.4.3 2008+
    • Operating System:
      ALL
    • Steps To Reproduce:
      Hide

      Collection details

      > db.test.stats()
      {
              "ns" : "test.test",
              "count" : 11235,
              "size" : 15639072,
              "avgObjSize" : 1391.995727636849,
              "storageSize" : 16875520,
              "numExtents" : 6,
              "nindexes" : 1,
              "lastExtentSize" : 8495104,
              "paddingFactor" : 1,
              "systemFlags" : 1,
              "userFlags" : 0,
              "totalIndexSize" : 376096,
              "indexSizes" : {
                      "_id_" : 376096
              },
              "ok" : 1
      }

      1. Restart mongodb
      2. Run db.test.find() from (python) client.

      Mongostat output for version 2.2.4 2008+

      connected to: 127.0.0.1
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
           0      0      0      0       0       1       0     0m    95m    35m      6 local:0.0%          0       0|0     0|0    62b     1k     1   09:54:20 
           0      0      0      0       0       1       0     0m    95m    35m      0 local:0.0%          0       0|0     0|0    62b     1k     1   09:54:21 
           0      0      0      0       0       1       0     0m    95m    35m      0 local:0.0%          0       0|0     0|0    62b     1k     1   09:54:22 
           0      1      0      0       3       1       0    80m   258m    65m  11008     .:0.5%          0       0|0     0|0   231b    12m     2   09:54:23 
           0      0      0      0       1       1       0    80m   258m    67m    966  test:0.0%          0       0|0     0|0   104b     1m     2   09:54:24 
           0      0      0      0       0       1       0    80m   258m    67m      0  test:0.0%          0       0|0     0|0    62b     1k     2   09:54:25 

      Mongostat output from version 2.4.3 2008+

      connected to: 127.0.0.1
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0    80m   300m    38m      2 local:0.0%          0       0|0     0|0    62b     2k     1   09:54:52 
          *0     *0     *0     *0       0     1|0       0    80m   300m    38m      0 local:0.0%          0       0|0     0|0    62b     2k     1   09:54:53 
          *0      1     *0     *0       1     1|0       0   160m   465m    40m    444     .:0.2%          0       0|0     0|0   105b   134k     2   09:54:54 
          *0     *0     *0     *0       0     1|0       0   160m   465m    41m    123     .:1.5%          0       0|0     0|0    62b     2k     2   09:54:55 
          *0     *0     *0     *0       0     1|0       0   160m   465m    41m    132     .:1.6%          0       0|0     0|0    62b     2k     2   09:54:56 
          *0     *0     *0     *0       0     1|0       0   160m   465m    42m    125     .:1.6%          0       0|0     0|0    62b     2k     2   09:54:57 
          *0     *0     *0     *0       0     1|0       0   160m   465m    42m    125  test:0.0%          0       0|0     0|0    62b     2k     2   09:54:58 
          *0     *0     *0     *0       0     1|0       0   160m   465m    43m    123     .:2.5%          0       0|0     0|0    62b     2k     2   09:54:59 
          *0     *0     *0     *0       0     1|0       0   160m   465m    43m    124     .:0.9%          0       0|0     0|0    62b     2k     2   09:55:00 
          *0     *0     *0     *0       0     1|0       0   160m   465m    44m    123  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:01 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0   160m   465m    44m    133     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:02 
          *0     *0     *0     *0       0     1|0       0   160m   465m    45m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:03 
          *0     *0     *0     *0       0     1|0       0   160m   465m    45m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:04 
          *0     *0     *0     *0       0     1|0       0   160m   465m    46m    133     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:05 
          *0     *0     *0     *0       0     1|0       0   160m   465m    46m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:06 
          *0     *0     *0     *0       0     1|0       0   160m   465m    47m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:07 
          *0     *0     *0     *0       0     1|0       0   160m   465m    47m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:08 
          *0     *0     *0     *0       0     1|0       0   160m   465m    48m    127     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:09 
          *0     *0     *0     *0       0     1|0       0   160m   465m    48m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:10 
          *0     *0     *0     *0       0     1|0       0   160m   461m    44m    116  test:0.0%          0       0|0     0|0   104b     4m     2   09:55:11 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       1     1|0       0   160m   465m    45m    103  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:12 
          *0     *0     *0     *0       0     1|0       0   160m   465m    45m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:13 
          *0     *0     *0     *0       0     1|0       0   160m   465m    46m    125  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:14 
          *0     *0     *0     *0       0     1|0       0   160m   465m    46m    122     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:15 
          *0     *0     *0     *0       0     1|0       0   160m   465m    47m    114     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:16 
          *0     *0     *0     *0       0     1|0       0   160m   465m    47m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:17 
          *0     *0     *0     *0       0     1|0       0   160m   465m    48m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:18 
          *0     *0     *0     *0       0     1|0       0   160m   465m    48m    122     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:19 
          *0     *0     *0     *0       0     1|0       0   160m   465m    49m    122     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:20 
          *0     *0     *0     *0       0     1|0       0   160m   465m    49m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:21 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0   160m   465m    50m    122     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:22 
          *0     *0     *0     *0       0     1|0       0   160m   465m    50m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:23 
          *0     *0     *0     *0       0     1|0       0   160m   465m    51m    123     .:0.9%          0       0|0     0|0    62b     2k     2   09:55:24 
          *0     *0     *0     *0       0     1|0       0   160m   465m    51m    118  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:25 
          *0     *0     *0     *0       0     1|0       0   160m   465m    52m    127     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:26 
          *0     *0     *0     *0       0     1|0       0   160m   465m    52m    122     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:27 
          *0     *0     *0     *0       0     1|0       0   160m   465m    53m    123  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:28 
          *0     *0     *0     *0       1     1|0       0   160m   465m    49m     93     .:1.5%          0       0|0     0|0   104b     4m     2   09:55:29 
          *0     *0     *0     *0       0     1|0       0   160m   465m    49m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:30 
          *0     *0     *0     *0       0     1|0       0   160m   465m    50m    128  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:31 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0   160m   465m    50m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:32 
          *0     *0     *0     *0       0     1|0       0   160m   465m    51m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:33 
          *0     *0     *0     *0       0     1|0       0   160m   465m    51m    123     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:34 
          *0     *0     *0     *0       0     1|0       0   160m   465m    52m    125  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:35 
          *0     *0     *0     *0       0     1|0       0   160m   465m    52m    123     .:1.2%          0       0|0     0|0    62b     2k     2   09:55:36 
          *0     *0     *0     *0       0     1|0       0   160m   465m    53m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:37 
          *0     *0     *0     *0       0     1|0       0   160m   465m    53m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:38 
          *0     *0     *0     *0       0     1|0       0   160m   465m    54m    125     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:39 
          *0     *0     *0     *0       0     1|0       1   160m   465m    54m    126     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:40 
          *0      2     *0     *0       0     1|0       0   160m   465m    56m    394  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:41 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0   160m   465m    56m    124     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:42 
          *0     *0     *0     *0       0     1|0       0   160m   465m    57m    124     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:43 
          *0     *0     *0     *0       0     1|0       0   160m   465m    57m    119     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:44 
          *0     *0     *0     *0       0     1|0       0   160m   465m    58m    133  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:45 
          *0     *0     *0     *0       0     1|0       0   160m   465m    58m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:46 
          *0     *0     *0     *0       1     1|0       0   160m   465m    54m     75     .:1.7%          0       0|0     0|0   104b     4m     2   09:55:47 
          *0     *0     *0     *0       0     1|0       0   160m   465m    55m    124  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:48 
          *0     *0     *0     *0       0     1|0       0   160m   465m    55m    123     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:49 
          *0     *0     *0     *0       0     1|0       0   160m   465m    56m    122     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:50 
          *0     *0     *0     *0       0     1|0       0   160m   465m    56m    123  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:51 
      insert  query update delete getmore command flushes mapped  vsize    res faults  locked db idx miss %     qr|qw   ar|aw  netIn netOut  conn       time 
          *0     *0     *0     *0       0     1|0       0   160m   465m    57m    123     .:2.6%          0       0|0     0|0    62b     2k     2   09:55:52 
          *0     *0     *0     *0       0     1|0       0   160m   465m    57m    122     .:1.5%          0       0|0     0|0    62b     2k     2   09:55:53 
          *0     *0     *0     *0       0     1|0       0   160m   465m    58m    122     .:1.6%          0       0|0     0|0    62b     2k     2   09:55:54 
          *0     *0     *0     *0       0     1|0       0   160m   461m    56m     36  test:0.0%          0       0|0     0|0   104b     1m     2   09:55:55 
          *0     *0     *0     *0       0     1|0       0   160m   461m    56m      0     .:0.0%          0       0|0     0|0    62b     2k     2   09:55:56 
          *0     *0     *0     *0       0     1|0       0   160m   461m    56m      0  test:0.0%          0       0|0     0|0    62b     2k     2   09:55:57 

      Show
      Collection details > db.test.stats() { "ns" : "test.test", "count" : 11235, "size" : 15639072, "avgObjSize" : 1391.995727636849, "storageSize" : 16875520, "numExtents" : 6, "nindexes" : 1, "lastExtentSize" : 8495104, "paddingFactor" : 1, "systemFlags" : 1, "userFlags" : 0, "totalIndexSize" : 376096, "indexSizes" : { "_id_" : 376096 }, "ok" : 1 } 1. Restart mongodb 2. Run db.test.find() from (python) client. Mongostat output for version 2.2.4 2008+ connected to: 127.0.0.1 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time 0 0 0 0 0 1 0 0m 95m 35m 6 local:0.0% 0 0|0 0|0 62b 1k 1 09:54:20 0 0 0 0 0 1 0 0m 95m 35m 0 local:0.0% 0 0|0 0|0 62b 1k 1 09:54:21 0 0 0 0 0 1 0 0m 95m 35m 0 local:0.0% 0 0|0 0|0 62b 1k 1 09:54:22 0 1 0 0 3 1 0 80m 258m 65m 11008 .:0.5% 0 0|0 0|0 231b 12m 2 09:54:23 0 0 0 0 1 1 0 80m 258m 67m 966 test:0.0% 0 0|0 0|0 104b 1m 2 09:54:24 0 0 0 0 0 1 0 80m 258m 67m 0 test:0.0% 0 0|0 0|0 62b 1k 2 09:54:25 Mongostat output from version 2.4.3 2008+ connected to: 127.0.0.1 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 80m 300m 38m 2 local:0.0% 0 0|0 0|0 62b 2k 1 09:54:52 *0 *0 *0 *0 0 1|0 0 80m 300m 38m 0 local:0.0% 0 0|0 0|0 62b 2k 1 09:54:53 *0 1 *0 *0 1 1|0 0 160m 465m 40m 444 .:0.2% 0 0|0 0|0 105b 134k 2 09:54:54 *0 *0 *0 *0 0 1|0 0 160m 465m 41m 123 .:1.5% 0 0|0 0|0 62b 2k 2 09:54:55 *0 *0 *0 *0 0 1|0 0 160m 465m 41m 132 .:1.6% 0 0|0 0|0 62b 2k 2 09:54:56 *0 *0 *0 *0 0 1|0 0 160m 465m 42m 125 .:1.6% 0 0|0 0|0 62b 2k 2 09:54:57 *0 *0 *0 *0 0 1|0 0 160m 465m 42m 125 test:0.0% 0 0|0 0|0 62b 2k 2 09:54:58 *0 *0 *0 *0 0 1|0 0 160m 465m 43m 123 .:2.5% 0 0|0 0|0 62b 2k 2 09:54:59 *0 *0 *0 *0 0 1|0 0 160m 465m 43m 124 .:0.9% 0 0|0 0|0 62b 2k 2 09:55:00 *0 *0 *0 *0 0 1|0 0 160m 465m 44m 123 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:01 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 160m 465m 44m 133 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:02 *0 *0 *0 *0 0 1|0 0 160m 465m 45m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:03 *0 *0 *0 *0 0 1|0 0 160m 465m 45m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:04 *0 *0 *0 *0 0 1|0 0 160m 465m 46m 133 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:05 *0 *0 *0 *0 0 1|0 0 160m 465m 46m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:06 *0 *0 *0 *0 0 1|0 0 160m 465m 47m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:07 *0 *0 *0 *0 0 1|0 0 160m 465m 47m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:08 *0 *0 *0 *0 0 1|0 0 160m 465m 48m 127 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:09 *0 *0 *0 *0 0 1|0 0 160m 465m 48m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:10 *0 *0 *0 *0 0 1|0 0 160m 461m 44m 116 test:0.0% 0 0|0 0|0 104b 4m 2 09:55:11 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 1 1|0 0 160m 465m 45m 103 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:12 *0 *0 *0 *0 0 1|0 0 160m 465m 45m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:13 *0 *0 *0 *0 0 1|0 0 160m 465m 46m 125 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:14 *0 *0 *0 *0 0 1|0 0 160m 465m 46m 122 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:15 *0 *0 *0 *0 0 1|0 0 160m 465m 47m 114 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:16 *0 *0 *0 *0 0 1|0 0 160m 465m 47m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:17 *0 *0 *0 *0 0 1|0 0 160m 465m 48m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:18 *0 *0 *0 *0 0 1|0 0 160m 465m 48m 122 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:19 *0 *0 *0 *0 0 1|0 0 160m 465m 49m 122 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:20 *0 *0 *0 *0 0 1|0 0 160m 465m 49m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:21 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 160m 465m 50m 122 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:22 *0 *0 *0 *0 0 1|0 0 160m 465m 50m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:23 *0 *0 *0 *0 0 1|0 0 160m 465m 51m 123 .:0.9% 0 0|0 0|0 62b 2k 2 09:55:24 *0 *0 *0 *0 0 1|0 0 160m 465m 51m 118 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:25 *0 *0 *0 *0 0 1|0 0 160m 465m 52m 127 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:26 *0 *0 *0 *0 0 1|0 0 160m 465m 52m 122 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:27 *0 *0 *0 *0 0 1|0 0 160m 465m 53m 123 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:28 *0 *0 *0 *0 1 1|0 0 160m 465m 49m 93 .:1.5% 0 0|0 0|0 104b 4m 2 09:55:29 *0 *0 *0 *0 0 1|0 0 160m 465m 49m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:30 *0 *0 *0 *0 0 1|0 0 160m 465m 50m 128 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:31 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 160m 465m 50m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:32 *0 *0 *0 *0 0 1|0 0 160m 465m 51m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:33 *0 *0 *0 *0 0 1|0 0 160m 465m 51m 123 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:34 *0 *0 *0 *0 0 1|0 0 160m 465m 52m 125 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:35 *0 *0 *0 *0 0 1|0 0 160m 465m 52m 123 .:1.2% 0 0|0 0|0 62b 2k 2 09:55:36 *0 *0 *0 *0 0 1|0 0 160m 465m 53m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:37 *0 *0 *0 *0 0 1|0 0 160m 465m 53m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:38 *0 *0 *0 *0 0 1|0 0 160m 465m 54m 125 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:39 *0 *0 *0 *0 0 1|0 1 160m 465m 54m 126 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:40 *0 2 *0 *0 0 1|0 0 160m 465m 56m 394 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:41 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 160m 465m 56m 124 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:42 *0 *0 *0 *0 0 1|0 0 160m 465m 57m 124 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:43 *0 *0 *0 *0 0 1|0 0 160m 465m 57m 119 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:44 *0 *0 *0 *0 0 1|0 0 160m 465m 58m 133 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:45 *0 *0 *0 *0 0 1|0 0 160m 465m 58m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:46 *0 *0 *0 *0 1 1|0 0 160m 465m 54m 75 .:1.7% 0 0|0 0|0 104b 4m 2 09:55:47 *0 *0 *0 *0 0 1|0 0 160m 465m 55m 124 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:48 *0 *0 *0 *0 0 1|0 0 160m 465m 55m 123 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:49 *0 *0 *0 *0 0 1|0 0 160m 465m 56m 122 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:50 *0 *0 *0 *0 0 1|0 0 160m 465m 56m 123 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:51 insert query update delete getmore command flushes mapped vsize res faults locked db idx miss % qr|qw ar|aw netIn netOut conn time *0 *0 *0 *0 0 1|0 0 160m 465m 57m 123 .:2.6% 0 0|0 0|0 62b 2k 2 09:55:52 *0 *0 *0 *0 0 1|0 0 160m 465m 57m 122 .:1.5% 0 0|0 0|0 62b 2k 2 09:55:53 *0 *0 *0 *0 0 1|0 0 160m 465m 58m 122 .:1.6% 0 0|0 0|0 62b 2k 2 09:55:54 *0 *0 *0 *0 0 1|0 0 160m 461m 56m 36 test:0.0% 0 0|0 0|0 104b 1m 2 09:55:55 *0 *0 *0 *0 0 1|0 0 160m 461m 56m 0 .:0.0% 0 0|0 0|0 62b 2k 2 09:55:56 *0 *0 *0 *0 0 1|0 0 160m 461m 56m 0 test:0.0% 0 0|0 0|0 62b 2k 2 09:55:57

      Description

      When doing a db.collection.find() after restarting mongodb on a collection of 15MB, this takes 2 seconds in 2.2.4 and 60 seconds in 2.4.3.

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              tad Tad Marshall
              Reporter:
              gregor Gregor Macadam
              Participants:
              Votes:
              0 Vote for this issue
              Watchers:
              8 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved: