Unrecoverable application error: se.call.process in panel call.rad.1

Hi, I'm having an issue in SM 9.34 with Proccess Designer P2, sometimes only, a Change DCAB approval phase is not closed automatically altough the approval.status changes to approved and, in the activity table, it's says "This change's approval status is changed"

At the same date/time of that activity, there's a record in the msglog table with the message :

Unrecoverable application error: se.call.process in panel call.rad.1

Please, I need help to understand what could be happening and why aleatory (that means : I can`t reproduce the issue)

As a workaround, I click de Close button and then the phase changes from DCAB Approval to Deployment

Best Regards.

 

  • Hi Norita,

    hope you are doing fine.

    I was investigating the message you posted, it is very common but with SD and IM, I tried to relate the message to changes but until now I have not ofund a workaround for that or the cause of the issue, please let me look for more in a deep investigation and hope come back with an update.

  • Carlos : thank you very much for your answer. Meanwhile I'll look at the log files to share additional information to this post

    Regards,

  • Hi Carlos,  I've found this log information.  I hope it can help.

     

    6124( 2840) 04/17/2017 18:34:34 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:34 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:34 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:34 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:34 RTE I dbUpdate: Error al intentar actualizar un registro
    6124( 2840) 04/17/2017 18:34:34 RTE I dbUpdate: file:(cm3r) key:(header,number=CH00643545)
    6124( 2840) 04/17/2017 18:34:34 RAD I The exit code is: final
    6124( 2840) 04/17/2017 18:34:34 RAD I The action code is: lockok
    6124( 10500) 04/17/2017 18:34:34 JRTE I LockHandler: try to unlock: cm3r;CH00643545, but it has not ever been locked.

    Best Regards,

  • Hi Norita,

    one question, is this OOB environment? if not, what versions are you using.

    please share exactly the steps you are following to try to reproduce the issue.

    could you please share a little more for the sm.log file?

  • Hi Carlos,  I'm working with SM 9.34 with Proccess Designer P2  and it isn't an OOB environmet

    This issue only happens sometimes in the Production environment and it cannot be reproduced because, I think, may be there's a query that fails or a data collission that causes the dbUpdate: Error al intentar actualizar un registro

    I've found one case : the number=CH00643545 and the user U532028  that followed the steps to approve that Change.  The approve is performed by clicking a button "Aprobar".

    Here's more of the log file.  Thanks in advance.

      6124( 2840) 04/17/2017 18:34:32 RTE A SUMMARY-1 The following event has been reported 6 times in the last 1 hour(s) 9 minute(s)
    6124( 2840) 04/17/2017 18:34:32 RTE A Performance-2-schedule, Exploración parcial de los archivos de la consulta que incluye los campos {application, object, strings, type}
    6124( 2840) 04/17/2017 18:34:32 RTE A SUMMARY-1 The following event has been reported 6 times in the last 1 hour(s) 9 minute(s)
    6124( 2840) 04/17/2017 18:34:32 RTE A Mapping-3-schedule,strings, SQL Query incomplete because field (strings) not mapped in file (schedule)
    6124( 2840) 04/17/2017 18:34:32 RTE A Performance-2-schedule, Exploración parcial de los archivos de la consulta que incluye los campos {application, object, strings, type} ; application(alert.schedule), panel(select.sched.tmp)
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:33 RTE I sqUpdate attempting retry #1
    6124( 2840) 04/17/2017 18:34:33 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:33 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:33 RTE I sqUpdate attempting retry #2
    6124( 2840) 04/17/2017 18:34:33 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:33 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:33 RTE I sqUpdate attempting retry #3
    6124( 2840) 04/17/2017 18:34:33 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:33 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:33 RTE I sqUpdate attempting retry #4
    6124( 2840) 04/17/2017 18:34:33 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:33 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:33 RTE I sqUpdate attempting retry #5
    6124( 2840) 04/17/2017 18:34:33 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:33 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:33 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:33 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:33 RTE E Error: SQL code=1403 message=ORA-01403: No se ha encontrado ningún dato
    6124( 2840) 04/17/2017 18:34:33 RTE E API=OCIDescriptorFree [in _ociDMLBind], Statement=UPDATE CM3RM1 SET "PAGE"=:X,"CURRENT_PHASE"=:X,"SYSMODCOUNT"=:X,"SYSMODTIME"=:X,"APPROVED_GROUPS"=:X,"DATE_ENTERED"=:X WHERE "NUMBER"=:X
    6124( 2840) 04/17/2017 18:34:34 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 2840) 04/17/2017 18:34:34 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle Client version: 11.2.0.4.0
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 2840) 04/17/2017 18:34:34 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 2840) 04/17/2017 18:34:34 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 2840) 04/17/2017 18:34:34 RTE I Oracle session is set up in BINARY mode
    6124( 2840) 04/17/2017 18:34:34 RTE I dbUpdate: Error al intentar actualizar un registro
    6124( 2840) 04/17/2017 18:34:34 RTE I dbUpdate: file:(cm3r) key:(header,number=CH00643545)
    6124( 2840) 04/17/2017 18:34:34 RAD I The exit code is: final
    6124( 2840) 04/17/2017 18:34:34 RAD I The action code is: lockok
    6124( 10500) 04/17/2017 18:34:34 JRTE I LockHandler: try to unlock: cm3r;CH00643545, but it has not ever been locked.
    6124( 1040) 04/17/2017 18:46:52 RTE I Idle session has been marked for termination
    6124( 1040) 04/17/2017 18:46:52 RTE I trcheck: terminating with signal 0
    6124( 1040) 04/17/2017 18:46:52 RTE I Thread Terminating
    6124( 396) 04/17/2017 18:46:52 JRTE W Send error response: 0
    6124( 1040) 04/17/2017 18:46:52 JRTE I Termination signal: 0
    6124( 1040) 04/17/2017 18:46:52 RTE I -Memory : S(13833400) O(3414996) MAX(22579009) - MALLOC's Total(12881893)
    6124( 1040) 04/17/2017 18:46:52 RTE I Thread termination in progress
    6124( 1040) 04/17/2017 18:46:53 RTE I User U556640 has logged out and freed a Named license ( 12 out of a maximum 55 )
    6124( 1040) 04/17/2017 18:46:54 RTE I sql total 31.342 call#:66200 -->sqociDisconnect:OCISessionEnd
    6124( 1040) 04/17/2017 18:46:54 RTE I sql total 31.357 call#:66201 -->sqociDisconnect:OCIServerDetach
    6124( 1040) 04/17/2017 18:46:54 RTE I Thread termination cleanup complete. Thread was active for 0 days,01:59:51. Threads remaining: 4 of 50
    6124( 10360) 04/17/2017 18:51:51 RTE I Idle session has been marked for termination
    6124( 10360) 04/17/2017 18:51:51 RTE I trcheck: terminating with signal 0
    6124( 10360) 04/17/2017 18:51:51 RTE I Thread Terminating
    6124( 3092) 04/17/2017 18:51:51 JRTE W Send error response: 0
    6124( 10360) 04/17/2017 18:51:51 JRTE I Termination signal: 0
    6124( 10360) 04/17/2017 18:51:51 RTE I -Memory : S(13586712) O(3593396) MAX(21342204) - MALLOC's Total(2483733)
    6124( 10360) 04/17/2017 18:51:51 RTE I Thread termination in progress
    6124( 10360) 04/17/2017 18:51:51 RTE A SUMMARY-1 The following event has been reported 4 times in the last 1 hour(s) 16 minute(s)
    6124( 10360) 04/17/2017 18:51:51 RTE A Performance-2-cm3r, Exploración parcial de los archivos de la consulta que incluye los campos {header,open, header,assign.dept, releaseCandidate, header,current.phase}
    6124( 10360) 04/17/2017 18:51:52 RTE I User U586845 has logged out and freed a Floating license ( 27 out of a maximum 282 )
    6124( 10360) 04/17/2017 18:51:53 RTE I sql total 5.810 call#:15339 -->sqociDisconnect:OCISessionEnd
    6124( 10360) 04/17/2017 18:51:53 RTE I sql total 5.810 call#:15340 -->sqociDisconnect:OCIServerDetach
    6124( 10360) 04/17/2017 18:51:53 RTE I Thread termination cleanup complete. Thread was active for 0 days,01:17:10. Threads remaining: 3 of 50
    6124( 396) 04/17/2017 18:53:56 JRTE I Creating new worker thread B52316867637CB02A8E7251D23F58228 t@77
    6124( 1100) 04/17/2017 18:53:56 RTE W the section parameter `plugin0:kmplugin.dll` from ini file is duplicated!
    6124( 1100) 04/17/2017 18:53:56 RTE I Using "utalloc" memory manager, mode [0]
    6124( 1100) 04/17/2017 18:53:56 RTE I Using unique key mode
    6124( 1100) 04/17/2017 18:53:56 RTE I Process sm 9.34.2003 (P2) System: 13095 (0x61E13C00) on PC (x64 64-bit) running Windows Server 2008 R2 Enterprise SP1 (6.1 Build 7601) Timezone GMT-03:00 from PWIN0705
    6124( 1100) 04/17/2017 18:53:56 RTE I Connected to SOAP client at 10.75.68.97
    6124( 1100) 04/17/2017 18:53:56 RTE I Thread attaching to resources with key 0x61E13C00
    6124( 1100) 04/17/2017 18:53:56 RTE I Thread B52316867637CB02A8E7251D23F58228 initialization done. Thread 4 of 50.
    6124( 1100) 04/17/2017 18:53:56 RTE I Total sessions since process began: 4256
    6124( 1100) 04/17/2017 18:53:56 RTE I SOAP client information scguiwweb 9.34.2003 (P2) at 10.249.46.170 Browser MSIE 7.0 AppServer Apache Tomcat 7.0.64
    6124( 1100) 04/17/2017 18:53:56 RTE I Connection to dbtype 'oracle10' Oracle server 'POSIMIT' as user 'A001111' successful
    6124( 1100) 04/17/2017 18:53:56 RTE I Connected to Oracle Version 12.1.0.2.0
    6124( 1100) 04/17/2017 18:53:56 RTE I Oracle Client version: 11.2.0.4.0
    6124( 1100) 04/17/2017 18:53:56 RTE I Oracle server settings for language, territory and character set: AMERICAN_AMERICA.AL32UTF8 (AL16UTF16)
    6124( 1100) 04/17/2017 18:53:56 RTE I OCI Client settings for language, territory and character set: SPANISH_SPAIN.AL32UTF8 (UTF16)
    6124( 1100) 04/17/2017 18:53:56 RTE W Oracle instance setting for NLS_SORT could not be determined. Default to BINARY
    6124( 1100) 04/17/2017 18:53:56 RTE I Oracle instance setting for NLS_COMP is set to BINARY
    6124( 1100) 04/17/2017 18:53:56 RTE I Oracle session is set up in BINARY mode