F.5. auto_explain
Модуль auto_explain предоставляет возможность автоматического протоколирования планов выполнения медленных операторов, что позволяет обойтись без выполнения EXPLAIN вручную. Это особенно полезно для выявления неоптимизированных запросов в больших приложениях.
Этот модуль не предоставляет функций, доступных из SQL. Чтобы использовать его, просто загрузите его в процесс сервера. Это можно сделать в отдельном сеансе:
LOAD 'auto_explain';
(Для этого нужно быть суперпользователем.) Более типична конфигурация, когда он загружается в некоторые или все сеансы в результате включения auto_explain в переменную session_preload_libraries или в shared_preload_libraries в файле postgresql.conf. Загрузив этот модуль, вы можете отслеживать исключительно медленные запросы, вне зависимости от того, когда они происходят. Конечно, это имеет свою цену.
F.5.1. Параметры конфигурации
Есть несколько параметров конфигурации, которые управляют поведением auto_explain. Заметьте, что поведение по умолчанию сводится к бездействию, так что необходимо установить как минимум переменную auto_explain.log_min_duration, если вы хотите получить какие-либо результаты.
auto_explain.log_min_duration(integer)Переменная
auto_explain.log_min_durationзадаёт время выполнения оператора, в миллисекундах, при превышении которого план оператора будет протоколироваться. Если это значение равно 0, протоколироваться будут планы всех операторов. При значении -1 (по умолчанию) протоколирование планов полностью отключается. Например, если вы установите значение250ms, протоколироваться будут все запросы, выполняющиеся 250 мс и дольше. Изменить этот параметр могут только суперпользователи.auto_explain.log_analyze(boolean)При включении параметра
auto_explain.log_analyzeв протокол будет записываться вывод командыEXPLAIN ANALYZE, а не простойEXPLAIN. По умолчанию этот параметр отключён. Изменить его могут только суперпользователи.Примечание
Когда этот параметр включён, замер времени на уровне узлов плана производится для всех операторов, даже если они выполняются недостаточно долго для протоколирования. Это может оказать крайне негативное влияние на производительность. Отключение
auto_explain.log_timingисключает это влияние, но при этом собирается меньше информации.auto_explain.log_buffers(boolean)Параметр
auto_explain.log_buffersопределяет, будет ли при протоколировании плана выполнения выводиться статистика об использовании буферов; он равносилен указаниюBUFFERSкомандыEXPLAIN. Этот параметр действует, только если включён параметрauto_explain.log_analyze. По умолчанию этот параметр отключён. Изменить его могут только суперпользователи.auto_explain.log_timing(boolean)Параметр
auto_explain.log_timingопределяет, будет ли при протоколировании плана выполнения выводиться длительность на уровне узлов: он равнозначен указаниюTIMINGкомандыEXPLAIN. Издержки от постоянного чтения системных часов могут значительно замедлить запросы в некоторых системах, так что может иметь смысл отключать этот параметр, когда нужно знать только знать количество строк, но не точную длительность каждого узла. Этот параметр действует, только если включёнauto_explain.log_analyze. По умолчанию этот параметр отключён. Изменить его могут только суперпользователи.auto_explain.log_triggers(boolean)При включении параметра
auto_explain.log_triggersв протокол будет записываться статистика выполнения триггеров. Этот параметр действует, только если включён параметрauto_explain.log_analyze. По умолчанию этот параметр отключён. Изменить его могут только суперпользователи.auto_explain.log_verbose(boolean)Параметр
auto_explain.log_verboseопределяет, будут ли при протоколировании плана выполнения выводиться подробные сведения; он равнозначен указаниюVERBOSEкомандыEXPLAIN. По умолчанию этот параметр отключён. Изменить его могут только суперпользователи.auto_explain.log_format(enum)Параметр
auto_explain.log_formatвыбирает формат вывода дляEXPLAIN. Он может принимать значениеtext,xml,jsonиyaml. Значение по умолчанию — text. Изменить этот параметр могут только суперпользователи.auto_explain.log_nested_statements(boolean)При включении параметра
auto_explain.log_nested_statementsпротоколированию могут подлежать и вложенные операторы (операторы, выполняемые внутри функции). Когда он отключён, протоколируются планы запросов только верхнего уровня. Изменить этот параметр могут только суперпользователи.auto_explain.sample_rate(real)Параметр
auto_explain.sample_rateзадаёт для auto_explain процент операторов, которые будут отслеживаться в каждом сеансе. Значение по умолчанию — 1, то есть отслеживаются все запросы. Вложенные операторы отслеживаются совместно — либо все, либо никакой из них. Изменить этот параметр могут только суперпользователи.
В обычной ситуации эти параметры устанавливаются в postgresql.conf, хотя суперпользователи могут изменить их «на лету» в рамках своих сеансов. Типичное их использование может выглядеть так:
# postgresql.conf session_preload_libraries = 'auto_explain' auto_explain.log_min_duration = '3s'
F.5.2. Пример
postgres=# LOAD 'auto_explain';
postgres=# SET auto_explain.log_min_duration = 0;
postgres=# SET auto_explain.log_analyze = true;
postgres=# SELECT count(*)
FROM pg_class, pg_index
WHERE oid = indrelid AND indisunique;В результате этих команд может быть получен такой вывод:
LOG: duration: 0.196 ms planning: 0.548 ms plan:
Query Text: SELECT count(*)
FROM pg_class, pg_index
WHERE oid = indrelid AND indisunique;
Aggregate (cost=16.79..16.80 rows=1 width=0) (actual time=3.626..3.627 rows=1 loops=1)
-> Hash Join (cost=4.17..16.55 rows=92 width=0) (actual time=3.349..3.594 rows=92 loops=1)
Hash Cond: (pg_class.oid = pg_index.indrelid)
-> Seq Scan on pg_class (cost=0.00..9.55 rows=255 width=4) (actual time=0.016..0.140 rows=255 loops=1)
-> Hash (cost=3.02..3.02 rows=92 width=4) (actual time=3.238..3.238 rows=92 loops=1)
Buckets: 1024 Batches: 1 Memory Usage: 4kB
-> Seq Scan on pg_index (cost=0.00..3.02 rows=92 width=4) (actual time=0.008..3.187 rows=92 loops=1)
Filter: indisunique
Заметьте, что в Postgres Pro было добавлено новое поле для отображения длительности планирования.