summaryrefslogtreecommitdiff
path: root/jstests/core/getlog2.js
blob: 597a85e20eed41e54f8f48cc45a46d68b5ca007f (plain)
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
// tests getlog as well as slow querying logging

glcol = db.getLogTest2;
glcol.drop();

contains = function(arr, func) {
    var i = arr.length;
    while (i--) {
        if (func(arr[i])) {
            return true;
        }
    }
    return false;
};

// test doesn't work when talking to mongos
if (db.isMaster().msg != "isdbgrid") {
    // run a slow query
    glcol.save({"SENTINEL": 1});
    glcol.findOne({
        "SENTINEL": 1,
        "$where": function() {
            sleep(1000);
            return true;
        }
    });

    // run a slow update
    glcol.update({
        "SENTINEL": 1,
        "$where": function() {
            sleep(1000);
            return true;
        }
    },
                 {"x": "x"});

    var resp = db.adminCommand({getLog: "global"});
    assert(resp.ok == 1, "error executing getLog command");
    assert(resp.log, "no log field");
    assert(resp.log.length > 0, "no log lines");

    // ensure that slow query is logged in detail
    assert(contains(resp.log, function(v) {
        print(v);
        var opString = db.getMongo().useReadCommands() ? " find " : " query ";
        var filterString = db.getMongo().useReadCommands() ? "filter:" : "query:";
        return v.indexOf(opString) != -1 && v.indexOf(filterString) != -1 &&
            v.indexOf("keysExamined:") != -1 && v.indexOf("docsExamined:") != -1 &&
            v.indexOf("SENTINEL") != -1;
    }));

    // same, but for update
    assert(contains(resp.log, function(v) {
        return v.indexOf(" update ") != -1 && v.indexOf("query") != -1 &&
            v.indexOf("keysExamined:") != -1 && v.indexOf("docsExamined:") != -1 &&
            v.indexOf("SENTINEL") != -1;
    }));
}