Java10.0.2、Tomcat9.0.13でアプリだけが応答なしになるときがある

前提・実現したいこと

Windows Server2016にJava10.0.2、Tomcat9.0.13、Oracle12cR2でWebアプリを2つ（アプリA、アプリB）動かしています。

アプリA・・・メインアプリ
アプリB・・・Tomcat上にアプリBを置き、そのtomcatが正しく動いているか、DBから期待する値を取ってきて期待する値がとれていれば正常稼働している、と判断するためだけのアプリ。

どのタイミングで問題が発生するか不定期なのですが、アプリAが応答なしになるときがあり、その時はTomcat毎サービスを再起動することで復旧させているのですが、原因がわかりません。

アプリAはTomcat5からTomcat7まで動かしている実績があり、Tomcat5,7でその現象が発生したことはありません。

何か調べるポイントや、原因がわかる、などありましたらご教授いただければ幸いです。

発生している問題・エラーメッセージ

エラーメッセージ

該当のソースコード

ソースコード

試したこと

アプリAが応答なしになった際に、アプリBは正しく動作しているため、Tomcat自体が死んでいるとは思えず、アプリAに関してだけが応答なし状態です。

tomcatが標準出力するログには何も出ておらず、正直行き詰っております。

DBへのコネクションクローズについては、プログラム側でクローズ漏れはなく、
コンテキストにも以下の設定を入れているためクローズ漏れがあったとしても
自動クローズしているものと認識しています。
removeAbandoned="true"
removeAbandonedTimeout="300"
logAbandoned="true"

補足情報（FW/ツールのバージョンなど）

ここにより詳細な情報を記載してください。

Orlofsky

2019/03/06 03:03

質問に現象を再現できるコードなど最小限の情報を提示すると適切なコメントが付きやすいです。

nakai_hiro

2019/03/06 03:25

ご指摘ありがとうございます。ソースコード云々というより、tomcatとアプリAの連携が応答なし状態に入ってからされていないような感じです。 Apacheとのajp連携も、tomcatの8080ポートからもアプリAの応答なしになってしまいます。きっかけは分からないのですが、朝からアプリAを使い出し、現象発生するタイミングとしてはだいたい14時頃に発生します。ある程度なにかが蓄積されると発生するのか、と思っていますが、ログが出ないため行き詰まってます。同じ経験をされた方がいらっしゃれば伝わりやすいのかも知れませんが…

Orlofsky

2019/03/06 03:30

Tomcatが怪しいならTomcatのログを調べましたか？

nakai_hiro

2019/03/06 03:31

それがログに何も出ていないのです…

Orlofsky

2019/03/06 04:09

他のログも調べましたか？ソフトウェアは上位互換が保証されているとは思わないでかかってください。

nakai_hiro

2019/03/07 10:55

gc.logを出す設定にしていなかったので、とりあえずログ出力の設定を入れて様子見しています。現象が発生した際に確認してみます。

行動規範の内容に同意します

回答2件

実は解決せず、また再現しましたのでさらに調査したところ、

これと同じ状況でした。
https://codeday.me/jp/qa/20190201/199986.html

かといってコネクションプーリングは設定しているし・・・と思ったのですが、
Tomcat7とTomcat9で設定項目名が違っており、Tomcatのログに起動時の「警告」が出ており、「その設定値はないから、初期値が適用されます」という旨のメッセージが出ていました。

13-May-2019 15:02:00.298 警告 [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = xxxDataSource Property maxActive is not used in DBCP2, use maxTotal instead. maxTotal default value is 8. You have set value of "200" for "maxActive" property, which is being ignored.
13-May-2019 15:02:00.298 警告 [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = xxxDataSource Property removeAbandoned is not used in DBCP2, use one or both of removeAbandonedOnBorrow or removeAbandonedOnMaintenance instead. Both have default value set to false. You have set value of "true" for "removeAbandoned" property, which is being ignored.
13-May-2019 15:02:00.298 警告 [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = xxxDataSource Property maxWait is not used in DBCP2 , use maxWaitMillis instead. maxWaitMillis default value is -1. You have set value of "10000" for "maxWait" property, which is being ignored.
13-May-2019 15:02:00.298 情報 [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = xxxDataSource Ignoring unknown property: value of "30000" for "validationInterval" property

現象が発生した箇所の時間帯ばかり見ていましたが、起動時の警告は見落としてました。

https://techblog.gmo-ap.jp/2018/12/06/tomcat7%E3%81%8B%E3%82%899%E3%81%B8%E3%81%AE%E3%82%A2%E3%83%83%E3%83%97%E3%82%B0%E3%83%AC%E3%83%BC%E3%83%89/

結局指定していたコネクションプーリングの値は無視され、初期値かつ
maxWaitMillis default value is -1　で無制限に待つというのがアプリが固まっていた原因のようでした。

Tomcat9で有効な設定項目にしたことで問題は解決しました。

投稿2019/05/13 23:37

nakai_hiro

総合スコア13