Closed knop-ster closed 3 years ago
Here some supplementary information.
My current docker-compose.yml
version: '2'
networks:
intern:
external: true
web:
external: true
services:
database:
image: postgres:13.3
restart: always
ports:
- 5432
environment:
- POSTGRES_PASSWORD=4nu8FuFcrnrjWqtzpCyFgvuCrPCzRHuTmr4MxHbwFyudB7Be6g8VgfaUDQQGc398
networks:
- intern
volumes:
- /var/docker/postgresql/data:/var/lib/postgresql/data
app:
image: myImage
restart: always
entrypoint:
- 'java'
- '-jar'
- 'app.jar'
ports:
- 8443
depends_on:
- database
networks:
- intern
- web
environment:
- SPRING_PROFILES_ACTIVE=stage
- SPRING_LIQUIBASE_ENABLED=false
- SPRING_DATASOURCE_URL=jdbc:postgresql://database:5432/postgres
- SPRING_DATASOURCE_USERNAME=postgres
- SPRING_DATASOURCE_PASSWORD=4nu8FuFcrnrjWqtzpCyFgvuCrPCzRHuTmr4MxHbwFyudB7Be6g8VgfaUDQQGc398
volumes:
- /etc/localtime:/etc/localtime:ro
- /etc/timezone:/etc/timezone:ro
configuration of my docker networks
[
{
"Name": "intern",
"Id": "76f8a09726789c351db97aa1fea68f1d02d0a1390a6ac384b63a666f212e8b37",
"Created": "2021-07-04T16:34:42.641320042+02:00",
"Scope": "local",
"Driver": "bridge",
"EnableIPv6": false,
"IPAM": {
"Driver": "default",
"Options": {},
"Config": [
{
"Subnet": "172.21.0.0/16",
"Gateway": "172.21.0.1"
}
]
},
"Internal": false,
"Attachable": false,
"Ingress": false,
"ConfigFrom": {
"Network": ""
},
"ConfigOnly": false,
"Containers": {
"2d9e0306c9b8e39c46169d3fecfade08ed7d12fa0510b07113deea22369bd48c": {
"Name": "stage_database_1",
"EndpointID": "f9c6a01a1f7a80eb727d84a0baca0fbe5d66c8c799cfa61982d87629181b7633",
"MacAddress": "02:42:ac:15:00:04",
"IPv4Address": "172.21.0.4/16",
"IPv6Address": ""
},
"b89adb628a8042cd933e6ef4da0d304d81fd86b46c05321d8846c4a686fb254b": {
"Name": "stage_app_1",
"EndpointID": "59aca6671c06dbf6f81ca4a13b58abd23a8a7496b885f57201e37637030d7ab8",
"MacAddress": "02:42:ac:15:00:05",
"IPv4Address": "172.21.0.5/16",
"IPv6Address": ""
}
},
"Options": {
"com.docker.network.bridge.enable_icc": "true",
"com.docker.network.driver.mtu": "9216"
},
"Labels": {}
}
]
[
{
"Name": "web",
"Id": "94eb39f3770cfbe87554def0ce8db7bb6925ff528fd00295b78b8c42c6fa26ce",
"Created": "2020-12-22T10:28:20.40424673+01:00",
"Scope": "local",
"Driver": "bridge",
"EnableIPv6": false,
"IPAM": {
"Driver": "default",
"Options": {},
"Config": [
{
"Subnet": "172.19.0.0/16",
"Gateway": "172.19.0.1"
}
]
},
"Internal": false,
"Attachable": false,
"Ingress": false,
"ConfigFrom": {
"Network": ""
},
"ConfigOnly": false,
"Containers": {
"b89adb628a8042cd933e6ef4da0d304d81fd86b46c05321d8846c4a686fb254b": {
"Name": "stage_app_1",
"EndpointID": "718d38e49509e4f9a11fce853f2c574a1b41cbe49df45440d0fb226ec14c277e",
"MacAddress": "02:42:ac:13:00:04",
"IPv4Address": "172.19.0.4/16",
"IPv6Address": ""
}
},
"Options": {},
"Labels": {}
}
]
2021-07-04 17:11:31,004 [community-event-manager-task-pool-83] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Timeout failure stats (total=0, active=0, idle=0, waiting=5)
2021-07-04 17:11:31,005 [community-event-manager-task-pool-83] WARN o.h.e.jdbc.spi.SqlExceptionHelper: SQL Error: 0, SQLState: 28P01
2021-07-04 17:11:31,006 [community-event-manager-task-pool-83] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: HikariPool-1 - Connection is not available, request timed out after 30003ms.
2021-07-04 17:11:31,006 [community-event-manager-task-pool-83] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: FATAL: password authentication failed for user "postgres"
2021-07-04 17:11:31,007 [community-event-manager-task-pool-83] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 6, queue 10
2021-07-04 17:11:31,010 [community-event-manager-task-pool-84] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Timeout failure stats (total=0, active=0, idle=0, waiting=5)
2021-07-04 17:11:31,011 [community-event-manager-task-pool-84] WARN o.h.e.jdbc.spi.SqlExceptionHelper: SQL Error: 0, SQLState: 28P01
2021-07-04 17:11:31,010 [community-event-manager-task-pool-21] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Timeout failure stats (total=0, active=0, idle=0, waiting=4)
2021-07-04 17:11:31,010 [community-event-manager-task-pool-42] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Timeout failure stats (total=0, active=0, idle=0, waiting=3)
2021-07-04 17:11:31,011 [community-event-manager-task-pool-21] WARN o.h.e.jdbc.spi.SqlExceptionHelper: SQL Error: 0, SQLState: 28P01
2021-07-04 17:11:31,012 [community-event-manager-task-pool-42] WARN o.h.e.jdbc.spi.SqlExceptionHelper: SQL Error: 0, SQLState: 28P01
2021-07-04 17:11:31,011 [community-event-manager-task-pool-84] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: HikariPool-1 - Connection is not available, request timed out after 30002ms.
2021-07-04 17:11:31,013 [community-event-manager-task-pool-84] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: FATAL: password authentication failed for user "postgres"
2021-07-04 17:11:31,012 [community-event-manager-task-pool-21] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: HikariPool-1 - Connection is not available, request timed out after 30002ms.
2021-07-04 17:11:31,013 [community-event-manager-task-pool-42] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: HikariPool-1 - Connection is not available, request timed out after 30002ms.
2021-07-04 17:11:31,014 [community-event-manager-task-pool-21] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: FATAL: password authentication failed for user "postgres"
2021-07-04 17:11:31,014 [community-event-manager-task-pool-42] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: FATAL: password authentication failed for user "postgres"
2021-07-04 17:11:31,016 [community-event-manager-task-pool-21] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 4, queue 10
2021-07-04 17:11:31,016 [community-event-manager-task-pool-42] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 6, queue 10
2021-07-04 17:11:31,016 [community-event-manager-task-pool-84] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 5, queue 10
2021-07-04 17:11:40,001 [community-event-manager-task-pool-85] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Timeout failure stats (total=0, active=0, idle=0, waiting=5)
2021-07-04 17:11:40,002 [community-event-manager-task-pool-85] WARN o.h.e.jdbc.spi.SqlExceptionHelper: SQL Error: 0, SQLState: 28P01
2021-07-04 17:11:40,002 [community-event-manager-task-pool-85] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: HikariPool-1 - Connection is not available, request timed out after 30001ms.
2021-07-04 17:11:40,002 [community-event-manager-task-pool-85] ERROR o.h.e.jdbc.spi.SqlExceptionHelper: FATAL: password authentication failed for user "postgres"
2021-07-04 17:11:40,003 [community-event-manager-task-pool-85] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 6, queue 10
Until shortly after that happens
2021-07-04 17:11:50,447 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Added connection org.postgresql.jdbc.PgConnection@2b4f8d8d
2021-07-04 17:11:50,447 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - After adding stats (total=2, active=1, idle=1, waiting=0)
2021-07-04 17:11:50,461 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Added connection org.postgresql.jdbc.PgConnection@400bb413
2021-07-04 17:11:50,461 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - After adding stats (total=3, active=1, idle=2, waiting=0)
2021-07-04 17:11:50,470 [community-event-manager-task-pool-42] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 1, queue 7
2021-07-04 17:11:50,471 [community-event-manager-task-pool-85] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 2, queue 7
2021-07-04 17:11:50,472 [community-event-manager-task-pool-84] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Add connection elided, waiting 3, queue 7
2021-07-04 17:11:50,478 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - Added connection org.postgresql.jdbc.PgConnection@16564673
2021-07-04 17:11:50,479 [HikariPool-1 connection adder] DEBUG com.zaxxer.hikari.pool.HikariPool: HikariPool-1 - After adding stats (total=4, active=4, idle=0, waiting=2)
I am out of ideas to analyze the problem.
I have had the same problem with my Spring Boot application as in ticket #1256 for a long time. Only now did I come across this ticket.
Hikari closes connections because maxLifeTime has been reached. When things go really bad, Hikari is unable to establish new connections. Allegedly because the login with the user fails.
At the end of the log, however, you can clearly see that the connection can then be established. So the password seems to be correct. Do you have any idea what the problem could be?
Here are some environment details:
Thats my current hikari configuration:
Here is the stack trace