操作問い合わせ #3768
未完了Jobが停止しない事象 ja_jobfile_check_processexist
ステータス:
回答中
優先度:
通常
担当者:
-
開始日:
2025/03/31
説明
お世話になります。
日次で実行しているジョブが、ある日終了せず実行中のままになる事象が発生しました。
恐れ入りますが、原因や対策についてご教示いただけますでしょうか。
■環境
・JobArrangerServer：Red Hat Enterprise Linux 8.10 / jobarranger-server-postgresql-7.0.0-1.el8.x86_64.rpm
・JobArrangerAgent：Windows Server 2022 Standard / Job Arranger Agent 7.0.0
■エラー内容
jobarg_agentd.log
34592:20250330:013309.073 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 96656
14396:20250330:013309.085 [INFO] In ja_agent_begin(),jobid: 96656, method: 0 is begin
34592:20250330:013309.094 [INFO] In ja_chk_job_begin(), running job does not exists. jobid :96656
34592:20250330:013309.115 [INFO] In ja_chk_job_begin(),job check finished. jobid: 96656
37268:20250330:013310.087 [ERROR] In ja_jobfile_check_processexist(), executing process found. dir :C:\Program Files\Job Arranger\Job Arranger Agent\temp\end, filename:96656-20250330013309-0000.job
37268:20250330:013310.100 [ERROR] In ja_job_check_beginfile() jobid: 96656 Job process is already running. process id : 0. delete [C:\Program Files\Job Arranger\Job Arranger Agent\temp\begin\96656-20250330013309.job] file.
37268:20250330:013310.121 [INFO] In job_to_error() [96656-20250330013309.job] file and it's data files will be moved to error folder.
37268:20250330:013310.140 [INFO] In job_to_error() [96656-20250330013309*] files will be moved from [data] and [close] folders to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\96656-20250330013309 folder.
37268:20250330:013310.154 [WARN] In job_to_error() [C:\Program Files\Job Arranger\Job Arranger Agent\temp\close\96656-20250330013309] folder does not exist. (No such file or directory)
37268:20250330:013310.170 [INFO] In job_to_error() move[96656-20250330013309.job] file from C:\Program Files\Job Arranger\Job Arranger Agent\temp\begin to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error folder.
37268:20250330:013310.189 [WARN] [96656-20250330013309.job] ,along with the data files, are moved to error folder.
37268:20250330:013310.203 [WARN] In read_datafile(),data file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json], [size : -1] is empty.
37268:20250330:013310.216 [INFO] In job_to_error() [96656-20250330013309-0000.job] file and it's data files will be moved to error folder.
37268:20250330:013310.233 [INFO] In job_to_error() [96656-20250330013309-0000*] files will be moved from [data] and [close] folders to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\96656-20250330013309-0000 folder.
37268:20250330:013310.254 [WARN] In job_to_error() [C:\Program Files\Job Arranger\Job Arranger Agent\temp\close\96656-20250330013309-0000] folder does not exist. (No such file or directory)
37268:20250330:013310.267 [INFO] In job_to_error() move[96656-20250330013309-0000.job] file from C:\Program Files\Job Arranger\Job Arranger Agent\temp\end to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error folder.
37268:20250330:013310.281 [WARN] [96656-20250330013309-0000.job] ,along with the data files, are moved to error folder.
37268:20250330:013310.303 [WARN] In read_json_data(), cannot parse json head. data[{ "sendretry": 0, "kind": "", "version": 0.000000, "serverid": "", "hostname": "", "data": { "type": "", "method": -1, "script": "", "argument": { }, "env": { }, "run_user": "", "run_user_password": "", "jobid": "0", "jobstatus": -1, "pid": 0, "message": "", "cur_unique_id": "", "pre_unique_id": "", "hostname": "SERVER1", "result": 0, "std_out": "", "std_err": "", "return_code": -1, "signal": 0 } }]. file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json].
37268:20250330:013310.317 [WARN] In read_datafile(),1 try : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json] file cannot be read.
37268:20250330:013310.329 [WARN] In read_json_data(), cannot parse json head. data[{ "sendretry": 0, "kind": "", "version": 0.000000, "serverid": "", "hostname": "", "data": { "type": "", "method": -1, "script": "", "argument": { }, "env": { }, "run_user": "", "run_user_password": "", "jobid": "0", "jobstatus": -1, "pid": 0, "message": "", "cur_unique_id": "", "pre_unique_id": "", "hostname": "SERVER1", "result": 0, "std_out": "", "std_err": "", "return_code": -1, "signal": 0 } }]. file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json].
37268:20250330:013310.345 [WARN] In read_datafile(),2 try : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json] file cannot be read.
37268:20250330:013310.363 [WARN] In read_json_data(), cannot parse json head. data[{ "sendretry": 0, "kind": "", "version": 0.000000, "serverid": "", "hostname": "", "data": { "type": "", "method": -1, "script": "", "argument": { }, "env": { }, "run_user": "", "run_user_password": "", "jobid": "0", "jobstatus": -1, "pid": 0, "message": "", "cur_unique_id": "", "pre_unique_id": "", "hostname": "SERVER1", "result": 0, "std_out": "", "std_err": "", "return_code": -1, "signal": 0 } }]. file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json].
37268:20250330:013310.377 [WARN] In read_datafile(),3 try : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json] file cannot be read.
37268:20250330:013310.389 [WARN] In read_json_data(), cannot parse json head. data[{ "sendretry": 0, "kind": "", "version": 0.000000, "serverid": "", "hostname": "", "data": { "type": "", "method": -1, "script": "", "argument": { }, "env": { }, "run_user": "", "run_user_password": "", "jobid": "0", "jobstatus": -1, "pid": 0, "message": "", "cur_unique_id": "", "pre_unique_id": "", "hostname": "SERVER1", "result": 0, "std_out": "", "std_err": "", "return_code": -1, "signal": 0 } }]. file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json].
37268:20250330:013310.404 [WARN] In read_datafile(),4 try : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json] file cannot be read.
37268:20250330:013310.421 [WARN] In read_json_data(), cannot parse json head. data[{ "sendretry": 0, "kind": "", "version": 0.000000, "serverid": "", "hostname": "", "data": { "type": "", "method": -1, "script": "", "argument": { }, "env": { }, "run_user": "", "run_user_password": "", "jobid": "0", "jobstatus": -1, "pid": 0, "message": "", "cur_unique_id": "", "pre_unique_id": "", "hostname": "SERVER1", "result": 0, "std_out": "", "std_err": "", "return_code": -1, "signal": 0 } }]. file [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json].
37268:20250330:013310.434 [WARN] In read_datafile(),5 try : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json] file cannot be read.
37268:20250330:013310.447 [WARN] In write_data_file(), file cannot be read after writing file.file : [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\96656-20250330013309.json]
36748:20250330:013409.109 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 96656
36748:20250330:013409.120 [INFO] In ja_chk_job_begin(), job information exists.jobid :96656, folder_type :5
36748:20250330:013413.185 [ERROR] In create_check_res_files(), 96656-20250330013309.json File move failed from C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\96656-20250330013309-0000 to C:\Program Files\Job Arranger\Job Arranger Agent\temp\data
36748:20250330:013413.195 [ERROR] In ja_chk_job_begin(), Cannot create check result files. jobid : 96656
53204:20250330:013509.141 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 96656
53204:20250330:013509.152 [INFO] In ja_chk_job_begin(), job information exists.jobid :96656, folder_type :5
53204:20250330:013513.217 [ERROR] In create_check_res_files(), 96656-20250330013309.json File move failed from C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\96656-20250330013309-0000 to C:\Program Files\Job Arranger\Job Arranger Agent\temp\data
53204:20250330:013513.226 [ERROR] In ja_chk_job_begin(), Cannot create check result files. jobid : 96656
・・・以降約1分毎に同様のログ出力繰り返し
5584:20250330:013609.182 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 96656
5584:20250330:013609.193 [INFO] In ja_chk_job_begin(), job information exists.jobid :96656, folder_type :5
5584:20250330:013613.258 [ERROR] In create_check_res_files(), 96656-20250330013309.json File move failed from C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\96656-20250330013309-0000 to C:\Program Files\Job Arranger\Job Arranger Agent\temp\data
5584:20250330:013613.272 [ERROR] In ja_chk_job_begin(), Cannot create check result files. jobid : 96656
■確認内容
目立つエラーとしては[ja_jobfile_check_processexist]があります。
以下2件、似た事象がありましたが、少し異なるエラーが出ていたり、未解決のようでした。
https://www.jobarranger.info/community/issues/3763
https://www.jobarranger.info/community/issues/3748
以上、恐れ入りますが、何卒よろしくお願いいたします。
ファイル
保守サポート 担当333 さんが5ヶ月前に更新
- ステータス を 担当者処理中 から 回答中 に変更
- 担当者 を削除 (
保守サポート 担当333)
34592:20250330:013309.073 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 96656 //job check process 14396:20250330:013309.085 [INFO] In ja_agent_begin(),jobid: 96656, method: 0 is begin // actual job run
ジョブチェックプロセスが実際のジョブ処理の前に実行されるために発生します。今後のJAZリリースでこの問題を修正する予定です。
匿名ユーザー さんが約2ヶ月前に更新
ご担当者様
お世話になっております。
先日は調査、ご返答頂きまして、誠にありがとうございます。
先日同環境で、似た事象のエラーが発生しました。
前回原因としてご提示いただいたログが出ていますが、エラー時の動作が少し異なります。
こちら、同原因の事象であるか、別事象であれば何か対策を実施できるかなど、ご教示いただけますでしょうか。
■事象
・Agentログより、前回エラー同様、ジョブチェックプロセスがジョブ処理の前に実行されたよう、ログから確認できます。
・ただし、前回エラーでは「ジョブが実行中のまま」となったのに対して、今回エラーでは「ジョブがすぐに以下エラー」となりました。
jobarg_server.log
3521669:20250729:061250.676 [ERROR] [JAJOBNETRUN000001] In ja_set_runerr() job execution error. inner_job_id: 571367 status is RUNERR jobnet_id: AAA_2_DA1_01, job_id: AAA_2_DA1_01/1_DA2_01_19/01_15_21, user_name: Server-Sub-Admin, job_exit_cd: -1, icon_status: 2
jobarg_agentd.log
491308:20250729:060840.983 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 571367 474984:20250729:060840.995 [INFO] In ja_agent_begin(),jobid: 571367, method: 0 is begin 491308:20250729:060841.005 [INFO] In ja_chk_job_begin(), running job does not exists. jobid :571367 491308:20250729:060841.027 [INFO] In ja_chk_job_begin(),job check finished. jobid: 571367 491308:20250729:060841.036 [INFO] In ja_chk_job_begin(), job status check starts. jobid : 108807 491308:20250729:060841.047 [INFO] In ja_chk_job_begin(), job information exists.jobid :108807, folder_type :5 491228:20250729:060841.638 [ERROR] In ja_jobfile_check_processexist(), executing process found. dir :C:\Program Files\Job Arranger\Job Arranger Agent\temp\end, filename:571367-20250729060841-0000.job 491228:20250729:060841.647 [ERROR] In ja_job_check_beginfile() jobid: 571367 Job process is already running. process id : 0. delete [C:\Program Files\Job Arranger\Job Arranger Agent\temp\begin\571367-20250729060840.job] file. 491228:20250729:060841.656 [INFO] In job_to_error() [571367-20250729060840.job] file and it's data files will be moved to error folder. 491228:20250729:060841.666 [INFO] In job_to_error() [571367-20250729060840*] files will be moved from [data] and [close] folders to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error\571367-20250729060840 folder. 491228:20250729:060841.675 [WARN] In job_to_error() [C:\Program Files\Job Arranger\Job Arranger Agent\temp\close\571367-20250729060840] folder does not exist. (No such file or directory) 491228:20250729:060841.685 [INFO] In job_to_error() move[571367-20250729060840.job] file from C:\Program Files\Job Arranger\Job Arranger Agent\temp\begin to C:\Program Files\Job Arranger\Job Arranger Agent\temp\error folder. 491228:20250729:060841.694 [WARN] [571367-20250729060840.job] ,along with the data files, are moved to error folder. 491228:20250729:060841.762 [INFO] In ja_agent_send(), job id :571367 sent OK, job result sent to server IP 10.96.19.84, 491228:20250729:060841.771 [INFO] In ja_agent_close() jobid: 571367 closed OK, status: -1, server ip : 10.96.19.84 491228:20250729:060841.782 [INFO] In ja_jobfile_remove(),job id :571367's all data have been archived in close-folder.
以上、恐れ入りますが、何卒よろしくお願いいたします。
保守サポート 担当360 さんが約1ヶ月前に更新
- ステータス を 担当者処理中 から 回答中 に変更
- 担当者 を削除 (
保守サポート 担当360)
こちら同原因の事象となります。
一時的な対応策として、以下のクエリを実行することで解決いただけます。
〇MySQLの場合
UPDATE ja_run_job_table AS target
JOIN (
SELECT inner_job_id
FROM ja_run_job_table
WHERE inner_jobnet_main_id = your_management_id
AND status IN (2, 6)
) AS source
ON target.inner_job_id = source.inner_job_id
SET target.status = 4;
〇PostgreSQLの場合
UPDATE ja_run_job_table AS target
SET status = 4
FROM (
SELECT inner_job_id
FROM ja_run_job_table
WHERE inner_jobnet_main_id = your_management_id
AND status IN (2, 6)
) AS source
WHERE target.inner_job_id = source.inner_job_id;
※your_management_id は、ジョブ実行管理画面に表示される管理IDを指します。