You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user-java@ibatis.apache.org by Gilles Schlienger <s_...@yahoo.com> on 2008/08/11 16:31:54 UTC

Strange behaviour with datasource ?

Hi everyone,
I am using iBATIS in my web application with the following versions:
- Spring 2.5.4
- iBATIS 2.3.0.677
- MySQL 5.0
- Tomcat 5.5
- JDK 1.5
I use a DBCP datasource version 1.2.1 (commons-dbcp)

In my search engine, if the search query is longer than a certain time (it seems to be 5 seconds?), then after receiving the results from the database (I can see the debug logs from the result set) the application hangs for 25 to 30 seconds!!

But when the query is fast (1 second), then I don't see anything wrong ! (everything is fast)

When I put the DEBUG level in log4j, I can see the following logs (see below)

I am not sure where it comes from, the last log I see before gettiing the results back in my code is :
<Returning JDBC Connection to DataSource>

If someone had any idea, that would really be nice.
Thanks a lot in advance
Regards
Gilles

I use the Spring template client integration mechanism with the foloowing config in my iBATIS configuration file:
    <settings cacheModelsEnabled="true" enhancementEnabled="true"
        lazyLoadingEnabled="false" maxRequests="128" maxSessions="10"
        maxTransactions="5" useStatementNamespaces="true"
        statementCachingEnabled="true"
        classInfoCacheEnabled="true" />


2008-08-11 16:26:12,929 DEBUG [java.sql.PreparedStatement] - <{pstm-100079} Executing Statement:   select STRAIGHT_JOIN distinct MDT_ID, MDH_ID, MDH_EC_NOM, MDH_MODIF_DATE,MDH_FK_CRCC_DESTINATAIRE_CRG_ID, IFNULL(CONCAT(TIT_PP.USR_NOM,' ', TIT_PP.USR_PRENOM), TIT_PM.CBT_DENOMINATION) AS TITULAIRE, IFNULL(CONCAT(SUP_PP.USR_NOM,' ', SUP_PP.USR_PRENOM), SUP_PM.CBT_DENOMINATION) AS SUPPLEANT, IFNULL(CONCAT(SIGN.USR_NOM,' ', SIGN.USR_PRENOM), NULL) MEMBRE_SIGNATAIRE, TIT_PP.USR_ID AS TIT_PP_ID, SIGN.USR_ID AS SIGN_ID, MDH_FK_CRCC_SIGNATAIRE_CRG_ID AS SIGN_CRG_ID, TIT_PM.CBT_ID AS TIT_PM_ID from MANDAT_HEADER H left join CABINET TIT_PM on H.MDH_FK_TITULAIRE_PM_CBT_ID = TIT_PM.CBT_ID left join UTILISATEUR TIT_PP on TIT_PP.USR_ID = H.MDH_FK_TITULAIRE_PP_USR_ID left join CABINET SUP_PM on H.MDH_FK_SUPPLEANT_PM_CBT_ID = SUP_PM.CBT_ID left join UTILISATEUR SUP_PP on SUP_PP.USR_ID = H.MDH_FK_SUPPLEANT_PP_USR_ID left join UTILISATEUR SIGN on H.MDH_FK_SIGNATAIRE_USR_ID
 = SIGN.USR_ID    ,MANDAT_PARENT    where MDH_FK_MANDAT_MDT_ID = MDT_ID     AND (( MDT_FK_ETAT_GLOBAL_MDE_ID = ? ) )   AND MDH_FK_CAMPAGNE_CPG_CODE = ?                                    ORDER BY MDT_ID        ASC     LIMIT  ?  ,?     >
2008-08-11 16:26:12,929 DEBUG [java.sql.PreparedStatement] - <{pstm-100079} Parameters: [0, 2008, 200, 20]>
2008-08-11 16:26:12,929 DEBUG [java.sql.PreparedStatement] - <{pstm-100079} Types: [java.lang.Integer, java.lang.Integer, java.lang.Integer, java.lang.Integer]>
2008-08-11 16:26:16,601 DEBUG [org.apache.catalina.session.ManagerBase] - <Start expire sessions StandardManager at 1218464776601 sessioncount 1>
2008-08-11 16:26:16,601 DEBUG [org.apache.catalina.session.ManagerBase] - <End expire sessions StandardManager processingTime 0 expired sessions: 0>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} ResultSet>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Header: [MDT_ID, MDH_ID, MDH_EC_NOM, MDH_MODIF_DATE, MDH_FK_CRCC_DESTINATAIRE_CRG_ID, TITULAIRE, SUPPLEANT, MEMBRE_SIGNATAIRE, TIT_PP_ID, SIGN_ID, SIGN_CRG_ID, TIT_PM_ID]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001860, 37120, LATREUILLE ETS, 2008-06-25 18:26:51.0, 3, CABINET RAMPNOUX & ASSOCIES SA, DURAND Philippe, BISTUER Frédéric, 0, 3000009, 3, 3090004]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001861, 37118, VILQUIN ETS, 2008-06-25 18:26:51.0, 3, CABINET RAMPNOUX & ASSOCIES SA, BERSANS Alain, BISTUER Frédéric, 0, 3000009, 3, 3090004]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001862, 37115, MIDATEST, 2008-06-25 18:26:51.0, 3, CABINET RAMPNOUX & ASSOCIES SA, BISTUER Frédéric, BISTUER Frédéric, 0, 3000009, 3, 3090004]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001864, 37112, SUPRANORD, 2008-06-25 18:26:51.0, 3, CABINET GOUL SAS, FABRE Didier, MANNECHEZ Roger, 0, 3000122, 3, 3090001]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001886, 37087, CLAUNI, 2008-06-25 18:26:51.0, 3, MAZAT Jean-Noël, SP COMMISSARIAT AUX COMPTES  SARL, null, 3000036, 0, 0, 0]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001892, 37076, HORTI INVESTISSEMENTS, 2008-06-25 18:26:51.0, 3, CABINET BAROUH SA, SILVA Philippe, FREZALS Serge, 0, 3253830, 3, 3090023]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001896, 37070, LESCOS, 2008-06-25 18:26:51.0, 3, CABINET BAROUH SA, MILLARD Claude, BONPUNT Marianne, 0, 3000096, 3, 3090023]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001898, 37067, PRIMEURS DU SUD-OUEST, 2008-06-25 18:26:51.0, 3, ASTOUX CRESTIA & ASSOCIES  SA, VERDES Michel, ASTOUX Eric, 0, 3000002, 3, 3090018]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001903, 37056, CIE PRODUITS GASCONS, 2008-06-25 18:26:51.0, 3, DRAPIER Bernard, DUCASSE Gilbert, null, 3000059, 0, 0, 0]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001904, 37053, METAL, 2008-06-25 18:26:51.0, 3, DRAPIER Bernard, ACQS EXPERTISE CONSEIL SARL, null, 3000059, 0, 0, 0]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001911, 37043, C.I.B.C. 47, 2008-06-25 18:26:51.0, 3, CABINET HUSSON SA, PANOUILLERES Patrick, DRAPE Pierre, 0, 3000019, 3, 3090002]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001913, 37039, SUD MANAGEMENT ENTREPRISE, 2008-06-25 18:26:51.0, 3, CABINET HUSSON SA, JCB AUDITEURS CONSEILS ASSOCIES, PANOUILLERES Patrick, 0, 3000093, 3, 3090002]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001914, 37038, SA GARRIGUES, 2008-06-25 18:26:51.0, 3, CABINET HUSSON SA, CABINET GOUL SAS, PANOUILLERES Patrick, 0, 3000093, 3, 3090002]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001917, 37034, TAQUIPNEU, 2008-06-25 18:26:51.0, 3, CABINET HUSSON SA, null, DRAPE Pierre, 0, 3000019, 3, 3090002]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001919, 37031, BRUNO VOLPATO SA, 2008-06-25 18:26:51.0, 3, LOUIT Dominique, BOURDIL Claude, null, 3000080, 0, 0, 0]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001923, 37027, PARNASSE PATRIMOINE, 2008-06-25 18:26:51.0, 3, KPMG SA, BOROTRA Xavier, FEIGNA Bernard, 0, 3000021, 3, 90090019]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001931, 37019, CENTRALE HYDROELECTRIQUE, 2008-06-25 18:26:51.0, 3, DRAPE Madeleine, GOULINAT Jean-Philippe, null, 3000018, 0, 0, 0]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001936, 37011, COLLONGUES ET FILS, 2008-06-25 18:26:51.0, 3, A.C.E. SA, DRAPIER Bernard, CASSAGNE Jacques, 0, 3000056, 3, 3090007]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001939, 37006, JEAN CUGINI, 2008-06-25 18:26:51.0, 3, A.C.E. SA, DRAPE Madeleine, CASSAGNE Jacques, 0, 3000056, 3, 3090007]>
2008-08-11 16:26:20,898 DEBUG [java.sql.ResultSet] - <{rset-100080} Result: [3001943, 37001, SICA APPROVISIONNEMENT RURAL, 2008-06-25 18:26:51.0, 3, A.C.E. SA, CASSAGNE Jacques, GOULINAT Jean-Philippe, 0, 3000027, 3, 3090007]>
2008-08-11 16:26:20,898 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Returning JDBC Connection to DataSource>
2008-08-11 16:26:47,148 DEBUG [fr.cncc.gestionmandats.service.impl.GestionMandatsServiceImpl] - <rechercherMandats returning : 20 elements>


      _____________________________________________________________________________ 
Envoyez avec Yahoo! Mail. Une boite mail plus intelligente http://mail.yahoo.fr