|
|
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Добрый день. Вопрос уже 100 раз обсуждался, но все-таки может кто ещё что подскажет. Есть приложение на Java (сторонняя разработка), которое иной раз вываливается при первичном запуске по тайм-ауту (даже вне рабочие часы) Особенной нагрузки на сервере Oracle нет в этот момент (смотрю в заббикс и в ОС) Ошибка со стороны приложения (сервер debian 9.6, java version "1.8.0_112") автор2018-июл-31 19:03:09 : DEBUG main com.zaxxer.hikari.pool.HikariPool - BaseHikariPool.addConnection(line:466) - Connection attempt to database dataSource failed: Ошибка ввода/вывода: Connection reset java.sql.SQLRecoverableException: Ошибка ввода/вывода: Connection reset Ошибка со стороны базы (других ошибок нет, oracle linux, касперского нет :) ) автор*********************************************************************** Fatal NI connect error 12170. VERSION INFORMATION: TNS for Linux: Version 12.1.0.2.0 - Production Oracle Bequeath NT Protocol Adapter for Linux: Version 12.1.0.2.0 - Production TCP/IP NT Protocol Adapter for Linux: Version 12.1.0.2.0 - Production Time: 31-JUL-2018 18:15:10 Tracing not turned on. Tns error struct: ns main err code: 12535 TNS-12535: TNS:operation timed out ns secondary err code: 12606 nt main err code: 0 nt secondary err code: 0 nt OS err code: 0 Client address: (ADDRESS=(PROTOCOL=tcp)(HOST=192.168.166.19)(PORT=36020)) Tue Jul 31 18:15:10 2018 WARNING: inbound connection timed out (ORA-3136) Не очень хочется ставить SQLNET.INBOUND_CONNECT_TIMEOUT и INBOUND_CONNECT_TIMEOUT_listener_name в 0 (или другие большие значения) Может у кого были такие пляски с бубном) p.s. с сетью проблем замечено не было. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 15:41 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Ну похоже в данном случае timeout срабатывает со стороны сервера. 1. Я бы смотрел на приложение на Java, что оно делает в момент загрузки. Особенно посмотрел бы на GC. Возможно там тормоза зашкаливают. 2. Могут ли быть банальные проблемы с сетью? ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 16:11 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Leonid Kudryavtsev, Да нет, с сетью должно быть все хорошо. А вот с Garbage Collection похоже Вы правы. Ошибки есть... авторJava HotSpot(TM) Client VM (25.112-b15) for linux-x86 JRE (1.8.0_112-b15), built on Sep 22 2016 19:45:47 by "java_re" with gcc 4.3.0 20080428 (Red Hat 4.3.0-8) Memory: 4k page, physical 4136972k(2977672k free), swap 349180k(349180k free) CommandLine flags: -XX:InitialHeapSize=16777216 -XX:MaxHeapSize=268435456 -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+UseCMSInitiatingOccupancyOnly -XX:+UseParNewGC 2018-07-31T21:29:23.294+0300: 0,217: [GC (Allocation Failure) 2018-07-31T21:29:23.294+0300: 0,217: [ParNew: 4416K->511K(4928K), 0,0024915 secs] 4416K->1080K(15872K), 0,0025797 secs] [Times: user=0,00 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.425+0300: 0,347: [GC (Allocation Failure) 2018-07-31T21:29:23.425+0300: 0,348: [ParNew: 4927K->512K(4928K), 0,0032925 secs] 5496K->2081K(15872K), 0,0033863 secs] [Times: user=0,00 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.514+0300: 0,437: [GC (Allocation Failure) 2018-07-31T21:29:23.515+0300: 0,437: [ParNew: 4928K->512K(4928K), 0,0032310 secs] 6497K->2812K(15872K), 0,0032839 secs] [Times: user=0,01 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.593+0300: 0,516: [GC (Allocation Failure) 2018-07-31T21:29:23.593+0300: 0,516: [ParNew: 4928K->512K(4928K), 0,0030476 secs] 7228K->3854K(15872K), 0,0031017 secs] [Times: user=0,00 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.699+0300: 0,622: [GC (Allocation Failure) 2018-07-31T21:29:23.699+0300: 0,622: [ParNew: 4928K->512K(4928K), 0,0022282 secs] 8270K->4496K(15872K), 0,0022770 secs] [Times: user=0,00 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.766+0300: 0,689: [GC (Allocation Failure) 2018-07-31T21:29:23.766+0300: 0,689: [ParNew: 4928K->512K(4928K), 0,0027286 secs] 8912K->5260K(15872K), 0,0027918 secs] [Times: user=0,00 sys=0,00, real=0,00 secs] 2018-07-31T21:29:23.885+0300: 0,808: [GC (Allocation Failure) 2018-07-31T21:29:23.885+0300: 0,808: [ParNew: 4928K->512K(4928K), 0,0029736 secs] 9676K->5806K(15872K), 0,0030553 secs] [Times: user=0,01 sys=0,00, real=0,00 secs] 2018-07-31T21:29:24.029+0300: 0,952: [GC (Allocation Failure) 2018-07-31T21:29:24.029+0300: 0,952: [ParNew: 4928K->512K(4928K), 0,0035641 secs] 10222K->6571K(15872K), 0,0036303 secs] [Times: user=0,00 sys=0,01, real=0,01 secs] 2018-07-31T21:29:24.101+0300: 1,024: [GC (Allocation Failure) 2018-07-31T21:29:24.101+0300: 1,024: [ParNew: 4928K->512K(4928K), 0,0038491 secs] 10987K->7721K(15872K), 0,0038949 secs] [Times: user=0,01 sys=0,00, real=0,00 secs] 2018-07-31T21:33:40.051+0300: 256,974: [GC (Allocation Failure) 2018-07-31T21:33:40.051+0300: 256,974: [ParNew: 4928K->512K(4928K), 0,0042898 secs] 12137K->8502K(15872K), 0,0043729 secs] [Times: user=0,01 sys=0,00, real=0,00 secs] Heap par new generation total 4928K, used 1819K [0xa4200000, 0xa4750000, 0xa9750000) eden space 4416K, 29% used [0xa4200000, 0xa4346f70, 0xa4650000) from space 512K, 100% used [0xa4650000, 0xa46d0000, 0xa46d0000) to space 512K, 0% used [0xa46d0000, 0xa46d0000, 0xa4750000) tenured generation total 10944K, used 7990K [0xa9750000, 0xaa200000, 0xb4200000) the space 10944K, 73% used [0xa9750000, 0xa9f1da40, 0xa9f1dc00, 0xaa200000) Metaspace used 10456K, capacity 10610K, committed 10648K, reserved 11568K ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 16:20 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Deemaas Код: plaintext 1. 2. 3. 4. 5. 6. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 16:30 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Basil A. Sidorov, Хз, Приложение стороннее, Разработчик такой конфиг заложил) Сам я с JVM к сожалению не очень знаком... Что в нем не так? ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 16:40 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
В интернетах пишут , что если клиент тупит на вводе пароля, то через минуту ожидания будет вот такой отлуп. P.S. Куча, которая может меняться от 16 мегабайт до четверти гигабайта - очень странно. Использование "дважды нестандартной опции" - ещё более странно. Garbage-First в Java 8 готов к промышленной эксплуатации, хотя и не включен по умолчанию. 32-разрядная клиентская JRE тоже странно. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 16:56 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
То что, в интернете пишут я знаю) Написал же, что не хочу менять стандартный параметр SQLNET.INBOUND_CONNECT_TIMEOUT равный 60 секунд. Мне он кажется волне разумный и ищу косяк на стороне ПО) ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:00 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
По поводу размера кучи, если мне не изменяет память то это стандартные параметры 16/256 если не указать xmx xms вручную ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:03 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
"Стандартные" это когда ничего не пишется шаловливыми ручонками. В моём, например, случае получается: Код: plaintext 1. 2. 3. 4. 5. 6. 7. 8. И всё это ещё и зависит от версии: Код: plaintext 1. 2. 3. 4. 5. 6. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:11 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
DeemaasА вот с Garbage Collection похоже Вы правы. Ошибки есть... Ошибок не увидел, задержки в 0,0025797 с. вообще шоколадно Потребление памяти в единицы мегабайт, вообще ни о чем. p.s. Allocation Fauilure - это причина по которой запускался GC. Все нормально, кончилась свободная память, GC и запустился. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:16 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
автор# java -XshowSettings:vm -version VM settings: Max. Heap Size (Estimated): 247.50M Ergonomics Machine Class: client Using VM: Java HotSpot(TM) Client VM java version "1.8.0_112" Java(TM) SE Runtime Environment (build 1.8.0_112-b15) Java HotSpot(TM) Client VM (build 25.112-b15, mixed mode) Да нет, вроде все нормально тут. 256 этому приложению должно хватать. Out of Memory в логе то нет... ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:17 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
DeemaasМне он кажется волне разумный и ищу косяк на стороне ПО)Тогда - простой поиск по hikarypool выдаёт, например, такое . ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:18 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
DeemaasДа нет, вроде все нормально тут. 256 этому приложению должно хватать. Out of Memory в логе то нет...Нормально это когда для долгоиграющего приложения ставят разумные -Xms/-Xmx с одинаковыми значениями. Теоретически, есть сценарий, когда это не так, но "меня опять терзают смутные сомнения ...". ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:20 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Basil A. Sidorov.... Куча, которая может меняться от 16 мегабайт до четверти гигабайта - очень странно. Использование "дважды нестандартной опции" - ещё более странно. IMHO Вы не правы. По логу приложение потребляет меньше 10 Mb, т.ч. 16 Mb - судя по всему экономили память. 256 Mb - похоже на всякий случай, вдруг 16Mb не хватит. -XX:+UseParNewGC - вполне нормальная опция, жестко выбрали алгоритм GC. Что, в общем, правильно. Под какой писали/тестировали, тот и в прод. -XX:+UseCMSInitiatingOccupancyOnly - никогда не сталкивался, но раз разработчики рекомендовали, должно быть у них были какие-то соображения. Особого вреда или "не стандартности" не видно. IMHO & AFAIK В общем, кроме исходной жалобы на timeout, лично я криминала не вижу ((( ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:22 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Leonid KudryavtsevПо логу приложение потребляет меньше 10 Mb, т.ч. 16 Mb - судя по всему экономили память. 256 Mb - похоже на всякий случай, вдруг 16Mb не хватит.Это свидетельствует ровно об одном - о неспособности (нежелании) разработчика адекватно оценить рабочий набор своего приложения в условиях заказчика. А это, в свою очередь, ставит под сомнение адекватность всех остальных выборов. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:26 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Приложение стандартное для всех заказчиков. Это шлюз. У кого-то много файлов, у кого-то мало через него проходит. Бывает, что в пик активности 16 Мб может не хватить, поэтому разрастется до 256 Мб. Вот и я не вижу пока в чем косяк... Если все-таки найду отпишусь) ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:34 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Тут сложно что нибудь советовать 1. С одной стороны, 7 запуском minor GC в одну секунду (2018-07-31T21:29:23) , это достаточно много. 2. С другой стороны, eden нормальной вычищается, full GC ни разу не сработал - т.е. eden'а вполне хватает, память распределена нормально Ну можно initial size увеличить, GC возможно станет меньше, хуже точно не будет. Но лично я по логу никакого криминала не вижу. Памяти вроде хватает. Еще вопрос: Сколько физических процессоров на клиентском компьютере (где Java работает) ? Сколько копий Java приложений работают одновременно ? P.S. Видел JVM которая использовалась для связи через RMI Java с Cobol'ом. Так там вроде вообще 512 Kb heap был ))) ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:37 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
авторConnection reset В нашем случае помогло указание параметра ENABLE=BROKEN в строке подключения, т.к. якобы без него keep-alive в JDBC не совсем работает: jdbc:oracle:thin:@(DESCRIPTION=(ENABLE=BROKEN)(ADDRESS=(PROTOCOL=TCP)(HOST=<host>)(PORT=<port>))(CONNECT_DATA=(SERVICE_NAME=<service>))) ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:39 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Leonid Kudryavtsev, На сервере/виртуалке выделено два ядра Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz и 4 Гб ОЗУ Помимо этого приложения (оно очень непрожорливое на мой взгляд) есть ещё 3 штуки. Одно прожорливое, для него я прописал -Xmx1024M, вроде хватает и ещё два маленьких. Т.е. всего 4 штуки. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 17:45 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 01.08.2018, 18:00 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Leonid Kudryavtsev, С фаерволом конечно идея хорошая, но сервер бд и приложение в одной сети, этот вариант тут не подойдет. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 02.08.2018, 09:38 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
DeemaasLeonid Kudryavtsev, С фаерволом конечно идея хорошая, но сервер бд и приложение в одной сети, этот вариант тут не подойдет. Фаерволл может работать и локально на машине. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 02.08.2018, 14:53 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
подытожу. Проблема была в энтропии ссылка Нужно было добавить в запуск приложения -Djava.security.egd=file:///dev/urandom либо добиться ее другими способами. ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 15.08.2018, 12:45 |
|
||
|
inbound connection timed out (ORA-3136)
|
|||
|---|---|---|---|
|
#18+
Deemaas, Спасибо за информацию. Веселая проблема. Никогда не сталкивался. Плотно работал только с weblogic (ADF) и в документации изначально советовали поменять генератор на не дефолтный. Не знал, что и с обычным JDBC могут быть проблемы. Enjoy Oracle on Linux/Unix! ))) ... |
|||
|
:
Нравится:
Не нравится:
|
|||
| 15.08.2018, 13:14 |
|
||
|
|

start [/forum/topic.php?fid=52&msg=39682149&tid=1883611]: |
0ms |
get settings: |
9ms |
get forum list: |
16ms |
check forum access: |
3ms |
check topic access: |
3ms |
track hit: |
154ms |
get topic data: |
9ms |
get forum data: |
3ms |
get page messages: |
71ms |
get tp. blocked users: |
1ms |
| others: | 208ms |
| total: | 477ms |

| 0 / 0 |
