#	exclusive	inclusive	rows x	rows	loops	node
1.	8.422	44.117	↑ 1.0	8503	1	→ Hash Join (cost=241.32..3584.64 rows=8503 width=134) (actual time=26.342..44.117 rows=8503 loops=1) Hash Cond: ((t.user_id)::text = (n.user_id)::text)
2.	5.598	28.070	↑ 1.0	8503	1	→ Subquery Scan on t (cost=0.00..3152.01 rows=8503 width=118) (actual time=18.708..28.070 rows=8503 loops=1)
3.	8.474	22.472	↑ 1.0	8503	1	→ HashSetOp Except (cost=0.00..3066.98 rows=8503 width=12) (actual time=18.707..22.472 rows=8503 loops=1)
4.	5.147	13.998	↑ 1.5	8503	1	→ Append (cost=0.00..3035.38 rows=12639 width=12) (actual time=0.014..13.998 rows=8503 loops=1)
5.	5.336	8.664	↑ 1.0	8503	1	→ Subquery Scan on "SELECT 1" (cost=0.00..220.06 rows=8503 width=12) (actual time=0.012..8.664 rows=8503 loops=1)
6.	3.328	3.328	↑ 1.0	8503	1	→ Seq Scan on tmp_log_node_69822 (cost=0.00..135.03 rows=8503 width=12) (actual time=0.012..3.328 rows=8503 loops=1)
7.	0.001	0.187	↓ 0.0	0	1	→ Result (cost=2773.96..2815.32 rows=4136 width=12) (actual time=0.187..0.187 rows=0 loops=1)
8.	0.020	0.186	↓ 0.0	0	1	→ HashAggregate (cost=2773.96..2815.32 rows=4136 width=12) (actual time=0.186..0.186 rows=0 loops=1)
9.	0.001	0.166	↓ 0.0	0	1	→ Append (cost=1340.45..2763.62 rows=4136 width=12) (actual time=0.166..0.166 rows=0 loops=1)
10.	0.008	0.118	↓ 0.0	0	1	→ HashAggregate (cost=1340.45..1361.13 rows=2068 width=12) (actual time=0.118..0.118 rows=0 loops=1)
11.	0.029	0.110	↓ 0.0	0	1	→ Hash Right Join (cost=1166.70..1335.28 rows=2068 width=12) (actual time=0.110..0.110 rows=0 loops=1) Hash Cond: ((d.user_id)::text = (a.user_id)::text)
12.	0.000	0.000	↓ 0.0		0	→ Seq Scan on tmp_log_node_69822 d (cost=0.00..135.03 rows=8503 width=12) (actual time=.. rows= loops=0)
13.	0.001	0.081	↓ 0.0	0	1	→ Hash (cost=1140.85..1140.85 rows=2068 width=12) (actual time=0.081..0.081 rows=0 loops=1) Buckets: 1024 Batches: 1 Memory Usage: 0kB
14.	0.000	0.080	↓ 0.0	0	1	→ Nested Loop (cost=0.00..1140.85 rows=2068 width=12) (actual time=0.080..0.080 rows=0 loops=1)
15.	0.080	0.080	↓ 0.0	0	1	→ Seq Scan on twf_log_channel b (cost=0.00..6.55 rows=1 width=14) (actual time=0.080..0.080 rows=0 loops=1) Filter: ((channel_type = 1::numeric) AND (plan_starttime >= to_date('2012-03-28'::text, 'yyyy-mm-dd'::text)) AND (plan_starttime < (to_date('2012-04-12'::text, 'yyyy-mm-dd'::text) + 1)))
16.	0.000	0.000	↓ 0.0		0	→ Index Scan using idx_twf_log_channel_user_subjob_id on twf_log_channel_user a (cost=0.00..981.37 rows=12235 width=19) (actual time=.. rows= loops=0) Index Cond: (subjob_id = b.subjob_id)
17.	0.008	0.047	↓ 0.0	0	1	→ HashAggregate (cost=1340.45..1361.13 rows=2068 width=12) (actual time=0.047..0.047 rows=0 loops=1)
18.	0.005	0.039	↓ 0.0	0	1	→ Hash Right Join (cost=1166.70..1335.28 rows=2068 width=12) (actual time=0.039..0.039 rows=0 loops=1) Hash Cond: ((d.user_id)::text = (a.user_id)::text)
19.	0.000	0.000	↓ 0.0		0	→ Seq Scan on tmp_log_node_69822 d (cost=0.00..135.03 rows=8503 width=12) (actual time=.. rows= loops=0)
20.	0.000	0.034	↓ 0.0	0	1	→ Hash (cost=1140.85..1140.85 rows=2068 width=12) (actual time=0.034..0.034 rows=0 loops=1) Buckets: 1024 Batches: 1 Memory Usage: 0kB
21.	0.001	0.034	↓ 0.0	0	1	→ Nested Loop (cost=0.00..1140.85 rows=2068 width=12) (actual time=0.034..0.034 rows=0 loops=1)
22.	0.033	0.033	↓ 0.0	0	1	→ Seq Scan on twf_log_channel b (cost=0.00..6.55 rows=1 width=14) (actual time=0.033..0.033 rows=0 loops=1) Filter: ((channel_type = 3::numeric) AND (plan_starttime >= to_date('2012-04-02'::text, 'yyyy-mm-dd'::text)) AND (plan_starttime < (to_date('2012-04-12'::text, 'yyyy-mm-dd'::text) + 1)))
23.	0.000	0.000	↓ 0.0		0	→ Index Scan using idx_twf_log_channel_user_subjob_id on twf_log_channel_user a (cost=0.00..981.37 rows=12235 width=19) (actual time=.. rows= loops=0) Index Cond: (subjob_id = b.subjob_id)
24.	4.525	7.625	↑ 1.0	8503	1	→ Hash (cost=135.03..135.03 rows=8503 width=16) (actual time=7.625..7.625 rows=8503 loops=1) Buckets: 1024 Batches: 1 Memory Usage: 413kB
25.	3.100	3.100	↑ 1.0	8503	1	→ Seq Scan on tmp_log_node_69822 n (cost=0.00..135.03 rows=8503 width=16) (actual time=0.005..3.100 rows=8503 loops=1)

Hash Join  (cost=241.32..3584.64 rows=8503 width=134) (actual time=26.342..44.117 rows=8503 loops=1)
  Hash Cond: ((t.user_id)::text = (n.user_id)::text)
  ->  Subquery Scan on t  (cost=0.00..3152.01 rows=8503 width=118) (actual time=18.708..28.070 rows=8503 loops=1)
        ->  HashSetOp Except  (cost=0.00..3066.98 rows=8503 width=12) (actual time=18.707..22.472 rows=8503 loops=1)
              ->  Append  (cost=0.00..3035.38 rows=12639 width=12) (actual time=0.014..13.998 rows=8503 loops=1)
                    ->  Subquery Scan on "*SELECT* 1"  (cost=0.00..220.06 rows=8503 width=12) (actual time=0.012..8.664 rows=8503 loops=1)
                          ->  Seq Scan on tmp_log_node_69822  (cost=0.00..135.03 rows=8503 width=12) (actual time=0.012..3.328 rows=8503 loops=1)
                    ->  Result  (cost=2773.96..2815.32 rows=4136 width=12) (actual time=0.187..0.187 rows=0 loops=1)
                          ->  HashAggregate  (cost=2773.96..2815.32 rows=4136 width=12) (actual time=0.186..0.186 rows=0 loops=1)
                                ->  Append  (cost=1340.45..2763.62 rows=4136 width=12) (actual time=0.166..0.166 rows=0 loops=1)
                                      ->  HashAggregate  (cost=1340.45..1361.13 rows=2068 width=12) (actual time=0.118..0.118 rows=0 loops=1)
                                            ->  Hash Right Join  (cost=1166.70..1335.28 rows=2068 width=12) (actual time=0.110..0.110 rows=0 loops=1)
                                                  Hash Cond: ((d.user_id)::text = (a.user_id)::text)
                                                  ->  Seq Scan on tmp_log_node_69822 d  (cost=0.00..135.03 rows=8503 width=12) (never executed)
                                                  ->  Hash  (cost=1140.85..1140.85 rows=2068 width=12) (actual time=0.081..0.081 rows=0 loops=1)
                                                        Buckets: 1024  Batches: 1  Memory Usage: 0kB
                                                        ->  Nested Loop  (cost=0.00..1140.85 rows=2068 width=12) (actual time=0.080..0.080 rows=0 loops=1)
                                                              ->  Seq Scan on twf_log_channel b  (cost=0.00..6.55 rows=1 width=14) (actual time=0.080..0.080 rows=0 loops=1)
                                                                    Filter: ((channel_type = 1::numeric) AND (plan_starttime >= to_date('2012-03-28'::text, 'yyyy-mm-dd'::text)) AND (plan_starttime < (to_date('2012-04-12'::text, 'yyyy-mm-dd'::text) + 1)))
                                                              ->  Index Scan using idx_twf_log_channel_user_subjob_id on twf_log_channel_user a  (cost=0.00..981.37 rows=12235 width=19) (never executed)
                                                                    Index Cond: (subjob_id = b.subjob_id)
                                      ->  HashAggregate  (cost=1340.45..1361.13 rows=2068 width=12) (actual time=0.047..0.047 rows=0 loops=1)
                                            ->  Hash Right Join  (cost=1166.70..1335.28 rows=2068 width=12) (actual time=0.039..0.039 rows=0 loops=1)
                                                  Hash Cond: ((d.user_id)::text = (a.user_id)::text)
                                                  ->  Seq Scan on tmp_log_node_69822 d  (cost=0.00..135.03 rows=8503 width=12) (never executed)
                                                  ->  Hash  (cost=1140.85..1140.85 rows=2068 width=12) (actual time=0.034..0.034 rows=0 loops=1)
                                                        Buckets: 1024  Batches: 1  Memory Usage: 0kB
                                                        ->  Nested Loop  (cost=0.00..1140.85 rows=2068 width=12) (actual time=0.034..0.034 rows=0 loops=1)
                                                              ->  Seq Scan on twf_log_channel b  (cost=0.00..6.55 rows=1 width=14) (actual time=0.033..0.033 rows=0 loops=1)
                                                                    Filter: ((channel_type = 3::numeric) AND (plan_starttime >= to_date('2012-04-02'::text, 'yyyy-mm-dd'::text)) AND (plan_starttime < (to_date('2012-04-12'::text, 'yyyy-mm-dd'::text) + 1)))
                                                              ->  Index Scan using idx_twf_log_channel_user_subjob_id on twf_log_channel_user a  (cost=0.00..981.37 rows=12235 width=19) (never executed)
                                                                    Index Cond: (subjob_id = b.subjob_id)
  ->  Hash  (cost=135.03..135.03 rows=8503 width=16) (actual time=7.625..7.625 rows=8503 loops=1)
        Buckets: 1024  Batches: 1  Memory Usage: 413kB
        ->  Seq Scan on tmp_log_node_69822 n  (cost=0.00..135.03 rows=8503 width=16) (actual time=0.005..3.100 rows=8503 loops=1)
Total runtime: 46.885 ms

Per node type stats

node type	count	sum of times	% of query
Append	2	5.148 ms	11.7 %
Hash	3	4.526 ms	10.3 %
Hash Join	1	8.422 ms	19.1 %
Hash Right Join	2	0.034 ms	0.1 %
HashAggregate	3	0.036 ms	0.1 %
HashSetOp Except	1	8.474 ms	19.2 %
Index Scan	2	0.000 ms	0.0 %
Nested Loop	2	0.001 ms	0.0 %
Result	1	0.001 ms	0.0 %
Seq Scan	6	6.541 ms	14.8 %
Subquery Scan on "SELECT 1"	1	5.336 ms	12.1 %
Subquery Scan on t	1	5.598 ms	12.7 %

Per table stats

Table name	Scan count	Total time	% of query
scan type	count	sum of times	% of table
tmp_log_node_69822	4	6.428 ms	14.6 %
Seq Scan	4	6.428 ms	100.0 %
twf_log_channel	2	0.113 ms	0.3 %
Seq Scan	2	0.113 ms	100.0 %
twf_log_channel_user	2	0.000 ms	0.0 %
Index Scan	2	0.000 ms	0.0 %

explain.depesz.com

A tool for finding a real cause for slow queries.

Result: mBY

Colorize:

Columns visibility:

Per node type stats

Per table stats