Optimizer generates bad plans. - Mailing list pgsql-hackers
From | Kris Jurka |
---|---|
Subject | Optimizer generates bad plans. |
Date | |
Msg-id | 3D8A4A5D.6070701@ejurka.com Whole thread Raw |
Responses |
Re: Optimizer generates bad plans.
Re: Optimizer generates bad plans. Re: Optimizer generates bad plans. |
List | pgsql-hackers |
While adding schema support to the JDBC Driver, I came across a query which occasionally generates some spectacularly bad plans. I have attached the query and explain analyze outputs against today's cvs head for queries that take between 9 and 845941 msec. In the JDBC Driver I will specify a reasonable join order using explicit JOINs, but I thought someone might be interested in a test case for the optimizer. Kris Jurka The query tries to determine what foreign keys exists between the following tables. create table people (id int4 primary key, name text); create table policy (id int4 primary key, name text); create table users (id int4 primary key, people_id int4, policy_id int4, CONSTRAINT people FOREIGN KEY (people_id) references people(id), constraint policy FOREIGN KEY (policy_id) references policy(id)); SELECT DISTINCT n.nspname as pnspname, n2.nspname as fnspname, c.relname as prelname, c2.relname as frelname, t.tgconstrname, a.attnum as keyseq, ic.relname as fkeyname, t.tgdeferrable, t.tginitdeferred, t.tgnargs,t.tgargs, p1.proname as updaterule, p2.proname as deleterule FROM pg_catalog.pg_namespace n, pg_catalog.pg_namespace n2, pg_catalog.pg_trigger t, pg_catalog.pg_trigger t1, pg_catalog.pg_class c, pg_catalog.pg_class c2, pg_catalog.pg_class ic, pg_catalog.pg_proc p1, pg_catalog.pg_proc p2, pg_catalog.pg_index i, pg_catalog.pg_attribute a WHERE (t.tgrelid=c.oid AND t.tgisconstraint AND t.tgconstrrelid=c2.oid AND t.tgfoid=p1.oid and p1.proname like 'RI\_FKey\_%\_upd') AND (t1.tgrelid=c.oid and t1.tgisconstraint and t1.tgconstrrelid=c2.oid AND t1.tgfoid=p2.oid and p2.proname like 'RI\_FKey\_%\_del') AND i.indrelid=c.oid AND i.indexrelid=ic.oid AND ic.oid=a.attrelid AND i.indisprimary AND c.relnamespace = n.oid AND c2.relnamespace=n2.oid AND c2.relname='users' ORDER BY prelname,keyseq ; Unique (cost=1.06..1.10 rows=1 width=594) (actual time=845786.00..845786.00 rows=2 loops=1) -> Sort (cost=1.06..1.07 rows=1 width=594) (actual time=845786.00..845786.00 rows=2 loops=1) Sort Key: c.relname, a.attnum, n.nspname, n2.nspname, c2.relname, t.tgconstrname, ic.relname, t.tgdeferrable, t.tginitdeferred,t.tgnargs, t.tgargs, p1.proname, p2.proname -> Merge Join (cost=1.03..1.05 rows=1 width=594) (actual time=844522. 00..845786.00 rows=2 loops=1) Merge Cond: ("outer".tgconstrrelid = "inner".tgconstrrelid) Join Filter: (("inner".tgfoid = "outer".oid) AND ("inner".tgrelid = "outer".oid)) -> Nested Loop (cost=0.00..27709.41 rows=1 width=510) (actual time=844522.00..845786.00 rows=12 loops=1) Join Filter: (("inner".indexrelid = "outer".oid) AND ("inner".indrelid = "outer".oid)) -> Nested Loop (cost=0.00..27706.67 rows=1 width=502) (actual time=843375.00..843954.00 rows=10620loops=1) Join Filter: (("inner".tgconstrrelid = "outer".oid) AND ("outer".relnamespace = "inner".oid)) -> Index Scan using pg_class_oid_index on pg_class c2 (cost=0.00..15.67 rows=1 width=72) (actualtime=1.00..1.00 rows=1 loops=1) Filter: (relname = 'users'::name) -> Materialize (cost=27690.93..27690.93 rows=4 width=430) (actual time=843374.00..843781.00rows=42480 loops=1) -> Nested Loop (cost=0.00..27690.93 rows=4 width=430) (actual time=614674.00..843125.00rows=42480 loops=1) -> Nested Loop (cost=0.00..27689.85 rows=1 width=362) (actual time=614674.00..842368.00rows=10620 loops=1) Join Filter: (("outer".tgfoid = "inner".oid) AND ("outer".tgrelid = "inner".oid)) -> Seq Scan on pg_trigger t1 (cost=0.00..1.02 rows=1 width=12) (actual time=0.00..1.00rows=6 loops=1) Filter: tgisconstraint -> Materialize (cost=26180.37..26180.37 rows=100564 width=350) (actual time=83492.50..135359.33rows=3637350 loops=6) -> Nested Loop (cost=0.00..26180.37 rows=100564 width=350) (actual time=68978.00..481414.00rows=3637350 loops=1) Join Filter: ("inner".relnamespace = "outer".oid) -> Seq Scan on pg_namespace n (cost=0.00..1.04 rows=4 width=68)(actual time=0.00..0.00 rows=4 loops=1) -> Materialize (cost=5287.78..5287.78 rows=100564 width=282) (actualtime=17273.75..110243.25 rows=3637350 loops=4) -> Nested Loop (cost=0.00..5287.78 rows=100564 width=282)(actual time=106.00..45489.00 rows=3637350 loops=1) -> Nested Loop (cost=0.00..843.50 rows=811 width=210)(actual time=106.00..557.00 rows=26550 loops=1) -> Index Scan using pg_proc_proname_args_nsp_indexon pg_proc p2 (cost=0.00..5.97 rows=1 width=68) (actual time=0.00..1.00 rows=5 loops=1) Index Cond: ((proname >= 'RI'::name) AND(proname < 'RJ'::name)) Filter: (proname ~~ 'RI_FKey_%_del'::text) -> Materialize (cost=829.42..829.42 rows=811width=142) (actual time=21.20..58.60 rows=5310 loops=5) -> Merge Join (cost=0.00..829.42 rows=811width=142) (actual time=0.00..87.00 rows=5310 loops=1) Merge Cond: ("outer".oid = "inner".attrelid) -> Nested Loop (cost=0.00..757.44rows=124 width=136) (actual time=0.00..27.00 rows=822 loops=1) -> Index Scan using pg_class_oid_indexon pg_class ic (cost=0.00..15.36 rows=124 width=68) (actual time=0.00..1.00 rows=137 loops=1) -> Index Scan using pg_proc_proname_args_nsp_indexon pg_proc p1 (cost=0.00..5.97 rows=1 width=68) (actual time=0.00..0.17 rows=6 loops=137) Index Cond: ((proname>= 'RI'::name) AND (proname < 'RJ'::name)) Filter: (proname ~~ 'RI_FKey_%_upd'::text) -> Index Scan using pg_attribute_relid_attnum_indexon pg_attribute a (cost=0.00..59.51 rows=811 width=6) (actual time=0.00..32.00 rows=5305loops=1) -> Seq Scan on pg_class c (cost=0.00..4.24 rows=124width=72) (actual time=0.01..0.96 rows=137 loops=26550) -> Seq Scan on pg_namespace n2 (cost=0.00..1.04 rows=4 width=68) (actual time=0.01..0.02rows=4 loops=10620) -> Seq Scan on pg_index i (cost=0.00..2.61 rows=9 width=8) (actual time=0.06..0.14 rows=15 loops=10620) Filter: indisprimary -> Sort (cost=1.03..1.03 rows=1 width=84) (actual time=0.00..0.00 rows=39 loops=1) Sort Key: t.tgconstrrelid -> Seq Scan on pg_trigger t (cost=0.00..1.02 rows=1 width=84) (actual time=0.00..0.00 rows=6 loops=1) Filter: tgisconstraint Total runtime: 845941.00 msec (46 rows) Unique (cost=1.06..1.10 rows=1 width=594) (actual time=7.00..7.00 rows=2 loops=1) -> Sort (cost=1.06..1.07 rows=1 width=594) (actual time=7.00..7.00 rows=2 loops=1) Sort Key: c.relname, a.attnum, n.nspname, n2.nspname, c2.relname, t.tgconstrname, ic.relname, t.tgdeferrable, t.tginitdeferred,t.tgnargs, t.tgargs, p1.proname, p2.proname -> Merge Join (cost=1.03..1.05 rows=1 width=594) (actual time=5.00..6.00 rows=2 loops=1) Merge Cond: ("outer".tgconstrrelid = "inner".tgconstrrelid) Join Filter: (("outer".indrelid = "inner".tgrelid) AND ("inner".tgfoid = "outer".oid)) -> Nested Loop (cost=0.00..85.31 rows=1 width=510) (actual time=4.00..6.00 rows=12 loops=1) Join Filter: ("inner".relnamespace = "outer".oid) -> Nested Loop (cost=0.00..63.49 rows=4 width=438) (actual time=3.00..4.00 rows=48 loops=1) -> Nested Loop (cost=0.00..62.41 rows=1 width=370) (actual time=3.00..3.00 rows=12 loops=1) Join Filter: (("inner".tgconstrrelid = "outer".oid) AND ("outer".relnamespace = "inner".oid)) -> Index Scan using pg_class_oid_index on pg_class c2 (cost=0.00..15.67 rows=1 width=72)(actual time=1.00..1.00 rows=1 loops=1) Filter: (relname = 'users'::name) -> Materialize (cost=46.67..46.67 rows=4 width=298) (actual time=2.00..2.00 rows=48 loops=1) -> Nested Loop (cost=0.00..46.67 rows=4 width=298) (actual time=1.00..2.00 rows=48loops=1) -> Nested Loop (cost=0.00..45.59 rows=1 width=230) (actual time=1.00..1.00rows=12 loops=1) Join Filter: ("inner".tgfoid = "outer".oid) -> Index Scan using pg_proc_proname_args_nsp_index on pg_proc p2 (cost=0.00..5.97rows=1 width=68) (actual time=0.00..0.00 rows=5 loops=1) Index Cond: ((proname > = 'RI'::name) AND (proname < 'RJ'::name)) Filter: (proname ~~ 'RI _FKey_%_del'::text) -> Materialize (cost=39.54..39.54 rows=7 width=162) (actual time=0.20..0.20rows=36 loops=5) -> Nested Loop (cost=0.00..39.54 rows=7 width=162) (actual time=1.00..1.00rows=36 loops=1) -> Index Scan using pg_proc_proname_args_nsp_index on pg_procp1 (cost=0.00..5.97 rows=1 width=68) (actual time=0.00..0.00 rows=6 loops=1) Index Cond: ((proname >= 'RI'::name) AND (proname <'RJ'::name)) Filter: (proname ~~ 'RI_FKey_%_upd'::text) -> Materialize (cost=33.50..33.50 rows=7 width=94) (actualtime=0.17..0.17 rows=6 loops=6) -> Nested Loop (cost=0.00..33.50 rows=7 width=94)(actual time=1.00..1.00 rows=6 loops=1) Join Filter: ("outer".oid = "inner".attrelid) -> Merge Join (cost=0.00..15.13 rows=1 width=88)(actual time=0.00..0.00 rows=6 loops=1) Merge Cond: ("outer".indrelid = "inner".tgrelid) -> Nested Loop (cost=0.00..58.89 rows=9width=76) (actual time=0.00..0.00 rows=15 loops=1) -> Index Scan using pg_index_indrelid_indexon pg_index i (cost=0.00..9.80 rows=9 width=8) (actual time=0.00..0.00 rows=15 loops=1) Filter: indisprimary -> Index Scan using pg_class_oid_indexon pg_class ic (cost=0.00..5.44 rows=1 width=68) (actual time=0.00..0.00 rows=1 loops=15) Index Cond: ("outer".indexrelid= ic.oid) -> Index Scan using pg_trigger_tgrelid_tgname_indexon pg_trigger t1 (cost=0.00..5.27 rows=1 width=12) (actual time=0.00..0.00 rows=6 loops=1) Filter: tgisconstraint -> Index Scan using pg_attribute_relid_attnum_indexon pg_attribute a (cost=0.00..18.29 rows=7 width=6) (actual time=0.00..0.00 rows=1 loops=6) Index Cond: (a.attrelid = "outer".indexrelid) -> Seq Scan on pg_namespace n2 (cost=0.00..1.04 rows=4 width=68) (actual time=0.00..0.00rows=4 loops=12) -> Seq Scan on pg_namespace n (cost=0.00..1.04 rows=4 width=68) (actual time=0.08..0.08 rows=4loops=12) -> Index Scan using pg_class_oid_index on pg_class c (cost=0.00..5.44 rows=1 width=72) (actual time=0.02..0.04rows=1 loops=48) Index Cond: ("outer".indrelid = c.oid) -> Sort (cost=1.03..1.03 rows=1 width=84) (actual time=0.00..0.00 rows=39 loops=1) Sort Key: t.tgconstrrelid -> Seq Scan on pg_trigger t (cost=0.00..1.02 rows=1 width=84) (actual time=0.00..0.00 rows=6 loops=1) Filter: tgisconstraint Total runtime: 9.00 msec (48 rows)
pgsql-hackers by date: