explain.depesz.com

A tool for finding a real cause for slow queries.

Result: Cnq

options
# exclusive inclusive rows x rows loops node
1. 1.550 10142.362 ↓ 352.0 352 1
→

HashAggregate (cost=81672.76..81672.77 rows=1 width=52) (actual time=10141.730..10142.362 rows=352 loops=1)

  • Output: tcd.id, tcd.time_range, cp.name, h.node_rank, h.context_rank, h.thread_rank
2.          

CTE cp
3. 0.196 533.723 ↓ 1.7 44 1
→

Recursive Union (cost=35.03..18677.39 rows=26 width=78) (actual time=144.398..533.723 rows=44 loops=1)

4. 253.669 532.167 ↑ 1.1 14 1
→

Hash Join (cost=35.03..5375.92 rows=16 width=46) (actual time=144.388..532.167 rows=14 loops=1)

  • Output: tc.id, tc.parent, tc.timer, t.name
  • Hash Cond: (tc.timer = t.id)
5. 278.405 278.405 ↓ 1.0 142855 1
→

Index Scan using timer_callpath_parent on timer_callpath tc (cost=0.00..4813.72 rows=140538 width=12) (actual time=0.035..278.405 rows=142855 loops=1)

  • Output: tc.id, tc.timer, tc.parent
  • Index Cond: (parent IS NULL)
6. 0.034 0.093 ↑ 1.1 14 1
→

Hash (cost=34.83..34.83 rows=16 width=38) (actual time=0.093..0.093 rows=14 loops=1)

  • Output: t.name, t.id
7. 0.059 0.059 ↑ 1.1 14 1
→

Index Scan using timer_trial_index on timer t (cost=0.00..34.83 rows=16 width=38) (actual time=0.031..0.059 rows=14 loops=1)

  • Output: t.name, t.id
  • Index Cond: (trial = 9034)
8. 0.114 1.360 ↓ 15.0 15 2
→

Subquery Scan "*SELECT* 2" (cost=35.03..1330.10 rows=1 width=78) (actual time=0.348..0.680 rows=15 loops=2)

  • Output: "*SELECT* 2".id, "*SELECT* 2".parent, "*SELECT* 2".timer, "*SELECT* 2"."?column?
9. 0.237 1.246 ↓ 15.0 15 2
→

Hash Join (cost=35.03..1330.09 rows=1 width=78) (actual time=0.343..0.623 rows=15 loops=2)

  • Output: d.id, d.parent, d.timer, (((cp.name)::text || ' => '::text) || (dt.name)::text)
  • Hash Cond: (d.timer = dt.id)
10. 0.282 0.902 ↑ 75.9 15 2
→

Nested Loop (cost=0.00..1290.78 rows=1139 width=44) (actual time=0.246..0.451 rows=15 loops=2)

  • Output: d.id, d.parent, d.timer, cp.name
11. 0.092 0.092 ↑ 7.3 22 2
→

WorkTable Scan on cp (cost=0.00..3.20 rows=160 width=36) (actual time=0.006..0.046 rows=22 loops=2)

  • Output: cp.id, cp.parent, cp.timer, cp.name
12. 0.528 0.528 ↑ 7.0 1 44
→

Index Scan using timer_callpath_parent on timer_callpath d (cost=0.00..7.96 rows=7 width=12) (actual time=0.010..0.012 rows=1 loops=44)

  • Output: d.id, d.timer, d.parent
  • Index Cond: (d.parent = cp.id)
13. 0.048 0.107 ↑ 1.1 14 1
→

Hash (cost=34.83..34.83 rows=16 width=38) (actual time=0.107..0.107 rows=14 loops=1)

  • Output: dt.name, dt.id
14. 0.059 0.059 ↑ 1.1 14 1
→

Index Scan using timer_trial_index on timer dt (cost=0.00..34.83 rows=16 width=38) (actual time=0.026..0.059 rows=14 loops=1)

  • Output: dt.name, dt.id
  • Index Cond: (trial = 9034)
15. 2.189 10140.812 ↓ 352.0 352 1
→

Nested Loop (cost=0.84..62995.36 rows=1 width=52) (actual time=2121.198..10140.812 rows=352 loops=1)

  • Output: tcd.id, tcd.time_range, cp.name, h.node_rank, h.context_rank, h.thread_rank
16. 4814.210 10136.511 ↓ 1.5 352 1
→

Hash Join (cost=0.84..62407.73 rows=232 width=44) (actual time=2121.120..10136.511 rows=352 loops=1)

  • Output: cp.name, tcd.id, tcd.time_range, tcd.thread
  • Hash Cond: (tcd.timer_callpath = cp.id)
17. 4788.248 4788.248 ↓ 1.0 2967624 1
→

Seq Scan on timer_call_data tcd (cost=0.00..51392.50 rows=2936550 width=16) (actual time=0.070..4788.248 rows=2967624 loops=1)

  • Output: tcd.id, tcd.timer_callpath, tcd.thread, tcd.calls, tcd.subroutines, tcd.time_range
18. 0.114 534.053 ↓ 1.7 44 1
→

Hash (cost=0.52..0.52 rows=26 width=36) (actual time=534.053..534.053 rows=44 loops=1)

  • Output: cp.name, cp.id
19. 533.939 533.939 ↓ 1.7 44 1
→

CTE Scan on cp (cost=0.00..0.52 rows=26 width=36) (actual time=144.405..533.939 rows=44 loops=1)

  • Output: cp.name, cp.id
20. 2.112 2.112 ↑ 1.0 1 352
→

Index Scan using thread_pkey on thread h (cost=0.00..2.52 rows=1 width=16) (actual time=0.004..0.006 rows=1 loops=352)

  • Output: h.id, h.trial, h.node_rank, h.context_rank, h.thread_rank, h.thread_index
  • Index Cond: (h.id = tcd.thread)
  • Filter: (h.trial = 9034)