SUSE - Error job server

Hello,

Since we have installed job server on SLES (http://en.community.dell.com/techcenter/iam/f/4789/t/19561200#87389), we meet the error above. We have to restart the daemon to fix it, but this is really problematic.

Does someone have already encountered this problem?

2013-10-02 00:07:57 +02:00 - VI.JobService.JobComponents.ScriptComponent - 136eaa8f-06ca-4a35-80f6-669a21c4354e: Errors occured
    [System.ComponentModel.Win32Exception] ApplicationName='mono', CommandLine='StdioProcessor.exe', CurrentDirectory='/home/visvc/JobService'
      at VI.JobService.ProcessorStdIO.ExecuteJob (VI.Base.JobProcessing.Job job) [0x00000] in <filename unknown>:0
      at VI.JobService.ProcessorStdIO.get__Processor () [0x00000] in <filename unknown>:0
      at VI.JobService.ProcessorStdIO._StartProcessor (System.String exe, System.String args) [0x00000] in <filename unknown>:0
      at System.Diagnostics.Process.Start (System.Diagnostics.ProcessStartInfo startInfo) [0x00000] in <filename unknown>:0
      at System.Diagnostics.Process.Start_common (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0
      at System.Diagnostics.Process.Start_noshell (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0

Regards,

Serge

  • Hello,

    I come back with more information on the error.

    Today the error appear for the first time at 10:07 as you can see in job server logs above.

    2013-10-16 10:06:05 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 10:06:05 +02:00 - Info: Last process step request succeeded.

    2013-10-16 10:06:05 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 10:06:05 +02:00 - Process step parameter E8D82BE7-640A-8BA2-E040-007F02000E39:
    [Job]
    ComponentAssembly=HandleObjectComponent
    ComponentClass=VI.JobService.JobComponents.HandleObjectComponent
    Task=FIREGENEVENT
    Executiontype=INTERNAL
    [Parameters]
    ConnectionProvider=VI.DB.Oracle.ViOracleFactory,VI.DB.Oracle
    EventName=UpdateUTCOffset
    ObjectType=DialogTimeZone
    ProcID=E8D82BE7-6409-8BA2-E040-007F02000E39
    WhereClause=ShortName = 'UTC'
    _paramName1=StopTime
    _paramValue1=2200-01-01 00:00:00
    2013-10-16 10:06:05 +02:00 - Info: Last process step request succeeded.

    2013-10-16 10:06:06 +02:00 - Info: Loading configuration parameters...

    2013-10-16 10:06:17 +02:00 - Info: Loading table definition for DialogTimeZone...

    2013-10-16 10:06:17 +02:00 - Info: Loading table definition for JobServer...

    2013-10-16 10:06:17 +02:00 - Info: Loading table definition for DialogTable...

    2013-10-16 10:06:17 +02:00 - Info: Loading table definition for DialogDatabase...

    2013-10-16 10:06:17 +02:00 - Info: Loading table definition for JobQueue...

    2013-10-16 10:06:17 +02:00 - VI.JobService.JobComponents.HandleObjectComponent - E8D82BE7-640A-8BA2-E040-007F02000E39: Successful
        The event  UpdateUTCOffset was triggered for 1 object(s) of type DialogTimeZone.
    2013-10-16 10:07:35 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 10:07:35 +02:00 - Info: Last process step request succeeded.

    2013-10-16 10:07:35 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 10:07:35 +02:00 - Process step parameter 8381cdbf-b504-48c3-8b23-ebbf7cc4ca3d:
    [Job]
    ComponentAssembly=ScriptComponent
    ComponentClass=VI.JobService.JobComponents.ScriptComponent
    Task=SCRIPTEXEC
    Executiontype=EXTERNAL
    [Parameters]
    ConnectionProvider=VI.DB.Oracle.ViOracleFactory,VI.DB.Oracle
    ProcID=E8D82BE7-6409-8BA2-E040-007F02000E39
    ScriptName=VID_TimeZone_Update_UTCOffSet
    2013-10-16 10:07:35 +02:00 - VI.JobService.JobComponents.ScriptComponent - 8381cdbf-b504-48c3-8b23-ebbf7cc4ca3d: Errors occured
        [System.ComponentModel.Win32Exception] ApplicationName='mono', CommandLine='StdioProcessor.exe', CurrentDirectory='/home/visvc/JobService'
          at VI.JobService.ProcessorStdIO.ExecuteJob (VI.Base.JobProcessing.Job job) [0x00000] in <filename unknown>:0
          at VI.JobService.ProcessorStdIO.get__Processor () [0x00000] in <filename unknown>:0
          at VI.JobService.ProcessorStdIO._StartProcessor (System.String exe, System.String args) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start (System.Diagnostics.ProcessStartInfo startInfo) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start_common (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start_noshell (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0

    Then it occurs at 11:01 and since this time every 5 minutes, always in a similar way.

    2013-10-16 11:09:05 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 11:09:05 +02:00 - Info: Last process step request succeeded.

    2013-10-16 11:09:05 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 11:09:05 +02:00 - Process step parameter E7BE5A37-3D84-06D3-E040-007F020042FC:
    [Job]
    ComponentAssembly=HandleObjectComponent
    ComponentClass=VI.JobService.JobComponents.HandleObjectComponent
    Task=FIREGENEVENT
    Executiontype=INTERNAL
    [Parameters]
    ConnectionProvider=VI.DB.Oracle.ViOracleFactory,VI.DB.Oracle
    EventName=Checkversion
    ObjectType=Jobserver
    ProcID=3DF35CB0-23B2-401E-9BF8-8D8AF924A071
    WhereClause=uid_jobserver = 'E2676F41-9650-1CF8-E040-007F02006D4A'
    2013-10-16 11:09:05 +02:00 - Info: Last process step request succeeded.

    2013-10-16 11:09:06 +02:00 - Info: Loading configuration parameters...

    2013-10-16 11:09:17 +02:00 - Info: Loading table definition for JobServer...

    2013-10-16 11:09:17 +02:00 - Info: Loading table definition for DialogTable...

    2013-10-16 11:09:21 +02:00 - Info: Loading table definition for DialogDatabase...

    2013-10-16 11:09:24 +02:00 - Info: Loading table definition for JobQueue...

    2013-10-16 11:09:24 +02:00 - VI.JobService.JobComponents.HandleObjectComponent - E7BE5A37-3D84-06D3-E040-007F020042FC: Successful
        The event  Checkversion was triggered for 1 object(s) of type Jobserver.
    2013-10-16 11:10:35 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 11:10:35 +02:00 - Info: Last process step request succeeded.

    2013-10-16 11:10:35 +02:00 - Info: Requesting process steps for queue \srv-oracle-int.

    2013-10-16 11:10:35 +02:00 - Process step parameter 3e9f1421-bc9e-4fd0-92a5-a917beee1d7f:
    [Job]
    ComponentAssembly=JobService
    ComponentClass=VI.JobService.JobComponents.AutoUpdateComponent
    Task=GETREVISION
    Executiontype=INTERNAL
    [Parameters]
    ProcID=3DF35CB0-23B2-401E-9BF8-8D8AF924A071
    2013-10-16 11:10:35 +02:00 - VI.JobService.JobComponents.AutoUpdateComponent - 3e9f1421-bc9e-4fd0-92a5-a917beee1d7f: Successful
    2013-10-16 11:10:35 +02:00 - Process step output parameter 3e9f1421-bc9e-4fd0-92a5-a917beee1d7f:
    SoftwareDate=2013-09-11 12:35:48
    SoftwareRevision=2002955
    2013-10-16 11:10:35 +02:00 - Process step parameter 8e0368de-58b2-4e5e-9c4d-4336038cead2:
    [Job]
    ComponentAssembly=ScriptComponent
    ComponentClass=VI.JobService.JobComponents.ScriptComponent
    Task=SCRIPTEXEC
    Executiontype=EXTERNAL
    [Parameters]
    ConnectionProvider=VI.DB.Oracle.ViOracleFactory,VI.DB.Oracle
    PassParametersAndResult=True
    ProcID=3DF35CB0-23B2-401E-9BF8-8D8AF924A071
    ScriptName=VID_CreateUpdateFilesList
    SoftwareDate=2013-09-11 12:35:48
    SoftwareRevision=2002955
    2013-10-16 11:10:35 +02:00 - VI.JobService.JobComponents.ScriptComponent - 8e0368de-58b2-4e5e-9c4d-4336038cead2: Errors occured
        [System.ComponentModel.Win32Exception] ApplicationName='mono', CommandLine='StdioProcessor.exe', CurrentDirectory='/home/visvc/JobService'
          at VI.JobService.ProcessorStdIO.ExecuteJob (VI.Base.JobProcessing.Job job) [0x00000] in <filename unknown>:0
          at VI.JobService.ProcessorStdIO.get__Processor () [0x00000] in <filename unknown>:0
          at VI.JobService.ProcessorStdIO._StartProcessor (System.String exe, System.String args) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start (System.Diagnostics.ProcessStartInfo startInfo) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start_common (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0
          at System.Diagnostics.Process.Start_noshell (System.Diagnostics.ProcessStartInfo startInfo, System.Diagnostics.Process process) [0x00000] in <filename unknown>:0


    Since 10:07 (first occurence of the error), I don't have StdioProcessor log files anymore...

    You can find an overview of our process history in screens above.

    process_history.PNG

    process_history_2.PNG

    It seems we have a problem with the VID_JobServerUpdateCheck job, but we don't know how fix it.

    Regards,

    Serge