SQLite Performance problem

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 9:14 am

Hi
I have a big problem with sqlite performace (I have this problem with ms access too).
When i run my application with vs 2003 ide (in debug or release mode) it took much time to permorm a query.
For example, it took 45 seconds (yes! 45 seconds!!!) to retrive 38 objects and when i run directly the exe file (or run tests with testdriven using "test with debugger"), it took less than 1 second.

I don't know what to do!!
Thanks!!

gert · **Joined:** Thu Jan 19, 2006 4:29 pm **Posts:** 348

DamnGoodCoffee wrote:

Hi
I have a big problem with sqlite performace (I have this problem with ms access too).
When i run my application with vs 2003 ide (in debug or release mode) it took much time to permorm a query.
For example, it took 45 seconds (yes! 45 seconds!!!) to retrive 38 objects and when i run directly the exe file (or run tests with testdriven using "test with debugger"), it took less than 1 second.

I don't know what to do!!
Thanks!!

Check the "output" window of Visual Studio. Maybe the NHiberrnate is sending exessive amount of log messages to console or debug output..

Gert

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 9:56 am

Quote:

Check the "output" window of Visual Studio. Maybe the NHiberrnate is sending exessive amount of log messages to console or debug output..

there are much message to debug output, maybe that is the problem. How can I fix that? my log4net configuration is

<log4net>
<appender name="ConsoleAppender" type="log4net.Appender.ConsoleAppender">
<layout type="log4net.Layout.PatternLayout">
<param name="ConversionPattern" value="%d %-5p %c{2} %m%n" />
</layout>
</appender>
<appender name="rollingFile" type="log4net.Appender.RollingFileAppender, log4net" >
<param name="File" value="Explora_Log.txt" />
<param name="AppendToFile" value="true" />
<param name="RollingStyle" value="Date" />
<param name="DatePattern" value=" yyyy.MM.dd" />
<param name="StaticLogFileName" value="true" />
<layout type="log4net.Layout.PatternLayout, log4net">
<param name="ConversionPattern" value="%d %-5p %c{2} %m%n" />
</layout>
</appender>
<logger>
<level value="WARN" />
<appender-ref ref="FileAppender" />
</logger>
<root>
<level value="INFO" />
<appender-ref ref="ConsoleAppender" />
</root>
</log4net>

Thanks again!

gert · **Joined:** Thu Jan 19, 2006 4:29 pm **Posts:** 348

DamnGoodCoffee wrote:

This tells that every possible message logged should be sent to console. Try something more restrictive than "INFO"

Gert

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 10:08 am

i watched the degug window and i realize that it's sending each message 14 times! now, i config log4net yo log error messages (level value="error") and still the same

For example:

222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac
222875 [2456] DEBUG NHibernate.Loader.Loader (null) - result row: 1e76662d3c7042c0b72d83a38f008fac

gert · **Joined:** Thu Jan 19, 2006 4:29 pm **Posts:** 348

DamnGoodCoffee wrote:

i watched the degug window and i realize that it's sending each message 14 times! now, i config log4net yo log error messages (level value="error") and still the same

I haven't done much configuration of log4net, so i can't says what's wrong here... Maybe someone else can help.

But what about NHibernates show_sql. Is it true or false?

Gert

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 10:35 am

mhhhh
I been reading some posts about performance and it seems like someone had the same problem and solved it

Quote:

Copy the log4net configuration in "nhibernate/src/NHibernate.Examples/App.config"
(replace the line "<priority value="ALL" />" by "<priority value="WARN" />")

You don't need to call "DOMConfigurator.Configure()" if you don't use Log4Net (NHibernate call it internally)

but i can't find nhibernate/src/NHibernate.Examples/App.config

about show_sql property, it's false

Code:

cfg.SetProperty( "hibernate.show_sql", "false" );

it's right?

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 10:35 am

mhhhh
I been reading some posts about performance and it seems like someone had the same problem and solved it

Quote:

Copy the log4net configuration in "nhibernate/src/NHibernate.Examples/App.config"
(replace the line "<priority value="ALL" />" by "<priority value="WARN" />")

You don't need to call "DOMConfigurator.Configure()" if you don't use Log4Net (NHibernate call it internally)

but i can't find nhibernate/src/NHibernate.Examples/App.config

about show_sql property, it's false

Code:

cfg.SetProperty( "hibernate.show_sql", "false" );

it's right?

but still shows me sql statements in the debug window

DamnGoodCoffee · **Posted:** Mon Jun 12, 2006 1:29 pm

:D
i solved the problem. It was configuring log4net several times (each factory configurates it) and it was logging the same several times
Thanks a lot for the support!!
you are great!