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