BUG #11939: Missing "actual time" in auto_explain plans

The following bug has been logged on the website:

Bug reference: 11939
Logged by: Robert Wysocki
Email address: robert.wysocki@essencedigital.com
PostgreSQL version: 9.3.5
Operating system: Debian GNU/Linux jessie/sid
Description:

Hello,

After loading auto_explain and configuring it to log "explain analyze" there
are no timings in logged plans.

postgresql-9.3 9.3.5-1.pgdg70+1
postgresql-contrib-9.3 9.3.5-1.pgdg70+1

# select version();
version

──────────────────────────────────────────────────────────────────────────────────────────────
PostgreSQL 9.3.5 on x86_64-unknown-linux-gnu, compiled by gcc (Debian
4.7.2-5) 4.7.2, 64-bit
(1 row)

console:
# load 'auto_explain';
LOAD
# set auto_explain.log_min_duration = 0;
SET
# set auto_explain.log_analyze = on;
SET
# set auto_explain.log_timing = on;
SET
# SELECT count(*)
FROM pg_class, pg_index
WHERE oid = indrelid AND indisunique;
count
───────
103
(1 row)

log file:
LOG: duration: 0.139 ms plan:
Query Text: SELECT count(*)
FROM pg_class, pg_index
WHERE oid = indrelid AND indisunique;
Aggregate (cost=19.78..19.79 rows=1 width=0) (actual rows=1 loops=1)
-> Hash Join (cost=5.40..19.52 rows=102 width=0) (actual rows=103
loops=1)
Hash Cond: (pg_class.oid = pg_index.indrelid)
-> Seq Scan on pg_class (cost=0.00..10.92 rows=292 width=4)
(actual rows=297 loops=1)
-> Hash (cost=4.12..4.12 rows=102 width=4) (actual rows=103
loops=1)
Buckets: 1024 Batches: 1 Memory Usage: 4kB
-> Seq Scan on pg_index (cost=0.00..4.12 rows=102 width=4)
(actual rows=103 loops=1)
Filter: indisunique
Rows Removed by Filter: 11

Cheers,
Robert

--
Sent via pgsql-bugs mailing list (pgsql-bugs@postgresql.org)
To make changes to your subscription:
http://www.postgresql.org/mailpref/pgsql-bugs