explain.depesz.com

PostgreSQL's explain analyze made readable

Result: f8oq : Optimization for: Optimization for: plan #ZN9x; plan #wgFb

Settings

Optimization path:

Optimization(s) for this plan:

# exclusive inclusive rows x rows loops node
1. 9,229.801 56,332.662 ↓ 6.1 18,664 1

GroupAggregate (cost=414,442.27..897,800.23 rows=3,067 width=376) (actual time=38,303.575..56,332.662 rows=18,664 loops=1)

  • Group Key: cu.id, ces.id, cf.fid, st.id, tm.id, cr.id, sh.id
2.          

CTE fd

3. 0.254 2.438 ↑ 1.0 1 1

Aggregate (cost=2,309.71..2,309.72 rows=1 width=8) (actual time=2.437..2.438 rows=1 loops=1)

4. 2.184 2.184 ↑ 1.0 297 1

Seq Scan on students_list_bck (cost=0.00..2,308.97 rows=297 width=8) (actual time=0.015..2.184 rows=297 loops=1)

5.          

Initplan (for GroupAggregate)

6. 2.442 2.442 ↑ 1.0 1 1

CTE Scan on fd (cost=0.00..0.02 rows=1 width=8) (actual time=2.440..2.442 rows=1 loops=1)

7. 0.002 0.002 ↑ 1.0 1 1

CTE Scan on fd fd_1 (cost=0.00..0.02 rows=1 width=8) (actual time=0.002..0.002 rows=1 loops=1)

8. 0.002 0.002 ↑ 1.0 1 1

CTE Scan on fd fd_2 (cost=0.00..0.02 rows=1 width=8) (actual time=0.001..0.002 rows=1 loops=1)

9. 0.001 0.001 ↑ 1.0 1 1

CTE Scan on fd fd_3 (cost=0.00..0.02 rows=1 width=8) (actual time=0.001..0.001 rows=1 loops=1)

10. 0.000 0.000 ↓ 0.0 0

CTE Scan on fd fd_4 (cost=0.00..0.02 rows=1 width=8) (never executed)

11. 0.002 0.002 ↑ 1.0 1 1

CTE Scan on fd fd_5 (cost=0.00..0.02 rows=1 width=8) (actual time=0.001..0.002 rows=1 loops=1)

12. 0.001 0.001 ↑ 1.0 1 1

CTE Scan on fd fd_6 (cost=0.00..0.02 rows=1 width=8) (actual time=0.001..0.001 rows=1 loops=1)

13. 0.002 0.002 ↑ 1.0 1 1

CTE Scan on fd fd_7 (cost=0.00..0.02 rows=1 width=8) (actual time=0.001..0.002 rows=1 loops=1)

14. 13,955.057 42,453.073 ↓ 1,151.8 3,532,717 1

Sort (cost=412,132.38..412,140.05 rows=3,067 width=376) (actual time=38,303.053..42,453.073 rows=3,532,717 loops=1)

  • Sort Key: cu.id, ces.id, cf.fid, st.id, tm.id, cr.id, sh.id
  • Sort Method: external merge Disk: 1115448kB
15. 6,261.827 28,498.016 ↓ 1,151.8 3,532,717 1

Merge Join (cost=399,225.10..411,954.76 rows=3,067 width=376) (actual time=17,698.191..28,498.016 rows=3,532,717 loops=1)

  • Merge Cond: ((sbd.user_id = (COALESCE(pd1.user_id, pd2.user_id))) AND (acd.sis_id = ((cr.sis_fid)::text)) AND (tmd.sis_id = ((tm.sis_fid)::text)))
  • Join Filter: ((st.updated_at > $9) OR ((pd1.updated_at IS NOT NULL) AND (pd1.updated_at > $10)) OR ((pd2.updated_at IS NOT NULL) AND (pd2.updated_at > $11)) OR ((en.updated_at IS NOT NULL) AND (en.updated_at > $12)) OR ((ese.updated_at IS NOT NULL) AND (ese.updated_at > $13)) OR ((sbd.created_at IS NOT NULL) AND (sbd.created_at > $14)) OR ((asd.created_at IS NOT NULL) AND (asd.created_at > $15)) OR ((crd.created_at IS NOT NULL) AND (crd.created_at > $16)))
  • Rows Removed by Join Filter: 3617
16. 5,351.339 16,471.806 ↓ 3.8 1,223,617 1

Sort (cost=299,207.09..300,011.74 rows=321,861 width=84) (actual time=14,228.872..16,471.806 rows=1,223,617 loops=1)

  • Sort Key: sbd.user_id, acd.sis_id, tmd.sis_id
  • Sort Method: external merge Disk: 108272kB
17. 4,783.340 11,120.467 ↓ 3.8 1,223,617 1

Hash Join (cost=109,507.06..254,359.62 rows=321,861 width=84) (actual time=3,914.677..11,120.467 rows=1,223,617 loops=1)

  • Hash Cond: (sbf.submission_id = sbd.id)
18. 2,422.701 2,422.701 ↑ 1.0 4,873,887 1

Seq Scan on raw_submissions_facts sbf (cost=0.00..71,357.87 rows=4,873,887 width=16) (actual time=0.009..2,422.701 rows=4,873,887 loops=1)

19. 935.238 3,914.426 ↓ 3.8 1,223,617 1

Hash (cost=101,082.80..101,082.80 rows=321,861 width=84) (actual time=3,914.426..3,914.426 rows=1,223,617 loops=1)

  • Buckets: 262144 (originally 262144) Batches: 8 (originally 2) Memory Usage: 30721kB
20. 1,137.574 2,979.188 ↓ 3.8 1,223,617 1

Nested Loop (cost=217.19..101,082.80 rows=321,861 width=84) (actual time=6.483..2,979.188 rows=1,223,617 loops=1)

21. 11.447 57.865 ↓ 2.4 9,339 1

Hash Join (cost=216.76..3,373.58 rows=3,870 width=42) (actual time=6.465..57.865 rows=9,339 loops=1)

  • Hash Cond: (asd.course_id = crd.id)
22. 14.942 42.492 ↓ 1.6 9,339 1

Hash Join (cost=131.75..3,227.50 rows=5,966 width=32) (actual time=2.533..42.492 rows=9,339 loops=1)

  • Hash Cond: (asd.assignment_group_id = agf.assignment_group_id)
23. 25.032 25.032 ↓ 1.0 16,126 1

Seq Scan on raw_assignments_dim asd (cost=0.00..2,975.93 rows=16,045 width=40) (actual time=0.006..25.032 rows=16,126 loops=1)

  • Filter: (status = 'published'::text)
  • Rows Removed by Filter: 28668
24. 1.078 2.518 ↑ 1.0 2,163 1

Hash (cost=104.71..104.71 rows=2,163 width=8) (actual time=2.518..2.518 rows=2,163 loops=1)

  • Buckets: 4096 Batches: 1 Memory Usage: 117kB
25. 1.440 1.440 ↑ 1.0 2,163 1

Seq Scan on raw_assignment_groups_facts agf (cost=0.00..104.71 rows=2,163 width=8) (actual time=0.007..1.440 rows=2,163 loops=1)

  • Filter: (group_weight > '0'::double precision)
  • Rows Removed by Filter: 3654
26. 0.626 3.926 ↓ 1.4 1,313 1

Hash (cost=73.63..73.63 rows=910 width=26) (actual time=3.926..3.926 rows=1,313 loops=1)

  • Buckets: 2048 (originally 1024) Batches: 1 (originally 1) Memory Usage: 88kB
27. 1.250 3.300 ↓ 1.4 1,313 1

Hash Join (cost=3.73..73.63 rows=910 width=26) (actual time=0.094..3.300 rows=1,313 loops=1)

  • Hash Cond: (crd.term_id = tmd.id)
28. 1.259 2.035 ↓ 1.4 1,313 1

Hash Join (cost=2.53..59.92 rows=910 width=30) (actual time=0.075..2.035 rows=1,313 loops=1)

  • Hash Cond: (crd.account_id = acd.id)
29. 0.711 0.711 ↑ 1.0 1,403 1

Seq Scan on raw_courses_dim crd (cost=0.00..43.03 rows=1,403 width=32) (actual time=0.004..0.711 rows=1,403 loops=1)

30. 0.027 0.065 ↑ 1.0 48 1

Hash (cost=1.93..1.93 rows=48 width=14) (actual time=0.065..0.065 rows=48 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 11kB
31. 0.038 0.038 ↑ 1.0 48 1

Seq Scan on raw_accounts_dim acd (cost=0.00..1.93 rows=48 width=14) (actual time=0.006..0.038 rows=48 loops=1)

  • Filter: (status <> 'deleted'::text)
  • Rows Removed by Filter: 26
32. 0.005 0.015 ↑ 1.0 9 1

Hash (cost=1.09..1.09 rows=9 width=12) (actual time=0.015..0.015 rows=9 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 9kB
33. 0.010 0.010 ↑ 1.0 9 1

Seq Scan on raw_terms_dim tmd (cost=0.00..1.09 rows=9 width=12) (actual time=0.002..0.010 rows=9 loops=1)

34. 1,783.749 1,783.749 ↑ 1.3 131 9,339

Index Scan using raw_dim_submissions_assignment_id_idx on raw_submissions_dim sbd (cost=0.43..23.50 rows=175 width=50) (actual time=0.009..0.191 rows=131 loops=9,339)

  • Index Cond: (assignment_id = asd.id)
  • Filter: (status <> 'deleted'::text)
  • Rows Removed by Filter: 7
35. 1,747.895 5,764.383 ↓ 13.3 3,660,699 1

Materialize (cost=100,018.02..101,395.06 rows=275,408 width=334) (actual time=3,466.838..5,764.383 rows=3,660,699 loops=1)

36. 2,085.979 4,016.488 ↑ 1.4 203,951 1

Sort (cost=100,018.02..100,706.54 rows=275,408 width=334) (actual time=3,466.832..4,016.488 rows=203,951 loops=1)

  • Sort Key: (COALESCE(pd1.user_id, pd2.user_id)), ((cr.sis_fid)::text), ((tm.sis_fid)::text)
  • Sort Method: external merge Disk: 68464kB
37. 472.688 1,930.509 ↑ 1.1 247,478 1

Hash Right Join (cost=24,597.10..32,772.74 rows=275,408 width=334) (actual time=1,141.363..1,930.509 rows=247,478 loops=1)

  • Hash Cond: (en.student_id = st.id)
38. 212.568 583.071 ↓ 1.1 245,387 1

Hash Right Join (cost=7,904.71..12,470.89 rows=228,101 width=41) (actual time=266.535..583.071 rows=245,387 loops=1)

  • Hash Cond: (ese.enroll_id = en.id)
39. 64.634 110.649 ↑ 1.0 59,738 1

Hash Join (cost=219.44..3,964.22 rows=59,738 width=23) (actual time=6.542..110.649 rows=59,738 loops=1)

  • Hash Cond: (ese.event_session_id = es.id)
40. 39.499 39.499 ↑ 1.0 59,738 1

Seq Scan on events_sessions_enrolls ese (cost=0.00..2,923.38 rows=59,738 width=21) (actual time=0.006..39.499 rows=59,738 loops=1)

41. 1.638 6.516 ↑ 1.0 3,084 1

Hash (cost=180.89..180.89 rows=3,084 width=10) (actual time=6.516..6.516 rows=3,084 loops=1)

  • Buckets: 4096 Batches: 1 Memory Usage: 159kB
42. 3.151 4.878 ↑ 1.0 3,084 1

Hash Join (cost=6.64..180.89 rows=3,084 width=10) (actual time=0.188..4.878 rows=3,084 loops=1)

  • Hash Cond: (es.event_id = ev.id)
43. 1.552 1.552 ↑ 1.0 3,084 1

Seq Scan on events_sessions es (cost=0.00..131.84 rows=3,084 width=8) (actual time=0.003..1.552 rows=3,084 loops=1)

44. 0.069 0.175 ↑ 1.0 162 1

Hash (cost=4.62..4.62 rows=162 width=6) (actual time=0.175..0.175 rows=162 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 15kB
45. 0.106 0.106 ↑ 1.0 162 1

Seq Scan on events ev (cost=0.00..4.62 rows=162 width=6) (actual time=0.005..0.106 rows=162 loops=1)

46. 129.687 259.854 ↑ 1.0 228,101 1

Hash (cost=4,834.01..4,834.01 rows=228,101 width=22) (actual time=259.854..259.854 rows=228,101 loops=1)

  • Buckets: 262144 Batches: 1 Memory Usage: 15414kB
47. 130.167 130.167 ↑ 1.0 228,101 1

Seq Scan on enrolls en (cost=0.00..4,834.01 rows=228,101 width=22) (actual time=0.012..130.167 rows=228,101 loops=1)

48. 57.886 874.750 ↑ 1.2 61,873 1

Hash (cost=15,766.37..15,766.37 rows=74,082 width=297) (actual time=874.750..874.750 rows=61,873 loops=1)

  • Buckets: 131072 Batches: 1 Memory Usage: 16715kB
49. 60.936 816.864 ↑ 1.2 61,873 1

Hash Join (cost=11,713.40..15,766.37 rows=74,082 width=297) (actual time=622.454..816.864 rows=61,873 loops=1)

  • Hash Cond: (st.center_id = sh.id)
50. 100.317 755.879 ↑ 1.2 61,873 1

Hash Right Join (cost=11,711.66..14,746.00 rows=74,082 width=282) (actual time=622.396..755.879 rows=61,873 loops=1)

  • Hash Cond: (pd2.unique_name = (cu.mail)::text)
51. 33.234 33.234 ↑ 1.0 71,019 1

Seq Scan on raw_pseudonym_dim pd2 (cost=0.00..2,027.19 rows=71,019 width=58) (actual time=0.004..33.234 rows=71,019 loops=1)

52. 60.670 622.328 ↓ 1.5 61,786 1

Hash (cost=11,205.95..11,205.95 rows=40,456 width=258) (actual time=622.328..622.328 rows=61,786 loops=1)

  • Buckets: 65536 Batches: 1 Memory Usage: 14984kB
53. 64.216 561.658 ↓ 1.5 61,786 1

Hash Join (cost=7,949.48..11,205.95 rows=40,456 width=258) (actual time=312.276..561.658 rows=61,786 loops=1)

  • Hash Cond: (st.course_id = cr.id)
54. 63.935 497.405 ↓ 1.5 61,786 1

Hash Join (cost=7,946.97..10,647.17 rows=40,456 width=216) (actual time=312.232..497.405 rows=61,786 loops=1)

  • Hash Cond: (st.term_id = tm.id)
55. 68.646 433.447 ↓ 1.5 61,786 1

Hash Join (cost=7,945.58..10,089.51 rows=40,456 width=201) (actual time=312.202..433.447 rows=61,786 loops=1)

  • Hash Cond: (cf.customer_id = cu.id)
56. 38.220 139.246 ↑ 1.0 26,087 1

Hash Left Join (cost=2,914.93..4,575.32 rows=26,347 width=32) (actual time=86.595..139.246 rows=26,087 loops=1)

  • Hash Cond: ((cf.fid)::text = pd1.sis_user_id)
57. 18.131 18.131 ↑ 1.0 26,087 1

Seq Scan on customers_fids cf (cost=0.00..1,133.45 rows=26,347 width=8) (actual time=3.621..18.131 rows=26,087 loops=1)

  • Filter: (data_source_id = 'sis'::text)
  • Rows Removed by Filter: 35267
58. 38.859 82.895 ↑ 1.8 38,501 1

Hash (cost=2,027.19..2,027.19 rows=71,019 width=34) (actual time=82.895..82.895 rows=38,501 loops=1)

  • Buckets: 131072 Batches: 1 Memory Usage: 3552kB
59. 44.036 44.036 ↑ 1.0 71,019 1

Seq Scan on raw_pseudonym_dim pd1 (cost=0.00..2,027.19 rows=71,019 width=34) (actual time=0.006..44.036 rows=71,019 loops=1)

60. 48.271 225.555 ↓ 1.3 61,357 1

Hash (cost=4,427.52..4,427.52 rows=48,251 width=173) (actual time=225.555..225.555 rows=61,357 loops=1)

  • Buckets: 65536 Batches: 1 Memory Usage: 9855kB
61. 69.651 177.284 ↓ 1.3 61,357 1

Hash Join (cost=2,311.35..4,427.52 rows=48,251 width=173) (actual time=71.898..177.284 rows=61,357 loops=1)

  • Hash Cond: (st.customer_id = cu.id)
62. 35.770 35.770 ↑ 1.0 61,357 1

Seq Scan on students st (cost=0.00..1,403.57 rows=61,357 width=43) (actual time=0.005..35.770 rows=61,357 loops=1)

63. 19.098 71.863 ↓ 1.0 26,009 1

Hash (cost=1,986.55..1,986.55 rows=25,984 width=130) (actual time=71.863..71.863 rows=26,009 loops=1)

  • Buckets: 32768 Batches: 1 Memory Usage: 3411kB
64. 29.468 52.765 ↓ 1.0 26,009 1

Hash Join (cost=1.38..1,986.55 rows=25,984 width=130) (actual time=0.052..52.765 rows=26,009 loops=1)

  • Hash Cond: (cu.enabled_status_id = ces.id)
65. 23.275 23.275 ↑ 1.0 33,042 1

Seq Scan on customers cu (cost=0.00..1,601.42 rows=33,042 width=74) (actual time=0.006..23.275 rows=33,042 loops=1)

66. 0.004 0.022 ↑ 1.0 17 1

Hash (cost=1.17..1.17 rows=17 width=56) (actual time=0.022..0.022 rows=17 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 10kB
67. 0.018 0.018 ↑ 1.0 17 1

Seq Scan on customer_enabled_status ces (cost=0.00..1.17 rows=17 width=56) (actual time=0.003..0.018 rows=17 loops=1)

68. 0.014 0.023 ↑ 1.0 17 1

Hash (cost=1.17..1.17 rows=17 width=19) (actual time=0.023..0.023 rows=17 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 9kB
69. 0.009 0.009 ↑ 1.0 17 1

Seq Scan on terms tm (cost=0.00..1.17 rows=17 width=19) (actual time=0.003..0.009 rows=17 loops=1)

70. 0.016 0.037 ↑ 1.0 23 1

Hash (cost=2.23..2.23 rows=23 width=42) (actual time=0.037..0.037 rows=23 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 10kB
71. 0.021 0.021 ↑ 1.0 23 1

Seq Scan on courses cr (cost=0.00..2.23 rows=23 width=42) (actual time=0.006..0.021 rows=23 loops=1)

72. 0.033 0.049 ↑ 1.0 33 1

Hash (cost=1.33..1.33 rows=33 width=19) (actual time=0.049..0.049 rows=33 loops=1)

  • Buckets: 1024 Batches: 1 Memory Usage: 10kB
73. 0.016 0.016 ↑ 1.0 33 1

Seq Scan on shops sh (cost=0.00..1.33 rows=33 width=19) (actual time=0.011..0.016 rows=33 loops=1)

74.          

SubPlan (for GroupAggregate)

75. 74.656 410.608 ↑ 1.0 1 18,664

Aggregate (cost=91.41..91.42 rows=1 width=4) (actual time=0.021..0.022 rows=1 loops=18,664)

76. 25.376 335.952 ↓ 0.0 0 18,664

Nested Loop Anti Join (cost=9.50..91.41 rows=3 width=4) (actual time=0.018..0.018 rows=0 loops=18,664)

  • Join Filter: ((od2.customer_id = od.customer_id) AND (ol2.product_id = ol.product_id))
  • Rows Removed by Join Filter: 1
77. 31.574 261.296 ↓ 0.0 0 18,664

Nested Loop (cost=4.75..32.27 rows=3 width=12) (actual time=0.014..0.014 rows=0 loops=18,664)

78. 149.312 223.968 ↓ 0.0 0 18,664

Bitmap Heap Scan on orders od (cost=4.33..23.51 rows=1 width=8) (actual time=0.012..0.012 rows=0 loops=18,664)

  • Recheck Cond: (customer_id = cu.id)
  • Filter: (last_order_state_id = 13)
  • Rows Removed by Filter: 4
  • Heap Blocks: exact=79609
79. 74.656 74.656 ↑ 1.0 5 18,664

Bitmap Index Scan on orders_customer_id_idx (cost=0.00..4.33 rows=5 width=0) (actual time=0.004..0.004 rows=5 loops=18,664)

  • Index Cond: (customer_id = cu.id)
80. 5.754 5.754 ↑ 4.0 1 822

Index Scan using orders_lines_order_id_idx on orders_lines ol (cost=0.42..8.72 rows=4 width=12) (actual time=0.006..0.007 rows=1 loops=822)

  • Index Cond: (order_id = od.id)
81. 10.072 49.280 ↑ 1.0 10 1,232

Materialize (cost=4.75..58.61 rows=10 width=8) (actual time=0.010..0.040 rows=10 loops=1,232)

82. 13.015 39.208 ↓ 1.1 11 754

Nested Loop (cost=4.75..58.56 rows=10 width=8) (actual time=0.014..0.052 rows=11 loops=754)

83. 6.786 9.048 ↓ 1.2 5 754

Bitmap Heap Scan on orders od2 (cost=4.33..23.52 rows=4 width=8) (actual time=0.006..0.012 rows=5 loops=754)

  • Recheck Cond: (customer_id = cu.id)
  • Filter: (last_order_state_id = ANY ('{2,4,16,17}'::integer[]))
  • Rows Removed by Filter: 1
  • Heap Blocks: exact=3831
84. 2.262 2.262 ↓ 1.6 8 754

Bitmap Index Scan on orders_customer_id_idx (cost=0.00..4.33 rows=5 width=0) (actual time=0.003..0.003 rows=8 loops=754)

  • Index Cond: (customer_id = cu.id)
85. 17.145 17.145 ↑ 2.0 2 3,429

Index Scan using orders_lines_order_id_idx on orders_lines ol2 (cost=0.42..8.72 rows=4 width=8) (actual time=0.004..0.005 rows=2 loops=3,429)

  • Index Cond: (order_id = od2.id)
86. 111.984 4,236.728 ↑ 1.0 1 18,664

Aggregate (cost=66.03..66.04 rows=1 width=1) (actual time=0.227..0.227 rows=1 loops=18,664)

87. 261.296 4,124.744 ↓ 1.4 7 18,664

HashAggregate (cost=65.92..65.97 rows=5 width=9) (actual time=0.218..0.221 rows=7 loops=18,664)

  • Group Key: fsb.id
88. 477.562 3,863.448 ↓ 2.6 13 18,664

Nested Loop Left Join (cost=0.71..65.89 rows=5 width=9) (actual time=0.048..0.207 rows=13 loops=18,664)

89. 287.313 2,892.920 ↓ 2.6 13 18,664

Nested Loop (cost=0.29..23.64 rows=5 width=8) (actual time=0.043..0.155 rows=13 loops=18,664)

90. 470.665 1,007.856 ↓ 2.0 2 18,664

Nested Loop (cost=0.29..16.67 rows=1 width=8) (actual time=0.029..0.054 rows=2 loops=18,664)

  • Join Filter: (fst.term_id = ftm.id)
  • Rows Removed by Join Filter: 38
91. 93.320 93.320 ↓ 3.0 3 18,664

Index Scan using students_customer_id_idx on students fst (cost=0.29..15.38 rows=1 width=12) (actual time=0.003..0.005 rows=3 loops=18,664)

  • Index Cond: (customer_id = st.customer_id)
  • Filter: (course_id = st.course_id)
  • Rows Removed by Filter: 0
92. 443.871 443.871 ↓ 2.5 15 49,319

Seq Scan on terms ftm (cost=0.00..1.21 rows=6 width=4) (actual time=0.001..0.009 rows=15 loops=49,319)

  • Filter: (enroll_from_date <= tm.enroll_from_date)
  • Rows Removed by Filter: 2
93. 1,597.751 1,597.751 ↓ 1.4 7 37,157

Seq Scan on subjects fsb (cost=0.00..6.93 rows=5 width=8) (actual time=0.010..0.043 rows=7 loops=37,157)

  • Filter: (mandatory_for_fct AND (course_id = st.course_id))
  • Rows Removed by Filter: 307
94. 492.966 492.966 ↓ 0.0 0 246,483

Index Scan using enrolls_student_id_subject_id_key on enrolls fen (cost=0.42..8.44 rows=1 width=13) (actual time=0.002..0.002 rows=0 loops=246,483)

  • Index Cond: ((student_id = fst.id) AND (subject_id = fsb.id))
Planning time : 8.370 ms
Execution time : 56,492.568 ms