2013-08-28 19:24:34 -04:00
|
|
|
[[index-modules-slowlog]]
|
2015-06-22 17:49:45 -04:00
|
|
|
== Slow Log
|
2013-08-28 19:24:34 -04:00
|
|
|
|
|
|
|
[float]
|
2013-09-25 12:17:40 -04:00
|
|
|
[[search-slow-log]]
|
2013-08-28 19:24:34 -04:00
|
|
|
=== Search Slow Log
|
|
|
|
|
|
|
|
Shard level slow search log allows to log slow search (query and fetch
|
2015-06-22 17:49:45 -04:00
|
|
|
phases) into a dedicated log file.
|
2013-08-28 19:24:34 -04:00
|
|
|
|
|
|
|
Thresholds can be set for both the query phase of the execution, and
|
|
|
|
fetch phase, here is a sample:
|
|
|
|
|
2015-06-22 17:49:45 -04:00
|
|
|
[source,yaml]
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
2015-06-22 17:49:45 -04:00
|
|
|
index.search.slowlog.threshold.query.warn: 10s
|
|
|
|
index.search.slowlog.threshold.query.info: 5s
|
|
|
|
index.search.slowlog.threshold.query.debug: 2s
|
|
|
|
index.search.slowlog.threshold.query.trace: 500ms
|
|
|
|
|
|
|
|
index.search.slowlog.threshold.fetch.warn: 1s
|
|
|
|
index.search.slowlog.threshold.fetch.info: 800ms
|
|
|
|
index.search.slowlog.threshold.fetch.debug: 500ms
|
|
|
|
index.search.slowlog.threshold.fetch.trace: 200ms
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
|
|
|
|
2016-10-17 10:50:32 -04:00
|
|
|
All of the above settings are _dynamic_ and are set per-index.
|
2015-06-22 17:49:45 -04:00
|
|
|
|
2013-08-28 19:24:34 -04:00
|
|
|
By default, none are enabled (set to `-1`). Levels (`warn`, `info`,
|
|
|
|
`debug`, `trace`) allow to control under which logging level the log
|
|
|
|
will be logged. Not all are required to be configured (for example, only
|
|
|
|
`warn` threshold can be set). The benefit of several levels is the
|
|
|
|
ability to quickly "grep" for specific thresholds breached.
|
|
|
|
|
|
|
|
The logging is done on the shard level scope, meaning the execution of a
|
|
|
|
search request within a specific shard. It does not encompass the whole
|
|
|
|
search request, which can be broadcast to several shards in order to
|
|
|
|
execute. Some of the benefits of shard level logging is the association
|
|
|
|
of the actual execution on the specific machine, compared with request
|
|
|
|
level.
|
|
|
|
|
|
|
|
The logging file is configured by default using the following
|
2016-08-31 15:51:52 -04:00
|
|
|
configuration (found in `log4j2.properties`):
|
2013-08-28 19:24:34 -04:00
|
|
|
|
2016-10-17 10:50:32 -04:00
|
|
|
[source,properties]
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
2016-08-31 15:51:52 -04:00
|
|
|
appender.index_search_slowlog_rolling.type = RollingFile
|
|
|
|
appender.index_search_slowlog_rolling.name = index_search_slowlog_rolling
|
|
|
|
appender.index_search_slowlog_rolling.fileName = ${sys:es.logs}_index_search_slowlog.log
|
|
|
|
appender.index_search_slowlog_rolling.layout.type = PatternLayout
|
|
|
|
appender.index_search_slowlog_rolling.layout.pattern = [%d{ISO8601}][%-5p][%-25c] %.10000m%n
|
|
|
|
appender.index_search_slowlog_rolling.filePattern = ${sys:es.logs}_index_search_slowlog-%d{yyyy-MM-dd}.log
|
|
|
|
appender.index_search_slowlog_rolling.policies.type = Policies
|
|
|
|
appender.index_search_slowlog_rolling.policies.time.type = TimeBasedTriggeringPolicy
|
|
|
|
appender.index_search_slowlog_rolling.policies.time.interval = 1
|
|
|
|
appender.index_search_slowlog_rolling.policies.time.modulate = true
|
|
|
|
|
|
|
|
logger.index_search_slowlog_rolling.name = index.search.slowlog
|
|
|
|
logger.index_search_slowlog_rolling.level = trace
|
|
|
|
logger.index_search_slowlog_rolling.appenderRef.index_search_slowlog_rolling.ref = index_search_slowlog_rolling
|
|
|
|
logger.index_search_slowlog_rolling.additivity = false
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
|
|
|
|
|
|
|
[float]
|
2013-09-25 12:17:40 -04:00
|
|
|
[[index-slow-log]]
|
2013-08-28 19:24:34 -04:00
|
|
|
=== Index Slow log
|
|
|
|
|
2014-04-15 00:55:39 -04:00
|
|
|
The indexing slow log, similar in functionality to the search slow
|
2016-06-21 07:20:23 -04:00
|
|
|
log. The log file name ends with `_index_indexing_slowlog.log`. Log and
|
2016-10-17 10:50:32 -04:00
|
|
|
the thresholds are configured in the same way as the search slowlog.
|
|
|
|
Index slowlog sample:
|
2013-08-28 19:24:34 -04:00
|
|
|
|
2015-06-22 17:49:45 -04:00
|
|
|
[source,yaml]
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
2015-06-22 17:49:45 -04:00
|
|
|
index.indexing.slowlog.threshold.index.warn: 10s
|
|
|
|
index.indexing.slowlog.threshold.index.info: 5s
|
|
|
|
index.indexing.slowlog.threshold.index.debug: 2s
|
|
|
|
index.indexing.slowlog.threshold.index.trace: 500ms
|
2015-08-11 16:13:43 -04:00
|
|
|
index.indexing.slowlog.level: info
|
|
|
|
index.indexing.slowlog.source: 1000
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
|
|
|
|
2016-10-17 10:50:32 -04:00
|
|
|
All of the above settings are _dynamic_ and are set per-index.
|
2015-06-22 17:49:45 -04:00
|
|
|
|
2015-08-11 16:13:43 -04:00
|
|
|
By default Elasticsearch will log the first 1000 characters of the _source in
|
|
|
|
the slowlog. You can change that with `index.indexing.slowlog.source`. Setting
|
|
|
|
it to `false` or `0` will skip logging the source entirely an setting it to
|
2016-11-28 13:12:43 -05:00
|
|
|
`true` will log the entire source regardless of size. The original `_source` is
|
|
|
|
reformatted by default to make sure that it fits on a single log line. If preserving
|
|
|
|
the original document format is important, you can turn off reformatting by setting
|
|
|
|
`index.indexing.slowlog.reformat` to `false`, which will cause the source to be
|
|
|
|
logged "as is" and can potentially span multiple log lines.
|
2015-08-11 16:13:43 -04:00
|
|
|
|
2016-10-17 10:50:32 -04:00
|
|
|
The index slow log file is configured by default in the `log4j2.properties`
|
2013-08-28 19:24:34 -04:00
|
|
|
file:
|
|
|
|
|
2016-10-17 10:50:32 -04:00
|
|
|
[source,properties]
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|
2016-10-17 10:50:32 -04:00
|
|
|
appender.index_indexing_slowlog_rolling.type = RollingFile
|
|
|
|
appender.index_indexing_slowlog_rolling.name = index_indexing_slowlog_rolling
|
|
|
|
appender.index_indexing_slowlog_rolling.fileName = ${sys:es.logs}_index_indexing_slowlog.log
|
|
|
|
appender.index_indexing_slowlog_rolling.layout.type = PatternLayout
|
|
|
|
appender.index_indexing_slowlog_rolling.layout.pattern = [%d{ISO8601}][%-5p][%-25c] %marker%.10000m%n
|
|
|
|
appender.index_indexing_slowlog_rolling.filePattern = ${sys:es.logs}_index_indexing_slowlog-%d{yyyy-MM-dd}.log
|
|
|
|
appender.index_indexing_slowlog_rolling.policies.type = Policies
|
|
|
|
appender.index_indexing_slowlog_rolling.policies.time.type = TimeBasedTriggeringPolicy
|
|
|
|
appender.index_indexing_slowlog_rolling.policies.time.interval = 1
|
|
|
|
appender.index_indexing_slowlog_rolling.policies.time.modulate = true
|
|
|
|
|
|
|
|
logger.index_indexing_slowlog.name = index.indexing.slowlog.index
|
|
|
|
logger.index_indexing_slowlog.level = trace
|
|
|
|
logger.index_indexing_slowlog.appenderRef.index_indexing_slowlog_rolling.ref = index_indexing_slowlog_rolling
|
|
|
|
logger.index_indexing_slowlog.additivity = false
|
2013-08-28 19:24:34 -04:00
|
|
|
--------------------------------------------------
|