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(dot)wysocki(at)essencedigital(dot)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

robert(dot)wysocki(at)essencedigital(dot)com writes:
> After loading auto_explain and configuring it to log "explain analyze" there
> are no timings in logged plans.

Yeah, this was fixed a couple weeks ago, commit
9474c9d8107ba21ed9b8b9c1efde433f3d20e45f. Will be in next releases.

regards, tom lane

On 11 November 2014 20:59, Tom Lane <tgl(at)sss(dot)pgh(dot)pa(dot)us> wrote:
> robert(dot)wysocki(at)essencedigital(dot)com writes:
>> After loading auto_explain and configuring it to log "explain analyze" there
>> are no timings in logged plans.
>
> Yeah, this was fixed a couple weeks ago, commit
> 9474c9d8107ba21ed9b8b9c1efde433f3d20e45f. Will be in next releases.

Good to know, thanks!

Cheers,
R.

--

Robert Wysocki
Essence

t: +44 207 758 0576
UK House • 180 Oxford Street • London • W1D 1NN

--
-------------------------------------
essencedigital.com <http://www.essencedigital.com/>
Google+ <https://plus.google.com/102138558390623994587/about> • Facebook
<http://www.facebook.com/essencedigital> • Twitter
<https://twitter.com/essencedigital> • YouTube
<http://www.youtube.com/essencedigitalvideos>
-------------------------------------
Essence Digital Ltd Registered in England No. 5467689
Registered Office as above
-------------------------------------
This email may be confidential or privileged. If you received this
communication by mistake, please don't forward it to anyone else, please
erase all copies and attachments and please let me know that it went to the
wrong person. Thank you.