I am newbie in GVP. I am trying to verify bridge transfer which always return noanswer. I have attached MCP logs. Can anyone tell me what is wrong?
- Enabled tranfer allowed, outbound allowed in IVR profile
- Create LRG profile for gateway.
- Transfer destination is ‘8101’ which is routing point.
- Routing strategy is loaded.
If i place call thru xlite to 8101. It routes the call to agent. However, it does not work thru IVR.
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02800000 LMCallSIP.C:1597 In CallDisconnect
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02800000 LMCallSIP.C:1639 LMCallSIP::CallDisconnect - strContentType=, strContent=
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02800000 LMCallSIPStack.C:167 LMCallSIP::SendRequest for X-Genesys-GVP-Session-ID
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02800000 LMCallSIPStack.C:190 LMCallSIP::SendRequest - X-Genesys-GVP-Session-ID: 2ED2C265-F19D-407D-8EA6-E5178A30EB94;gvp.rm.datanodes=1;gvp.rm.tenant-id=1.101_TestIVR
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 08500000 VGSIPTransportMgr.cxx:3474 VGSIPTransportMgr::ResolveDNS for 192.168.3.43:5060
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 08500000 VGSIPTransportMgr.cxx:3585 VGSIPTransportMgr::ResolveDNS nResult 2
2014-11-17T16:29:31.541 Trc 33009 INFO 00000000-00000000 6624 02800FA1 Request sent: CANCEL sip:8101@192.168.3.43:5080;user=phone SIP/2.0
Via: SIP/2.0/TCP 192.168.3.43:5070;branch=z9hG4bK0000000019015E5069d4f3
From: sip:1003@192.168.3.43:5080;tag=04086DEF-5287-4974-7B9B-656CC48FB332
To: <sip:8101@192.168.3.43:5080;user=phone>
Max-Forwards: 70
CSeq: 1 CANCEL
Call-ID: F38AF297-4FBB-45C7-078A-1EBB74E8D352-5070@192.168.3.43
Content-Length: 0
Route: <sip:0000000011BF6CC0@192.168.3.43:5060;transport=TCP;lr;gvp.rm.datanodes=1;idtag=0000003B>
X-Genesys-GVP-Session-ID: 2ED2C265-F19D-407D-8EA6-E5178A30EB94;gvp.rm.datanodes=1;gvp.rm.tenant-id=1.101_TestIVR
Supported: timer, uui
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02800000 LMCallSIP.C:3481 LMCallSIP::CloseMRCPResources - ASRState=0, TTSState=0, bForceClose=false
2014-11-17 16:29:31.541 DBUG 00770146-6C002330 6624 02F00000 MPCWrapper.cxx:997 Processing Media Destroy on Session 100031FF
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:1740 MPCConnection::Destroy nLogID=00770146-6C002330 nConnID=100031FF
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2100 MPCConnection::DisconnectRoute nLogID=00770146-6C002330 nConnID=100031FF ControlSinkID=100031D9
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2387 MPCConnection::GetFCRSink nLogID=00770146-10002D59 nConnID=100031D9
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2362 MPCConnection::GetSinks nLogID=00770146-10002D59 nConnID=100031D9 ControlSourceID=100031FF
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2375 MPCConnection::GetSink - Sinks.size()=[1]
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2387 MPCConnection::GetFCRSink nLogID=00770146-10002D59 nConnID=100031D9
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCControlObject.cxx:416 MPCControlObject::UnregisterSinkHelper LogID=00770146-6C002330 ObjectID=100031FF OperatorID=00010011 SinkID=00010000 pRoute=18FF8170
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCConnection.cxx:2341 MPCConnection::UnregisterReverseRoute nLogID=00770146-10002D59 nConnID=100031D9 ControlSourceID=100031FF
2014-11-17 16:29:31.541 DBUG 00000000-00000000 6624 0B100000 MPCControlObject.cxx:368 MPCControlObject::UnregisterSinkHelper LogID=00770146-6C002330 ObjectID=100031FF SourceID=00010010 SinkID=00010011 pRoute=00000000
2014-11-17 16:29:31.542 DBUG 00000000-00000000 924 02F00000 MPCWrapper.cxx:3753 Inside Route Destroyed From: 100031FF To: 100031D9 Route: 00000021, obtained route mutex
2014-11-17 16:29:31.542 DBUG 00000000-00000000 924 02F00000 MPCWrapper.cxx:2967 Processing Call Term. From: 268448255, To: 268448217
2014-11-17 16:29:31.542 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2257 MPCConnection::RegisterReverseRoute nLogID=00770146-10002D59 nConnID=100031D9 ControlSourceID=200031DA
2014-11-17 16:29:31.542 DBUG 00000000-00000000 924 0B100000 MPCDialog.cxx:886 MPCDialog::RegisterForwardRoute, LogID=00770146-10002D59 DialogID=200031DA ControlSinkID=100031D9
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCDialog.cxx:918 MPCDialog::ConnectRoute, LogID=00770146-10002D59 DialogID=200031DA ControlSinkID=100031D9
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2362 MPCConnection::GetSinks nLogID=00770146-10002D59 nConnID=100031D9 ControlSourceID=200031DA
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2375 MPCConnection::GetSink - Sinks.size()=[1]
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2387 MPCConnection::GetFCRSink nLogID=00770146-10002D59 nConnID=100031D9
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCControlObject.cxx:333 MPCControlObject::RegisterSinkHelper LogID=00770146-10002D59 ObjectID=200031DA OperatorID=00010003 SinkID=00010000
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 02F00000 MPCWrapper.cxx:4131 Inside Route Created From: 200031DA To: 100031D9 Route: 34
2014-11-17T16:29:31.543 Trc 49005 INFO 00000000-00000000 7152 0B300FA1 RTP 20046: Rx 0/0 lost 0 dropped 0 dec_err 0 jitter 0, Tx 0/0 enc_err 0
2014-11-17T16:29:31.543 Trc 49005 INFO 00000000-00000000 8480 0B300FA1 RTP 20048: Rx 0/0 lost 0 dropped 0 dec_err 0 jitter 0, Tx 0/0 enc_err 0
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2712 MPCConnection::ProcessRTPSocketClosed nLogID=00770146-6C002330 nConnID=100031FF
2014-11-17 16:29:31.543 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:2533 MPCConnection::QueueDestroyedEvent nLogID=00770146-6C002330 nConnID=100031FF
2014-11-17 16:29:31.543 DBUG 00770146-6C002330 924 02F00000 MPCWrapper.cxx:3954 ConnDestroyed for Connection ID: 268448255
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 01C00000 CMMsgBase.h:183 cmapi.msg: CM_MSG_LMCC_DISCONNECT - 00000000-6C002330; Reason=1; Initiator=1; ProtocolCause=No Answer
2014-11-17 16:29:31.543 DBUG 00770146-88002D5B 1792 01C00000 CallLegStateOutboundTransfer.C:124 m_TransferState is 2
2014-11-17 16:29:31.543 DBUG 00770146-6C002330 1792 01C00000 CMCallBilling.C:872 REASON IS 1
2014-11-17 16:29:31.543 DBUG 00770146-6C002330 1792 02100000 BillingMetricsLogger.C:483 Logging Billing RE
2014-11-17T16:29:31.543 Int 50056 00770146-6C002330 1792 call_reference F38AF297-4FBB-45C7-078A-1EBB74E8D352-5070@192.168.3.43|2ED2C265-F19D-407D-8EA6-E5178A30EB94|N/A|N/A
2014-11-17 16:29:31.543 DBUG 00770146-6C002330 1792 02100000 BillingMetricsLogger.C:375 Logging Billing BR
2014-11-17T16:29:31.543 Int 50005 00770146-6C002330 1792 bridge_reject sip:1003@192.168.3.43:5080|tel:8101|00770146-10002D59|N/A|noanswer
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 01C00000 CMCallObjMgr.C:459 00000000-6C002330 CMCallObjMgr::ReleaseCallLeg
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 01C00000 CMCallManager.h:246 Releasing license 1486DB60
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 02400000 GVPLicenseLogger.C:112 Release GVP License:Product=vggateway; Features=out; Current Use=0; Peak Use=1
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 01C00000 CMCallManager.h:252 license usage: Product=vggateway; Features=in; Current Use=1; Peak Use=1
Product=vggateway; Features=out; Current Use=0; Peak Use=1
2014-11-17 16:29:31.543 DBUG 00770146-6C002330 1792 01C00000 CMCallLeg.C:157 CMCallLeg::~CMCallLeg - CallEnd called - szComponentId=00770146-6C002330
2014-11-17T16:29:31.543 Trc 20001 INFO 00770146-6C002330 1792 01C00000 CMCallLeg deleted 00000000149254D0
2014-11-17 16:29:31.543 DBUG 00770146-88002D5B 7096 0C000000 AppCallEvent.h:58 app.VXML-NG AMEVENT_CALL_CALLLEGDISCONNECTED - CallLegID=00770146-6C002330,CallDur=0,DiscReason=1,DiscInit=1
2014-11-17 16:29:31.543 DBUG 00000000-00000000 7096 0C000000 VXMLCall.cxx:93 ProcessCallEvent() Call 00770146-88002D5B Event 3
2014-11-17 16:29:31.543 DBUG 00000000-00000000 1792 01C00000 CMMsgBase.h:183 cmapi.msg: CM_MSG_LMMEDIA_SOURCECHANGE - 00000000-10002D59; SourceType=0; SourcePlatID=00000000-10002D59; SourceSessID=00000000-00000000; ChangeReason=4
2014-11-17T16:29:31.543 Trc 21005 INFO 00770146-10002D59 1792 02B00FAA route_result session|00770146-10002D59|platform|callterm
2014-11-17T16:29:31.548 Trc 33009 INFO 00000000-00000000 8004 02800FA1 Response received: SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.3.43:5070;branch=z9hG4bK0000000019015E5069d4f3
From: sip:1003@192.168.3.43:5080;tag=04086DEF-5287-4974-7B9B-656CC48FB332
To: <sip:8101@192.168.3.43:5080;user=phone>;tag=FDB74A48-355F-472E-DEA0-314972680744
CSeq: 1 CANCEL
Call-ID: F38AF297-4FBB-45C7-078A-1EBB74E8D352-5070@192.168.3.43
Content-Length: 0
2014-11-17T16:29:31.548 Trc 33009 INFO 00000000-00000000 8004 02800FA1 Response received: SIP/2.0 487 Request Terminated
Via: SIP/2.0/TCP 192.168.3.43:5070;branch=z9hG4bK0000000019015E5069d4f3
From: sip:1003@192.168.3.43:5080;tag=04086DEF-5287-4974-7B9B-656CC48FB332
To: <sip:8101@192.168.3.43:5080;user=phone>;tag=6663B47B-5B9C-4965-876B-F41B4A0E09A2-17
CSeq: 1 INVITE
Call-ID: F38AF297-4FBB-45C7-078A-1EBB74E8D352-5070@192.168.3.43
Content-Length: 0
X-Genesys-CallUUID: GV2555MIC50KNBM2EP9PBE7694000007
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
X-Genesys-GVP-Session-ID: 2ED2C265-F19D-407D-8EA6-E5178A30EB94;gvp.rm.datanodes=1
X-Genesys-RM-Application-dbid: 102
2014-11-17 16:29:31.548 DBUG 00000000-00000000 8004 08500000 VGSIPTransportMgr.cxx:3474 VGSIPTransportMgr::ResolveDNS for 192.168.3.43:5060
2014-11-17 16:29:31.548 DBUG 00000000-00000000 8004 08500000 VGSIPTransportMgr.cxx:3585 VGSIPTransportMgr::ResolveDNS nResult 2
2014-11-17T16:29:31.548 Trc 33009 INFO 00000000-00000000 8004 02800FA1 Request sent: ACK sip:8101@192.168.3.43:5080;user=phone SIP/2.0
Via: SIP/2.0/TCP 192.168.3.43:5070;branch=z9hG4bK0000000019015E5069d4f3
From: sip:1003@192.168.3.43:5080;tag=04086DEF-5287-4974-7B9B-656CC48FB332
To: <sip:8101@192.168.3.43:5080;user=phone>;tag=6663B47B-5B9C-4965-876B-F41B4A0E09A2-17
Max-Forwards: 70
CSeq: 1 ACK
Call-ID: F38AF297-4FBB-45C7-078A-1EBB74E8D352-5070@192.168.3.43
Content-Length: 0
Route: <sip:0000000011BF6CC0@192.168.3.43:5060;transport=TCP;lr;gvp.rm.datanodes=1;idtag=0000003A>
2014-11-17 16:29:31.549 DBUG 00770146-10002D59 6624 02800000 LMCallSIP.C:1774 Received MediaSource: 0,
2014-11-17 16:29:31.549 DBUG 00000000-00000000 6624 02F00000 MPCWrapper.cxx:2244 Processing MediaSource Sink: 100031D9, SourcePlat 100031D9, SouceSess 00000000
2014-11-17 16:29:31.549 DBUG 00770146-6C002330 6624 02800000 LMCallSIPHandler.C:153 LMCallSIP::HandleDerivedEvent - nEventType=1, m_nState=9, m_nIntfState=8
2014-11-17 16:29:31.549 DBUG 00770146-6C002330 6624 02800000 LMCallSIPHandler.C:153 LMCallSIP::HandleDerivedEvent - nEventType=1, m_nState=9, m_nIntfState=8
2014-11-17 16:29:31.549 DBUG 00770146-6C002330 6624 02800000 LMCallSIPHandler.C:2714 Handle Sip Invite Response
2014-11-17 16:29:31.549 DBUG 00000000-00000000 1792 01C00000 CMMsgBase.h:183 cmapi.msg: CM_MSG_LMMEDIA_SOURCECHANGE - 00000000-10002D59; SourceType=0; SourcePlatID=00000000-10002D59; SourceSessID=00000000-00000000; ChangeReason=3
2014-11-17T16:29:31.549 Trc 21005 INFO 00770146-10002D59 1792 02B00FAA route_result session|00770146-10002D59|platform|requested
2014-11-17 16:29:31.549 DBUG 00770146-88002D5B 7096 0C000000 AppCallEvent.h:58 app.VXML-NG AMEVENT_CALL_TRANSFERFINISHED - Result=11,ISDNCause=0
2014-11-17 16:29:31.549 DBUG 00000000-00000000 7096 0C000000 VXMLCall.cxx:93 ProcessCallEvent() Call 00770146-88002D5B Event 13
2014-11-17T16:29:31.549 Trc 21010 INFO 00770146-10002D59 7096 02B00FA3 transfer_end
2014-11-17 16:29:31.549 DBUG 00000000-00000000 7096 0C000000 VXMLUtils.h:285 PlatformTransferResult() Create PlatformTransferResult 0x18fedd20
2014-11-17 16:29:31.549 DBUG 00000000-00000000 7096 0C000000 VXMLUtils.h:252 PlatformPromptDoneInfo() Create PlatformPromptDoneInfo 0x14c264c0
2014-11-17T16:29:31.549 Int 50014 00770146-10002D59 7096 transfer_end :noanswer
2014-11-17 16:29:31.549 DBUG 00770146-6C002330 7096 0C000000 VXMLCallLeg.cxx:5000 Calling DeleteDTMFAppSession 19
2014-11-17 16:29:31.549 DBUG 00770146-6C002330 7096 03A00000 VGDTMFRecognitionThread.C:316 DeleteAppSession(): Session ID: 19
2014-11-17 16:29:31.553 DBUG 00770146-6C002330 3672 03A00000 VGDTMFRecognitionThread.C:944 HandleDeleteSession(): Session ID: 19
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1910 ProcessEvents() Started
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1927 ProcessEvents() Event type [8], session id: [00770146-10002D59]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1990 ProcessEvents(): Transfer Done
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:2374 HandleTransferCompleteEvent() Started
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:2963 PopulateApplicationLastResult(VXMLPromptDoneInfo) Filling in application.lastresult$
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:3005 PopulateApplicationLastResult(VXMLRecoResult) Filling in application.lastresult$
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:4351 HandleInputEnd() Started for result: [56]
2014-11-17T16:29:31.553 Int 50025 00770146-10002D59 8684 input_end TRANSFER_END|||||
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeRoot.cxx:213 Execute() Started, event type: 8
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeDoc.cxx:162 Execute() Started, event type: 8
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 InputItemRuntime.cxx:127 Execute() Started
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 TransferRuntime.cxx:530 HandleIncomingEventForAction() Started
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 InputItemRuntime.cxx:1745 PopulateNameDollarSign(VXMLPromptDoneInfo) Started
2014-11-17T16:29:31.553 Int 50076 00770146-10002D59 8684 filling :RouteRequest1.RouteRequest1Call:Transfer:noanswer
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 InputItemRuntime.cxx:257 Execute() Returning done
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.553 Int 50032 00770146-10002D59 8684 filled_enter all
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 FilledInputItemRuntime.cxx:39 Execute() Started, event type: -1
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [153]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Assign] at line [153]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 AssignRuntime.cxx:34 Execute() Started, event type: -1
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 AssignRuntime.cxx:48 Execute(): Script [AppState.RouteRequest1;AppState.RouteRequest1=RouteRequest1Call;]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 AssignRuntime.cxx:64 Execute(): Returning done
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [154]
2014-11-17 16:29:31.553 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Goto] at line [154]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 GotoRuntime.cxx:39 Execute() Started, event type: -1
2014-11-17T16:29:31.554 Int 50033 00770146-10002D59 8684 goto :#Prompt1
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.554 Int 50030 00770146-10002D59 8684 form_exit normal
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeDoc.cxx:216 ExecuteForm() Performing goto to a specified form in the current page
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Form] at line [113]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.554 Int 50028 00770146-10002D59 8684 form_enter :Prompt1
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:888 FIASelect() Formitem variable with name [Prompt1P0] is undefined
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:919 FIASelect() Selected formitem with name [Prompt1P0] (condition test passed)
2014-11-17T16:29:31.554 Int 50029 00770146-10002D59 8684 form_select :Prompt1P0:BLOCK
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Block] at line [115]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 BlockRuntime.cxx:195 Execute() Started
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [116]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Prompt] at line [116]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 PromptRuntime.cxx:58 Execute() Started
2014-11-17 16:29:31.554 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [bargeintype], value [speech]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.tts.defaultengine]; Value=[default]; [Global]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 PromptRuntime.cxx:112 Execute() Using configured TTS: [default]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:425 EvaluateExpression(): Expression [AppState.APP_LANGUAGE]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:447 EvaluateExpression(): Expression [en-US]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Text] at line [117]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 TextRuntime.cxx:26 Execute() Queueing: []
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Audio] at line [117]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 AudioRuntime.cxx:85 Execute() Started
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 AudioRuntime.cxx:136 Execute() Evaluated script result [../Resources/Prompts/en-US/goodbye.wav]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [bargeintype], value [speech]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Text] at line [118]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 TextRuntime.cxx:26 Execute() Queueing: []
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [119]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:942 FIASelect() Skipping formitem with name [Prompt1P0] because it's already defined
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:888 FIASelect() Formitem variable with name [__FORMITEM_NAME_$35$_] is undefined
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:919 FIASelect() Selected formitem with name [__FORMITEM_NAME_$35$_] (condition test passed)
2014-11-17T16:29:31.555 Int 50029 00770146-10002D59 8684 form_select :__FORMITEM_NAME_$35$_:BLOCK
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Block] at line [120]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 BlockRuntime.cxx:195 Execute() Started
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [121]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Goto] at line [121]
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 GotoRuntime.cxx:39 Execute() Started, event type: -1
2014-11-17T16:29:31.555 Int 50033 00770146-10002D59 8684 goto :#Exit1
2014-11-17 16:29:31.555 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.556 Int 50030 00770146-10002D59 8684 form_exit normal
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeDoc.cxx:216 ExecuteForm() Performing goto to a specified form in the current page
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Form] at line [126]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.556 Int 50028 00770146-10002D59 8684 form_enter :Exit1
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:888 FIASelect() Formitem variable with name [__FORMITEM_NAME_$36$_] is undefined
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:919 FIASelect() Selected formitem with name [__FORMITEM_NAME_$36$_] (condition test passed)
2014-11-17T16:29:31.556 Int 50029 00770146-10002D59 8684 form_select :__FORMITEM_NAME_$36$_:BLOCK
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Block] at line [127]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 BlockRuntime.cxx:195 Execute() Started
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [128]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [LogElement] at line [128]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 LogRuntime.cxx:29 Execute() Started, event type: -1
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:559 EvaluateCond(): Condition [AppState.EnableReports]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:584 EvaluateCond(): Result was 'false'
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [129]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Return] at line [129]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 ReturnRuntime.cxx:24 Execute() Started, event type: -1
2014-11-17T16:29:31.556 Int 50023 00770146-10002D59 8684 subdialog_return :|event|
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:2434 HandleTransferCompleteEvent() Done
2014-11-17 16:29:31.556 DBUG 00000000-00000000 8684 0C000000 VXMLUtils.h:288 ~PlatformTransferResult() Delete PlatformTransferResult 0x18fedd20
2014-11-17 16:29:31.556 DBUG 00000000-00000000 8684 0C000000 VXMLUtils.h:253 ~PlatformPromptDoneInfo() Delete PlatformPromptDoneInfo 0x14c264c0
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1927 ProcessEvents() Event type [11], session id: [00770146-10002D59]
2014-11-17 16:29:31.556 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1997 ProcessEvents(): subdialog return
2014-11-17T16:29:31.556 Int 50030 00770146-10002D59 8684 form_exit normal
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.maintainer.sendwhen], value [on_message]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.557 DBUG 00000000-00000000 8684 08500500 VGDocumentManager.cxx:385 Deleting document, uri: http://192.168.12.165:9090/POC/src-gen/agentTransferBridge.vxml
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeRoot.cxx:213 Execute() Started, event type: 11
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeDoc.cxx:162 Execute() Started, event type: 11
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 InputItemRuntime.cxx:127 Execute() Started
2014-11-17T16:29:31.557 Int 50076 00770146-10002D59 8684 filling :Subdialog1.Subdialog1Subdialog:Subdialog:[object default]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 InputItemRuntime.cxx:257 Execute() Returning done
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.557 Int 50032 00770146-10002D59 8684 filled_enter all
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 FilledInputItemRuntime.cxx:39 Execute() Started, event type: -1
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [543]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Goto] at line [543]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 GotoRuntime.cxx:39 Execute() Started, event type: -1
2014-11-17T16:29:31.557 Int 50033 00770146-10002D59 8684 goto :#Exit2
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.557 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.557 Int 50030 00770146-10002D59 8684 form_exit normal
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 VxmlRuntimeDoc.cxx:216 ExecuteForm() Performing goto to a specified form in the current page
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Form] at line [530]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1349 HandleSaveTempFileSettings() Get property; Name [__.savetmpfiles], value [none]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 PropertyStore.cxx:1355 HandleSaveTempFileSettings() Get property; Name [__.savetmpfilesmode], value [immediate]
2014-11-17T16:29:31.559 Int 50028 00770146-10002D59 8684 form_enter :Exit2
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:888 FIASelect() Formitem variable with name [__FORMITEM_NAME_$15$_] is undefined
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 FormRuntime.cxx:919 FIASelect() Selected formitem with name [__FORMITEM_NAME_$15$_] (condition test passed)
2014-11-17T16:29:31.559 Int 50029 00770146-10002D59 8684 form_select :__FORMITEM_NAME_$15$_:BLOCK
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Block] at line [531]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 BlockRuntime.cxx:195 Execute() Started
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [SimplePrompt] at line [532]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 08500000 SessionConfiguration.C:112 SessConfig::GetValue - Param=[vxmli.conformance.strict_tts_mode]; Value=[FALSE]; [Global]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 SimplePromptRuntime.cxx:33 Execute() Started
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:80 RuntimeFactory(): Called for [Exit] at line [532]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 ExitRuntime.cxx:52 Execute() Started, event type: -1
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 Runtime.cxx:922 RuntimeHelper::GetStringProperty() Name [__.maintainer.sendwhen], value [on_message]
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 ExitRuntime.cxx:176 Execute(): Flushing prompt queue
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 8684 0C000000 VXMLSession.cxx:1916 ProcessEvents() Event queue was empty
2014-11-17 16:29:31.559 DBUG 00000000-00000000 7096 0C000000 AppEventBase.h:61 app.VXML-NG AMEVENT_VXML_DTMF_SESSION_DONE -
2014-11-17 16:29:31.559 DBUG 00770146-6C002330 7096 0C000000 VXMLCallLeg.cxx:299 Delete Call Leg 00770146-6C002330
2014-11-17 16:29:31.559 DBUG 00770146-6C002330 7096 0C000000 VXMLCallLeg.cxx:5413 Entered delete noinput timeout timer.
2014-11-17 16:29:31.559 DBUG 00770146-6C002330 7096 0C000000 VXMLCallLeg.cxx:5413 Entered delete noinput timeout timer.
2014-11-17 16:29:31.559 DBUG 00770146-6C002330 7096 0C000000 AppCallLeg.h:46 ~AppCallLeg m_pCallLegHandler->ReleaseReference();
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 7096 0C000000 VXMLInstance.cxx:1548 FinalCleanup() App 0x18eb2a00
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 7096 0C000000 VXMLInstance.cxx:1552 FinalCleanup() must wait for all CallLegs to unbind first
2014-11-17 16:29:31.559 DBUG 00000000-00000000 7096 0C000000 AppEventBase.h:61 app.VXML-NG AMEVENT_VXML_PLAY_TRANSITIONAL -
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:119 LogPrompts() Prompt: http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav Next:
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:128 LogPrompts() Alternative:
2014-11-17 16:29:31.559 DBUG 00770146-10002D59 7096 0C000000 VXMLCallLeg.cxx:3000 VXMLCallLeg::HandlePlayTransitional
2014-11-17T16:29:31.559 Int 50018 00770146-10002D59 7096 prompt
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:187 GetNextPrompt() Playing 0x1454e790
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:979 Using streaming value: 0
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 VXMLFetchHelper.cxx:32 VXMLFetchHelper::VXMLFetchHelper[0000000018F7A3D0]
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 VXMLFetchObject.cxx:31 VXMLFetchObject() Create VXMLFetchObject 0x18f7a3d0
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 VXMLInstance.cxx:629 Fetching Document http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000000 VXMLFetchManager.cxx:195 Using FM:CreateRequest as streaming is 0
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 7096 01F00000 FMUserResponse.cxx:30 UserResponse created. 19010d10
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 7096 0C000500 VXMLFetchManager.cxx:313 Fetching document, uri: http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav, nMaxage: -1, nMaxstale: 0, enctype: (null), post: false, timeout: 30000
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000D5 6528 01F00000 FMThreadQueue.cxx:1262 Processing ReleaseRequest
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000D5 6528 01F00000 FMUserRequest.cxx:132 Deleting Request
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:950 Processing GET request, URL: http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:982 found in cache, check if cache is valid
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:447 Re-using existing connection! (#0) with host 192.168.12.165
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:447 Connected to 192.168.12.165 (192.168.12.165) port 9090 (#0)
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:578 GET /POC/Resources/Prompts/en-US/goodbye.wav HTTP/1.1
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 User-Agent: NGi/GVP Media Control Platform (Build: 8.5.041.14)
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 Host: 192.168.12.165:9090
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 Accept: */*
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 If-Modified-Since: Fri, 24 Oct 2014 06:49:11 GMT
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 If-None-Match: W/"193996-1414133351600"
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 Cache-Control: max-stale=0
2014-11-17 16:29:31.560 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:547 X-Genesys-FMSessionID: 00770146-10002D59-000000DE
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 6732 0C000000 VXMLSession.cxx:1910 ProcessEvents() Started
2014-11-17 16:29:31.560 DBUG 00770146-10002D59 6732 0C000000 VXMLSession.cxx:1916 ProcessEvents() Event queue was empty
2014-11-17 16:29:31.562 DBUG 00000000-00000000 6528 01F00000 FMResponseData.cxx:341 HTTP/1.1 304 Not Modified
2014-11-17 16:29:31.562 DBUG 00000000-00000000 6528 01F00000 FMResponseData.cxx:360 Server: Apache-Coyote/1.1
2014-11-17 16:29:31.562 DBUG 00000000-00000000 6528 01F00000 FMResponseData.cxx:360 ETag: W/"193996-1414133351600"
2014-11-17 16:29:31.562 DBUG 00000000-00000000 6528 01F00000 FMResponseData.cxx:360 Date: Mon, 17 Nov 2014 11:04:33 GMT
2014-11-17 16:29:31.562 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:447 Connection #0 to host 192.168.12.165 left intact
2014-11-17 16:29:31.562 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:710 Request successful: uri=http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17 16:29:31.563 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:825 HTTP request completed
2014-11-17 16:29:31.563 DBUG 00000000-00000000 6528 01F00000 FMResponseData.cxx:421 Received fetch completed, response code=200
2014-11-17 16:29:31.563 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMThreadQueue.cxx:1550 Added response to cache
2014-11-17 16:29:31.563 DBUG 00770146-10002D59-000000DE 6528 01F00000 FMUserRequest.cxx:511 Request completed, sending RequestCompleteNotify.
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6528 0C000000 VXMLFetchManager.cxx:86 VXMLFetchHelper_FetchDone entered
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6528 0C000000 VXMLFetchManager.cxx:362 FetchDocDone() FSTATE_FETCHED or FSTATE_MODIFIED
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6528 0C000000 VXMLFetchManager.cxx:380 FetchDocDone() calling ProcessDocComplete()
2014-11-17 16:29:31.563 DBUG 00000000-00000000 7096 0C000000 AppEventBase.h:61 app.VXML-NG AMEVENT_VXML_FETCH_DONE -
2014-11-17T16:29:31.563 Trc 20001 INFO 00770146-10002D59 7096 02B6871A Fetching Done|0ms|audio|http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17T16:29:31.563 Int 50043 00770146-10002D59 7096 fetch_end Done (revalidated):http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 VXMLCallLeg.cxx:504 HandleFetchDone() entered
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:661 HandleFetchDone() Play fetched audio
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:187 GetNextPrompt() Playing 0x1454e790
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:429 Using smm branch 2 to fetch content as streaming is 0
2014-11-17T16:29:31.563 Int 50134 00770146-10002D59 7096 prompt_play audio|http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17T16:29:31.563 Trc 21007 INFO 00770146-10002D59 7096 02B00FA4 prompt_type regular
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:1092 PromptPlay() first:1 changed:0 asr:0 speech:0
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 PromptSession.cxx:1114 Katie - Audio Streaming is set: 0
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 7096 0C000000 VXMLCallLeg.h:318 Calling AppCallLeg::MediaPlay
2014-11-17 16:29:31.563 DBUG 00000000-00000000 1792 01C00000 CMMsgBase.h:183 cmapi.msg: CM_MSG_DIALOG_APP_MEDIA_PLAY - Dialog ID == 536883674; RequestID == 6; PlayFlag == 11; PromptType == 1; MimeType == audio/x-wav; Volume == 0; SkipDuration == 6000; SpeechBargeIn == 0; DTMFBargeIn == none; HeaderBytes == 0; StartOffset == 0; TimeLimit == 4294967295; TTSEngine == ; TTSEngineUri == ;TTSCap == ; TTSGender == ; TTSVoiceName == ; TTSContent == ; SilenceDelay == 1000; MediaLocation == smm://000000DE; OrigURL == http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav; CacheParam == ; MarkTag == ; Encoding ==
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6624 02F00000 MPCWrapper.cxx:1107 MediaPlayStored for session 100031D9
2014-11-17 16:29:31.563 DBUG 00000000-00000000 6624 0B100000 MPCDialog.cxx:212 MPCDialog::PlayStart, LogID=00770146-10002D59 DialogID=200031DA
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6624 0B200000 MPCPlayer.cxx:325 MPCPlayer::AddToQueue: audio/video[http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav type=audio/x-wav] visual[ type=] firstplay=1 lastplay=1
2014-11-17 16:29:31.563 DBUG 00770146-10002D59 6624 0B200000 MPCPlayer.cxx:349 MPCPlayer Starting m_State=0
2014-11-17 16:29:31.563 DBUG 00000000-00000000 6932 0B000000 MMPlayCached.cxx:897 MMPlayCached[0000000014B3C0A0]::Open CacheEntry needs version check, http%3A%2F%2F19_S%2Fgoodbye.wav_6ad68689e4fb3e44aed24e7a4066faf1
2014-11-17 16:29:31.563 DBUG 00000000-00000000 6932 0B000000 MMPlayCached.cxx:912 MMPlayCached[0000000014B3C0A0]::Open CacheEntry had to be created for http://192.168.12.165:9090/POC/Resources/Prompts/en-US/goodbye.wav
2014-11-17 16:29:31.564 DBUG 00770146-10002D59 924 02F00000 MPCWrapper.cxx:3289 DialogPlayNotify with Dialog ID: 536883674
2014-11-17 16:29:31.564 DBUG 00000000-00000000 6932 0B000000 TrackCache.cxx:860 CacheEntry[000000000711B820]::GetOrAddCacheEntry version matches, http%3A%2F%2F19_S%2Fgoodbye.wav_6ad68689e4fb3e44aed24e7a4066faf1
2014-11-17 16:29:31.564 DBUG 00770146-10002D59 6932 0B200000 MPCPlayer.cxx:2627 IsReady=true, m_nMaxDuration=6061, nResult=0
2014-11-17 16:29:31.564 DBUG 00000000-00000000 6932 0B000000 MMPlayCached.cxx:90 MMPlayCachedTrack[0000000014821100]::Map nMediaType=0 BeginTime=0 EndTime=1000, http%3A%2F%2F19_S%2Fgoodbye.wav_6ad68689e4fb3e44aed24e7a4066faf1
2014-11-17 16:29:31.564 DBUG 00000000-00000000 6932 0B000000 VGMediaFileCached.cxx:122 VGMediaFileCached[0000000014B3C1E0]::ParseIndex: AddEntry Time=0 Duration=1000 IsRef=1 Offset=16 Size=8000
2014-11-17 16:29:31.564 DBUG 00000000-00000000 6932 0B000000 MMPlayCached.cxx:199 MMPlayCachedTrack[0000000014821100]::Map nResult=0 nPackets=1 bEOD=0
2014-11-17T16:29:31.564 Trc 21003 INFO 00770146-10002D59 6932 02B00FAC prompt_start
2014-11-17 16:29:31.643 DBUG 00000000-00000000 924 0B100000 MPCConnection.cxx:123 MPCConnection::~MPCConnection nLogID=00770146-6C002330 nConnID=100031FF
2014-11-17 16:29:31.848 DBUG 00000000-00000000 7224 06100000 VRMServerPing.C:419 ResIdToLastResponseMap size: 0
2014-11-17 16:29:31.848 DBUG 00000000-00000000 7224 06100000 VRMServerPing.C:482 Total number of Ping send for the session is 0. Resetting the number of ASR and TTS
2014-11-17 16:29:32.054 DBUG 00000000-00000000 8792 0B000000 MMPlayCached.cxx:90 MMPlayCachedTrack[0000000014821100]::Map nMediaType=0 BeginTime=1000 EndTime=1480, http%3A%2F%2F19_S%2Fgoodbye.wav_6ad68689e4fb3e44aed24e7a4066faf1
2014-11-17 16:29:32.054 DBUG 00000000-00000000 8792 0B000000 VGMediaFileCached.cxx:122 VGMediaFileCached[0000000014B3C1E0]::ParseIndex: AddEntry Time=1000 Duration=1000 IsRef=1 Offset=8032 Size=8000
2014-11-17 16:29:32.054 DBUG 00000000-00000000 8792 0B000000 MMPlayCached.cxx:199 MMPlayCachedTrack[0000000014821100]::Map nResult=0 nPackets=1 bEOD=0
2014-11-17T16:29:32.129 Trc 33009 INFO 00000000-00000000 8004 02800FA1 Request received: OPTIONS sip:192.168.3.43:5070 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.43:5064;branch=z9hG4bK000000001B6D714069d514
From: <sip:GVP@192.168.3.43:5064>;tag=F0F73C60-D97B-48A0-C18C-14130D3E4DCD
To: sip:192.168.3.43:5070
Max-Forwards: 70
CSeq: 1786016 OPTIONS
Call-ID: A07ACEAC-D698-4C30-FA92-0BEE5C7ACC12-5064@192.168.3.43
Contact: <sip:GVP@192.168.3.43:5064>
Content-Length: 0
Supported: timer, uui