VoiceGuide IVR Software Main Page
Jump to content

After Hangup Script not running

By invoso.com, in General

Recommended Posts

invoso.com   

Posted

I still have problems with script witch no run script after call is finished.

 

connection

212233.032 6 4 2 ls set Disconnect_Pending (scriptstate=LS_ENDINGCALL)

should follow to termination script

but doesn't terminate with

after hangup script: [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

as other connections.

 

 

I don't see any error message to find reason why script don't follow to termination script.

 

 

 

 

212233.032 6 4 2 qScr add evScriptEvent 0 OTHER_LEG_Disconnected

212233.032 6 2 1 LineEvCallState LINECALLSTATE_DISCONNECTED begin

212233.032 6 2 1 ignore callstate LINECALLSTATE_DISCONNECTED event as current linestate is Disconnected

212233.032 6 4 2 qScr run evScriptEvent sCode=[OTHER_LEG_Disconnected] iActionID=0, crn=0[16384|0|64|0|0][|||||]

212233.032 6 4 2 scriptevent OTHER_LEG_Disconnected 16384|0|64 ||

212233.032 6 4 2 LsXfer_9_WaitEndCall_OutLeg : 0(), [OTHER_LEG_Disconnected], 16384,0,64,,,

212233.032 6 4 2 hanging up 2nd leg of call (other leg cause)

212233.032 6 4 2 HangupCall, source=xfer_9_leg2 remote, WorkModeScript=Running_Normal, yLineStateAppPov=[Connected], lPlayId=0, lRecId=0

212233.032 6 4 2 moduleTitle=[connection_time_sql]

212233.032 6 4 2 ScriptWorkingMode set Stopping, (called from HangupCall)

212233.032 6 4 2 ls set Disconnect_Pending (scriptstate=LS_ENDINGCALL)

212233.032 6 4 2 state Hanging up... [xfer_9_leg2 remote]

212233.032 6 4 2 rv add [Hangup Source]{xfer_9_leg2 remote}

212233.032 6 d added Hangup Source | xfer_9_leg2 remote | ( 48 61 6e 67 75 70 20 53 6f 75 72 63 65 | 78 66 65 72 5f 39 5f 6c 65 67 32 20 72 65 6d 6f 74 65 | 4) id=4

212233.032 6 d added Hangup Source | xfer_9_leg2 remote | ( 48 61 6e 67 75 70 20 53 6f 75 72 63 65 | 78 66 65 72 5f 39 5f 6c 65 67 32 20 72 65 6d 6f 74 65 | 13) id=4

212233.032 6 4 2 GoOnHoook_IssueDropCall start (hCall_crn=28002b5, yLineStateAppPov=Disconnect_Pending)

212233.032 6 4 2 logs not written as LogEntryWritten=1

212233.032 6 4 2 t timer set 10 sec : EV_TIMEOUT_WAITFORIDLEAFTERDropCall

212233.032 6 4 2 qTel add cmd_DropCall [0,0,0,0,0][||||]

212233.032 6 4 2 GoOnHoook_IssueDropCall end

212233.032 7 4 2 qTel run cmd_DropCall

212233.048 18 2 1 ev CallState GCEV_DROPCALL, crn=28002b4, iEvent=2 ,1,0,32,,,]. vgEngine v7.2.4160.36381 (2011-05-23 19:12:42.09)

212233.048 18 2 1 qScr add evCallState 2 GCEV_DROPCALL

212233.048 6 2 1 qScr run evCallState sCode=[GCEV_DROPCALL] iActionID=0, crn=28002b4[1|0|32|0|0][|||||]

212233.048 6 2 1 LineEvCallState crn=28002b4 event=2|GCEV_DROPCALL iCallState=1calldirection=0 gcCallState=32 [||]

212233.048 6 2 1 LineEvCallState eLineStateThisEvent=Idle_NotYetReleased

212233.048 6 2 1 LineEvCallState ScriptState=LS_ENDINGCALL

212233.048 6 2 1 ls set Idle_NotYetReleased (scriptstate=LS_ENDINGCALL)

212233.048 6 2 1 yLineStateAppPov: Idle_NotYetReleased crn=28002b4,ev=2,GCEV_DROPCALL,0, sParam1=

212233.048 6 4 2 qScr add evScriptEvent 0 OTHER_LEG_Idle_NotYetReleased

212233.048 6 2 1 LineEvCallState LINECALLSTATE_IDLE begin

212233.048 6 2 1 t timer clear (force=True)

212233.048 6 2 1 CallData.dateCallStartTime=2012-01-21 21:21:33

212233.048 6 2 1 rv add [CallHangupTime]{2012-01-21 21:22:33}

212233.048 6 d added CallHangupTime | 2012-01-21 21:22:33 | ( 43 61 6c 6c 48 61 6e 67 75 70 54 69 6d 65 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 131) id=2

212233.048 6 d added CallHangupTime | 2012-01-21 21:22:33 | ( 43 61 6c 6c 48 61 6e 67 75 70 54 69 6d 65 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 175) id=2

212233.048 6 2 1 WriteCDRlogs: begin (campaign=, CallData.iLineId=0)

212233.048 6 2 1 writing CDR logs

212233.048 6 2 1 vg.ini/Log/CDR_LastData_SaveRv=0 - RVs XML will NOT be saved in LastApplicationData CDR field.

212233.048 6 2 1 rv replace start [$RV[CDR_amaflags]]

212233.048 6 rv dbg rv_retrieve did not find [CDR_amaflags] ( 43 44 52 5f 61 6d 61 66 6c 61 67 73)

212233.048 6 2 1 rv replace end []

212233.048 6 2 1 rv replace start [$RV[CDR_user]]

212233.048 6 2 1 rv replace end [12012121213352]

212233.048 6 2 1 $RV[CDR_amaflags]=[], $RV[CDR_user]=[12012121213352]

212233.048 6 2 1 CDR (in) : "222054603","502131658","","","","","","C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_2011_10_14.vgp","","'2012-01-21 21:21:33'","'2012-01-21 21:21:33'","'2012-01-21 21:22:33'",59,59,"ANSWERED","","12012121213352" => C:\Program Files\VoiceGuide723\cdr\CDR_20120121_in.txt , C:\Program Files\VoiceGuide723\cdr\CDR_20120121_in_ANSWERED.txt

212233.048 6 2 1 CDR SQL: INSERT INTO cdrin (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('222054603','502131658','','','','','','C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_2011_10_14.vgp','','2012-01-21 21:21:33','2012-01-21 21:21:33','2012-01-21 21:22:33',59,59,'ANSWERED','','12012121213352')

212233.048 6 2 1 sIni_CDRLOG_ODBC_ConnectString (secondary CDR DB) not set.

212233.048 12 db PerformWork_RunCoreSql assign sql

212233.048 6 2 1 after hangup script: [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

212233.048 12 db PerformWork_RunCoreSql ExecuteNonQuery call: INSERT INTO cdrin (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('222054603','502131658','','','','','','C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_2011_10_14.vgp','','2012-01-21 21:21:33','2012-01-21 21:21:33','2012-01-21 21:22:33',59,59,'ANSWERED','','12012121213352')

212233.048 6 2 1 FindFullPathToScript_VgsOrVgp start [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

212233.048 6 2 1 after hangup script: [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

212233.048 6 LoadedVgs_Find C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp at idx=5

212233.048 6 2 1 After Hangup script started (from LineEvCallState() LINECALLSTATE_IDLE handler)

212233.048 6 2 1 ScriptWorkingMode set Running_AfterHangup, (called from StartAfterHangupScriptIfDefined)

212233.048 6 2 1 t timer clear (force=False)

212233.048 6 2 1 RunModule start Evaluate, [call_end], iModuleIdx=309, previous: vgm=87, vgs=5:4

212233.048 6 2 1 state [call_end] Evaluate [now()]

212233.048 6 2 1 eval[now()]

212233.048 6 2 1 CreateInstance ScriptControl objSC is: [system.__ComObject]

212233.048 6 d added call_end_Input | now() | ( 63 61 6c 6c 5f 65 6e 64 5f 49 6e 70 75 74 | 6e 6f 77 28 29 | 176) id=2

212233.048 6 d added call_end | 2012-01-21 21:22:33 | ( 63 61 6c 6c 5f 65 6e 64 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 177) id=2

212233.048 6 d updtd call_end | 2012-01-21 21:22:33 | ( 63 61 6c 6c 5f 65 6e 64 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 2) id=0

212233.048 6 d updtd call_end | 2012-01-21 21:22:33 | ( 63 61 6c 6c 5f 65 6e 64 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 177) id=2

212233.048 6 2 1 Eval Expr result:[2012-01-21 21:22:33] stored in RV[call_end]

212233.048 6 2 1 path {2012-01-21 21:22:33} not found

212233.048 6 2 1 matching path not found [2012-01-21 21:22:33], try boolean paths.

212233.048 6 2 1 ex: ScriptIo.VGMTYPE_EVALEXPR [2012-01-21 21:22:33] could not be converted to boolean. Take Success path as string non-empty.

212233.048 6 2 1 FindNextVgmTitleInPathList: next module title is=[update finish call timestamp]

212233.048 6 2 1 matching path found (Boolean True)

212233.048 6 2 1 t timer clear (force=False)

212233.048 6 2 1 RunModule start Database Query, [update finish call timestamp], iModuleIdx=308, previous: vgm=309, vgs=5:5

212233.048 6 2 1 state [update finish call timestamp] DB Query

212233.048 6 2 1 rv replace start [uPDATE vg_billing SET call_end='$RV[call_end]', called_number=$RV_DNIS WHERE session_id=$RV[session_id]]

212233.048 6 2 1 rv replace end [uPDATE vg_billing SET call_end='2012-01-21 21:22:33', called_number=222054603 WHERE session_id=12012121213352]

212233.048 6 2 1 db strDatabaseName=[invoso] strDbConnectString=[ODBC; Driver=PostgreSQL Unicode; Server=IP;Database=database;UID=user;PWD=pwd;] sAdoNetFactoriesAvailable=[|System.Data.Odbc|System.Data.OleDb|System.Data.OracleClient|System.Data.SqlClient|VistaDB.NET20|System.Data.SQLite|]

212233.048 6 2 1 db using ado.net ODBC data provider : OdbcConnection/OdbcCommand/etc

212233.048 6 2 1 t timer set 30 sec : EV_TIMEOUT_HANGUP

212233.048 6 2 1 db odbc RunQuery_Odbc [update finish call timestamp], db=[invoso], connect=[ Driver=PostgreSQL Unicode; Server=IP;Database=database;UID=user;PWD=pwd;], sql=[uPDATE vg_billing SET call_end='2012-01-21 21:22:33', called_number=222054603 WHERE session_id=12012121213352]

212233.048 6 2 1 db odbc thread init (threadpool)

212233.048 3 2 1 db odbc connection create call.

212233.048 3 2 1 db odbc connection open call.

212233.048 6 2 1 db odbc thread started (threadpool)

212233.048 6 4 2 qScr run evScriptEvent sCode=[OTHER_LEG_Idle_NotYetReleased] iActionID=0, crn=0[1|0|32|0|0][|||||]

212233.048 6 4 2 scriptevent OTHER_LEG_Idle_NotYetReleased 1|0|32 ||

212233.048 6 4 2 LsEndingCall OTHER_LEG_Idle_NotYetReleased 1

212233.095 18 4 2 ev CallState GCEV_DROPCALL, crn=28002b5, iEvent=2 ,1,0,32,,,]. vgEngine v7.2.4160.36381 (2011-05-23 19:12:42.09)

212233.095 18 4 2 qScr add evCallState 2 GCEV_DROPCALL

212233.095 6 4 2 qScr run evCallState sCode=[GCEV_DROPCALL] iActionID=0, crn=28002b5[1|0|32|0|0][|||||]

212233.095 6 4 2 LineEvCallState crn=28002b5 event=2|GCEV_DROPCALL iCallState=1calldirection=0 gcCallState=32 [||]

212233.095 6 4 2 LineEvCallState eLineStateThisEvent=Idle_NotYetReleased

212233.095 6 4 2 LineEvCallState ScriptState=LS_ENDINGCALL

212233.095 6 4 2 ls set Idle_NotYetReleased (scriptstate=LS_ENDINGCALL)

212233.095 6 4 2 yLineStateAppPov: Idle_NotYetReleased crn=28002b5,ev=2,GCEV_DROPCALL,0, sParam1=

212233.095 6 4 2 LineEvCallState LINECALLSTATE_IDLE begin

212233.095 6 4 2 t timer clear (force=True)

212233.095 6 4 2 CallData.dateCallStartTime=2012-01-21 21:22:22

212233.095 6 4 2 rv add [CallHangupTime]{2012-01-21 21:22:33}

212233.095 6 d added CallHangupTime | 2012-01-21 21:22:33 | ( 43 61 6c 6c 48 61 6e 67 75 70 54 69 6d 65 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 5) id=4

212233.095 6 d added CallHangupTime | 2012-01-21 21:22:33 | ( 43 61 6c 6c 48 61 6e 67 75 70 54 69 6d 65 | 32 30 31 32 2d 30 31 2d 32 31 20 32 31 3a 32 32 3a 33 33 | 14) id=4

212233.095 6 4 2 WriteCDRlogs: begin (campaign=, CallData.iLineId=4)

212233.095 6 4 2 writing CDR logs

212233.095 6 4 2 cdr.sDisposition=ANSWERED

212233.095 6 4 2 NOTE strDialoutTelNbr=502131638, strCallerIdNbr=

212233.095 6 4 2 vg.ini/Log/CDR_LastData_SaveRv=0 - RVs XML will NOT be saved in LastApplicationData CDR field.

212233.095 6 4 2 rv replace start [$RV[CDR_amaflags]]

212233.095 6 rv dbg rv_retrieve did not find [CDR_amaflags] ( 43 44 52 5f 61 6d 61 66 6c 61 67 73)

212233.095 6 4 2 rv replace end []

212233.095 6 4 2 rv replace start [$RV[CDR_user]]

212233.095 6 rv dbg rv_retrieve did not find [CDR_user] ( 43 44 52 5f 75 73 65 72)

212233.095 6 4 2 rv replace end []

212233.095 6 4 2 rv replace start [$RV[CDR_usr]]

212233.095 6 rv dbg rv_retrieve did not find [CDR_usr] ( 43 44 52 5f 75 73 72)

212233.095 6 4 2 rv replace end []

212233.095 6 4 2 $RV[CDR_amaflags]=[], $RV[CDR_user]=[]

212233.095 6 4 2 CDR (out) : "","502131638","","","","","","","","'2012-01-21 21:22:22'","'2012-01-21 21:22:28'","'2012-01-21 21:22:33'",11,4,"ANSWERED","","" => C:\Program Files\VoiceGuide723\cdr\CDR_20120121_out.txt , C:\Program Files\VoiceGuide723\cdr\CDR_20120121_out_ANSWERED.txt

212233.095 6 4 2 CDR SQL: INSERT INTO cdrout (Account, Src, Dst, Dcontext, Clid, Channel, Dstchannel, Lastapp, Lastdata, dtStart, dtAnswer, dtHangup, Duration, Billsec, Disposition, Amaflags, Usr) VALUES ('','502131638','','','','','','','','2012-01-21 21:22:22','2012-01-21 21:22:28','2012-01-21 21:22:33',11,4,'ANSWERED','','')

212233.095 6 4 2 sIni_CDRLOG_ODBC_ConnectString (secondary CDR DB) not set.

212233.095 6 4 2 no script running on this line. AfterHangup script will not be ran.

212233.095 6 4 2 AllScritpsCompleted_Cleanup start crn=28002b5

212233.095 6 4 2 ScriptWorkingMode set Idle_WaitingForNextCall, (called from AllScritpsCompleted_Cleanup)

212233.095 6 4 2 logs not written as LogEntryWritten=1

212233.095 6 4 2 LineState_Reinit_AndReleaseCall: crn=28002b5

212233.095 6 4 2 LineState_Reinit_CleanLineState : begin

212233.095 6 4 2 ls set Null_InGuardTimeAfterEndOfCall (scriptstate=LS_ENDINGCALL)

212233.095 6 4 2 ScriptWorkingMode set Idle_WaitingForNextCall, (called from LineState_Reinit_CleanLineState)

212233.095 6 4 2 strThisCallIsInboundOrOutbound clear. (LineState_Reinit_CleanLineState)

212233.095 6 4 2 qTel add cmd_ReleaseCall [0,0,0,0,0][||||]

212233.095 6 4 2 t timer set 3 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT

212233.095 7 4 2 qTel run cmd_ReleaseCall

212233.095 6 4 2 state Waiting for a call...

212233.095 6 4 2 ScriptWorkingMode set Idle_WaitingForNextCall, (called from LineState_Reinit_AndReleaseCall)

212233.095 18 4 2 ev CallState GCEV_RELEASECALL, crn=28002b5, iEvent=2137 ,0,0,0,GCST_NULL,,]. vgEngine v7.2.4160.36381 (2011-05-23 19:12:42.09)

212233.095 18 4 2 qScr add evCallState 2137 GCEV_RELEASECALL

212233.095 6 4 2 qScr run evCallState sCode=[GCEV_RELEASECALL] iActionID=0, crn=28002b5[0|0|0|0|0][GCST_NULL|||||]

212233.095 6 4 2 LineEvCallState crn=28002b5 event=2137|GCEV_RELEASECALL iCallState=0calldirection=0 gcCallState=0 [GCST_NULL||]

212233.095 6 4 2 LineEvCallState_GCEV_RELEASECALL begin

212233.095 6 4 2 ls set Null_InGuardTimeAfterEndOfCall (scriptstate=LS_NULL_AWAITINGCALLS)

212233.095 6 4 2 ScriptWorkingMode set Idle_WaitingForNextCall, (called from gcev_releasecall)

212233.095 6 4 2 t timer set 3 sec : EV_TIMEOUT_AFTERIDLE_ALLOWOUT

212233.095 6 4 2 callstate: left as is (Null_InGuardTimeAfterEndOfCall) as sEvent==GCEV_RELEASECALL. crn=28002b5,ev=2137,GCEV_RELEASECALL,0, sParam1=GCST_NULL

Share this post

Link to post

SupportTeam   

Posted

Trace shows that the "On Hangup" script was started:

212233.048 6 2 1 FindFullPathToScript_VgsOrVgp start [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

212233.048 6 2 1 after hangup script: [C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp]

212233.048 6 LoadedVgs_Find C:\Documents and Settings\user\Moje dokumenty\VG\Scripts\Maciej\prepaid_temination_pro_15.vgp at idx=5

212233.048 6 2 1 After Hangup script started (from LineEvCallState() LINECALLSTATE_IDLE handler)

212233.048 6 2 1 ScriptWorkingMode set Running_AfterHangup, (called from StartAfterHangupScriptIfDefined)

 

and we can see in the trace attached the first two modules of that script being ran:

 

module "call_end":

 

212233.048 6 2 1 state [call_end] Evaluate [now()]

 

and module "update finish call timestamp":

212233.048 6 2 1 state [update finish call timestamp] DB Query

 

The trace is too short to see what happens, after the module "update finish call timestamp" does the SQL UPDATE (or whether the database update completed OK).

Share this post

Link to post

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
Go To Topic Listing General
×