
dm-rails: Logger displays incorrect queries time
Reported by kgiszczak | May 29th, 2010 @ 04:12 AM | in 1.0.0
Logger displays 0.0ms for every query.
Here is sample log:
Started GET "/" for 127.0.0.1 at 2010-05-29 11:03:55
Processing by PostsController#index as HTML
SQL (0.0ms) SET backslash_quote = off
SQL (0.0ms) SET standard_conforming_strings = on
SQL (0.0ms) SET client_min_messages = warning
SQL (0.0ms) SELECT "id", "name" FROM "posts" ORDER BY "id"
Rendered text template (0.0ms)
Completed 200 OK in 11ms (Views: 1.8ms | Models: 8.7ms)
This log comes from postgres adapter, but I tested also with mysql database and the result is the same.
Comments and changes to this ticket
-
Dan Kubb (dkubb) May 29th, 2010 @ 10:56 PM
- Milestone set to 1.0.0
- State changed from new to unconfirmed
- Assigned user set to Martin Gamsjaeger (snusnu)
-
Martin Gamsjaeger (snusnu) June 3rd, 2010 @ 09:13 AM
- State changed from unconfirmed to resolved
I can't reproduce that behavior with current codes. Have a look at my sample session at: http://pastie.org/990513
Marking this resolved.
-
kgiszczak June 3rd, 2010 @ 10:04 AM
Actually you reproduced this behavior :)
Just check queries time. Everywhere is:
SQL (0.0ms) -
Martin Gamsjaeger (snusnu) June 3rd, 2010 @ 10:55 AM
- State changed from resolved to confirmed
Haha! Of course you're right, but I have an "excuse" :P For some reason, I was always only looking at the text that said:
Rendered text template (0.0ms)
For whatever reason :) Your problem description was very exact and to the point, my brain was the problem :)
-
Dan Kubb (dkubb) June 4th, 2010 @ 10:54 AM
- State changed from confirmed to resolved
(from [7768b05014660d22ce03bec90c8ce87a66e02c7b]) Provide proper SQL execution time information in logs
The current implementation relies on a monkeypatch
because the current upstream implementation doesn't
respect a possibly already present duration info.TODO: Look into providing a proper patch for
active_support/notifications so that we don't need
to keep on monkeypatching forever.[#1296 state:resolved] http://github.com/datamapper/dm-rails/commit/7768b05014660d22ce03be...
Please Sign in or create a free account to add a new ticket.
With your very own profile, you can contribute to projects, track your activity, watch tickets, receive and update tickets through your email and much more.
Create your profile
Help contribute to this project by taking a few moments to create your personal profile. Create your profile »
People watching this ticket
Tags
Referenced by
-
1296 dm-rails: Logger displays incorrect queries time [#1296 state:resolved] http://github.com/datamapper/dm-r...