search

brettwooldridge / HikariCP

光 HikariCP・A solid, high-performance, JDBC connection pool at last.
Apache License 2.0
19.83k stars 2.92k forks source link

Connection is not available, request timed out after 3000ms and Pool stats (total=0, active=0, idle=0, waiting=0) #1710

Open PC-Chiu opened 3 years ago

PC-Chiu commented 3 years ago

When I use Spring boot auto configuaration with default HikariCP config, I get this erro, What happend ?

2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:05.735 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:19:05.736 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:13.916 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@723efb7f: (connection has passed maxLifetime)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:05.749 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:05.750 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 1
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@4417aebf: (connection has passed maxLifetime)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 2
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@64009e8d: (connection has passed maxLifetime)
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 3
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@1792d6f6: (connection has passed maxLifetime)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 4
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@a0f494f: (connection has passed maxLifetime)
2020-12-28 08:23:35.766 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:23:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:35.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 5
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@3c1213ee: (connection has passed maxLifetime)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 6
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@5bc78f12: (connection has passed maxLifetime)
2020-12-28 08:27:05.772 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:05.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 7
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@414df39f: (connection has passed maxLifetime)
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 8
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@40f43dd7: (connection has passed maxLifetime)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=1, active=0, idle=1, waiting=0)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 9
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@eaad8b2: (connection has passed maxLifetime)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:29:35.780 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:35.781 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:31:05.791 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
PC-Chiu commented 3 years ago

HikariCP version is 3.4.5

ismaan1998 commented 3 years ago

@PC-Chiu Have you got any solution? , I am also facing the same error.

PC-Chiu commented 3 years ago

@ismaan1998 Not yet！It takes a long time for this problem to recur.

tanel3203 commented 3 years ago

same question

tanel3203 commented 3 years ago

We managed to resolve this issue for our case. Didn't deep dive, but the general idea is the following. Hope this helps.

In select query string we have this 

ourIdentifier = ANY (?)

which we need to resolve to a list argument like ourIdentifier = ANY ({'ValueA, ValueB'})

To execute, we use 

jdbcTemplate.query(..)

which works just fine, but the problem was caused by using 

jdbcTemplate.getDataSource().getConnection().createArrayOf("varchar", ourIdentifiers.toArray())

to populate that ANY list argument.

I guess using jdbcTemplate for getConnection didn't close the connection without writing the closure out (or alternatively using try-with-resources), expectation was that the jdbcTemplate handles it. Possibly using a hikari managed bean would solve the issue without writing the closures/tries everywhere, we just went the simple approach and pre-generate the correctly formatted list without grabbing a connection and input it to the query string as a string so it resolves to the latter above.

The full jdbc query for example is below (the one that caused this issue for us):

jdbcTemplate.query(selectQueryString, multiRowExtractor(OurRepository::transformDbRowToObject),
                                         jdbcTemplate.getDataSource().getConnection().createArrayOf("varchar", ourIdentifiers.toArray()), anotherQuestionMarkedArgument);
wanglunhui2012 commented 3 years ago

do you config the maxLifetime property?

PC-Chiu commented 3 years ago

@wanglunhui2012 No, I am not. It's spring boot auto config default value 30 minutes. After about 3 days of normal running, this problem occurred.

PC-Chiu commented 3 years ago

normal log:

2020-12-28 00:02:30.472 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@2f9129bc: (connection has passed maxLifetime)
2020-12-28 00:02:31.080 DEBUG 28421 --- [Hikari connection adder] com.zaxxer.hikari.pool.HikariPool        : Hikari - Added connection oracle.jdbc.driver.T4CConnection@1d125f23
2020-12-28 00:02:33.330 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 00:02:33.330 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.

abnormal log:

2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 1
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@4417aebf: (connection has passed maxLifetime)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 2
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@64009e8d: (connection has passed maxLifetime)
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 3
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@1792d6f6: (connection has passed maxLifetime)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 4
wanglunhui2012 commented 3 years ago

@wanglunhui2012 No, I am not. It's spring boot auto config default value 30 minutes. After about 3 days of normal running, this problem occurred.

Can you show your Hikari Config?

PC-Chiu commented 3 years ago 
spring:
  datasource:
    type: com.zaxxer.hikari.HikariDataSource
    url: ****
    username: ****
    password: ****
    hikari:
      poolName: Hikari
      auto-commit: false

With Spring Boot 2.2.x, it use com.zaxxer.hikari.HikariConfig default config value.

@wanglunhui2012 No, I am not. It's spring boot auto config default value 30 minutes. After about 3 days of normal running, this problem occurred.

Can you show your Hikari Config?

catalinbalasoiu commented 3 years ago

I'm having the exact same issue with the default configuration. I'm using Spring Boot 2.3.8.RELEASE driver-class-name: com.microsoft.sqlserver.jdbc.SQLServerDriver hikari: data-source-properties: cachePrepStmts: true prepStmtCacheSize: 250 prepStmtCacheSqlLimit: 2048 useServerPrepStmts: false maximum-pool-size: 50 jpa: database-platform: org.hibernate.dialect.SQLServer2008Dialect database: SQL_SERVER show-sql: false hibernate: ddl-auto: none properties: hibernate.dialect: org.hibernate.dialect.SQLServer2012Dialect hibernate.jdbc.batch_size: 20 hibernate.cache.use_second_level_cache: false hibernate.enable_lazy_load_no_trans: true hibernate.show_sql: false hibernate.format_sql: false jta: enabled: false

BilgehanYildiz commented 3 years ago

Do you find any solution?

PC-Chiu commented 3 years ago

Do you find any solution?

@BilgehanYildiz Not yet, I have not found this problem in other environments, I guess it‘s problem from Oracle session or network.

TomasTokaMrazek commented 3 years ago

@PC-Chiu Same problem, but with PostgreSQL DB. I don't think it's related to Oracle.

feinier commented 3 years ago

Same problem,with PG,HikariCP version is 2.5.1

brettwooldridge commented 3 years ago

This issue is very likely fixed in HikariCP v5.0.0 with a rewrite of pool filling code.

KarthikeyanB007 commented 3 years ago

Hi Do you have an equivalent fix for Java 8? It appears HikariCP V5 works only with Java 11.

KarthikeyanB007 commented 3 years ago

Hi,

I still see the issue with HikariCP V5 + OpenJDK 11. Could you please help with a resolution at the earliest possible?

minimumIdle: 5 maximumPoolSize: 5 maxLifetime: 1800000 leakDetectionThreshold: 45000 keepaliveTime: 120000 connectionTimeout: 300000

When total becomes less than maximumPoolSize, why are nt new connections added to the pool to retain minimumIdle (which is = maximumPoolSize = 5)?

Just the relevant DEBUG logs as below please.

===

... 2021-07-26 13:15:13.459 DEBUG 97145 --- [ main] com.zaxxer.hikari.HikariConfig : validationTimeout...............5000 2021-07-26 13:15:13.459 INFO 97145 --- [ main] com.zaxxer.hikari.HikariDataSource : prod-postingestion-pool - Starting... 2021-07-26 13:15:14.289 INFO 97145 --- [ main] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Added connection org.postgresql.jdbc.PgConnection@733037 2021-07-26 13:15:14.291 INFO 97145 --- [ main] com.zaxxer.hikari.HikariDataSource : prod-postingestion-pool - Start completed. 2021-07-26 13:15:14.348 INFO 97145 --- [ main] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [name: default] 2021-07-26 13:15:14.391 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=1, active=0, idle=1, waiting=0) ... 2021-07-26 21:41:47.679 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=5, active=0, idle=5, waiting=0) 2021-07-26 21:41:47.679 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0. ... 2021-07-26 21:42:06.578 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@2b18cf39 is alive 2021-07-26 21:42:17.679 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=5, active=0, idle=5, waiting=0) 2021-07-26 21:42:17.680 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0. ... 2021-07-26 21:42:40.350 WARN 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Failed to validate connection org.postgresql.jdbc.PgConnection@5f05f5ef (This connection has been closed.). Possibly consider using a shorter maxLifetime value. 2021-07-26 21:42:40.351 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=0, adders pending/running=1 2021-07-26 21:42:40.351 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@5f05f5ef: (connection is dead) 2021-07-26 21:42:40.351 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@2b18cf39: (connection has passed maxLifetime)

2021-07-27 14:33:18.262 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=5, active=0, idle=5, waiting=0) 2021-07-27 14:33:18.262 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0. ... 2021-07-27 14:33:34.807 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@3b2a1c76: (connection has passed maxLifetime) 2021-07-27 14:33:48.263 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) 2021-07-27 14:34:12.700 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6d16cbcd is alive 2021-07-27 14:34:18.263 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) ... 2021-07-27 14:34:48.263 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) ... 2021-07-27 14:34:54.699 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6b4ef4a7 is alive 2021-07-27 14:35:04.665 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@763217ad is alive 2021-07-27 14:35:10.616 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@52857c47 is alive 2021-07-27 14:35:18.263 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) ... 2021-07-27 14:35:48.263 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) 2021-07-27 14:36:09.980 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6d16cbcd is alive 2021-07-27 14:36:18.264 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=4, active=0, idle=4, waiting=0) 2021-07-27 14:36:44.154 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=0, adders pending/running=8 2021-07-27 14:36:44.154 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@763217ad: (connection has passed maxLifetime) 2021-07-27 14:36:46.545 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6b4ef4a7 is alive 2021-07-27 14:36:48.264 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:37:08.340 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@52857c47 is alive 2021-07-27 14:37:18.264 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:37:48.264 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) 2021-07-27 14:38:07.259 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6d16cbcd is alive 2021-07-27 14:38:18.265 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:38:38.391 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6b4ef4a7 is alive 2021-07-27 14:38:48.265 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:39:06.063 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@52857c47 is alive 2021-07-27 14:39:18.265 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:39:48.266 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:58:43.303 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@52857c47 is alive 2021-07-27 14:58:48.275 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:59:08.699 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - keepalive: connection org.postgresql.jdbc.PgConnection@6b4ef4a7 is alive 2021-07-27 14:59:18.276 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=3, active=0, idle=3, waiting=0) ... 2021-07-27 14:59:35.641 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=0, adders pending/running=17 2021-07-27 14:59:35.641 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@6d16cbcd: (connection has passed maxLifetime) 2021-07-27 14:59:48.276 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=2, active=0, idle=2, waiting=0) ... 2021-07-27 15:02:48.277 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=2, active=0, idle=2, waiting=0) ... 2021-07-27 15:02:51.934 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=0, adders pending/running=17 2021-07-27 15:02:51.935 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@6b4ef4a7: (connection has passed maxLifetime) 2021-07-27 15:03:12.268 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=0, adders pending/running=17 2021-07-27 15:03:12.268 DEBUG 97145 --- [nnection closer] com.zaxxer.hikari.pool.PoolBase : prod-postingestion-pool - Closing connection org.postgresql.jdbc.PgConnection@52857c47: (connection has passed maxLifetime) 2021-07-27 15:03:18.279 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=0) ... 2021-07-27 15:03:48.279 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=0) 2021-07-27 15:04:18.279 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=0) ... 2021-07-27 15:04:48.279 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=0) ... 2021-07-27 15:04:51.612 DEBUG 97145 --- [ scheduling-1] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Add connection elided, waiting=1, adders pending/running=17 2021-07-27 15:05:18.280 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=1) 2021-07-27 15:05:48.280 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=1) ... 2021-07-27 15:09:18.282 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=1) 2021-07-27 15:09:48.282 DEBUG 97145 --- [ool housekeeper] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Pool stats (total=0, active=0, idle=0, waiting=1) 2021-07-27 15:09:51.612 DEBUG 97145 --- [ scheduling-1] com.zaxxer.hikari.pool.HikariPool : prod-postingestion-pool - Timeout failure stats (total=0, active=0, idle=0, waiting=0) 2021-07-27 15:09:51.622 WARN 97145 --- [ scheduling-1] o.h.engine.jdbc.spi.SqlExceptionHelper : SQL Error: 0, SQLState: null 2021-07-27 15:09:51.622 ERROR 97145 --- [ scheduling-1] o.h.engine.jdbc.spi.SqlExceptionHelper : prod-postingestion-pool - Connection is not available, request timed out after 300001ms. 2021-07-27 15:09:51.631 ERROR 97145 --- [ scheduling-1] o.s.s.s.TaskUtils$LoggingErrorHandler : Unexpected error occurred in scheduled task

org.springframework.dao.DataAccessResourceFailureException: Unable to acquire JDBC Connection; nested exception is org.hibernate.exception.JDBCConnectionException: Unable to acquire JDBC Connection at org.springframework.orm.jpa.vendor.HibernateJpaDialect.convertHibernateAccessException(HibernateJpaDialect.java:277) ~[spring-orm-5.2.6.RELEASE.jar!/:5.2.6.RELEASE] at org.springframework.orm.jpa.vendor.HibernateJpaDialect.translateExceptionIfPossible(HibernateJpaDialect.java:255) ~[spring-orm-5.2.6.RELEASE.jar!/:5.2.6.RELEASE] at org.springframework.orm.jpa.AbstractEntityManagerFactoryBean.translateExceptionIfPossible(AbstractEntityManagerFactoryBean.java:528) ~[

===

cherryBerry23 commented 2 years ago

Hi all,

Any update on this issue? Any fix available?

CodeMSC commented 2 years ago

When I use Spring boot auto configuaration with default HikariCP config, I get this erro, What happend ?

2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:05.735 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:19:05.736 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:13.916 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@723efb7f: (connection has passed maxLifetime)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:05.749 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:05.750 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 1
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@4417aebf: (connection has passed maxLifetime)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 2
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@64009e8d: (connection has passed maxLifetime)
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 3
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@1792d6f6: (connection has passed maxLifetime)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 4
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@a0f494f: (connection has passed maxLifetime)
2020-12-28 08:23:35.766 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:23:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:35.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 5
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@3c1213ee: (connection has passed maxLifetime)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 6
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@5bc78f12: (connection has passed maxLifetime)
2020-12-28 08:27:05.772 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:05.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 7
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@414df39f: (connection has passed maxLifetime)
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 8
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@40f43dd7: (connection has passed maxLifetime)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=1, active=0, idle=1, waiting=0)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 9
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@eaad8b2: (connection has passed maxLifetime)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:29:35.780 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:35.781 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:31:05.791 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)

Could you try to use driver manager to get a connection from the oracle database Instead of jdbctemplate.getConnection() Connection con = DriverManager.getConnection("URL", "USERNAME","PASSWORD");

PC-Chiu commented 2 years ago

When I use Spring boot auto configuaration with default HikariCP config, I get this erro, What happend ?

2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:17:35.692 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:05.693 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:18:35.694 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:05.735 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=10, active=0, idle=10, waiting=0)
2020-12-28 08:19:05.736 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:19:13.916 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@723efb7f: (connection has passed maxLifetime)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:19:35.744 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:05.749 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:05.750 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:20:35.751 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=9, active=0, idle=9, waiting=0)
2020-12-28 08:21:05.752 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 1
2020-12-28 08:21:13.338 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@4417aebf: (connection has passed maxLifetime)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:21:35.754 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=8, active=0, idle=8, waiting=0)
2020-12-28 08:22:05.757 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 2
2020-12-28 08:22:13.784 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@64009e8d: (connection has passed maxLifetime)
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 3
2020-12-28 08:22:34.265 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@1792d6f6: (connection has passed maxLifetime)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:22:35.758 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=6, active=0, idle=6, waiting=0)
2020-12-28 08:23:05.761 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 4
2020-12-28 08:23:19.823 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@a0f494f: (connection has passed maxLifetime)
2020-12-28 08:23:35.766 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:23:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:05.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:24:35.767 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=5, active=0, idle=5, waiting=0)
2020-12-28 08:24:35.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 5
2020-12-28 08:25:04.516 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@3c1213ee: (connection has passed maxLifetime)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:05.768 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:25:35.769 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:05.770 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=4, active=0, idle=4, waiting=0)
2020-12-28 08:26:35.771 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 6
2020-12-28 08:26:44.334 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@5bc78f12: (connection has passed maxLifetime)
2020-12-28 08:27:05.772 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:05.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:27:35.773 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=3, active=0, idle=3, waiting=0)
2020-12-28 08:28:05.774 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 7
2020-12-28 08:28:10.515 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@414df39f: (connection has passed maxLifetime)
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 8
2020-12-28 08:28:35.023 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@40f43dd7: (connection has passed maxLifetime)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=1, active=0, idle=1, waiting=0)
2020-12-28 08:28:35.776 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Add connection elided, waiting 0, queue 9
2020-12-28 08:28:38.349 DEBUG 28421 --- [Hikari connection closer] com.zaxxer.hikari.pool.PoolBase          : Hikari - Closing connection oracle.jdbc.driver.T4CConnection@eaad8b2: (connection has passed maxLifetime)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:05.779 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:29:35.780 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:29:35.781 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:05.782 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)
2020-12-28 08:30:35.787 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Fill pool skipped, pool is at sufficient level.
2020-12-28 08:31:05.791 DEBUG 28421 --- [Hikari housekeeper] com.zaxxer.hikari.pool.HikariPool        : Hikari - Pool stats (total=0, active=0, idle=0, waiting=0)

Could you try to use driver manager to get a connection from the oracle database Instead of jdbctemplate.getConnection() Connection con = DriverManager.getConnection("URL", "USERNAME","PASSWORD");

@CodeMSC Good idea, I will try it.

brettwooldridge commented 2 years ago

@PC-Chiu @cherryBerry23 el al,

Anyone interested in trying 5.0.2-SNAPSHOT, just published, please do so. It contains simplified connection replacement logic. Feedback is welcome.

brettwooldridge commented 2 years ago

@PC-Chiu By the way, the format of the message, "Fill pool skipped, pool is at sufficient level", suggests that you are using an older version of HikariCP. The most recent versions log, "Fill pool skipped, pool has sufficient level or currently being filled."

PC-Chiu commented 2 years ago

My HikariCP version is 3.4.5, And I will upgrade it.

jfierro commented 2 years ago

Hi @PC-Chiu, did the newest version of Hikari fix the issue for you?

PC-Chiu commented 2 years ago

Hi @PC-Chiu, did the newest version of Hikari fix the issue for you?

Sorry!!! It only happens in certain circumstances, doesn't happen in any other environment and the original environment has been destroyed.

jfierro commented 2 years ago

Thank you! Issue #1702 sounds like the same thing and the author seems to have a way to reproduce it related to using containers. FWIW, I have seen this happen in our environment when VM's get suspended for some time to do back ups.

yifanzheng commented 1 year ago

Same problem, with SQLServer, HikariCP version is 2.7.8

brendanjlynch4014 commented 1 year ago

Hi, if anyone is listening need help too. Any solutions. I'll check the other mention. But, in my scenario it is consistently happening Every 30minutes when connection reaches maxlifetime and no new connections getting added. And, what interesting in my case is that in only started when I rebuilt the application to add a piece of totally unrelated functionality. apps are running from the exact same servers so my runtime environment is exactly the same for both versions of app. I'm connecting to a MySQL database. Suggestions?

EdenTian commented 1 year ago

HI everyone，did you resoveld this issue ,i'm version is 4.0.3 ,happend the error ,help me @brettwooldridge

jfierro commented 1 year ago

Hi Tian. We solved it by disabling snapshot backups on our VMs. More of a workaround than a root cause fix, but it resolved the issue for us.

ravibarkhani commented 1 year ago

Hi everyone, anybody solved this issue, we are facing with HikariCP v5.0.1

Connection pool settings minimumIdle: 5 maximumPoolSize: 30 maxLifetime: default 30 min connectionTimeout: 180000 idleTimeout : 600000

============== Very strange part is that all of sudden connection queue depth reached to 17 and I feel it is causing issue

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Add connection elided, waiting=0, adders pending/running=17

normal behavior logs

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0). [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6000 ClientConnectionId: a714e18f-7d0b-4281-bf6c-f4a7bea4aec7: (connection has passed maxLifetime) [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection ConnectionID:6005 ClientConnectionId: 3e44897d-e233-4015-acc9-c1f484e5c46e [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0)

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0). [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6001 ClientConnectionId: d8115256-c18a-451b-9f03-e5ada04ea695: (connection has passed maxLifetime) [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection ConnectionID:6006 ClientConnectionId: e00a6c79-48a9-4f16-acc4-981ef34ac2dc [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) ......

Abnormal Logs when connections are closed but not created new one

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool has sufficient level or currently being filled (queueDepth=0). [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6002 ClientConnectionId: 0006da1d-e212-4dbb-b7f7-7ce61d90bee1: (connection has passed maxLifetime) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=4, active=0, idle=4, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=4, active=0, idle=4, waiting=0)

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=4, active=0, idle=4, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=4, active=0, idle=4, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Add connection elided, waiting=0, adders pending/running=17 [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6003 ClientConnectionId: 64dd58d8-14c3-4a53-9493-3eb0e0a9e878: (connection has passed maxLifetime) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=3, active=0, idle=3, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=3, active=0, idle=3, waiting=0)

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=3, active=0, idle=3, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=3, active=0, idle=3, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Add connection elided, waiting=0, adders pending/running=17 [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6004 ClientConnectionId: 73908f80-f68c-4877-9c30-214d474dc865: (connection has passed maxLifetime) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=2, active=0, idle=2, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=2, active=0, idle=2, waiting=0)

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=2, active=0, idle=2, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=2, active=0, idle=2, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Add connection elided, waiting=0, adders pending/running=17 [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6005 ClientConnectionId: 3e44897d-e233-4015-acc9-c1f484e5c46e: (connection has passed maxLifetime) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=1, active=0, idle=1, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=1, active=0, idle=1, waiting=0)

[HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=1, active=0, idle=1, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=1, active=0, idle=1, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Add connection elided, waiting=0, adders pending/running=17 [HikariPool-1 connection closer] DEBUG com.zaxxer.hikari.pool.PoolBase - HikariPool-1 - Closing connection ConnectionID:6006 ClientConnectionId: e00a6c79-48a9-4f16-acc4-981ef34ac2dc: (connection has passed maxLifetime) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=0, active=0, idle=0, waiting=0) [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=0, active=0, idle=0, waiting=0)

vasanthsumanath commented 1 year ago

Facing the same problem with Postgres 12.5,

Java Version: 11 Spring Boot Version: 2.5.5 HikariCP Version: 4.0.3

Have no control on the occurrence, Not able to reproduce too, but is happening at-least once in a month in prod. Only solution (work around) we have now is to restart the application which is not the right approach. Please help.

romairi commented 1 year ago

Somebody can help me. I have application in spring boot 2.6.8</version,

terajdbc 20.00.00.10 Java 11 I have many connections to teradata (every two sec it connected and open a new session) I have the next logs: (if good behavior of HikariCP, and what can be a problem) help, pleaseee? 07-2023 16:40:52.302 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.teradata.jdbc.jdbc_4.TDSession@3874af47 09-07-2023 16:40:54.471 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection com.teradata.jdbc.jdbc_4.TDSession@166416f9 09-07-2023 16:40:54.471 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After adding stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:41:15.818 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:41:15.818 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:41:15.818 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:41:45.822 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:41:45.822 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:41:45.822 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:42:15.831 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:42:15.831 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:42:15.831 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:42:45.841 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:42:45.841 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:42:45.841 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:43:15.857 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:43:15.857 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:43:15.857 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:43:45.865 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:43:45.865 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:43:45.865 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:44:15.875 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:44:15.875 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:44:15.875 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:44:45.893 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:44:45.893 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:44:45.893 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:45:15.902 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:45:15.902 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:45:15.902 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level. 09-07-2023 16:45:45.912 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Before cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:45:45.912 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After cleanup stats (total=5, active=0, idle=5, waiting=0) 09-07-2023 16:45:45.912 [HikariPool-1 housekeeper] DEBUG com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Fill pool skipped, pool is at sufficient level.
GauravSisodiya04 commented 7 months ago

Hi @brettwooldridge , can you please help us to confirm if this issue was fixed in any of the latest versions. I am still seeing people reporting this issue in 5.X.X here
We wanted to make a decision if we should upgrade to newer version but I am not able to get a confirmation if this issue has been ever fixed ?

sreejiths12345 commented 1 month ago

same issue here , please could some one able to fix ?