Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Setting transactionIsolation level in HikariCP configuration #135

Closed
PriyaSodexo opened this issue Aug 21, 2014 · 12 comments
Closed

Setting transactionIsolation level in HikariCP configuration #135

PriyaSodexo opened this issue Aug 21, 2014 · 12 comments
Assignees
Labels

Comments

@PriyaSodexo
Copy link

Hello,
I have the following configuration

<property name="idleTimeout" value = "3600000" />
<property name="maximumPoolSize" value = "10" />
<property name="connectionTestQuery" value="SELECT 1 from dual" />
<property name="maxLifetime" value="1800000" />
<property name="transactionIsolation" value="TRANSACTION_READ_COMMITTED" />

for which I get the exception given below:

Initialization of bean failed; nested exception is org.springframework.beans.TypeMismatchException: Failed to convert property value of type 'java.lang.String' to required type 'int' for property 'transactionIsolation'; nested exception is java.lang.NumberFormatException: For input string: "TRANSACTION_READ_COMMITTED"

so i tried this:

 <property name="transactionIsolation" value="2" />

And this still throws an exception as shown below
Property 'transactionIsolation' threw exception; nested exception is java.lang.IllegalArgumentException: argument type mismatch

Can someone pls help. Thanks in advance, Priya

@brettwooldridge
Copy link
Owner

Ah, this one is tricky. This issue is caused by the signatures of the HikariConfig class:

public int getTransactionIsolation() {
   return transactionIsolation;
}

public void setTransactionIsolation(String isolationLevel) {
   this.transactionIsolationName = isolationLevel;
}

These methods do not follow typical bean signatures, where getX() returns the same type as the parameter to setX(). I didn't realize this could confuse a bean setter like Spring.

I'll fix the code, but in the meantime can you try forcing the type, like so?

<property name="transactionIsolation">
   <value="TRANSACTION_READ_COMMITTED" type="java.lang.String"/>
</property>

@brettwooldridge
Copy link
Owner

A fix has been committed. You can build and test it if you like, or use the "force" method above. The fix will appear in the next release of HikariCP (2.1.0).

@PriyaSodexo
Copy link
Author

Thank you Brett,

But unfortunately, type forcing does not work. You say "A fix has been committed. You can build and test it if you like" and then "The fix will appear in the next release of HikariCP (2.1.0)."; what does this actually mean, I am using 2.0.1 - is the fix available here or I shd wait for 2.1.0 release and when is this release available.

Thanks and regards,
Priya

@brettwooldridge
Copy link
Owner

The next 2.1.0 release may be available next week. If you want to use it before that, you can build it like this:

git clone https://github.com/brettwooldridge/HikariCP.git
cd HikariCP/hikaricp-java6
mvn install

You can then use HikariCP-java6 version 2.1.0-SNAPSHOT as a maven dependency, or use the HikariCP-java6-2.1.0-SNAPSHOT.jar that is created in the target directory.

if you want to continue using 2.0.1, as a further workaround you can put the pool properties in a separate properties file and use Spring to construct a HikariConfig using that file, and a HikariDataSource bean using the HikariConfig.

Incidentally, TRANSACTION_READ_COMMITTED is the default for almost every database I am aware of, so you probably do not even need to initialize this property. HikariCP will read the default automatically from the JDBC driver.

@PriyaSodexo
Copy link
Author

Thanks Brett,

I am actually in the middle of trying out HikariCp for an application that is currently using DBCP. The transaction related issue that I had for which I wanted to set transactionIsolation level on HikariCP got resolved by setting autoCommit to true.

Currently I am running clean install and trying to get it successfully done with all the testcases passing. But what I see now is a lot of time out exceptions as shown below,

[JdbcServicesImpl| WARN|14:47:21,619] HHH000342: Could not obtain connection to query metadata : Timeout of 30000ms encountered waiting for connection. - (logged at Thread(main):org.hibernate.engine.jdbc.internal.JdbcServicesImpl:169)
[SchemaValidator|ERROR|14:47:51,641] HHH000319: Could not get database metadata - (logged at Thread(main):org.hibernate.tool.hbm2ddl.SchemaValidator:151)
java.sql.SQLException: Timeout of 30000ms encountered waiting for connection.
at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:207)
at com.zaxxer.hikari.HikariDataSource.getConnection(HikariDataSource.java:93)
at net.sxpbelux.mirage.database.datasource.TraceableDataSource.getConnection(TraceableDataSource.java:16)
at org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider.getConnection(InjectedDataSourceConnectionProvider.java:70)
at org.hibernate.tool.hbm2ddl.SuppliedConnectionProviderConnectionHelper.prepare(SuppliedConnectionProviderConnectionHelper.java:51)
at org.hibernate.tool.hbm2ddl.SchemaValidator.validate(SchemaValidator.java:146)
at org.hibernate.internal.SessionFactoryImpl.(SessionFactoryImpl.java:511)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1797)
at org.hibernate.ejb.EntityManagerFactoryImpl.(EntityManagerFactoryImpl.java:96)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:914)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:899)
at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:76)
at org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.createNativeEntityManagerFactory(LocalContainerEntityManagerFactoryBean.java:288)
at org.springframework.orm.jpa.AbstractEntityManagerFactoryBean.afterPropertiesSet(AbstractEntityManagerFactoryBean.java:310)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1571)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1509)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:521)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:458)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:296)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1117)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:922)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:479)
at org.springframework.test.context.support.AbstractGenericContextLoader.loadContext(AbstractGenericContextLoader.java:120)
at org.springframework.test.context.support.AbstractGenericContextLoader.loadContext(AbstractGenericContextLoader.java:60)
at org.springframework.test.context.support.AbstractDelegatingSmartContextLoader.delegateLoading(AbstractDelegatingSmartContextLoader.java:100)
at org.springframework.test.context.support.AbstractDelegatingSmartContextLoader.loadContext(AbstractDelegatingSmartContextLoader.java:248)
at org.springframework.test.context.CacheAwareContextLoaderDelegate.loadContextInternal(CacheAwareContextLoaderDelegate.java:64)
at org.springframework.test.context.CacheAwareContextLoaderDelegate.loadContext(CacheAwareContextLoaderDelegate.java:91)
at org.springframework.test.context.TestContext.getApplicationContext(TestContext.java:122)
at net.sxpbelux.mirage.junit.ReconfigureAnnotationBeanConfigurerAspectTestExecutionListener.prepareTestInstance(ReconfigureAnnotationBeanConfigurerAspectTestExecutionListener.java:15)
at org.springframework.test.context.TestContextManager.prepareTestInstance(TestContextManager.java:312)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.createTest(SpringJUnit4ClassRunner.java:211)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner$1.runReflectiveCall(SpringJUnit4ClassRunner.java:288)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.methodBlock(SpringJUnit4ClassRunner.java:284)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:231)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:88)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:71)
at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:174)
at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:62)
at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:140)
at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:127)
at org.apache.maven.surefire.Surefire.run(Surefire.java:177)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:345)
at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:1009)
Caused by: java.sql.SQLException: Listener refused the connection with the following error:
ORA-12519, TNS:no appropriate service handler found

at oracle.jdbc.driver.T4CConnection.logon(T4CConnection.java:458)
at oracle.jdbc.driver.PhysicalConnection.<init>(PhysicalConnection.java:546)
at oracle.jdbc.driver.T4CConnection.<init>(T4CConnection.java:236)
at oracle.jdbc.driver.T4CDriverExtension.getConnection(T4CDriverExtension.java:32)
at oracle.jdbc.driver.OracleDriver.connect(OracleDriver.java:521)
at oracle.jdbc.pool.OracleDataSource.getPhysicalConnection(OracleDataSource.java:280)
at oracle.jdbc.pool.OracleDataSource.getConnection(OracleDataSource.java:207)
at oracle.jdbc.pool.OracleDataSource.getConnection(OracleDataSource.java:157)
at com.zaxxer.hikari.pool.HikariPool.addConnection(HikariPool.java:399)
at com.zaxxer.hikari.pool.HikariPool.access$400(HikariPool.java:64)
at com.zaxxer.hikari.pool.HikariPool$1AddConnection.run(HikariPool.java:316)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)

I also see a lot of these
[SqlExceptionHelper| WARN|14:05:50,806] SQL Error: 1, SQLState: 23000 - (logged at Thread(main):org.hibernate.engine.jdbc.spi.SqlExceptionHelper:145)
[SqlExceptionHelper|ERROR|14:05:50,807] ORA-00001: unique constraint (PHOENIX_DEV.SYS_C00274581_CUST) violated

  • (logged at Thread(main):org.hibernate.engine.jdbc.spi.SqlExceptionHelper:147)
    [ConnectionProxy| WARN|14:05:50,809] Connection oracle.jdbc.driver.T4CConnection@3004460d (HikariPool-1) marked as broken because of SQLSTATE(08003), ErrorCode(17009). - (logged at Thread(main):com.zaxxer.hikari.proxy.ConnectionProxy:173)
    java.sql.SQLRecoverableException: Closed Statement
    at oracle.jdbc.driver.OracleClosedStatement.clearBatch(OracleClosedStatement.java:3648)
    at oracle.jdbc.driver.OracleStatementWrapper.clearBatch(OracleStatementWrapper.java:206)
    at com.zaxxer.hikari.proxy.PreparedStatementJavassistProxy.clearBatch(PreparedStatementJavassistProxy.java)
    at net.sxpbelux.mirage.database.datasource.statement.TraceableStatement.clearBatch(TraceableStatement.java:31)
    at org.hibernate.engine.jdbc.batch.internal.AbstractBatchImpl.releaseStatements(AbstractBatchImpl.java:171)
    at org.hibernate.engine.jdbc.batch.internal.AbstractBatchImpl.release(AbstractBatchImpl.java:205)
    at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.close(JdbcCoordinatorImpl.java:174)
    at org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.close(TransactionCoordinatorImpl.java:280)
    at org.hibernate.internal.SessionImpl.close(SessionImpl.java:354)
    at org.hibernate.ejb.EntityManagerImpl.close(EntityManagerImpl.java:137)
    at org.springframework.orm.jpa.EntityManagerFactoryUtils.closeEntityManager(EntityManagerFactoryUtils.java:341)
    at org.springframework.orm.jpa.JpaTransactionManager.doCleanupAfterCompletion(JpaTransactionManager.java:596)
    at org.springframework.transaction.support.AbstractPlatformTransactionManager.cleanupAfterCompletion(AbstractPlatformTransactionManager.java:1009)
    at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:876)
    at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823)
    at org.springframework.test.context.transaction.TransactionalTestExecutionListener$TransactionContext.endTransaction(TransactionalTestExecutionListener.java:588)
    at org.springframework.test.context.transaction.TransactionalTestExecutionListener.endTransaction(TransactionalTestExecutionListener.java:297)
    at org.springframework.test.context.transaction.TransactionalTestExecutionListener.afterTestMethod(TransactionalTestExecutionListener.java:192)
    at org.springframework.test.context.TestContextManager.afterTestMethod(TestContextManager.java:395)
    at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:91)
    at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:72)
    at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:231)
    at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:88)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
    at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
    at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:71)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
    at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:174)
    at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:62)
    at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:140)
    at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:127)
    at org.apache.maven.surefire.Surefire.run(Surefire.java:177)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:345)
    at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:1009)

are they related? and in an other post I see that for this issue above you suggested upgrading to Hibernate 4.3.2 - would this resolve the time outs as well? BTW i tried increasing Oracle processes to solve the "ORA-12519, TNS:no appropriate service handler found" - but that didnt help. Please note that with DBCP clean install was successful with no such errors.

Thanks and regards,
Priya

@brettwooldridge
Copy link
Owner

Oh, I didn't realize this was Hibernate deployment. In that case, I strongly recommend you use Hibernate 4.3.6. Hibernate officially added support for HikariCP in that release. I also recommend using the Hibernate provided HikariCPConnectionProvider to create connections by setting the hibernate.connection.provider_class.

Finally, I recommend enabling debug logging for HikariCP so you can easily collect information on the pool size (active, idle, waiting).

EDIT: Make sure the maxPoolSize in HikariCP is set to the same as it was in DBCP.

@PriyaSodexo
Copy link
Author

I upgraded to hibernate 4.3.6, but now I get java.sql.SQLException: ORA-01017: invalid username/password; logon denied, with the same settings that worked before with 4.2.8. I am pasting the contents of my configuration file, could you please let me know what is missing?

       <bean id="entityManagerFactory"  
       class="org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean">
    <property name="persistenceXmlLocation" value="classpath:/META-INF/persistence.xml"/>
    <property name="persistenceUnitName" value="domain"/>
    <property name="dataSource" ref="dataSource" />
    <property name="jpaVendorAdapter" ref="hibernateJpaVendorAdapter"/>
    <property name="jpaProperties" ref="hibernateJpaProperties"/>
</bean>

<bean id="hibernateJpaProperties" 
    class="org.springframework.beans.factory.config.PropertiesFactoryBean">
    <property name="properties">
        <props>
            <prop key="hibernate.statement_cache.size">0</prop>
            <prop key="hibernate.jdbc.batch_size">0</prop>
            <prop key="hibernate.dbcp.ps.maxIdle">0</prop>
            <prop key="hibernate.jdbc.rap_result_sets">true</prop>
            <prop key="hibernate.hbm2ddl.auto">${db.hbm2.ddl.auto}</prop>
            <prop key="hibernate.dialect">${db.dialect}</prop>
            <prop key="hibernate.default_schema">${db.default.schema}</prop>
            <prop      
       key="hibernate.cache.provider_class">org.hibernate.cache.HashtableCacheProvider</prop>
            <prop key="hibernate.connection.autocommit">true</prop>
            <prop key="hibernate.connection.release_mode">after_transaction</prop>
        </props>
    </property>
</bean>


    <bean id="hikariConfig" class="com.zaxxer.hikari.HikariConfig">

    <property name="dataSourceProperties">
        <props>
            <prop key="url">${db.url}</prop>
            <prop key="user">${db.username}</prop>
            <prop key="password">${db.password}</prop>
        </props>
    </property>
    <property name="dataSourceClassName" value="oracle.jdbc.pool.OracleDataSource" />
    <property name="autoCommit" value = "true" />
    <property name="idleTimeout" value = "3600000" />
    <property name="maximumPoolSize" value = "10" />
    <property name="connectionTestQuery" value="SELECT 1 from dual" />
    <property name="maxLifetime" value="1800000" />
</bean>
<bean id="dataSource" class="net.sxpbelux.mirage.database.datasource.TraceableDataSource">
    <property name="delegateDataSource">
        <bean class="com.zaxxer.hikari.HikariDataSource" destroy-method="close">
            <constructor-arg ref="hikariConfig" />
        </bean>
    </property>
</bean>

@brettwooldridge
Copy link
Owner

Try adding initializationFailFast="true" to your HikariConfig properties, and turn on debug logging to verify that all properties appear correct when the pool starts up.

@PriyaSodexo
Copy link
Author

Enabling debug helped identify invalid connection parameter to one of the datasources. The clean install was running smoothly till some point and then it started failing with the same exception as before:

[HikariPool|DEBUG|14:05:17,759] Before cleanup pool stats HikariPool-32 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-32)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:17,760] After cleanup pool stats HikariPool-32 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-32)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:17,806] Before cleanup pool stats HikariPool-56 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-56)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:17,806] After cleanup pool stats HikariPool-56 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-56)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:17,968] Before cleanup pool stats HikariPool-33 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-33)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:17,968] After cleanup pool stats HikariPool-33 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-33)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,037] Before cleanup pool stats HikariPool-57 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-57)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,037] After cleanup pool stats HikariPool-57 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-57)):com.zaxxer.hikari.pool.HikariPool:542)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.595 sec
Running net.sxpbelux.mirage.service.batch.uploadCardBatch.UploadProducedCardBatchWithCardReplaceOrderlLineHappyPathBatchITest
[HikariPool|DEBUG|14:05:18,227] Before cleanup pool stats HikariPool-34 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-34)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,227] After cleanup pool stats HikariPool-34 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-34)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,269] Before cleanup pool stats HikariPool-35 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-35)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,269] After cleanup pool stats HikariPool-35 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-35)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,293] Before cleanup pool stats HikariPool-58 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-58)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,293] After cleanup pool stats HikariPool-58 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-58)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,328] Before cleanup pool stats HikariPool-59 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-59)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,328] After cleanup pool stats HikariPool-59 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-59)):com.zaxxer.hikari.pool.HikariPool:542)
[test|DEBUG|14:05:18,337] Expecting file : happyPath.html - (logged at Thread(main):net.sxpbelux.mirage.junit.MailMessageAssert:50)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.801 sec
Running net.sxpbelux.mirage.service.batch.uploadSecretariatOrders.PlpOrderPlacingServiceBatchITest
[HikariPool|DEBUG|14:05:18,900] Before cleanup pool stats HikariPool-0 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-0)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:18,900] After cleanup pool stats HikariPool-0 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-0)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,280] Before cleanup pool stats HikariPool-12 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-12)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,280] After cleanup pool stats HikariPool-12 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-12)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,498] Before cleanup pool stats HikariPool-13 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-13)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,498] After cleanup pool stats HikariPool-13 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-13)):com.zaxxer.hikari.pool.HikariPool:542)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.799 sec
Running net.sxpbelux.mirage.service.reimbursement.ReimbursementRequestBatchTestThrowsNullPointerExceptionInWriterBatchITest
[HikariPool|DEBUG|14:05:19,700] Before cleanup pool stats HikariPool-76 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-76)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,700] After cleanup pool stats HikariPool-76 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-76)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,784] Before cleanup pool stats HikariPool-14 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-14)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,784] After cleanup pool stats HikariPool-14 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-14)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,844] Before cleanup pool stats HikariPool-15 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-15)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,844] After cleanup pool stats HikariPool-15 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-15)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,942] Before cleanup pool stats HikariPool-77 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-77)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:19,942] After cleanup pool stats HikariPool-77 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-77)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,096] Before cleanup pool stats HikariPool-1 (total=10, inUse=3, avail=7, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-1)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,096] After cleanup pool stats HikariPool-1 (total=10, inUse=3, avail=7, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-1)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,185] Before cleanup pool stats HikariPool-78 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-78)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,185] After cleanup pool stats HikariPool-78 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-78)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,218] Before cleanup pool stats HikariPool-79 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-79)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:20,218] After cleanup pool stats HikariPool-79 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-79)):com.zaxxer.hikari.pool.HikariPool:542)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.752 sec
Running net.sxpbelux.mirage.service.delivery.ExceptionInWriterOrderDeliveryBatchITest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.665 sec
Running net.sxpbelux.mirage.service.batch.uploadSecretariatOrders.ProcessSecretariatBeneficiaryXmlManagementExceptionHandlingBatchITest
[TemplateRenderer| WARN|14:05:22,008] HHH000174: Function template anticipated 4 arguments, but 1 arguments encountered - (logged at Thread(main):org.hibernate.dialect.function.TemplateRenderer:121)
[TemplateRenderer| WARN|14:05:22,008] HHH000174: Function template anticipated 4 arguments, but 1 arguments encountered - (logged at Thread(main):org.hibernate.dialect.function.TemplateRenderer:121)
[HikariPool|DEBUG|14:05:22,339] Before cleanup pool stats HikariPool-60 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-60)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,339] After cleanup pool stats HikariPool-60 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-60)):com.zaxxer.hikari.pool.HikariPool:542)
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.405 sec
Running net.sxpbelux.mirage.service.batch.uploadSecretariatOrders.VouchersOrderXMLTypeMarshallerBatchITest
[HikariPool|DEBUG|14:05:22,583] Before cleanup pool stats HikariPool-61 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-61)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,583] After cleanup pool stats HikariPool-61 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-61)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,865] Before cleanup pool stats HikariPool-62 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-62)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,865] After cleanup pool stats HikariPool-62 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-62)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,903] Before cleanup pool stats HikariPool-63 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-63)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:22,903] After cleanup pool stats HikariPool-63 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-63)):com.zaxxer.hikari.pool.HikariPool:542)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.61 sec
Running net.sxpbelux.mirage.service.batch.change.event.ProcessChangeEventBatchServiceLogWithExistingCommunicationEventInStatePendingBatchITest
[PropertyPlaceholderConfigurer| WARN|14:05:24,548] Could not load properties from class path resource [configuration/extranet-configuration.properties]: class path resource [configuration/extranet-configuration.properties] cannot be opened because it does not exist - (logged at Thread(main):org.springframework.core.io.support.PropertiesLoaderSupport:181)
[HikariConfig|DEBUG|14:05:24,582] HikariCP pool HikariPool-88 configuration: - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:759)
[HikariConfig|DEBUG|14:05:24,582] autoCommit......................true - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,582] catalog......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,583] connectionCustomizer............com.zaxxer.hikari.HikariConfig$1@1002f69a - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,583] connectionCustomizerClassName... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,583] connectionInitSql............... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,583] connectionTestQuery.............SELECT 1 from dual - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,583] connectionTimeout...............30000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] dataSource...................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] dataSourceClassName.............oracle.jdbc.pool.OracleDataSource - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] dataSourceJNDI.................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] dataSourceProperties............{user=EURODB, url=jdbc:oracle:thin:@BESVC-HQ1000041.sxpbelux.net:1521:xe, password=} - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] driverClassName................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,584] idleTimeout.....................3600000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,585] initializationFailFast..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,585] isolateInternalQueries..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,585] jdbc4ConnectionTest.............false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,585] jdbcUrl......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,585] leakDetectionThreshold..........0 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,586] maxLifetime.....................1800000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,586] maximumPoolSize.................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,586] metricsTrackerClassName.........com.zaxxer.hikari.metrics.CodaHaleMetricsTracker - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,586] minimumIdle.....................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,586] password........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,587] poolName........................HikariPool-88 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,587] readOnly........................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,587] recordMetrics...................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,587] registerMbeans..................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,587] threadFactory................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,588] transactionIsolation............-1 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,588] username........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariDataSource| INFO|14:05:24,588] HikariCP pool HikariPool-88 is starting. - (logged at Thread(main):com.zaxxer.hikari.HikariDataSource:79)
[HibernatePersistence| WARN|14:05:24,592] HHH015016: Encountered a deprecated javax.persistence.spi.PersistenceProvider [org.hibernate.ejb.HibernatePersistence]; use [org.hibernate.jpa.HibernatePersistenceProvider] instead. - (logged at Thread(main):org.hibernate.ejb.HibernatePersistence:58)
[HikariPool|DEBUG|14:05:24,818] Before cleanup pool stats HikariPool-36 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-36)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:24,818] After cleanup pool stats HikariPool-36 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-36)):com.zaxxer.hikari.pool.HikariPool:542)
[EntityManagerFactoryRegistry| WARN|14:05:24,954] HHH000436: Entity manager factory name (euroDB) is already registered. If entity manager will be clustered or passivated, specify a unique value for property 'hibernate.ejb.entitymanager_factory_name' - (logged at Thread(main):org.hibernate.jpa.internal.EntityManagerFactoryRegistry:80)
[HikariConfig|DEBUG|14:05:24,983] HikariCP pool HikariPool-89 configuration: - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:759)
[HikariConfig|DEBUG|14:05:24,983] autoCommit......................true - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,983] catalog......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,984] connectionCustomizer............com.zaxxer.hikari.HikariConfig$1@1d0ec223 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,984] connectionCustomizerClassName... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,984] connectionInitSql............... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,984] connectionTestQuery.............SELECT 1 from dual - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,985] connectionTimeout...............30000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,985] dataSource...................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,985] dataSourceClassName.............oracle.jdbc.pool.OracleDataSource - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,986] dataSourceJNDI.................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,986] dataSourceProperties............{user=PHOENIX_DEV, url=jdbc:oracle:thin:@BESVC-HQ1000041.sxpbelux.net:1521:xe, password=} - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,986] driverClassName................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,986] idleTimeout.....................3600000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,987] initializationFailFast..........true - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,987] isolateInternalQueries..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,987] jdbc4ConnectionTest.............false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,987] jdbcUrl......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,988] leakDetectionThreshold..........0 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,988] maxLifetime.....................1800000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,988] maximumPoolSize.................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,988] metricsTrackerClassName.........com.zaxxer.hikari.metrics.CodaHaleMetricsTracker - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,989] minimumIdle.....................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,989] password........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,989] poolName........................HikariPool-89 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,989] readOnly........................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,990] recordMetrics...................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,990] registerMbeans..................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,990] threadFactory................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,990] transactionIsolation............-1 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:24,991] username........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariDataSource| INFO|14:05:24,991] HikariCP pool HikariPool-89 is starting. - (logged at Thread(main):com.zaxxer.hikari.HikariDataSource:79)
[HikariPool|DEBUG|14:05:25,034] Before cleanup pool stats HikariPool-37 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-37)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,034] After cleanup pool stats HikariPool-37 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-37)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,287] Before cleanup pool stats HikariPool-38 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-38)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,287] After cleanup pool stats HikariPool-38 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-38)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,320] Before cleanup pool stats HikariPool-39 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-39)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,320] After cleanup pool stats HikariPool-39 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-39)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariConfig|DEBUG|14:05:25,346] HikariCP pool HikariPool-90 configuration: - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:759)
[HikariConfig|DEBUG|14:05:25,346] autoCommit......................true - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,346] catalog......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,346] connectionCustomizer............com.zaxxer.hikari.HikariConfig$1@6886bd5a - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,347] connectionCustomizerClassName... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,347] connectionInitSql............... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,347] connectionTestQuery.............SELECT 1 from dual - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,347] connectionTimeout...............30000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,347] dataSource...................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,348] dataSourceClassName.............oracle.jdbc.pool.OracleDataSource - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,348] dataSourceJNDI.................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,348] dataSourceProperties............{user=bird2_exploit, url=jdbc:oracle:thin:@CEBESVC-DB-DMIRA:1521:DBIR, password=} - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,348] driverClassName................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,349] idleTimeout.....................3600000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,349] initializationFailFast..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,349] isolateInternalQueries..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,349] jdbc4ConnectionTest.............false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,349] jdbcUrl......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,350] leakDetectionThreshold..........0 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,350] maxLifetime.....................1800000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,350] maximumPoolSize.................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,350] metricsTrackerClassName.........com.zaxxer.hikari.metrics.CodaHaleMetricsTracker - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,350] minimumIdle.....................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,351] password........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,351] poolName........................HikariPool-90 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,351] readOnly........................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,351] recordMetrics...................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,351] registerMbeans..................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,352] threadFactory................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,352] transactionIsolation............-1 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,352] username........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariDataSource| INFO|14:05:25,352] HikariCP pool HikariPool-90 is starting. - (logged at Thread(main):com.zaxxer.hikari.HikariDataSource:79)
[HikariConfig|DEBUG|14:05:25,379] HikariCP pool HikariPool-91 configuration: - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:759)
[HikariConfig|DEBUG|14:05:25,379] autoCommit......................true - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,379] catalog......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,379] connectionCustomizer............com.zaxxer.hikari.HikariConfig$1@c07166c - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,380] connectionCustomizerClassName... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,380] connectionInitSql............... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,380] connectionTestQuery.............SELECT 1 from dual - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,380] connectionTimeout...............30000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,381] dataSource...................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,381] dataSourceClassName.............oracle.jdbc.pool.OracleDataSource - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,381] dataSourceJNDI.................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,381] dataSourceProperties............{user=compro_phoenix, url=jdbc:oracle:thin:@CEBESVC-DB-DMIRA:1521:EVIP, password=} - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,381] driverClassName................. - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,382] idleTimeout.....................3600000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,382] initializationFailFast..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,382] isolateInternalQueries..........false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,382] jdbc4ConnectionTest.............false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,383] jdbcUrl......................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,383] leakDetectionThreshold..........0 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,383] maxLifetime.....................1800000 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,383] maximumPoolSize.................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,383] metricsTrackerClassName.........com.zaxxer.hikari.metrics.CodaHaleMetricsTracker - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,384] minimumIdle.....................10 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,384] password........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,384] poolName........................HikariPool-91 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,384] readOnly........................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,385] recordMetrics...................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,385] registerMbeans..................false - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,385] threadFactory................... - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,385] transactionIsolation............-1 - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariConfig|DEBUG|14:05:25,385] username........................ - (logged at Thread(main):com.zaxxer.hikari.HikariConfig:770)
[HikariDataSource| INFO|14:05:25,386] HikariCP pool HikariPool-91 is starting. - (logged at Thread(main):com.zaxxer.hikari.HikariDataSource:79)
[HikariPool|DEBUG|14:05:25,899] Before cleanup pool stats HikariPool-16 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-16)):com.zaxxer.hikari.pool.HikariPool:542)
[HikariPool|DEBUG|14:05:25,900] After cleanup pool stats HikariPool-16 (total=10, inUse=0, avail=10, waiting=0) - (logged at Thread(Hikari Housekeeping Timer (pool HikariPool-16)):com.zaxxer.hikari.pool.HikariPool:542)
[ResourceUtils| WARN|14:05:25,915] No resource classpath:META-INF/jax-rs-catalog.xml is available - (logged at Thread(main):org.apache.cxf.jaxrs.utils.ResourceUtils:465)
[SchemaHandler| WARN|14:05:25,915] Validation will be disabled, failed to create schema : Content is not allowed in prolog. - (logged at Thread(main):org.apache.cxf.jaxrs.utils.schemas.SchemaHandler:143)
[HikariPool|DEBUG|14:05:25,935] Connection attempt to database HikariPool-90 failed: Listener refused the connection with the following error:
ORA-12519, TNS:no appropriate service handler found

  • (logged at Thread(HikariCP connection filler):com.zaxxer.hikari.pool.HikariPool:418)
    java.sql.SQLException: Listener refused the connection with the following error:
    ORA-12519, TNS:no appropriate service handler found

    at oracle.jdbc.driver.T4CConnection.logon(T4CConnection.java:458)
    at oracle.jdbc.driver.PhysicalConnection.(PhysicalConnection.java:546)
    at oracle.jdbc.driver.T4CConnection.(T4CConnection.java:236)
    at oracle.jdbc.driver.T4CDriverExtension.getConnection(T4CDriverExtension.java:32)

It looks like either something is wrong with the application or the testcases themselves, that so many connection pools get created. Any thoughts?

@brettwooldridge
Copy link
Owner

You need to make sure to shutdown the HikariDataSource at the end of each test, using either the HikariDataSource.shutdown() or HikariDataSource.close() method.

Take a look at the setup() and teardown() methods of this junit test as an example: StatementTest.java

@PriyaSodexo
Copy link
Author

Thanks Brett, after some tuning of the connection pool parameters clean install was successful.

@rnjava86
Copy link

Can you please let me know how can we setup the CallableStatement into cache.
hikariConfig.addDataSourceProperty("dataSource.cachePrepStmts", "true");
hikariConfig.addDataSourceProperty("dataSource.prepStmtCacheSize", "250");
hikariConfig.addDataSourceProperty("dataSource.prepStmtCacheSqlLimit", "2048");
hikariConfig.addDataSourceProperty("dataSource.useServerPrepStmts", "true");

	    hikariConfig.addDataSourceProperty("dataSource.useLocalSessionState", "true");
	    hikariConfig.addDataSourceProperty("dataSource.rewriteBatchedStatements", "true");
	    hikariConfig.addDataSourceProperty("dataSource.cacheResultSetMetadata", "true");
	    hikariConfig.addDataSourceProperty("dataSource.cacheServerConfiguration", "true");
	    hikariConfig.addDataSourceProperty("dataSource.elideSetAutoCommits", "true");
	    hikariConfig.addDataSourceProperty("dataSource.maintainTimeStats", "false");

what should be the parameter to setup the Callable statement caching in config object.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

3 participants