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?
1. Enabled tranfer allowed, outbound allowed in IVR profile
2. Create LRG profile for gateway.
3. Transfer destination is '8101' which is routing point.
4. 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.
[code]
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
[/code]
