Jump to content


< Back to Forum


 

Long Time Freeing Other Leg.


  • Please log in to reply

#1 Maciej 17 May 2016 - 07:37 PM

Sometimes other leg is occupated for several seconds by (qScr) status.

 

Can You explain what is reason of this? How to fix this?

Example (34 is outbound channel)

104606.969  19 101  34 ev    CallState GCEV_RELEASECALL, crn=2b002b7, iEvent=2137 ,0,0,0, s1:GCST_NULL, s2:, s3:]. build_date: 09-Mar-15 20:02:50.85
104606.969  63               Bridge_Disconnect iLineId1=70, iLineId2=101, iOtherLeg=-1
104606.969  63         WARN  Bridge_Disconnect iOtherLeg=-1 but iLineId2=101
104606.969   7 101  34       LineEvCallState_GCEV_RELEASECALL begin crn_event=2b002b7,ev=2137,GCEV_RELEASECALL
104606.970   7 101  34       crn_event==crn_InProcessOfReleasing as expecetd
104606.970   7               d     rmvd 45089463 2b002b7
104606.970  63         rem   Line_Hangup 101
104606.970   7 101  34       HangupCall (qScr) crn_in=0, L1=Null_InGuardTimeAfterEndOfCall, L2=Idle_WaitingForNextCall, lPlayId=0, lRecId=0
104606.970   7 101  34       ccrn_connected=0, crn_NewCallArriving=0, crn_CurrentMakeCall=0, crn_InProcessOfReleasing=0
104606.970   7 101  34       HangupCall sScriptToRunOnHangup=[temination.vgp]
104606.970   7 101  34       no script running on this line. AfterHangup script will not be ran.
104606.971   7 101  34 WARN  could not find crn for call to hangup A


#2 SupportTeam 18 May 2016 - 04:43 AM

Line Hangup takes a small fraction of a second.

 

Could you please post a full trace capturing the entire time that you are referring to

 

The trace excerpt posted just shows the 'Release Call' event - which happens at the very end of the line hangup process - and then a Line_Hangup COM command which would have been issued from some external script - but is made after the line has fully hung up already.

And entire trace excerpt posted only covers a 2 millisecond time range.



#3 SupportTeam 18 May 2016 - 11:17 AM

Here are some excerpts from traces supplied before from your system which show how fast call hangup is.

As you can see these actions take a small fraction of a second on a VoiceGuide system:

 

 

Example 1:  Call hung up up by VoiceGuide:

VoiceGuide received confirmation from ISDN network that call was dropped within 50 milliseconds of call hangup being requested by the script, and then VoiceGuide released the call which was confirmed by Dialogic driver layer 5 milliseconds later.

125340.232   7 131  44 state Hanging up... [xfer_9_leg2 remote]
125340.232   7 131  44       logs not written as LogEntryWritten=1
125340.232   7 131  44       L2_set Idle_WaitingForNextCall  (called from HangupCall)
125340.232   7 131  44 t     timer set   3 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT
125340.232   7 131  44       GoOnHoook_IssueDropCall start (crn_in=2880206, crn_connected=2880206, crn_makecall=0, crn_NewCallArriving=0, crn_InProcessOfReleasing=0, L1=Disconnect_Pending)
125340.232   7 131  44 t     timer set   20 sec : EV_TIMEOUT_WAITFORIDLEAFTERDropCall
125340.232   7 131  44       set crn_InProcessOfReleasing=2880206
125340.232   7 131  44       set crn_connected=0 (called from GoOnHoook_IssueDropCall)
125340.232   7 131  44       q_tel +     cmd_DropCall 2880206 [0,0,0,0,0][||||]
125340.232   7 131  44       GoOnHoook_IssueDropCall end

125340.266   8 131  44       q_tel run   cmd_DropCall 2880206 00:00:00.0340019 max:11|00:00:06.4723702

125340.285  21 131  44 ev    CallState GCEV_DROPCALL, crn=2880206, iEvent=0 ,1,0,32, s1:, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
125340.285  21 131  44       q_scr +     evCallState 0 GCEV_DROPCALL
125340.285   7 131  44       q_scr run   evCallState sCode=[GCEV_DROPCALL] iActionID=0, crn=2880206 [1|0|32|0|0][|||||] 00:00:00 max:16|00:00:11.1726390
125340.285   7 131  44       callstate crn_event=2880206 0|GCEV_DROPCALL state=1|Idle_NotYetReleased calldirection=0 gcCallState=32 [||], ScriptState=LS_ENDINGCALL
125340.285   7 131  44       LineEvCallState L1_current=Disconnect_Pending crn_event=2880206,ev=0,GCEV_DROPCALL,0, sParam1=
125340.285   7 131  44       LineEvCallState LINECALLSTATE_IDLE begin crn=2880206
125340.285   7 131  44       clear timer as timer event is WAITFORIDLEAFTERDropCall, and IDLE was received
125340.285   7 131  44 t     timer clear (force=True)
125340.285   7 131  44       L1_set Idle_NotYetReleased (iState=LS_ENDINGCALL)
125340.285   7 131  44       CallData.dateCallStartTime=2015-01-22 12:44:57, CallAnswerTime=2015-01-22 12:45:00, CallEndTime=2015-01-22 12:53:40
125340.285   7 131  44       rv    add   CallHangupTime|2015-01-22 12:53:40
125340.285   7 131  44       WriteCDRlogs: begin (campaign=, CallData.iLineId=131)
125340.285   7 131  44       writing CDR logs
125340.285   7 131  44       cdr.sDisposition=ANSWERED
125340.285   7 131  44       strDialoutTelNbr=225763161, strCdrBagCallerIdNbr=
125340.285   7 131  44       vg.ini/Log/CDR_LastData_SaveRv=0 - RVs XML will NOT be saved in LastApplicationData CDR field.
125340.285   7 131  44       rv    replace start [$RV[CDR_amaflags]]
125340.285   7               rv    dbg   rv_retrieve did not find [CDR_amaflags] ( 43 44 52 5f 61 6d 61 66 6c 61 67 73)
125340.285   7 131  44       rv    replace end   []
125340.285   7 131  44       rv    replace start [$RV[CDR_user]]
125340.286   7 131  44       rv    replace end   [ID20150122124436183]
125340.286   7 131  44       $RV[CDR_amaflags]=[], $RV[CDR_user]=[ID20150122124436183]
125340.286   7 131  44       CDR (out) : "","122900557","225763161","","","","","","","'2015-01-22 12:44:57'","'2015-01-22 12:45:00'","'2015-01-22 12:53:40'",522,520,"ANSWERED","","ID20150122124436183" => C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out.txt , C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out_ANSWERED.txt
125340.286   7 131  44       CDR SQL: INSERT INTO cdrout (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('','122900557','225763161','','','','','','','2015-01-22 12:44:57','2015-01-22 12:45:00','2015-01-22 12:53:40',522,520,'ANSWERED','','ID20150122124436183')
125340.286   7 131  44       sIni_CDRLOG_ODBC_ConnectString (secondary CDR DB) not set.
125340.286  12               db    PerformWork_RunCoreSql assign sql
125340.286   7 131  44       q_tel +     cmd_ReleaseCall 2880206 [0,0,0,0,0][||||]
125340.286   7 131  44       L1_set Null_InGuardTimeAfterEndOfCall (iState=LS_ENDINGCALL)
125340.286   8 131  44       q_tel run   cmd_ReleaseCall 2880206 00:00:00 max:11|00:00:06.4723702
125340.286   7 131  44 t     timer set   0,5 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT
125340.286   7 131  44 state Waiting for a call
125340.290  21 131  44 ev    CallState GCEV_RELEASECALL, crn=2880206, iEvent=2137 ,0,0,0, s1:GCST_NULL, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26

Example 2: Call hung up by remote side:

VoiceGuide script drops the call  1 milliseconds after being advised that remote end hung up, confirmation of that is returned to the script from Dialogic 20 milliseconds later, and then performing a call release takes 5 milliseconds.

130313.581  21 125  42 ev    CallState GCEV_DISCONNECTED, crn=28802dc, iEvent=0 ,16384,0,64, s1:, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
130313.581  21 125  42       q_scr +     evCallState 0 GCEV_DISCONNECTED
130313.581   7 125  42       q_scr run   evCallState sCode=[GCEV_DISCONNECTED] iActionID=0, crn=28802dc [16384|0|64|0|0][|||||] 00:00:00 max:16|00:00:11.1726390
130313.581   7 125  42       callstate crn_event=28802dc 0|GCEV_DISCONNECTED state=16384|Disconnected calldirection=0 gcCallState=64 [||], ScriptState=LS_XFER_7_WAITOUTCOMEOUTGOINGCALL_DIALINGSIDE
130313.581   7 125  42       LineEvCallState L1_current=Dialing crn_event=28802dc,ev=0,GCEV_DISCONNECTED,0, sParam1=
130313.581   7 125  42       LineEvCallState LINECALLSTATE_DISCONNECTED begin
130313.581   7 125  42       TsReset [LineEvCallState_LINECALLSTATE_DISCONNECTED], OtherLegLid=34
130313.581   7               q_tel +     cmd_TsReset 0 [0,0,0,0,0][dtiB2T12||dtiB1T12||]
130313.581   7 125  42       OtherLegLid_ClearBoth 125 34 caller:LineEvCallState_LINECALLSTATE_DISCONNECTED
130313.581   7  34  12       OtherLegLid_ClearBoth 125 34 caller:LineEvCallState_LINECALLSTATE_DISCONNECTED
130313.581   8               q_tel run   cmd_TsReset 0 00:00:00 max:11|00:00:06.4723702
130313.581   7 125  42       send OTHER_LEG_DISCONNECTED to OtherLegLid=34

130313.581   7 125  42       we will drop an outgoing call that got Disconnected after Dialing
130313.581   7 125  42       rvns  add   OutDial_Result|DISCONNECTED
130313.582   7 125  42 t     timer clear (force=False)
130313.582   7 125  42       dial  OnOutboundCallNotAnswered begin, RetriesLeft=-1
130313.582   7 125  42       dial  OnOutboundCallNotAnswered - call was a 2nd leg of transfer.
130313.582   7 125  42       dial  not deleting call from dial queue as ID=0 and guid is empty. Call was not loaded from DB queue.
130313.582   7 125  42       dial  no escalation calls
130313.582   7 125  42       ct    calltrack_set not updating as ID=0 and guid is empty. Call was not loaded from DB queue.
130313.582   7 125  42       q_tel +     cmd_DropCall 28802dc [0,0,0,0,0][||||]

130313.606  21 125  42 ev    CallState GCEV_DROPCALL, crn=28802dc, iEvent=0 ,1,0,32, s1:, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
130313.606  21 125  42       q_scr +     evCallState 0 GCEV_DROPCALL
130313.606   7 125  42       q_scr run   evCallState sCode=[GCEV_DROPCALL] iActionID=0, crn=28802dc [1|0|32|0|0][|||||] 00:00:00 max:16|00:00:11.1726390
130313.606   7 125  42       callstate crn_event=28802dc 0|GCEV_DROPCALL state=1|Idle_NotYetReleased calldirection=0 gcCallState=32 [||], ScriptState=LS_XFER_7_WAITOUTCOMEOUTGOINGCALL_DIALINGSIDE
130313.606   7 125  42       LineEvCallState L1_current=Dialing crn_event=28802dc,ev=0,GCEV_DROPCALL,0, sParam1=
130313.606   7 125  42       LineEvCallState LINECALLSTATE_IDLE begin crn=28802dc
130313.606   7 125  42       L1_set Idle_NotYetReleased (iState=LS_XFER_7_WAITOUTCOMEOUTGOINGCALL_DIALINGSIDE)
130313.606   7 125  42       CallData.dateCallStartTime=2015-01-22 13:03:12, CallAnswerTime=0001-01-01 00:00:00, CallEndTime=2015-01-22 13:03:13
130313.606   7 125  42       rv    add   CallHangupTime|2015-01-22 13:03:13
130313.606   7 125  42       WriteCDRlogs: begin (campaign=, CallData.iLineId=125)
130313.606   7 125  42       writing CDR logs
130313.606   7 125  42       cdr.sDisposition=DISCONNECTED
130313.606   7 125  42       strDialoutTelNbr=327288591, strCdrBagCallerIdNbr=
130313.606   7 125  42       vg.ini/Log/CDR_LastData_SaveRv=0 - RVs XML will NOT be saved in LastApplicationData CDR field.
130313.606   7 125  42       rv    replace start [$RV[CDR_amaflags]]
130313.606   7               rv    dbg   rv_retrieve did not find [CDR_amaflags] ( 43 44 52 5f 61 6d 61 66 6c 61 67 73)
130313.606   7 125  42       rv    replace end   []
130313.606   7 125  42       rv    replace start [$RV[CDR_user]]
130313.607   7 125  42       rv    replace end   [ID201501221302501812]
130313.607   7 125  42       $RV[CDR_amaflags]=[], $RV[CDR_user]=[ID201501221302501812]
130313.607   7 125  42       CDR (out) : "","227114400","327288591","","","","","","","'2015-01-22 13:03:12'","'0001-01-01 00:00:00'","'2015-01-22 13:03:13'",1,0,"DISCONNECTED","","ID201501221302501812" => C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out.txt , C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out_DISCONNECTED.txt
130313.607   7 125  42       CDR SQL: INSERT INTO cdrout (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('','227114400','327288591','','','','','','','2015-01-22 13:03:12','0001-01-01 00:00:00','2015-01-22 13:03:13',1,0,'DISCONNECTED','','ID201501221302501812')
130313.607   7 125  42       sIni_CDRLOG_ODBC_ConnectString (secondary CDR DB) not set.
130313.607  12               db    PerformWork_RunCoreSql assign sql
130313.607  12               db    PerformWork_RunCoreSql ExecuteNonQuery call: INSERT INTO cdrout (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('','227114400','327288591','','','','','','','2015-01-22 13:03:12','0001-01-01 00:00:00','2015-01-22 13:03:13',1,0,'DISCONNECTED','','ID201501221302501812')
130313.607   7 125  42       q_tel +     cmd_ReleaseCall 28802dc [0,0,0,0,0][||||]
130313.608   7 125  42       L1_set Null_InGuardTimeAfterEndOfCall (iState=LS_XFER_7_WAITOUTCOMEOUTGOINGCALL_DIALINGSIDE)
130313.608   8 125  42       q_tel run   cmd_ReleaseCall 28802dc 00:00:00 max:11|00:00:06.4723702
130313.608   7 125  42 t     timer set   0,5 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT
130313.608   7 125  42 state Waiting for a call
130313.609  12               db    PerformWork_RunCoreSql cmd_CoreSql.ExecuteNonQuery returned
130313.611  21 125  42 ev    CallState GCEV_RELEASECALL, crn=28802dc, iEvent=2137 ,0,0,0, s1:GCST_NULL, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26

Example 3: Call hung up by remote side:

The process of VoiceGuide script dropping the call completes in 25 milliseconds, and then call release is completed within 10ms thereafter.

130258.338  21 107  36 ev    CallState GCEV_DISCONNECTED, crn=2880280, iEvent=0 ,16384,0,64, s1:, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
130258.338  21 107  36       q_scr +     evCallState 0 GCEV_DISCONNECTED
130258.338   7 107  36       q_scr run   evCallState sCode=[GCEV_DISCONNECTED] iActionID=0, crn=2880280 [16384|0|64|0|0][|||||] 00:00:00 max:16|00:00:11.1726390
130258.338   7 107  36       callstate crn_event=2880280 0|GCEV_DISCONNECTED state=16384|Disconnected calldirection=0 gcCallState=64 [||], ScriptState=LS_XFER_9_WAITENDCALL_DialingSide
130258.338   7 107  36       LineEvCallState L1_current=Connected crn_event=2880280,ev=0,GCEV_DISCONNECTED,0, sParam1=
130258.338   7 107  36       LineEvCallState LINECALLSTATE_DISCONNECTED begin
130258.338   7 107  36       TsReset [LineEvCallState_LINECALLSTATE_DISCONNECTED], OtherLegLid=16
130258.339   7               q_tel +     cmd_TsReset 0 [0,0,0,0,0][dtiB2T6||dtiB1T6||]
130258.339   7 107  36       OtherLegLid_ClearBoth 107 16 caller:LineEvCallState_LINECALLSTATE_DISCONNECTED
130258.339   8               q_tel run   cmd_TsReset 0 00:00:00 max:11|00:00:06.4723702
130258.339   7 107  36       send OTHER_LEG_DISCONNECTED to OtherLegLid=16
130258.339   7 107  36       L1_set Disconnected (iState=LS_XFER_9_WAITENDCALL_DialingSide)
130258.339   7 107  36       in LINECALLSTATE_DISCONNECTED iState=LS_XFER_9_WAITENDCALL_DialingSide
130258.339   7 107  36       HangupCall (LineEvCallState_DISCONNECTED) crn_in=2880280, L1=Disconnected, L2=Running_Normal, lPlayId=0, lRecId=0
130258.339   7 107  36       ccrn_connected=0, crn_NewCallArriving=0, crn_CurrentMakeCall=0, crn_InProcessOfReleasing=2880280
130258.339   7 107  36       rv    add   Hangup Source|LineEvCallState_DISCONNECTED
130258.340   7 107  36       HangupCall sScriptToRunOnHangup=[]
130258.340   7 107  36       no script running on this line. AfterHangup script will not be ran.
130258.340   7 107  36       rv    add   DropCall_Source|
130258.340   7 107  36 state Hanging up... [LineEvCallState_DISCONNECTED]
130258.340   7 107  36       logs not written as LogEntryWritten=1
130258.340   7 107  36       L2_set Idle_WaitingForNextCall  (called from HangupCall)
130258.340   7 107  36 t     timer set   3 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT
130258.340   7 107  36       GoOnHoook_IssueDropCall start (crn_in=2880280, crn_connected=0, crn_makecall=0, crn_NewCallArriving=0, crn_InProcessOfReleasing=2880280, L1=Disconnected)
130258.340   7 107  36 t     timer set   20 sec : EV_TIMEOUT_WAITFORIDLEAFTERDropCall
130258.340   7 107  36       set crn_InProcessOfReleasing=2880280
130258.340   7 107  36       set crn_connected=0 (called from GoOnHoook_IssueDropCall)
130258.340   7 107  36       q_tel +     cmd_DropCall 2880280 [0,0,0,0,0][||||]
130258.340   7 107  36       GoOnHoook_IssueDropCall end

130258.359   8 107  36       q_tel run   cmd_DropCall 2880280 00:00:00.0190011 max:11|00:00:06.4723702
130258.363  21 107  36 ev    CallState GCEV_DROPCALL, crn=2880280, iEvent=0 ,1,0,32, s1:, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
130258.363  21 107  36       q_scr +     evCallState 0 GCEV_DROPCALL
130258.363   7 107  36       q_scr run   evCallState sCode=[GCEV_DROPCALL] iActionID=0, crn=2880280 [1|0|32|0|0][|||||] 00:00:00 max:16|00:00:11.1726390
130258.363   7 107  36       callstate crn_event=2880280 0|GCEV_DROPCALL state=1|Idle_NotYetReleased calldirection=0 gcCallState=32 [||], ScriptState=LS_XFER_9_WAITENDCALL_DialingSide
130258.363   7 107  36       LineEvCallState L1_current=Disconnected crn_event=2880280,ev=0,GCEV_DROPCALL,0, sParam1=
130258.363   7 107  36       LineEvCallState LINECALLSTATE_IDLE begin crn=2880280
130258.363   7 107  36       clear timer as timer event is WAITFORIDLEAFTERDropCall, and IDLE was received
130258.363   7 107  36 t     timer clear (force=True)
130258.363   7 107  36       L1_set Idle_NotYetReleased (iState=LS_XFER_9_WAITENDCALL_DialingSide)
130258.364   7 107  36       CallData.dateCallStartTime=2015-01-22 12:55:13, CallAnswerTime=2015-01-22 12:55:28, CallEndTime=2015-01-22 13:02:58
130258.364   7 107  36       rv    add   CallHangupTime|2015-01-22 13:02:58
130258.364   7 107  36       WriteCDRlogs: begin (campaign=, CallData.iLineId=107)
130258.364   7 107  36       writing CDR logs
130258.364   7 107  36       cdr.sDisposition=ANSWERED
130258.364   7 107  36       strDialoutTelNbr=525001202, strCdrBagCallerIdNbr=
130258.364   7 107  36       vg.ini/Log/CDR_LastData_SaveRv=0 - RVs XML will NOT be saved in LastApplicationData CDR field.
130258.365   7 107  36       rv    replace start [$RV[CDR_amaflags]]
130258.365   7               rv    dbg   rv_retrieve did not find [CDR_amaflags] ( 43 44 52 5f 61 6d 61 66 6c 61 67 73)
130258.365   7 107  36       rv    replace end   []
130258.365   7 107  36       rv    replace start [$RV[CDR_user]]
130258.365   7 107  36       rv    replace end   [ID20150122125500186]
130258.365   7 107  36       $RV[CDR_amaflags]=[], $RV[CDR_user]=[ID20150122125500186]
130258.365   7 107  36       CDR (out) : "","222023747","525001202","","","","","","","'2015-01-22 12:55:13'","'2015-01-22 12:55:28'","'2015-01-22 13:02:58'",464,450,"ANSWERED","","ID20150122125500186" => C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out.txt , C:\Program Files (x86)\VoiceGuide\cdr\CDR_20150122_out_ANSWERED.txt
130258.366   7 107  36       CDR SQL: INSERT INTO cdrout (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('','222023747','525001202','','','','','','','2015-01-22 12:55:13','2015-01-22 12:55:28','2015-01-22 13:02:58',464,450,'ANSWERED','','ID20150122125500186')
130258.366   7 107  36       sIni_CDRLOG_ODBC_ConnectString (secondary CDR DB) not set.
130258.366   7 107  36       q_tel +     cmd_ReleaseCall 2880280 [0,0,0,0,0][||||]
130258.366   7 107  36       L1_set Null_InGuardTimeAfterEndOfCall (iState=LS_XFER_9_WAITENDCALL_DialingSide)
130258.366   7 107  36 t     timer set   0,5 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT
130258.366   7 107  36 state Waiting for a call
130258.366   8 107  36       q_tel run   cmd_ReleaseCall 2880280 00:00:00 max:11|00:00:06.4723702

130258.373  21 107  36 ev    CallState GCEV_RELEASECALL, crn=2880280, iEvent=2137 ,0,0,0, s1:GCST_NULL, s2:, s3:]. build_date: 22-Jan-15 14:22:36.26
130258.373  21 107  36       q_scr +     evCallState 2137 GCEV_RELEASECALL
130258.373   7 107  36       q_scr run   evCallState sCode=[GCEV_RELEASECALL] iActionID=0, crn=2880280 [0|0|0|0|0][GCST_NULL|||||] 00:00:00 max:16|00:00:11.1726390
130258.373   7 107  36       callstate crn_event=2880280 2137|GCEV_RELEASECALL state=0|Error calldirection=0 gcCallState=0 [GCST_NULL||], ScriptState=LS_NULL_AWAITINGCALLS
130258.374   7 107  36       LineEvCallState_GCEV_RELEASECALL begin crn_event=2880280,ev=2137,GCEV_RELEASECALL