Full disclosure: I posted a similar question at https://stackoverflow.com/questions/52088298/elasticsearch-profile-api-time-in-nanoseconds-value-higher-than-took-time.
Unexpected results from Profile API
When using the Profile API I'm seeing many time_in_nanoseconds
values that are higher than the took
time of the whole query.
Example:
{
"took": 109695,
...
"profile": {
"shards": [
{
"searches": [
{
"query": [
{
"type": "BooleanQuery",
"time": "1550750.786ms",
"time_in_nanos": 1550750786163
...
}
]
}
]
}
...
]
}
}
Regression to previous bug?
This seems similar to the bug described here:
However, this bug was supposed so be fixed in version 5. We're on version 5.6.
Full version information:
"version" : {
"number" : "5.6.0",
"build_hash" : "781a835",
"build_date" : "2017-09-07T03:09:58.087Z",
"build_snapshot" : false,
"lucene_version" : "6.6.0"
},
Unknowns
Is this behavior a regression? Am I misunderstanding the results of Profile API?