# Reading log summary line from log file **URL:** \ **Category:** Rails\ **Created:** [March 17, 2009, 7:10am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080 "2009-03-17T07:10:24Z")\ **Posts on this page:** 7\ **Page:** 1
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 7:10am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/1 "2009-03-17T07:10:24Z")
I am trying to understand the last line from an action log in development mode. I have the following line and understand the view and DB as the length of time it takes to process the view and the db access. But what is the rest of the time? Completed in 923ms (View: 220, DB: 45) | 200 OK [[http://localhost:3000/](http://localhost:3000/) properties/2-hale-kamaole] I get 265 ms but the total is 923ms. a long time. ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 10:05am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/2 "2009-03-17T10:05:17Z")
Completed in 923ms (View: 220, DB: 45) | 200 OK 923 is total time it took since the request arrived untill it was cmpletely served. 220 is how long it took to process the view 45 is the time the db took 200 is not a time is the HTTP header (200 means its all good to go, 404 means its not … etc) ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 10:07am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/3 "2009-03-17T10:07:38Z")
So sory! i didnt read that last line! Didnt mean to be so obvious. there are more factors to completing a request than view and db (such as watiting time). and if you do the same test on a heavily loaded machine, it would take longer. ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 10:14am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/4 "2009-03-17T10:14:05Z")
On Mon, Mar 16, 2009 at 11:09 PM, Don F. [removed\_email\_address@domain.invalid](mailto:removed_email_address@domain.invalid) wrote: > You try running that request several times using ‘ab’ on your localhost and see how that number compares to the measurement above. -Conrad ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 10:24am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/5 "2009-03-17T10:24:40Z")
On Mar 17, 6:09 am, Don F. [removed\_email\_address@domain.invalid](mailto:removed_email_address@domain.invalid) wrote: > I am trying to understand the last line from an action log in > development mode. I have the following line and understand the view > and DB as the length of time it takes to process the view and the db > access. But what is the rest of the time? > > Completed in 923ms (View: 220, DB: 45) | 200 OK [[http://localhost:3000/](http://localhost:3000/) > properties/2-hale-kamaole] > > I get 265 ms but the total is 923ms. a long time. Controller code, model code that didn’t hit the database etc… In development there’s also all the reloading of your source code. Fred ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 12:10pm UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/6 "2009-03-17T12:10:25Z")
Without exact code posted it is hard to know why your setup is slow. I do agree hoever tht your numbers seem higher than “usual”. ---
**Author:** ![Don\_F](https://www.ruby-forum.com/letter_avatar_proxy/v4/letter/d/f04885/32.png) [@Don\_F](https://www.ruby-forum.com/u/Don_F)\ **Post date:** [March 17, 2009, 10:27am UTC](https://www.ruby-forum.com/t/reading-log-summary-line-from-log-file/163080/7 "2009-03-17T10:27:36Z")
Waiting time? What waiting time? I have run the test many times and most are in this range. I am trying to figure out why it is taking so long to complete. The current time is unacceptable. On Mar 16, 11:06 pm, “"Wolas!"” [removed\_email\_address@domain.invalid](mailto:removed_email_address@domain.invalid) wrote: > So sory! i didnt read that last line! Didnt mean to be so obvious. > > there are more factors to completing a request than view and db (such > as watiting time). and if you do the same test on a heavily loaded > machine, it would take longer.