その他問い合わせ #3740
openジョブが実行中に異常終了する
Description
ジョブでmove処理を実行させているが、途中で処理が異常終了し一部ファイルが移動されませんでした。
原因と対策を調査回答頂けないでしょうか。
実行しているコマンドは以下Move /y 【移動元】\*.txt 【移動先】\
JOBARG_MESSAGEに以下が出力していた。Check job status(end) failed. jobid: 70828
jobarg_agentd.logを抜粋
20324:20240722:040630.220 [INFO] In ja_agent_begin(),jobid: 70828, method: 0 is begin 8720:20240722:040630.232 [INFO] In ja_jobfile_remove(),job id :70830's all data have been archived in close-folder. 15224:20240722:040632.281 [INFO] In ja_job_check_beginfile(),job id :70828[70828-20240722040630.job] is found, and will be starting. 15224:20240722:040632.293 [INFO] In ja_agent_run(),jobid: 70828 process started.Status: 0 15224:20240722:040632.352 [INFO] In ja_job_check_beginfile(),job id :70828[70828-20240722040630.job] started and deleted begin file. 15224:20240722:040632.363 [INFO] In ja_job_checkprocess() No pid yet in [70828-20240722040630-.job] file. 13692:20240722:040633.393 [ERROR] In ja_job_checkprocess(), jobid: 70828 job execution is incomplete. 23036:20240722:040634.424 [ERROR] In ja_job_checkprocess(), jobid: 70828 job execution is incomplete. 23036:20240722:040634.448 [ERROR] In ja_jobfile_chkend() jobfile check failed.One of [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\70828-20240722040630-3944.end.ret] or [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\70828-20240722040630-3944.end.start] or [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\70828-20240722040630-3944.end.end] files are empty. 23036:20240722:040634.460 [ERROR] In ja_job_exec_end(), job file check end failed. 23036:20240722:040634.472 [INFO] In ja_job_exec_end(), job id : 70828,total files read :1, process id : 3944 finished. 23036:20240722:040634.492 [ERROR] Can not open the file: C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\70828-20240722040630-3944.ret (No such file or directory) 23036:20240722:040634.504 [ERROR] In ja_job_exec_close(), job file load process failed. 23036:20240722:040634.635 [INFO] In ja_agent_send(), job id :70828 sent OK, job result sent to server IP xxx.xxx.xxx.xxx, 23036:20240722:040634.646 [INFO] In ja_agent_close() jobid: 70828 closed OK, status: 2, server ip : xxx.xxx.xxx.xxx
Files
Updated by 保守サポート 担当333 8 months ago
- Status changed from 担当者処理中 to 回答中
調査に伴い以下の情報を連携していただきたいのですが、可能でしょうか。
ご確認よろしくお願いいたします。
1) jobarg_agent.log ファイルのログの提供をお願いいたします。
2) ファイルが存在するか確認するために、以下のコマンドを実行をお願いいたします。
for %F in ("C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\*70828-20240722040630-3944*") do @echo %~zF bytes - %F
Updated by Anonymous 7 months ago
ご回答頂きありがとうございます。
1) jobarg_agent.log ファイルのログの提供をお願いいたします。
→添付いたします。
2) ファイルが存在するか確認するために、以下のコマンドを実行をお願いいたします。
→実行しましたが該当のファイルは存在していませんでした。
Updated by 保守サポート 担当333 7 months ago
- Status changed from 回答中 to 担当者処理中
- Assignee set to 保守サポート 担当333
Updated by 保守サポート 担当333 7 months ago
- Assignee deleted (
保守サポート 担当333)
回答が遅くなり申し訳ございません。
本件については現在も調査中になります。
回答まで今しばらくお待ちください。
Updated by Anonymous 7 months ago
- File 20240903.zip 20240903.zip added
お世話になっております。
本日、同サーバーの別のジョブで、同事象と思われるエラーが発生しました。
ログやJobArrangerAgentフォルダの対象ジョブのものと思われるファイルを取得しましたので、本件も合わせて調査いただけますと幸いです。
・JOBARG_MESSAGE:Check job status(end) failed. jobid 206030
・jobarg_agentd.log
18040:20240903:040508.971 [INFO] In ja_agent_begin(),jobid: 206030, method: 0 is begin 9520:20240903:040510.935 [INFO] In ja_job_check_beginfile(),job id :206030[206030-20240903040508.job] is found, and will be starting. 9520:20240903:040510.947 [INFO] In ja_agent_run(),jobid: 206030 process started.Status: 0 9520:20240903:040511.017 [INFO] In ja_job_check_beginfile(),job id :206030[206030-20240903040508.job] started and deleted begin file. 9520:20240903:040511.029 [INFO] In ja_job_checkprocess() No pid yet in [206030-20240903040508-.job] file. 15716:20240903:040512.062 [ERROR] In ja_job_checkprocess(), jobid: 206030 job execution is incomplete. 5380:20240903:040513.092 [ERROR] In ja_job_checkprocess(), jobid: 206030 job execution is incomplete. 5380:20240903:040513.115 [ERROR] In ja_jobfile_chkend() jobfile check failed.One of [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\206030-20240903040508-23448.end.ret] or [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\206030-20240903040508-23448.end.start] or [C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\206030-20240903040508-23448.end.end] files are empty. 5380:20240903:040513.127 [ERROR] In ja_job_exec_end(), job file check end failed. 5380:20240903:040513.139 [INFO] In ja_job_exec_end(), job id : 206030,total files read :1, process id : 23448 finished. 5380:20240903:040513.160 [ERROR] Can not open the file: C:\Program Files\Job Arranger\Job Arranger Agent\temp\data\206030-20240903040508-23448.ret (No such file or directory) 5380:20240903:040513.172 [ERROR] In ja_job_exec_close(), job file load process failed. 5380:20240903:040513.332 [INFO] In ja_agent_send(), job id :206030 sent OK, job result sent to server IP 192.168.249.15, 5380:20240903:040513.344 [INFO] In ja_agent_close() jobid: 206030 closed OK, status: 2, server ip : 192.168.249.15 9748:20240903:040515.089 [INFO] In ja_agent_begin(),jobid: 206816, method: 0 is begin 5380:20240903:040521.470 [ERROR] In ja_jobfile_remove() file move failed for [206030-20240903040508-23448.start] 5380:20240903:040521.482 [ERROR] In ja_job_exec_close() jobid: 206030,job file[206030-20240903040508-23448.*] under data folder move failed.
・同ジョブについて、エラー発生後に再度実行した際は、正常に動作しました。
・添付ファイルについて、ジョブIDの206030が付くファイルを取得しております。(一部サーバー名、ジョブコマンド部分は修正させていただいております)
以上、何卒よろしくお願いいたします。
Updated by 保守サポート 担当333 6 months ago
- Status changed from 担当者処理中 to 回答中
原因を調査したところJAZ側のバグだと確認しました。
次のバージョン ( 7.0.1 、 6.1.9 、 6.0.9 ) で修正する予定となります。
Updated by Anonymous 6 months ago
調査頂きまして誠にありがとうございます。
原因を調査したところJAZ側のバグだと確認しました。
こちら、バグの詳細や発生条件等、可能な範囲でご教示頂くことは可能でしょうか。
弊社では複数環境で同バージョンのJobArrangerを利用させていただいておりますが、
こちらのエラーは問い合わせさせていただいた1環境でのみ発生しているため、
発生しやすい条件などがありましたら参考にさせていただきたく存じます。
次のバージョン ( 7.0.1 、 6.1.9 、 6.0.9 ) で修正する予定となります。
こちらのリリース時期について、概ねいつ頃になるかご教示頂くことは可能でしょうか。
現在リリースされているバージョン番号(6.1.5.1 / 6.0.5.1)から離れたバージョンなので、
ある程度先のリリースとなりますでしょうか。
Updated by 保守サポート 担当333 4 months ago
名前変更機能が失敗する原因として考えられるのは、リソースが別のプロセスで使用されている場合に発生するリソース使用率 (EBUSY) です。このエラーはタイミングの問題が原因で発生した可能性があります。
また、バージョン 7.0.1、6.1.9、および 6.0.9 のリリース日は、 12 月末頃になる可能性があります。