explain.depesz.com

PostgreSQL's explain analyze made readable

Result: fjWh : Bricolage slow running query

Settings
# exclusive inclusive rows x rows loops node
1. 0.592 161.990 ↓ 4.0 4 1

GroupAggregate (cost=3,620.27..3,620.38 rows=1 width=338) (actual time=161.822..161.990 rows=4 loops=1)

2. 0.198 161.398 ↓ 8.0 8 1

Sort (cost=3,620.27..3,620.28 rows=1 width=338) (actual time=161.391..161.398 rows=8 loops=1)

  • Sort Key: i.cover_date, s.id, s.uuid, s.priority, s.source__id, s.usr__id, s.element_type__id, s.first_publish_date, s.publish_date, s.expire_date, s.current_version, s.published_version, s.workflow__id, s.publish_status, s.primary_uri, s.active, s.desk__id, s.site__id, s.alias_id, i.id, i.name, i.description, i.story__id, i.version, i.usr__id, i.primary_oc__id, i.slug, i.note, i.checked_out
  • Sort Method: quicksort Memory: 28kB
3. 0.035 161.200 ↓ 8.0 8 1

Nested Loop (cost=0.00..3,620.26 rows=1 width=338) (actual time=0.510..161.200 rows=8 loops=1)

4. 0.029 160.957 ↓ 8.0 8 1

Nested Loop (cost=0.00..3,616.30 rows=1 width=338) (actual time=0.465..160.957 rows=8 loops=1)

5. 0.022 160.808 ↓ 4.0 4 1

Nested Loop (cost=0.00..3,612.01 rows=1 width=334) (actual time=0.431..160.808 rows=4 loops=1)

6. 0.021 160.738 ↓ 4.0 4 1

Nested Loop (cost=0.00..3,611.73 rows=1 width=334) (actual time=0.405..160.738 rows=4 loops=1)

7. 0.032 160.521 ↓ 4.0 4 1

Nested Loop (cost=0.00..3,607.37 rows=1 width=330) (actual time=0.357..160.521 rows=4 loops=1)

8. 0.087 160.437 ↓ 4.0 4 1

Nested Loop (cost=0.00..3,607.09 rows=1 width=326) (actual time=0.326..160.437 rows=4 loops=1)

  • Join Filter: (i.version = s.current_version)
9. 54.112 54.112 ↓ 1.4 11 1

Index Scan using fkx_usr__story_instance on story_instance i (cost=0.00..3,572.77 rows=8 width=174) (actual time=0.267..54.112 rows=11 loops=1)

  • Index Cond: (usr__id = 2249)
  • Filter: checked_out
10. 106.238 106.238 ↑ 1.0 1 11

Index Scan using pk_story__id on story s (cost=0.00..4.28 rows=1 width=152) (actual time=9.657..9.658 rows=1 loops=11)

  • Index Cond: (s.id = i.story__id)
  • Filter: s.active
11. 0.052 0.052 ↑ 1.0 1 4

Index Scan using pk_workflow__id on workflow w (cost=0.00..0.27 rows=1 width=8) (actual time=0.013..0.013 rows=1 loops=4)

  • Index Cond: (w.id = s.workflow__id)
12. 0.196 0.196 ↑ 1.0 1 4

Index Scan using udx_story_category__story__cat on story__category sc (cost=0.00..4.35 rows=1 width=8) (actual time=0.049..0.049 rows=1 loops=4)

  • Index Cond: (sc.story_instance__id = i.id)
13. 0.048 0.048 ↑ 1.0 1 4

Index Scan using pk_category__id on category c (cost=0.00..0.27 rows=1 width=8) (actual time=0.011..0.012 rows=1 loops=4)

  • Index Cond: (c.id = sc.category__id)
14. 0.120 0.120 ↓ 2.0 2 4

Index Scan using fkx_story__story_member on story_member sm (cost=0.00..4.27 rows=1 width=8) (actual time=0.027..0.030 rows=2 loops=4)

  • Index Cond: (sm.object_id = s.id)
15. 0.208 0.208 ↑ 1.0 1 8

Index Scan using pk_member__id on member m (cost=0.00..3.95 rows=1 width=8) (actual time=0.025..0.026 rows=1 loops=8)

  • Index Cond: (m.id = sm.member__id)
  • Filter: m.active