止った処理が数時間後動き出したようにみえる現象
Javaのシステムで、11月16日(土)の午後運用中に、画面のボタンを押しても、サーバーから応答が返ってこないので、他のボタンを押したところ返ってきた処理もあれば、返ってこない処理もありました。
その後、数時間経ってから(真夜中に)、サーバーで返ってこなかった処理が実行されているようなLogが取れました。
サーバーの環境は、OS:CentOS5、java:jdk1.4、Tomcat5.0、PostgreSQL を使っています。
Tomcatを再起動すると正常に動作します。
以下にその時のアプリケーションLOGを示します。
通常、画面でボタン操作などを行うと、サーバーでは「開始」LOGを出力し、処理が終わると「終了」LOGを出力します。
1行目2013/11/16 13:53:56 実行予算内訳書 予算変更画面 初期画面処理開始の後、終了Logが出されるのは、10時間44分後の2013/11/17 00:37:16です。
また、2013/11/16 13:54:21予定日報入力 初期画面処理 開始の後、終了Logが出されるのは、10時間43分後の2013/11/17 00:37:32です。
このようなことがどうして起こるのでしょうか。
原因をどのようにして調査すればよいのでしょうか。
よろしくご教授ください。
アプリケーションLOG
2013/11/16 13:53:56.00978 INFO- 工事 太郎 実行予算内訳書 予算変更画面 初期画面処理開始。
2013/11/16 13:54:17.00419 INFO- 工事 太郎 予定日報入力 初期画面処理 開始。
2013/11/16 13:54:21.00179 INFO- 工事 太郎 日報入力 初期画面処理
2013/11/16 13:54:34.00680 INFO- 工事 太郎 日報入力 戻るボタン処理 開始。
2013/11/16 13:54:34.00693 INFO- 工事 太郎 日報入力 戻るボタン処理終了。
2013/11/16 13:54:36.00365 INFO- 工事 太郎 実行予算内訳書 修正画面 新規作成画面処理開始。
2013/11/16 13:54:36.00367 INFO- 工事 太郎 実行予算内訳書 修正画面 新規作成画面処理終了。
2013/11/16 13:54:40.00022 INFO- 工事 太郎 実行予算内訳書 修正画面 戻るボタン処理開始。
2013/11/16 13:54:40.00023 INFO- 工事 太郎 実行予算内訳書 修正画面 戻るボタン処理終了。
2013/11/16 13:54:44.00312 INFO- 工事 太郎 実績原価月次修正 初期画面処理
2013/11/17 00:37:16.00811 INFO- 工事 太郎 実行予算内訳書 予算変更画面 初期画面処理終了。
2013/11/17 00:37:23.00505 INFO- 工事 太郎 実績原価月次修正 初期画面処理終了。
2013/11/17 00:37:32.00413 INFO- 工事 太郎 予定日報入力 初期画面処理終了。
2013/11/17 00:37:32.00561 INFO- 工事 太郎 実績原価月次修正 初期画面処理終了。