#	exclusive	inclusive	rows x	rows	loops	node
1.	0.005	10248.360	↑ 4.0	1	1	→ Merge Right Join (cost=256130.91..256332.84 rows=4 width=109) (actual time=10248.333..10248.360 rows=1 loops=1) Merge Cond: ((ar.req_transaction_id)::text = (ar.req_transaction_id)::text)
2.	0.024	2302.091	↑ 430.0	1	1	→ GroupAggregate (cost=43082.00..43172.62 rows=430 width=45) (actual time=2302.091..2302.091 rows=1 loops=1)
3.	813.001	2302.067	↑ 5683.0	2	1	→ Sort (cost=43082.00..43110.42 rows=11366 width=45) (actual time=2302.067..2302.067 rows=2 loops=1) Sort Key: ar.req_transaction_id Sort Method: quicksort Memory: 20986kB
4.	242.984	1489.066	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1231.557..1489.066 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
5.	6.534	8.903	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=57) (actual time=8.808..8.903 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 145kB
6.	0.551	2.369	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=57) (actual time=1.211..2.369 rows=909 loops=1) Hash Cond: (u.uow_id = buok.uow_id)
7.	0.483	1.138	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=45) (actual time=0.525..1.138 rows=909 loops=1) Hash Cond: (u.uow_validation_process_id = ar.req_id)
8.	0.144	0.144	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.006..0.144 rows=909 loops=1)
9.	0.137	0.511	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=45) (actual time=0.511..0.511 rows=430 loops=1)
10.	0.196	0.374	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.022..0.374 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
11.	0.165	0.165	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.003..0.165 rows=430 loops=1)
12.	0.003	0.013	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.013..0.013 rows=15 loops=1)
13.	0.010	0.010	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.010 rows=15 loops=1)
14.	0.324	0.680	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.680..0.680 rows=909 loops=1)
15.	0.356	0.356	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.003..0.356 rows=909 loops=1)
16.	973.069	1237.179	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1222.739..1237.179 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
17.	143.305	264.110	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.032..264.110 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
18.	70.147	70.147	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.016..70.147 rows=189969 loops=1)
19.	50.658	50.658	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.012..50.658 rows=189969 loops=1)
20.	0.005	7946.264	↑ 4.0	1	1	→ Materialize (cost=213048.91..213154.80 rows=4 width=138) (actual time=7946.237..7946.264 rows=1 loops=1)
21.	0.005	7946.259	↑ 4.0	1	1	→ Merge Left Join (cost=213048.91..213154.76 rows=4 width=138) (actual time=7946.233..7946.259 rows=1 loops=1) Merge Cond: ((ar.req_transaction_id)::text = (without_errors.transaction_id)::text)
22.	0.004	6519.169	↑ 4.0	1	1	→ Merge Left Join (cost=170520.37..170626.20 rows=4 width=130) (actual time=6519.143..6519.169 rows=1 loops=1) Merge Cond: ((ar.req_transaction_id)::text = (rules_without_error.transaction_id)::text)
23.	0.008	3659.838	↑ 4.0	1	1	→ Nested Loop Left Join (cost=85688.70..85794.50 rows=4 width=122) (actual time=3659.813..3659.838 rows=1 loops=1) Join Filter: (notifications.anu_request_id = request.req_id)
24.	0.004	3659.758	↑ 4.0	1	1	→ Merge Left Join (cost=85686.03..85788.59 rows=4 width=122) (actual time=3659.757..3659.758 rows=1 loops=1) Merge Cond: ((ar.req_transaction_id)::text = (invoice_with_error.transaction_id)::text)
25.	0.005	1456.344	↑ 4.0	1	1	→ Merge Left Join (cost=42604.03..42606.24 rows=4 width=114) (actual time=1456.343..1456.344 rows=1 loops=1) Merge Cond: ((ar.req_transaction_id)::text = (total_errors.transaction_id)::text)
26.	0.004	2.319	↑ 4.0	1	1	→ Sort (cost=202.36..202.37 rows=4 width=106) (actual time=2.319..2.319 rows=1 loops=1) Sort Key: ar.req_transaction_id Sort Method: quicksort Memory: 25kB
27.	0.007	2.315	↑ 4.0	1	1	→ Hash Left Join (cost=190.86..202.32 rows=4 width=106) (actual time=2.256..2.315 rows=1 loops=1) Hash Cond: (request.req_id = rules_select.arr_request_id)
28.	0.050	1.871	↑ 2.0	1	1	→ Hash Join (cost=157.67..168.98 rows=2 width=98) (actual time=1.812..1.871 rows=1 loops=1) Hash Cond: (r.req_id = request.req_id)
29.	0.374	1.044	↑ 1.0	430	1	→ HashAggregate (cost=83.27..88.65 rows=430 width=16) (actual time=0.982..1.044 rows=430 loops=1)
30.	0.422	0.670	↑ 1.0	1412	1	→ Hash Join (cost=17.68..76.21 rows=1412 width=16) (actual time=0.134..0.670 rows=1412 loops=1) Hash Cond: (i.aiv_adhoc_request_id = r.req_id)
31.	0.122	0.122	↑ 1.0	1412	1	→ Seq Scan on adhoc_invoices i (cost=0.00..39.12 rows=1412 width=16) (actual time=0.001..0.122 rows=1412 loops=1)
32.	0.068	0.126	↑ 1.0	430	1	→ Hash (cost=12.30..12.30 rows=430 width=8) (actual time=0.126..0.126 rows=430 loops=1)
33.	0.058	0.058	↑ 1.0	430	1	→ Seq Scan on adhoc_requests r (cost=0.00..12.30 rows=430 width=8) (actual time=0.001..0.058 rows=430 loops=1)
34.	0.002	0.777	↑ 1.0	1	1	→ Hash (cost=74.39..74.39 rows=1 width=90) (actual time=0.777..0.777 rows=1 loops=1)
35.	0.038	0.775	↑ 1.0	1	1	→ Nested Loop Left Join (cost=33.97..74.39 rows=1 width=90) (actual time=0.543..0.775 rows=1 loops=1) Join Filter: (ar.req_id = u.uow_validation_process_id)
36.	0.060	0.310	↑ 1.0	1	1	→ Nested Loop Left Join (cost=1.34..27.19 rows=1 width=90) (actual time=0.133..0.310 rows=1 loops=1) Join Filter: ((request.req_transaction_id)::text = (ar.req_transaction_id)::text)
37.	0.022	0.022	↑ 1.0	1	1	→ Index Scan using req_transaction_id_ix on adhoc_requests request (cost=0.00..2.27 rows=1 width=45) (actual time=0.021..0.022 rows=1 loops=1) Index Cond: ((req_transaction_id)::text = '0095a714-8eb3-463e-85e3-b34f3a5279d0'::text)
38.	0.114	0.228	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.020..0.228 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
39.	0.104	0.104	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.003..0.104 rows=430 loops=1)
40.	0.004	0.010	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)
41.	0.006	0.006	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.006 rows=15 loops=1)
42.	0.327	0.427	↑ 1.0	416	1	→ HashAggregate (cost=32.63..37.83 rows=416 width=16) (actual time=0.375..0.427 rows=416 loops=1)
43.	0.100	0.100	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.003..0.100 rows=909 loops=1)
44.	0.071	0.437	↑ 1.0	430	1	→ Hash (cost=27.81..27.81 rows=430 width=16) (actual time=0.437..0.437 rows=430 loops=1)
45.	0.048	0.366	↑ 1.0	430	1	→ Subquery Scan rules_select (cost=18.14..27.81 rows=430 width=16) (actual time=0.251..0.366 rows=430 loops=1)
46.	0.268	0.318	↑ 1.0	430	1	→ HashAggregate (cost=18.14..23.51 rows=430 width=16) (actual time=0.251..0.318 rows=430 loops=1)
47.	0.050	0.050	↑ 1.0	609	1	→ Seq Scan on adhoc_request_rules ru (cost=0.00..15.09 rows=609 width=16) (actual time=0.002..0.050 rows=609 loops=1)
48.	0.127	1454.020	↑ 430.0	1	1	→ Sort (cost=42401.68..42402.75 rows=430 width=524) (actual time=1454.020..1454.020 rows=1 loops=1) Sort Key: total_errors.transaction_id Sort Method: quicksort Memory: 33kB
49.	0.017	1453.893	↑ 3.8	114	1	→ Subquery Scan total_errors (cost=42373.19..42382.87 rows=430 width=524) (actual time=1453.860..1453.893 rows=114 loops=1)
50.	46.126	1453.876	↑ 3.8	114	1	→ HashAggregate (cost=42373.19..42378.57 rows=430 width=45) (actual time=1453.860..1453.876 rows=114 loops=1)
51.	243.841	1407.750	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1149.569..1407.750 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
52.	3.866	5.209	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=57) (actual time=5.118..5.209 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 145kB
53.	0.295	1.343	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=57) (actual time=0.699..1.343 rows=909 loops=1) Hash Cond: (u.uow_id = buok.uow_id)
54.	0.285	0.674	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=45) (actual time=0.318..0.674 rows=909 loops=1) Hash Cond: (u.uow_validation_process_id = ar.req_id)
55.	0.078	0.078	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.002..0.078 rows=909 loops=1)
56.	0.086	0.311	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=45) (actual time=0.311..0.311 rows=430 loops=1)
57.	0.127	0.225	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.012..0.225 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
58.	0.091	0.091	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.001..0.091 rows=430 loops=1)
59.	0.005	0.007	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.007..0.007 rows=15 loops=1)
60.	0.002	0.002	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.002 rows=15 loops=1)
61.	0.189	0.374	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.374..0.374 rows=909 loops=1)
62.	0.185	0.185	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.185 rows=909 loops=1)
63.	961.100	1158.700	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1144.441..1158.700 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
64.	106.448	197.600	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.026..197.600 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
65.	52.625	52.625	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.011..52.625 rows=189969 loops=1)
66.	38.527	38.527	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.527 rows=189969 loops=1)
67.	0.004	2203.410	↑ 430.0	1	1	→ Materialize (cost=43082.00..43181.22 rows=430 width=524) (actual time=2203.410..2203.410 rows=1 loops=1)
68.	0.001	2203.406	↑ 430.0	1	1	→ Subquery Scan invoice_with_error (cost=43082.00..43176.92 rows=430 width=524) (actual time=2203.406..2203.406 rows=1 loops=1)
69.	0.020	2203.405	↑ 430.0	1	1	→ GroupAggregate (cost=43082.00..43172.62 rows=430 width=45) (actual time=2203.405..2203.405 rows=1 loops=1)
70.	813.484	2203.385	↑ 5683.0	2	1	→ Sort (cost=43082.00..43110.42 rows=11366 width=45) (actual time=2203.384..2203.385 rows=2 loops=1) Sort Key: ar.req_transaction_id Sort Method: quicksort Memory: 20986kB
71.	240.356	1389.901	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1134.397..1389.901 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
72.	3.912	5.310	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=65) (actual time=5.241..5.310 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 152kB
73.	0.309	1.398	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=65) (actual time=0.739..1.398 rows=909 loops=1) Hash Cond: (u.uow_id = buok.uow_id)
74.	0.293	0.683	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=45) (actual time=0.327..0.683 rows=909 loops=1) Hash Cond: (u.uow_validation_process_id = ar.req_id)
75.	0.086	0.086	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.006..0.086 rows=909 loops=1)
76.	0.084	0.304	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=45) (actual time=0.304..0.304 rows=430 loops=1)
77.	0.113	0.220	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.019..0.220 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
78.	0.098	0.098	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.098 rows=430 loops=1)
79.	0.006	0.009	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.009..0.009 rows=15 loops=1)
80.	0.003	0.003	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.003 rows=15 loops=1)
81.	0.181	0.406	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.406..0.406 rows=909 loops=1)
82.	0.225	0.225	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.001..0.225 rows=909 loops=1)
83.	948.354	1144.235	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1129.148..1144.235 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
84.	105.018	195.881	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.029..195.881 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
85.	52.172	52.172	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..52.172 rows=189969 loops=1)
86.	38.691	38.691	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.691 rows=189969 loops=1)
87.	0.013	0.072	↑ 1.0	36	1	→ Materialize (cost=2.67..3.03 rows=36 width=16) (actual time=0.048..0.072 rows=36 loops=1)
88.	0.004	0.059	↑ 1.0	36	1	→ Subquery Scan notifications (cost=1.82..2.63 rows=36 width=16) (actual time=0.046..0.059 rows=36 loops=1)
89.	0.042	0.055	↑ 1.0	36	1	→ HashAggregate (cost=1.82..2.27 rows=36 width=16) (actual time=0.046..0.055 rows=36 loops=1)
90.	0.013	0.013	↑ 1.0	55	1	→ Seq Scan on adhoc_request_notificat_users arn (cost=0.00..1.55 rows=55 width=16) (actual time=0.007..0.013 rows=55 loops=1)
91.	0.004	2859.327	↓ 0.0	0	1	→ Sort (cost=84831.67..84831.67 rows=1 width=524) (actual time=2859.327..2859.327 rows=0 loops=1) Sort Key: rules_without_error.transaction_id Sort Method: quicksort Memory: 25kB
92.	0.000	2859.323	↓ 0.0	0	1	→ Subquery Scan rules_without_error (cost=84831.64..84831.66 rows=1 width=524) (actual time=2859.323..2859.323 rows=0 loops=1)
93.	0.002	2859.323	↓ 0.0	0	1	→ HashAggregate (cost=84831.64..84831.65 rows=1 width=45) (actual time=2859.323..2859.323 rows=0 loops=1)
94.	30.368	2859.321	↓ 0.0	0	1	→ Hash Anti Join (cost=82276.60..84831.63 rows=1 width=45) (actual time=2859.321..2859.321 rows=0 loops=1) Hash Cond: (u.uow_id = uw.uow_id)
95.	245.471	1404.651	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=53) (actual time=1145.348..1404.651 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
96.	3.823	5.202	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=65) (actual time=5.098..5.202 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 152kB
97.	0.301	1.379	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=65) (actual time=0.726..1.379 rows=909 loops=1) Hash Cond: (u.uow_id = buok.uow_id)
98.	0.292	0.682	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=45) (actual time=0.325..0.682 rows=909 loops=1) Hash Cond: (u.uow_validation_process_id = ar.req_id)
99.	0.077	0.077	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.003..0.077 rows=909 loops=1)
100.	0.081	0.313	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=45) (actual time=0.313..0.313 rows=430 loops=1)
101.	0.130	0.232	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.023..0.232 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
102.	0.092	0.092	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.092 rows=430 loops=1)
103.	0.005	0.010	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)
104.	0.005	0.005	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.003..0.005 rows=15 loops=1)
105.	0.182	0.396	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.396..0.396 rows=909 loops=1)
106.	0.214	0.214	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.214 rows=909 loops=1)
107.	957.163	1153.978	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1140.242..1153.978 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
108.	104.837	196.815	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.028..196.815 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
109.	53.056	53.056	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.014..53.056 rows=189969 loops=1)
110.	38.922	38.922	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.922 rows=189969 loops=1)
111.	32.321	1424.302	↓ 16.7	189968	1	→ Hash (cost=42316.36..42316.36 rows=11366 width=8) (actual time=1424.302..1424.302 rows=189968 loops=1)
112.	242.987	1391.981	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=8) (actual time=1134.963..1391.981 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
113.	3.781	5.107	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=28) (actual time=5.014..5.107 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 92kB
114.	0.288	1.326	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=28) (actual time=0.703..1.326 rows=909 loops=1) Hash Cond: (uw.uow_id = buok.uow_id)
115.	0.269	0.637	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=8) (actual time=0.295..0.637 rows=909 loops=1) Hash Cond: (uw.uow_validation_process_id = ar.req_id)
116.	0.084	0.084	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works uw (cost=0.00..28.09 rows=909 width=16) (actual time=0.007..0.084 rows=909 loops=1)
117.	0.065	0.284	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=8) (actual time=0.284..0.284 rows=430 loops=1)
118.	0.115	0.219	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=8) (actual time=0.020..0.219 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
119.	0.094	0.094	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=16) (actual time=0.002..0.094 rows=430 loops=1)
120.	0.004	0.010	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)
121.	0.006	0.006	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.003..0.006 rows=15 loops=1)
122.	0.179	0.401	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.401..0.401 rows=909 loops=1)
123.	0.222	0.222	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.222 rows=909 loops=1)
124.	948.196	1143.887	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1129.939..1143.887 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
125.	104.859	195.691	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.027..195.691 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
126.	52.023	52.023	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..52.023 rows=189969 loops=1)
127.	38.809	38.809	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.809 rows=189969 loops=1)
128.	0.414	1427.085	↓ 2.0	2	1	→ Sort (cost=42528.53..42528.54 rows=1 width=524) (actual time=1427.085..1427.085 rows=2 loops=1) Sort Key: without_errors.transaction_id Sort Method: quicksort Memory: 50kB
129.	0.050	1426.671	↓ 322.0	322	1	→ Subquery Scan without_errors (cost=42528.50..42528.52 rows=1 width=524) (actual time=1426.575..1426.671 rows=322 loops=1)
130.	0.282	1426.621	↓ 322.0	322	1	→ HashAggregate (cost=42528.50..42528.51 rows=1 width=45) (actual time=1426.574..1426.621 rows=322 loops=1)
131.	1.199	1426.339	↓ 695.0	695	1	→ Hash Anti Join (cost=42483.36..42528.50 rows=1 width=45) (actual time=1424.698..1426.339 rows=695 loops=1) Hash Cond: (u.uow_id = uw.uow_id)
132.	0.355	0.774	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=45) (actual time=0.325..0.774 rows=909 loops=1) Hash Cond: (u.uow_validation_process_id = ar.req_id)
133.	0.111	0.111	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works u (cost=0.00..28.09 rows=909 width=16) (actual time=0.007..0.111 rows=909 loops=1)
134.	0.091	0.308	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=45) (actual time=0.308..0.308 rows=430 loops=1)
135.	0.097	0.217	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=45) (actual time=0.018..0.217 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
136.	0.111	0.111	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.111 rows=430 loops=1)
137.	0.003	0.009	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.009..0.009 rows=15 loops=1)
138.	0.006	0.006	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.006 rows=15 loops=1)
139.	32.665	1424.366	↓ 16.7	189968	1	→ Hash (cost=42316.36..42316.36 rows=11366 width=8) (actual time=1424.366..1424.366 rows=189968 loops=1)
140.	241.478	1391.701	↓ 16.7	189968	1	→ Merge Join (cost=39818.17..42316.36 rows=11366 width=8) (actual time=1136.057..1391.701 rows=189968 loops=1) Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))
141.	3.737	5.061	↑ 1.1	792	1	→ Sort (cost=162.13..164.41 rows=909 width=28) (actual time=4.966..5.061 rows=792 loops=1) Sort Key: buok.uow_key, buok.uow_validation_process_id Sort Method: quicksort Memory: 92kB
142.	0.299	1.324	↑ 1.0	909	1	→ Hash Join (cost=64.38..117.47 rows=909 width=28) (actual time=0.682..1.324 rows=909 loops=1) Hash Cond: (uw.uow_id = buok.uow_id)
143.	0.259	0.624	↑ 1.0	909	1	→ Hash Join (cost=24.92..65.51 rows=909 width=8) (actual time=0.276..0.624 rows=909 loops=1) Hash Cond: (uw.uow_validation_process_id = ar.req_id)
144.	0.096	0.096	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works uw (cost=0.00..28.09 rows=909 width=16) (actual time=0.002..0.096 rows=909 loops=1)
145.	0.068	0.269	↑ 1.0	430	1	→ Hash (cost=19.55..19.55 rows=430 width=8) (actual time=0.269..0.269 rows=430 loops=1)
146.	0.110	0.201	↑ 1.0	430	1	→ Hash Join (cost=1.34..19.55 rows=430 width=8) (actual time=0.012..0.201 rows=430 loops=1) Hash Cond: (ar.req_user_creation = u.usr_id)
147.	0.085	0.085	↑ 1.0	430	1	→ Seq Scan on adhoc_requests ar (cost=0.00..12.30 rows=430 width=16) (actual time=0.001..0.085 rows=430 loops=1)
148.	0.003	0.006	↑ 1.0	15	1	→ Hash (cost=1.15..1.15 rows=15 width=8) (actual time=0.006..0.006 rows=15 loops=1)
149.	0.003	0.003	↑ 1.0	15	1	→ Seq Scan on auth_users u (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.003 rows=15 loops=1)
150.	0.168	0.401	↑ 1.0	909	1	→ Hash (cost=28.09..28.09 rows=909 width=28) (actual time=0.401..0.401 rows=909 loops=1)
151.	0.233	0.233	↑ 1.0	909	1	→ Seq Scan on bck_unit_of_works buok (cost=0.00..28.09 rows=909 width=28) (actual time=0.001..0.233 rows=909 loops=1)
152.	947.064	1145.162	↑ 1.0	189969	1	→ Sort (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1131.082..1145.162 rows=189969 loops=1) Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint) Sort Method: quicksort Memory: 20986kB
153.	105.884	198.098	↑ 1.0	189969	1	→ Merge Join (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.028..198.098 rows=189969 loops=1) Merge Cond: (bc.attribute_uid = bca.objid)
154.	53.275	53.275	↑ 1.0	189969	1	→ Index Scan using bck_03_ix on bck_consequences bc (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..53.275 rows=189969 loops=1)
155.	38.939	38.939	↑ 1.0	189969	1	→ Index Scan using bca_objid_ix on bck_consequence_attr bca (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.008..38.939 rows=189969 loops=1)

"Merge Right Join  (cost=256130.91..256332.84 rows=4 width=109) (actual time=10248.333..10248.360 rows=1 loops=1)"
"  Merge Cond: ((ar.req_transaction_id)::text = (ar.req_transaction_id)::text)"
"  ->  GroupAggregate  (cost=43082.00..43172.62 rows=430 width=45) (actual time=2302.091..2302.091 rows=1 loops=1)"
"        ->  Sort  (cost=43082.00..43110.42 rows=11366 width=45) (actual time=2302.067..2302.067 rows=2 loops=1)"
"              Sort Key: ar.req_transaction_id"
"              Sort Method:  quicksort  Memory: 20986kB"
"              ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1231.557..1489.066 rows=189968 loops=1)"
"                    Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                    ->  Sort  (cost=162.13..164.41 rows=909 width=57) (actual time=8.808..8.903 rows=792 loops=1)"
"                          Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                          Sort Method:  quicksort  Memory: 145kB"
"                          ->  Hash Join  (cost=64.38..117.47 rows=909 width=57) (actual time=1.211..2.369 rows=909 loops=1)"
"                                Hash Cond: (u.uow_id = buok.uow_id)"
"                                ->  Hash Join  (cost=24.92..65.51 rows=909 width=45) (actual time=0.525..1.138 rows=909 loops=1)"
"                                      Hash Cond: (u.uow_validation_process_id = ar.req_id)"
"                                      ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.006..0.144 rows=909 loops=1)"
"                                      ->  Hash  (cost=19.55..19.55 rows=430 width=45) (actual time=0.511..0.511 rows=430 loops=1)"
"                                            ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.022..0.374 rows=430 loops=1)"
"                                                  Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                  ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.003..0.165 rows=430 loops=1)"
"                                                  ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.013..0.013 rows=15 loops=1)"
"                                                        ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.010 rows=15 loops=1)"
"                                ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.680..0.680 rows=909 loops=1)"
"                                      ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.003..0.356 rows=909 loops=1)"
"                    ->  Sort  (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1222.739..1237.179 rows=189969 loops=1)"
"                          Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                          Sort Method:  quicksort  Memory: 20986kB"
"                          ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.032..264.110 rows=189969 loops=1)"
"                                Merge Cond: (bc.attribute_uid = bca.objid)"
"                                ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.016..70.147 rows=189969 loops=1)"
"                                ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.012..50.658 rows=189969 loops=1)"
"  ->  Materialize  (cost=213048.91..213154.80 rows=4 width=138) (actual time=7946.237..7946.264 rows=1 loops=1)"
"        ->  Merge Left Join  (cost=213048.91..213154.76 rows=4 width=138) (actual time=7946.233..7946.259 rows=1 loops=1)"
"              Merge Cond: ((ar.req_transaction_id)::text = (without_errors.transaction_id)::text)"
"              ->  Merge Left Join  (cost=170520.37..170626.20 rows=4 width=130) (actual time=6519.143..6519.169 rows=1 loops=1)"
"                    Merge Cond: ((ar.req_transaction_id)::text = (rules_without_error.transaction_id)::text)"
"                    ->  Nested Loop Left Join  (cost=85688.70..85794.50 rows=4 width=122) (actual time=3659.813..3659.838 rows=1 loops=1)"
"                          Join Filter: (notifications.anu_request_id = request.req_id)"
"                          ->  Merge Left Join  (cost=85686.03..85788.59 rows=4 width=122) (actual time=3659.757..3659.758 rows=1 loops=1)"
"                                Merge Cond: ((ar.req_transaction_id)::text = (invoice_with_error.transaction_id)::text)"
"                                ->  Merge Left Join  (cost=42604.03..42606.24 rows=4 width=114) (actual time=1456.343..1456.344 rows=1 loops=1)"
"                                      Merge Cond: ((ar.req_transaction_id)::text = (total_errors.transaction_id)::text)"
"                                      ->  Sort  (cost=202.36..202.37 rows=4 width=106) (actual time=2.319..2.319 rows=1 loops=1)"
"                                            Sort Key: ar.req_transaction_id"
"                                            Sort Method:  quicksort  Memory: 25kB"
"                                            ->  Hash Left Join  (cost=190.86..202.32 rows=4 width=106) (actual time=2.256..2.315 rows=1 loops=1)"
"                                                  Hash Cond: (request.req_id = rules_select.arr_request_id)"
"                                                  ->  Hash Join  (cost=157.67..168.98 rows=2 width=98) (actual time=1.812..1.871 rows=1 loops=1)"
"                                                        Hash Cond: (r.req_id = request.req_id)"
"                                                        ->  HashAggregate  (cost=83.27..88.65 rows=430 width=16) (actual time=0.982..1.044 rows=430 loops=1)"
"                                                              ->  Hash Join  (cost=17.68..76.21 rows=1412 width=16) (actual time=0.134..0.670 rows=1412 loops=1)"
"                                                                    Hash Cond: (i.aiv_adhoc_request_id = r.req_id)"
"                                                                    ->  Seq Scan on adhoc_invoices i  (cost=0.00..39.12 rows=1412 width=16) (actual time=0.001..0.122 rows=1412 loops=1)"
"                                                                    ->  Hash  (cost=12.30..12.30 rows=430 width=8) (actual time=0.126..0.126 rows=430 loops=1)"
"                                                                          ->  Seq Scan on adhoc_requests r  (cost=0.00..12.30 rows=430 width=8) (actual time=0.001..0.058 rows=430 loops=1)"
"                                                        ->  Hash  (cost=74.39..74.39 rows=1 width=90) (actual time=0.777..0.777 rows=1 loops=1)"
"                                                              ->  Nested Loop Left Join  (cost=33.97..74.39 rows=1 width=90) (actual time=0.543..0.775 rows=1 loops=1)"
"                                                                    Join Filter: (ar.req_id = u.uow_validation_process_id)"
"                                                                    ->  Nested Loop Left Join  (cost=1.34..27.19 rows=1 width=90) (actual time=0.133..0.310 rows=1 loops=1)"
"                                                                          Join Filter: ((request.req_transaction_id)::text = (ar.req_transaction_id)::text)"
"                                                                          ->  Index Scan using req_transaction_id_ix on adhoc_requests request  (cost=0.00..2.27 rows=1 width=45) (actual time=0.021..0.022 rows=1 loops=1)"
"                                                                                Index Cond: ((req_transaction_id)::text = '0095a714-8eb3-463e-85e3-b34f3a5279d0'::text)"
"                                                                          ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.020..0.228 rows=430 loops=1)"
"                                                                                Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.003..0.104 rows=430 loops=1)"
"                                                                                ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)"
"                                                                                      ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.006 rows=15 loops=1)"
"                                                                    ->  HashAggregate  (cost=32.63..37.83 rows=416 width=16) (actual time=0.375..0.427 rows=416 loops=1)"
"                                                                          ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.003..0.100 rows=909 loops=1)"
"                                                  ->  Hash  (cost=27.81..27.81 rows=430 width=16) (actual time=0.437..0.437 rows=430 loops=1)"
"                                                        ->  Subquery Scan rules_select  (cost=18.14..27.81 rows=430 width=16) (actual time=0.251..0.366 rows=430 loops=1)"
"                                                              ->  HashAggregate  (cost=18.14..23.51 rows=430 width=16) (actual time=0.251..0.318 rows=430 loops=1)"
"                                                                    ->  Seq Scan on adhoc_request_rules ru  (cost=0.00..15.09 rows=609 width=16) (actual time=0.002..0.050 rows=609 loops=1)"
"                                      ->  Sort  (cost=42401.68..42402.75 rows=430 width=524) (actual time=1454.020..1454.020 rows=1 loops=1)"
"                                            Sort Key: total_errors.transaction_id"
"                                            Sort Method:  quicksort  Memory: 33kB"
"                                            ->  Subquery Scan total_errors  (cost=42373.19..42382.87 rows=430 width=524) (actual time=1453.860..1453.893 rows=114 loops=1)"
"                                                  ->  HashAggregate  (cost=42373.19..42378.57 rows=430 width=45) (actual time=1453.860..1453.876 rows=114 loops=1)"
"                                                        ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1149.569..1407.750 rows=189968 loops=1)"
"                                                              Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                                                              ->  Sort  (cost=162.13..164.41 rows=909 width=57) (actual time=5.118..5.209 rows=792 loops=1)"
"                                                                    Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                                                                    Sort Method:  quicksort  Memory: 145kB"
"                                                                    ->  Hash Join  (cost=64.38..117.47 rows=909 width=57) (actual time=0.699..1.343 rows=909 loops=1)"
"                                                                          Hash Cond: (u.uow_id = buok.uow_id)"
"                                                                          ->  Hash Join  (cost=24.92..65.51 rows=909 width=45) (actual time=0.318..0.674 rows=909 loops=1)"
"                                                                                Hash Cond: (u.uow_validation_process_id = ar.req_id)"
"                                                                                ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.002..0.078 rows=909 loops=1)"
"                                                                                ->  Hash  (cost=19.55..19.55 rows=430 width=45) (actual time=0.311..0.311 rows=430 loops=1)"
"                                                                                      ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.012..0.225 rows=430 loops=1)"
"                                                                                            Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                            ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.001..0.091 rows=430 loops=1)"
"                                                                                            ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.007..0.007 rows=15 loops=1)"
"                                                                                                  ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.002 rows=15 loops=1)"
"                                                                          ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.374..0.374 rows=909 loops=1)"
"                                                                                ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.185 rows=909 loops=1)"
"                                                              ->  Sort  (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1144.441..1158.700 rows=189969 loops=1)"
"                                                                    Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                                                                    Sort Method:  quicksort  Memory: 20986kB"
"                                                                    ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.026..197.600 rows=189969 loops=1)"
"                                                                          Merge Cond: (bc.attribute_uid = bca.objid)"
"                                                                          ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.011..52.625 rows=189969 loops=1)"
"                                                                          ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.527 rows=189969 loops=1)"
"                                ->  Materialize  (cost=43082.00..43181.22 rows=430 width=524) (actual time=2203.410..2203.410 rows=1 loops=1)"
"                                      ->  Subquery Scan invoice_with_error  (cost=43082.00..43176.92 rows=430 width=524) (actual time=2203.406..2203.406 rows=1 loops=1)"
"                                            ->  GroupAggregate  (cost=43082.00..43172.62 rows=430 width=45) (actual time=2203.405..2203.405 rows=1 loops=1)"
"                                                  ->  Sort  (cost=43082.00..43110.42 rows=11366 width=45) (actual time=2203.384..2203.385 rows=2 loops=1)"
"                                                        Sort Key: ar.req_transaction_id"
"                                                        Sort Method:  quicksort  Memory: 20986kB"
"                                                        ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=45) (actual time=1134.397..1389.901 rows=189968 loops=1)"
"                                                              Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                                                              ->  Sort  (cost=162.13..164.41 rows=909 width=65) (actual time=5.241..5.310 rows=792 loops=1)"
"                                                                    Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                                                                    Sort Method:  quicksort  Memory: 152kB"
"                                                                    ->  Hash Join  (cost=64.38..117.47 rows=909 width=65) (actual time=0.739..1.398 rows=909 loops=1)"
"                                                                          Hash Cond: (u.uow_id = buok.uow_id)"
"                                                                          ->  Hash Join  (cost=24.92..65.51 rows=909 width=45) (actual time=0.327..0.683 rows=909 loops=1)"
"                                                                                Hash Cond: (u.uow_validation_process_id = ar.req_id)"
"                                                                                ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.006..0.086 rows=909 loops=1)"
"                                                                                ->  Hash  (cost=19.55..19.55 rows=430 width=45) (actual time=0.304..0.304 rows=430 loops=1)"
"                                                                                      ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.019..0.220 rows=430 loops=1)"
"                                                                                            Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                            ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.098 rows=430 loops=1)"
"                                                                                            ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.009..0.009 rows=15 loops=1)"
"                                                                                                  ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.003 rows=15 loops=1)"
"                                                                          ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.406..0.406 rows=909 loops=1)"
"                                                                                ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.001..0.225 rows=909 loops=1)"
"                                                              ->  Sort  (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1129.148..1144.235 rows=189969 loops=1)"
"                                                                    Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                                                                    Sort Method:  quicksort  Memory: 20986kB"
"                                                                    ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.029..195.881 rows=189969 loops=1)"
"                                                                          Merge Cond: (bc.attribute_uid = bca.objid)"
"                                                                          ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..52.172 rows=189969 loops=1)"
"                                                                          ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.691 rows=189969 loops=1)"
"                          ->  Materialize  (cost=2.67..3.03 rows=36 width=16) (actual time=0.048..0.072 rows=36 loops=1)"
"                                ->  Subquery Scan notifications  (cost=1.82..2.63 rows=36 width=16) (actual time=0.046..0.059 rows=36 loops=1)"
"                                      ->  HashAggregate  (cost=1.82..2.27 rows=36 width=16) (actual time=0.046..0.055 rows=36 loops=1)"
"                                            ->  Seq Scan on adhoc_request_notificat_users arn  (cost=0.00..1.55 rows=55 width=16) (actual time=0.007..0.013 rows=55 loops=1)"
"                    ->  Sort  (cost=84831.67..84831.67 rows=1 width=524) (actual time=2859.327..2859.327 rows=0 loops=1)"
"                          Sort Key: rules_without_error.transaction_id"
"                          Sort Method:  quicksort  Memory: 25kB"
"                          ->  Subquery Scan rules_without_error  (cost=84831.64..84831.66 rows=1 width=524) (actual time=2859.323..2859.323 rows=0 loops=1)"
"                                ->  HashAggregate  (cost=84831.64..84831.65 rows=1 width=45) (actual time=2859.323..2859.323 rows=0 loops=1)"
"                                      ->  Hash Anti Join  (cost=82276.60..84831.63 rows=1 width=45) (actual time=2859.321..2859.321 rows=0 loops=1)"
"                                            Hash Cond: (u.uow_id = uw.uow_id)"
"                                            ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=53) (actual time=1145.348..1404.651 rows=189968 loops=1)"
"                                                  Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                                                  ->  Sort  (cost=162.13..164.41 rows=909 width=65) (actual time=5.098..5.202 rows=792 loops=1)"
"                                                        Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                                                        Sort Method:  quicksort  Memory: 152kB"
"                                                        ->  Hash Join  (cost=64.38..117.47 rows=909 width=65) (actual time=0.726..1.379 rows=909 loops=1)"
"                                                              Hash Cond: (u.uow_id = buok.uow_id)"
"                                                              ->  Hash Join  (cost=24.92..65.51 rows=909 width=45) (actual time=0.325..0.682 rows=909 loops=1)"
"                                                                    Hash Cond: (u.uow_validation_process_id = ar.req_id)"
"                                                                    ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.003..0.077 rows=909 loops=1)"
"                                                                    ->  Hash  (cost=19.55..19.55 rows=430 width=45) (actual time=0.313..0.313 rows=430 loops=1)"
"                                                                          ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.023..0.232 rows=430 loops=1)"
"                                                                                Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.092 rows=430 loops=1)"
"                                                                                ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)"
"                                                                                      ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.003..0.005 rows=15 loops=1)"
"                                                              ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.396..0.396 rows=909 loops=1)"
"                                                                    ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.214 rows=909 loops=1)"
"                                                  ->  Sort  (cost=39656.03..40130.95 rows=189969 width=22) (actual time=1140.242..1153.978 rows=189969 loops=1)"
"                                                        Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                                                        Sort Method:  quicksort  Memory: 20986kB"
"                                                        ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=22) (actual time=0.028..196.815 rows=189969 loops=1)"
"                                                              Merge Cond: (bc.attribute_uid = bca.objid)"
"                                                              ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=19) (actual time=0.014..53.056 rows=189969 loops=1)"
"                                                              ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.922 rows=189969 loops=1)"
"                                            ->  Hash  (cost=42316.36..42316.36 rows=11366 width=8) (actual time=1424.302..1424.302 rows=189968 loops=1)"
"                                                  ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=8) (actual time=1134.963..1391.981 rows=189968 loops=1)"
"                                                        Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                                                        ->  Sort  (cost=162.13..164.41 rows=909 width=28) (actual time=5.014..5.107 rows=792 loops=1)"
"                                                              Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                                                              Sort Method:  quicksort  Memory: 92kB"
"                                                              ->  Hash Join  (cost=64.38..117.47 rows=909 width=28) (actual time=0.703..1.326 rows=909 loops=1)"
"                                                                    Hash Cond: (uw.uow_id = buok.uow_id)"
"                                                                    ->  Hash Join  (cost=24.92..65.51 rows=909 width=8) (actual time=0.295..0.637 rows=909 loops=1)"
"                                                                          Hash Cond: (uw.uow_validation_process_id = ar.req_id)"
"                                                                          ->  Seq Scan on bck_unit_of_works uw  (cost=0.00..28.09 rows=909 width=16) (actual time=0.007..0.084 rows=909 loops=1)"
"                                                                          ->  Hash  (cost=19.55..19.55 rows=430 width=8) (actual time=0.284..0.284 rows=430 loops=1)"
"                                                                                ->  Hash Join  (cost=1.34..19.55 rows=430 width=8) (actual time=0.020..0.219 rows=430 loops=1)"
"                                                                                      Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                      ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=16) (actual time=0.002..0.094 rows=430 loops=1)"
"                                                                                      ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.010..0.010 rows=15 loops=1)"
"                                                                                            ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.003..0.006 rows=15 loops=1)"
"                                                                    ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.401..0.401 rows=909 loops=1)"
"                                                                          ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.002..0.222 rows=909 loops=1)"
"                                                        ->  Sort  (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1129.939..1143.887 rows=189969 loops=1)"
"                                                              Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                                                              Sort Method:  quicksort  Memory: 20986kB"
"                                                              ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.027..195.691 rows=189969 loops=1)"
"                                                                    Merge Cond: (bc.attribute_uid = bca.objid)"
"                                                                    ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..52.023 rows=189969 loops=1)"
"                                                                    ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.009..38.809 rows=189969 loops=1)"
"              ->  Sort  (cost=42528.53..42528.54 rows=1 width=524) (actual time=1427.085..1427.085 rows=2 loops=1)"
"                    Sort Key: without_errors.transaction_id"
"                    Sort Method:  quicksort  Memory: 50kB"
"                    ->  Subquery Scan without_errors  (cost=42528.50..42528.52 rows=1 width=524) (actual time=1426.575..1426.671 rows=322 loops=1)"
"                          ->  HashAggregate  (cost=42528.50..42528.51 rows=1 width=45) (actual time=1426.574..1426.621 rows=322 loops=1)"
"                                ->  Hash Anti Join  (cost=42483.36..42528.50 rows=1 width=45) (actual time=1424.698..1426.339 rows=695 loops=1)"
"                                      Hash Cond: (u.uow_id = uw.uow_id)"
"                                      ->  Hash Join  (cost=24.92..65.51 rows=909 width=45) (actual time=0.325..0.774 rows=909 loops=1)"
"                                            Hash Cond: (u.uow_validation_process_id = ar.req_id)"
"                                            ->  Seq Scan on bck_unit_of_works u  (cost=0.00..28.09 rows=909 width=16) (actual time=0.007..0.111 rows=909 loops=1)"
"                                            ->  Hash  (cost=19.55..19.55 rows=430 width=45) (actual time=0.308..0.308 rows=430 loops=1)"
"                                                  ->  Hash Join  (cost=1.34..19.55 rows=430 width=45) (actual time=0.018..0.217 rows=430 loops=1)"
"                                                        Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                        ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=53) (actual time=0.002..0.111 rows=430 loops=1)"
"                                                        ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.009..0.009 rows=15 loops=1)"
"                                                              ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.002..0.006 rows=15 loops=1)"
"                                      ->  Hash  (cost=42316.36..42316.36 rows=11366 width=8) (actual time=1424.366..1424.366 rows=189968 loops=1)"
"                                            ->  Merge Join  (cost=39818.17..42316.36 rows=11366 width=8) (actual time=1136.057..1391.701 rows=189968 loops=1)"
"                                                  Merge Cond: (((buok.uow_key)::text = (bca.attr_value_1)::text) AND (buok.uow_validation_process_id = ((bc.transaction_id)::bigint)))"
"                                                  ->  Sort  (cost=162.13..164.41 rows=909 width=28) (actual time=4.966..5.061 rows=792 loops=1)"
"                                                        Sort Key: buok.uow_key, buok.uow_validation_process_id"
"                                                        Sort Method:  quicksort  Memory: 92kB"
"                                                        ->  Hash Join  (cost=64.38..117.47 rows=909 width=28) (actual time=0.682..1.324 rows=909 loops=1)"
"                                                              Hash Cond: (uw.uow_id = buok.uow_id)"
"                                                              ->  Hash Join  (cost=24.92..65.51 rows=909 width=8) (actual time=0.276..0.624 rows=909 loops=1)"
"                                                                    Hash Cond: (uw.uow_validation_process_id = ar.req_id)"
"                                                                    ->  Seq Scan on bck_unit_of_works uw  (cost=0.00..28.09 rows=909 width=16) (actual time=0.002..0.096 rows=909 loops=1)"
"                                                                    ->  Hash  (cost=19.55..19.55 rows=430 width=8) (actual time=0.269..0.269 rows=430 loops=1)"
"                                                                          ->  Hash Join  (cost=1.34..19.55 rows=430 width=8) (actual time=0.012..0.201 rows=430 loops=1)"
"                                                                                Hash Cond: (ar.req_user_creation = u.usr_id)"
"                                                                                ->  Seq Scan on adhoc_requests ar  (cost=0.00..12.30 rows=430 width=16) (actual time=0.001..0.085 rows=430 loops=1)"
"                                                                                ->  Hash  (cost=1.15..1.15 rows=15 width=8) (actual time=0.006..0.006 rows=15 loops=1)"
"                                                                                      ->  Seq Scan on auth_users u  (cost=0.00..1.15 rows=15 width=8) (actual time=0.001..0.003 rows=15 loops=1)"
"                                                              ->  Hash  (cost=28.09..28.09 rows=909 width=28) (actual time=0.401..0.401 rows=909 loops=1)"
"                                                                    ->  Seq Scan on bck_unit_of_works buok  (cost=0.00..28.09 rows=909 width=28) (actual time=0.001..0.233 rows=909 loops=1)"
"                                                  ->  Sort  (cost=39656.03..40130.95 rows=189969 width=14) (actual time=1131.082..1145.162 rows=189969 loops=1)"
"                                                        Sort Key: bca.attr_value_1, ((bc.transaction_id)::bigint)"
"                                                        Sort Method:  quicksort  Memory: 20986kB"
"                                                        ->  Merge Join  (cost=0.00..23000.12 rows=189969 width=14) (actual time=0.028..198.098 rows=189969 loops=1)"
"                                                              Merge Cond: (bc.attribute_uid = bca.objid)"
"                                                              ->  Index Scan using bck_03_ix on bck_consequences bc  (cost=0.00..8058.79 rows=189969 width=11) (actual time=0.014..53.275 rows=189969 loops=1)"
"                                                              ->  Index Scan using bca_objid_ix on bck_consequence_attr bca  (cost=0.00..12091.79 rows=189969 width=19) (actual time=0.008..38.939 rows=189969 loops=1)"
"Total runtime: 10253.750 ms"

Per node type stats

node type	count	sum of times	% of query
GroupAggregate	2	0.044 ms	0.0 %
Hash	26	66.995 ms	0.7 %
Hash Anti Join	2	31.567 ms	0.3 %
Hash Join	23	5.753 ms	0.1 %
Hash Left Join	1	0.007 ms	0.0 %
HashAggregate	7	47.421 ms	0.5 %
Index Scan	13	577.866 ms	5.6 %
Materialize	3	0.022 ms	0.0 %
Merge Join	12	2127.468 ms	20.8 %
Merge Left Join	4	0.018 ms	0.0 %
Merge Right Join	1	0.005 ms	0.0 %
Nested Loop Left Join	3	0.106 ms	0.0 %
Seq Scan	34	3.335 ms	0.0 %
Sort	18	7387.633 ms	72.1 %
Subquery Scan invoice_with_error	1	0.001 ms	0.0 %
Subquery Scan notifications	1	0.004 ms	0.0 %
Subquery Scan rules_select	1	0.048 ms	0.0 %
Subquery Scan rules_without_error	1	0.000 ms	0.0 %
Subquery Scan total_errors	1	0.017 ms	0.0 %
Subquery Scan without_errors	1	0.050 ms	0.0 %

Per table stats

Table name	Scan count	Total time	% of query
scan type	count	sum of times	% of table
adhoc_invoices	1	0.122 ms	0.0 %
Seq Scan	1	0.122 ms	100.0 %
adhoc_request_notificat_users	1	0.013 ms	0.0 %
Seq Scan	1	0.013 ms	100.0 %
adhoc_request_rules	1	0.050 ms	0.0 %
Seq Scan	1	0.050 ms	100.0 %
adhoc_requests	10	0.920 ms	0.0 %
Index Scan	1	0.022 ms	2.4 %
Seq Scan	9	0.898 ms	97.6 %
auth_users	8	0.041 ms	0.0 %
Seq Scan	8	0.041 ms	100.0 %
bck_consequence_attr	6	244.546 ms	2.4 %
Index Scan	6	244.546 ms	100.0 %
bck_consequences	6	333.298 ms	3.3 %
Index Scan	6	333.298 ms	100.0 %
bck_unit_of_works	14	2.211 ms	0.0 %
Seq Scan	14	2.211 ms	100.0 %

explain.depesz.com

A tool for finding a real cause for slow queries.

Result: K2U : aa

Colorize:

Columns visibility:

Per node type stats

Per table stats