Project

General

Profile

Edit Actions

その他問い合わせ #3740

open

ジョブが実行中に異常終了する

Added by Anonymous 8 months ago. Updated 4 months ago.

Status:
回答中
Priority:
通常
Assignee:
-
Start date:
07/24/2024

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

jobarg_agentd.log.old.zip (816 KB) jobarg_agentd.log.old.zip Anonymous, 08/08/2024 10:11 AM
20240903.zip (50.2 KB) 20240903.zip Anonymous, 09/03/2024 02:20 PM

Updated by Anonymous 8 months ago

エージェント側
OS:Windows Server 2022 Standard
JobArrangerAgent:5.1.2
JobArrangerManager:5.1.2
ZabbixAgent:5.0.6

サーバー側
OS:Red Hat Enterprise Linux 8.8
JobArrangerServer:5.1.2
ZabbixServer:5.0.42

Actions #2

Updated by 保守サポート 担当333 8 months ago

  • Status changed from 新規登録 to 担当者処理中

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) ファイルが存在するか確認するために、以下のコマンドを実行をお願いいたします。
→実行しましたが該当のファイルは存在していませんでした。

Actions #5

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

お世話になっております。
本日、同サーバーの別のジョブで、同事象と思われるエラーが発生しました。
ログや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

原因は同じ可能性が高いと思われます。
原因については現在も調査しております。

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 月末頃になる可能性があります。

Edit Actions

Also available in: Atom PDF