explain analyze

foreman=# EXPLAIN ANALYZE SELECT fact_names.* FROM "fact_names" WHERE (fact_names.name <> '_timestamp') ORDER BY fact_names.name;
                                                                           QUERY PLAN
----------------------------------------------------------------------------------------------------------------------------------------------------------------
 Index Scan using index_fact_names_on_name_and_type on fact_names  (cost=0.42..19099.73 rows=352645 width=110) (actual time=0.031..240.294 rows=351772 loops=1)
   Filter: ((name)::text <> '_timestamp'::text)
   Rows Removed by Filter: 3
 Planning time: 0.287 ms
 Execution time: 251.467 ms
(5 rows)

Time: 252.282 ms

log

2018-10-31T14:18:53 [I|app|7d1fc] Processing by TrendsController#new as HTML
2018-10-31T14:18:53 [D|sql|7d1fc]   User Load (0.5ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 9], ["LIMIT", 1]]
2018-10-31T14:18:53 [D|sql|7d1fc]   AuthSource Load (0.5ms)  SELECT  "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
2018-10-31T14:18:53 [I|app|7d1fc] Current user set to XXX (admin)
2018-10-31T14:18:53 [D|app|7d1fc] Cache read: authorize_login_delegation
2018-10-31T14:18:53 [D|app|7d1fc] Cache read: authorize_login_delegation
2018-10-31T14:18:53 [D|app|7d1fc] Cache read: idle_timeout
2018-10-31T14:18:53 [D|app|7d1fc] Cache delete: views/tabs_and_title_records-9
2018-10-31T14:18:53 [D|app|7d1fc] Current location set to none
2018-10-31T14:18:53 [D|sql|7d1fc]   Organization Load (0.6ms)  SELECT  "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Organization') AND "taxonomies"."id" = $1 ORDER BY "taxonomies"."title" ASC LIMIT $2  [["id", 3], ["LIMIT", 1]]
2018-10-31T14:18:53 [D|app|7d1fc] Current organization set to XXX
2018-10-31T14:18:53 [D|sql|7d1fc]    (0.4ms)  SELECT  "taxonomies"."id" FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT $1  [["LIMIT", 1]]
2018-10-31T14:18:53 [D|sql|7d1fc]    (0.4ms)  SELECT  "taxonomies"."id" FROM "taxonomies" WHERE "taxonomies"."type" IN ('Organization') LIMIT $1  [["LIMIT", 1]]
2018-10-31T14:18:53 [I|app|7d1fc]   Rendering trends/new.html.erb within layouts/application
2018-10-31T14:18:53 [D|sql|7d1fc]    (0.5ms)  SELECT "trends"."trendable_type" FROM "trends" WHERE "trends"."type" IN ('ForemanTrend') AND "trends"."fact_value" IS NULL
2018-10-31T14:18:54 [D|sql|7d1fc]   FactName Load (1125.8ms)  SELECT "fact_names".* FROM "fact_names" WHERE (fact_names.name <> '_timestamp') ORDER BY fact_names.name
2018-10-31T14:19:32 [I|app|7d1fc]   Rendered trends/_form.html.erb (39734.6ms)
2018-10-31T14:19:33 [I|app|7d1fc]   Rendered trends/new.html.erb within layouts/application (39869.1ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered layouts/_application_content.html.erb (2533.4ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendering layouts/base.html.erb
2018-10-31T14:19:35 [D|app|7d1fc] Cache read: views/tabs_and_title_records-9 ({:skip_digest=>true})
2018-10-31T14:19:35 [D|sql|7d1fc]   Organization Load (0.7ms)  SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Organization') ORDER BY "taxonomies"."title" ASC
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_organization_dropdown.html.erb (3.6ms)
2018-10-31T14:19:35 [D|sql|7d1fc]   Location Load (0.5ms)  SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') ORDER BY "taxonomies"."title" ASC
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_location_dropdown.html.erb (5.1ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_org_switcher.html.erb (9.7ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_user_dropdown.html.erb (1.4ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_topbar.html.erb (12.1ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (5.7ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (4.0ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (1.2ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (4.4ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (3.2ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (2.5ms)
2018-10-31T14:19:35 [D|app|7d1fc] Cache read: lab_features
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (3.3ms)
2018-10-31T14:19:35 [D|sql|7d1fc]   CACHE Organization Load (0.0ms)  SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Organization') ORDER BY "taxonomies"."title" ASC
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_taxonomies.html.erb (4.3ms)
2018-10-31T14:19:35 [D|sql|7d1fc]   CACHE Location Load (0.0ms)  SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') ORDER BY "taxonomies"."title" ASC
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_taxonomies.html.erb (8.4ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_vertical_menu.html.erb (1.3ms)
2018-10-31T14:19:35 [I|app|7d1fc]   Rendered home/_navbar.html.erb (45.6ms)
2018-10-31T14:19:35 [D|app|7d1fc] Cache write: views/tabs_and_title_records-9 ({:skip_digest=>true})
2018-10-31T14:19:36 [I|app|7d1fc]   Rendered layouts/base.html.erb (175.2ms)
2018-10-31T14:19:36 [I|app|7d1fc] Completed 200 OK in 43046ms (Views: 41899.8ms | ActiveRecord: 1129.9ms