From: Emmanuel C. <ma...@fr...> - 2009-06-02 12:49:42
|
> Master Hello young padawan :-) > I manage to put the SQL Monitoring, and now my surprise is even great... The delays you see at MySQL can come from Sequoia or your application. > Look this a trace in MySQL : > select user0_.ID as ID19_0_, user0_.SPROLE_FK as SPROLE2_19_0_, > user0_.IAPROLE_FK as IAPROLE3_19_0_, user0_.COROLE_FK as > COROLE4_19_0_, user0_.NAME as NAME19_0_, user0_.PASSWORD as > PASSWORD19_0_, user0_.ROLETYPE as ROLETYPE19_0_, user0_.USER_FK as > USER8_19_0_, user0_.EMAIL as EMAIL19_0_, user0_.STATUS as STATUS19_0_, > user0_.TRUSTED as TRUSTED19_0_, user0_.VALIDATIONUSERID as > VALIDAT12_19_0_, user0_.CMSCO as CMSCO19_0_ from cdpdb.user user0_ > where user0_.ID=21 Jun 2, 2009 10:19:17.149134000 > Jun 2, 2009 10:19:17.149185000 > select roleiap0_.ID as ID16_0_, roleiap0_.USER_FK as USER2_16_0_, > roleiap0_.NAME as NAME16_0_ from cdpdb.roleiap roleiap0_ where > roleiap0_.ID=1 Jun 2, 2009 10:19:17.149605000 > select itvplat0_.ID as ID9_0_, itvplat0_.NAME as NAME9_0_, > itvplat0_.BROADCAST as BROADCAST9_0_, itvplat0_.AUTHCLASS as > AUTHCLASS9_0_, itvplat0_.PUBLISHER as PUBLISHER9_0_ from cdpdb.itvplat > itvplat0_ where itvplat0_.ID=4 Jun 2, 2009 10:19:17.149856000 > Jun 2, 2009 10:19:17.149876000 > Jun 2, 2009 10:19:17.149881000 > Jun 2, 2009 10:19:17.167730000 > Jun 2, 2009 10:19:17.187738000 > Jun 2, 2009 10:19:17.188534000 > Jun 2, 2009 10:19:17.188737000 > Jun 2, 2009 10:19:17.188743000 > Jun 2, 2009 10:19:17.189901000 > Jun 2, 2009 10:19:17.189905000 > select itvplat0_.ID as ID9_0_, itvplat0_.NAME as NAME9_0_, > itvplat0_.BROADCAST as BROADCAST9_0_, itvplat0_.AUTHCLASS as > AUTHCLASS9_0_, itvplat0_.PUBLISHER as PUBLISHER9_0_ from cdpdb.itvplat > itvplat0_ where itvplat0_.ID=4 Jun 2, 2009 10:19:18.030538000 > Jun 2, 2009 10:19:18.030604000 > Jun 2, 2009 10:19:18.030620000 > select itvplat0_.ID as ID9_0_, itvplat0_.NAME as NAME9_0_, > itvplat0_.BROADCAST as BROADCAST9_0_, itvplat0_.AUTHCLASS as > AUTHCLASS9_0_, itvplat0_.PUBLISHER as PUBLISHER9_0_ from cdpdb.itvplat > itvplat0_ where itvplat0_.ID=4 Jun 2, 2009 10:19:18.030825000 > > Which it looks that between 10:19:17.149856000 and 10:19:18.030538000 > we have a gap that no selects are executed.... ( remember that > LoadTests are beeing executed so it is impossible this), but when I > try to see the results of SQLMonitoring in Sequoia we don´t have any > "Max time (ms):" bigger that 50, so... It might well be that the problem comes from the application. Can you try to add some monitoring code in your application to output the query execution time you see in the application? Something like: start = System.currentTimeInMillis(); s.execute(...); end = System.currentTimeInMillis(); print(end-start); > So, it seems that we have any kind of problem related to transmission > or queue or I don´t no what.... > > Do you know is there is any chance to monitor traffic between > hibernate ( JBOSS) and the input of sequoia, I think that it is in > port 25322 ( controller ? ). You can turn the Sequoia driver in DEBUG mode if you append the option debugLevel=debug to the JDBC URL you give to the Sequoia driver. You can also set the VirtualDatabaseWorkerThread logger to DEBUG in log4j.properties to log all the commands issued by the driver with their timestamp. > Anyway, now I am going to check Jconsole ! Ok, keep me posted with your progress. Emmanuel -- Emmanuel Cecchet FTO @ Frog Thinker Open Source Development & Consulting -- Web: http://www.frogthinker.org email: ma...@fr... Skype: emmanuel_cecchet |