I'm also seeing this double logging behavior (using PostgreSQL. I was
under the impression that it was happening because the SQL statement
is first prepared, and then executed (each of which cause a log
entry). It's not critical or anything, but it would be nice if this
could be fixed at some point.
-harryh
On Oct 13, 10:28 am, Derek Chen-Becker <dchenbec...@gmail.com> wrote:
> I'm not sure how that could be getting logged twice, especially since it's
> the exact same ResultSet being returned. When I tested this on my local app
> (against both PG and MySQL) I didn't get this behavior, but I'll try pulling
> from master and testing again just in case something changed.
>
> Derek
>
> On Tue, Oct 13, 2009 at 6:56 AM, Jeppe Nejsum Madsen <je...@ingolfs.dk>wrote:
>
>
>
> > Hi,
>
> > Just wanted to update to the new db logging by adding the following to
> > boot:
>
> > DB.addLogFunc {
> > case (query:DBLog, time) => {
> > LogBoot.loggerByName("query").info(">>> All queries took " + time +
> > "ms: ")
> > query.statementEntries.foreach({ case DBLogEntry(stmt, duration) =>
> > LogBoot.loggerByName("query").info(" "+stmt + " took " + duration +
> > "ms")})
> > LogBoot.loggerByName("query").info("<<< End queries")
> > }
> > }
>
> > But it seems all executed statements are logged twice. I have this
> > snippet:
>
> > def currentUser(xhtml: Group): NodeSeq =
> > Text(User.currentUser.dmap(S.?("Anonym"))(user => user.firstName + " " +
> > user.lastName))
>
> > This logs:
>
> > 14:46:09.068 [tp-1029120287-4] INFO query -
> > >>> All queries took 5ms:
> > 14:46:09.068 [tp-1029120287-4] INFO query -
> > Exec query "SELECT users.id, users.firstname, users.lastname,
> > users.email, users.locale, users.timezone, users.password_pw,
> > users.password_slt, users.account_id, users.superuser, users.uniqueid,
> > users.validated FROM users WHERE id = 2 (scale -5)" :
> > org.postgresql.jdbc4.jdbc4result...@77f31432 took 4ms
> > 14:46:09.069 [tp-1029120287-4] INFO query -
> > <<< End queries
> > 14:46:09.069 [tp-1029120287-4] INFO query -
> > >>> All queries took 8ms:
> > 14:46:09.069 [tp-1029120287-4] INFO query -
> > Exec query "SELECT users.id, users.firstname, users.lastname,
> > users.email, users.locale, users.timezone, users.password_pw,
> > users.password_slt, users.account_id, users.superuser, users.uniqueid,
> > users.validated FROM users WHERE id = 2 (scale -5)" :
> > org.postgresql.jdbc4.jdbc4result...@77f31432 took 4ms
> > 14:46:09.069 [tp-1029120287-4] INFO query
> > - <<< End queries
>
> > Note the same resultset. The postgres logs also shows that only a single
> > statement is executed....
>
> > So, what did I miss?
>
> > /Jeppe
--~--~---------~--~----~------------~-------~--~----~
You received this message because you are subscribed to the Google Groups
"Lift" group.
To post to this group, send email to liftweb@googlegroups.com
To unsubscribe from this group, send email to
liftweb+unsubscr...@googlegroups.com
For more options, visit this group at
http://groups.google.com/group/liftweb?hl=en
-~----------~----~----~----~------~----~------~--~---
