Understanding the production log.

Added by Tom Shaw over 1 year ago


I have been noticing our instance of redmine 2.6.6 started running slow after several years of glorious running.

On inspecting the log file I can see that there are line which start with the word "Completed 302 Found" which often have large amounts of time associated with them.

Can somebody tell my what the line is representing and if known why it could be taking so long to complete? I understand HTTP 302 found just dont know why the application is doing this and why it is taking so long when the DB seems to be fine.

I have turned on the slow query log in MYSQL with a timer of 10s and there not much going in there at all.

Started GET "/redmine/projects/errors/issues" for at 2017-05-09 09:31:26 +0100
Processing by IssuesController#index as HTML
  Parameters: {"project_id"=>"errors"}
  Current user: carrg (id=218)
  Rendered queries/_filters.html.erb (48.4ms)
  Rendered queries/_columns.html.erb (1.9ms)
  Rendered issues/_list.html.erb (154.6ms)
  Rendered issues/_sidebar.html.erb (6.3ms)
  Rendered issues/index.html.erb within layouts/base (226.3ms)
  Rendered plugins/clipboard_image_paste/app/views/clipboard_image_paste/_headers.html.erb (1.5ms)
  Rendered plugins/clipboard_image_paste/app/views/clipboard_image_paste/_add_form.html.erb (2.8ms)
Completed 200 OK in 511.6ms (Views: 243.6ms | ActiveRecord: 87.2ms)
Redirected to http://app01/redmine/issues/91800
Completed 302 Found in 29031.8ms (ActiveRecord: 94.7ms)