Gentlefolk, we have just installed Activa 1.6 on a Windows 7 64bit system running Outlook 2010 32 bit.
Outlook dials fine but when we hangup the call (from the handset) outlook generates the following error:
'An internal error occured in the automatic phone dialer. Close the Dial Phone dialog box, and then open it again.
The following Activa logs were generated. Any ideas on where to start looking?
03/24/11 16:49:50 TRACE TSPI_lineGetDevCaps() dwDeviceIdBase=0x5 dwDeviceID=0x5 03/24/11 16:49:50 TRACE TSPI_lineGetAddressCaps() dwDeviceID=0x5 dwAddressID=0x0 03/24/11 16:50:53 TRACE TSPI_lineOpen() dwDeviceID=0x5 htLine=0x102ff 03/24/11 16:50:53 INFO Connect(test2.noojee.com.au,5038,click,kaiy5Bei) 03/24/11 16:50:53 TRACE ASTProvider::SendCommand => Action: Login UserName: click Secret: kaiy5Bei ActionID: 3
03/24/11 16:50:53 TRACE ASTProvider::ReaderProc 03/24/11 16:50:53 TRACE Asterisk protocol header: Asterisk Call Manager/1.0 03/24/11 16:50:53 TRACE <== asterisk protocol response__________________________ 03/24/11 16:50:53 TRACE Connect() returns ok 03/24/11 16:50:53 TRACE ASTCstaProvider::OpenMonitor device = SIP/701 => 701 03/24/11 16:50:53 TRACE first monitor for this device: {701,2} 03/24/11 16:50:53 TRACE AddTapiLine xref=2 htapiLine=0x102ff pTapiLine=0x3f88630 03/24/11 16:50:53 TRACE TSPI_lineGetNumAddressIDs hdLine=0000000000000002 03/24/11 16:50:53 TRACE TSPI_lineMakeCall() hdLine=0x2, htCall=0x102dd, phdCall=0x3a45d38, calledDevice=T0404609818 03/24/11 16:50:53 TRACE filtered calledDevice T0404609818 => 0404609818 03/24/11 16:50:53 TRACE Reading NOANSWERTIMEOUT 0 03/24/11 16:50:53 TRACE UserData not found 03/24/11 16:50:53 TRACE creating TapiCall 0x3f88040 with htCall 0x102dd 03/24/11 16:50:53 TRACE ASTTapiProvider::AddTapiCall pNewTapiCall=0x3f88040 callId=5, htapicall=0x102dd, callerId=SIP/701, calledId=0404609818 userData= 03/24/11 16:50:53 TRACE first TapiCall for this callid: => 0x3f88040 03/24/11 16:50:53 TRACE Executing MakeCall 03/24/11 16:50:53 TRACE AddConnectionToCallModel {5, SIP/701} => INITIALIZED 03/24/11 16:50:53 TRACE done, now CALL map has 1 items 03/24/11 16:50:53 TRACE ASTProvider::Originate call 5 channel:SIP/701 callerId:SIP/701 to calledExtension:0404609818 in context:default, callerIdName=88776655 timeout=30 03/24/11 16:50:53 TRACE ASTProvider::SendCommand => Action: Originate Channel: SIP/701 Exten: 0404609818 Priority: 1 Callerid: 88776655 #CallId=5 <SIP/701> Context: default Variable: originating=SIP/701 ActionID: 4 Timeout: 30000 Async: 1
03/24/11 16:50:53 TRACE <== asterisk protocol response__________________________ 03/24/11 16:50:53 TRACE Originate success 03/24/11 16:50:53 TRACE makecall or makepredictivecall success 03/24/11 16:50:53 TRACE NEW callId=5 for htapicall=0x102dd and hdcall=0x3a45d38 03/24/11 16:50:53 TRACE returning dwRequestId 66286 03/24/11 16:50:53 TRACE TSPI_lineGetCallInfo: changing callerIDName= by callerId=SIP/701 because is unknown 03/24/11 16:50:53 TRACE TSPI_lineGetCallInfo hdCall=0x3f88040 callerId=SIP/701, callerIdName=SIP/701, calledId=0404609818, calledIdName=0404609818, connectedId=, connectedIdName=, redirectingId=, redirectingIdName=, redirectionId=, userData=, isPredictive=0, dwOrigin=0x10 03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newchannel 03/24/11 16:50:53 TRACE OnNewChannelEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="<unknown>"; ev.m_callerIdName="<unknown>"; ev.m_state="Down"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewChannelEvent(ev); 03/24/11 16:50:53 TRACE Create AsteriskCall with uniqueId = 1300945849.47 and callid =6 03/24/11 16:50:53 TRACE setting {1300945849.47}.callerID=, callerIdName= 03/24/11 16:50:53 TRACE setting {1300945849.47}.channel=SIP/701-00000007, address=701 03/24/11 16:50:53 TRACE state = DOWN => do nothing 03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newcallerid 03/24/11 16:50:53 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="SIP/701"; ev.m_callerIdName="88776655 #CallId=5"; m_pxCstaProvider->OnNewCallerIdEvent(ev); 03/24/11 16:50:53 TRACE overriding {1300945849.47}. to new callerId SIP/701 03/24/11 16:50:53 TRACE Call does not contains UserData 03/24/11 16:50:53 TRACE Call containsCallId(5) 03/24/11 16:50:53 TRACE overriding {1300945849.47}.6 to new callId 5 03/24/11 16:50:53 TRACE Call is NOT predictive 03/24/11 16:50:53 TRACE overriding {1300945849.47}. to new calleridname 88776655 03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newcallerid 03/24/11 16:50:53 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="SIP/701"; ev.m_callerIdName="88776655 #CallId=5"; m_pxCstaProvider->OnNewCallerIdEvent(ev); 03/24/11 16:50:53 TRACE Call does not contains UserData 03/24/11 16:50:53 TRACE Call containsCallId(5) 03/24/11 16:50:53 TRACE overriding {1300945849.47}.5 to new callId 5 03/24/11 16:50:53 TRACE Call is NOT predictive 03/24/11 16:50:53 TRACE overriding {1300945849.47}.88776655 to new calleridname 88776655 03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:54 TRACE ASTProvider::HandleEvent => newstate 03/24/11 16:50:54 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Ringing"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewStateEvent(ev); 03/24/11 16:50:54 TRACE Call does not contains UserData 03/24/11 16:50:54 TRACE Call containsCallId(5) 03/24/11 16:50:54 TRACE overriding {1300945849.47}.5 to new callId 5 03/24/11 16:50:54 TRACE Call is NOT predictive 03/24/11 16:50:54 TRACE setting {1300945849.47}.callerID=SIP/701, callerIdName=88776655 03/24/11 16:50:54 TRACE state = RINGING => Send ALERTING to {1300945849.47}.address=701 03/24/11 16:50:54 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945849.47, address=701 03/24/11 16:50:54 TRACE ASTCallMap::GetSourceCall(0x3f8a080) 03/24/11 16:50:54 TRACE SourceCall is 1300945849.47 03/24/11 16:50:54 TRACE GetCIDInfoFromSourceCall(1300945849.47) 03/24/11 16:50:54 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 / 03/24/11 16:50:54 TRACE AddConnectionToCallModel {5, 701} => ALERTING 03/24/11 16:50:54 TRACE done, now CALL map has 1 items 03/24/11 16:50:54 TRACE event minimization RULE-4: (callerid = SIP/701) == (called = SIP/701) == (address = 701) 03/24/11 16:50:54 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:58 TRACE ASTProvider::HandleEvent => newstate 03/24/11 16:50:58 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Up"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewStateEvent(ev); 03/24/11 16:50:58 TRACE Call does not contains UserData 03/24/11 16:50:58 TRACE Call containsCallId(5) 03/24/11 16:50:58 TRACE Call is NOT predictive 03/24/11 16:50:58 TRACE setting {1300945849.47}.callerID=SIP/701, callerIdName=88776655 03/24/11 16:50:58 TRACE state = UP => Send ESTABLISHED to {1300945849.47}.address=701 03/24/11 16:50:58 TRACE ASTCstaProvider::handleConnectionConnected uniqueId=1300945849.47, address=701 03/24/11 16:50:58 TRACE ASTCallMap::GetSourceCall(0x3f8a080) 03/24/11 16:50:58 TRACE SourceCall is 1300945849.47 03/24/11 16:50:58 TRACE GetCIDInfoFromSourceCall(1300945849.47) 03/24/11 16:50:58 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 / 03/24/11 16:50:58 TRACE AddConnectionToCallModel {5, 701} => CONNECTED 03/24/11 16:50:58 TRACE done, now CALL map has 1 items 03/24/11 16:50:58 TRACE event minimization RULE-4: (callerid = SIP/701) == (called = SIP/701) == (address = 701) 03/24/11 16:50:58 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:58 TRACE ASTProvider::HandleEvent => originateresponse 03/24/11 16:50:58 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newchannel 03/24/11 16:50:59 TRACE OnNewChannelEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerId="<unknown>"; ev.m_callerIdName="<unknown>"; ev.m_state="Down"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewChannelEvent(ev); 03/24/11 16:50:59 TRACE Create AsteriskCall with uniqueId = 1300945855.48 and callid =7 03/24/11 16:50:59 TRACE setting {1300945855.48}.callerID=, callerIdName= 03/24/11 16:50:59 TRACE setting {1300945855.48}.channel=IAX2/test2-6463, address=test2 03/24/11 16:50:59 TRACE state = DOWN => do nothing 03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newstate 03/24/11 16:50:59 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Ringing"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewStateEvent(ev); 03/24/11 16:50:59 TRACE Call does not contains UserData 03/24/11 16:50:59 TRACE Call containsCallId(5) 03/24/11 16:50:59 TRACE overriding {1300945855.48}.7 to new callId 5 03/24/11 16:50:59 TRACE Call is NOT predictive 03/24/11 16:50:59 TRACE setting {1300945855.48}.callerID=, callerIdName=88776655 03/24/11 16:50:59 TRACE state = RINGING => Send ALERTING to {1300945855.48}.address=test2 03/24/11 16:50:59 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945855.48, address=test2 03/24/11 16:50:59 TRACE ASTCallMap::GetSourceCall(0x3f87430) 03/24/11 16:50:59 TRACE SourceCall is 1300945855.48 03/24/11 16:50:59 TRACE GetCIDInfoFromSourceCall(1300945855.48) 03/24/11 16:50:59 TRACE Exiting with cid/cidname/userData=// 03/24/11 16:50:59 TRACE AddConnectionToCallModel {5, test2} => ALERTING 03/24/11 16:50:59 TRACE done, now CALL map has 1 items 03/24/11 16:50:59 TRACE event minimization RULE-4: (callerid = ) == (called = ) == (address = test2) 03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => dial 03/24/11 16:50:59 TRACE OnDialEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_callerId="SIP/701"; m_pxCstaProvider->OnDialEvent(ev); 03/24/11 16:50:59 TRACE linked calls {1300945849.47} <- {1300945855.48} 03/24/11 16:50:59 TRACE Call does not contains UserData 03/24/11 16:50:59 TRACE Call containsCallId(5) 03/24/11 16:50:59 TRACE overriding {1300945849.47}.5 to new callId 5 03/24/11 16:50:59 TRACE overriding {1300945855.48}.5 to new callId 5 03/24/11 16:50:59 TRACE Call is NOT predictive 03/24/11 16:50:59 TRACE overriding {1300945849.47}. to new callerid SIP/701 03/24/11 16:50:59 TRACE state = RINGING => Send ALERTING to {1300945855.48}.address=test2 03/24/11 16:50:59 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945855.48, address=test2 03/24/11 16:50:59 TRACE ASTCallMap::GetSourceCall(0x3f87430) 03/24/11 16:50:59 TRACE SourceCall is 1300945849.47 03/24/11 16:50:59 TRACE GetCIDInfoFromSourceCall(1300945855.48) 03/24/11 16:50:59 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 / 03/24/11 16:50:59 TRACE AddConnectionToCallModel {5, test2} => ALERTING 03/24/11 16:50:59 TRACE done, now CALL map has 1 items 03/24/11 16:50:59 TRACE overriding calledNumber from => 03/24/11 16:50:59 TRACE source call is {1300945849.47} and lastRedirection= 03/24/11 16:50:59 TRACE GetMonitorSetFromCallModel = 5 03/24/11 16:50:59 TRACE num_monitors = 1 03/24/11 16:50:59 TRACE sending event:CFTE:ALERTING to xref=2, address=test2, callid=5, lastRedirection=, userData= 03/24/11 16:50:59 TRACE OnAlerting xref=2 callId=5, alertingDevice=test2, callerId=SIP/701, callerIdName=88776655 , calledID= userData= cause=0 03/24/11 16:50:59 TRACE searching a TapiCall with callId 5 in tapiLine 0x3f88630 03/24/11 16:50:59 TRACE pTapiCall found with callId 5 and htCall 0x102dd 03/24/11 16:50:59 TRACE alertingDevice callerid = 701 03/24/11 16:50:59 TRACE htapiline=0x102ff, deviceID=SIP/701, htapicall=0x102dd, callerid=SIP/701, callerIdName=88776655 , calleddevice=0404609818, lastRedirection= userData= 03/24/11 16:50:59 TRACE LINECALLSTATE_RINGBACK htapicall=0x102dd 03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newcallerid 03/24/11 16:50:59 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerId="0404609818"; ev.m_callerIdName="<Unknown>"; m_pxCstaProvider->OnNewCallerIdEvent(ev); 03/24/11 16:50:59 TRACE overriding {1300945855.48}. to new callerId 0404609818 03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => newstate 03/24/11 16:51:09 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerIdName="<unknown>"; ev.m_state="Up"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewStateEvent(ev); 03/24/11 16:51:09 TRACE state = UP => Send ESTABLISHED to {1300945855.48}.address=test2 03/24/11 16:51:09 TRACE ASTCstaProvider::handleConnectionConnected uniqueId=1300945855.48, address=test2 03/24/11 16:51:09 TRACE ASTCallMap::GetSourceCall(0x3f87430) 03/24/11 16:51:09 TRACE SourceCall is 1300945849.47 03/24/11 16:51:09 TRACE GetCIDInfoFromSourceCall(1300945855.48) 03/24/11 16:51:09 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 / 03/24/11 16:51:09 TRACE AddConnectionToCallModel {5, test2} => CONNECTED 03/24/11 16:51:09 TRACE done, now CALL map has 1 items 03/24/11 16:51:09 TRACE source call is {1300945849.47} and lastRedirection= 03/24/11 16:51:09 TRACE GetMonitorSetFromCallModel = 5 03/24/11 16:51:09 TRACE num_monitors = 1 03/24/11 16:51:09 TRACE sending event:CFTE:ESTABLISHED to xref=2, address=test2, callid=5, lastRedirection=, userData= 03/24/11 16:51:09 TRACE OnEstablished xref=2 callId=5, establishedDevice=test2, callerId=SIP/701, callerIdName=88776655 , calledDevice =0404609818, lastRedirectingDevice=, userData= cause=0 03/24/11 16:51:09 TRACE searching a TapiCall with callId 5 in tapiLine 0x3f88630 03/24/11 16:51:09 TRACE htapiline=0x102ff, deviceID=SIP/701, htapicall=0x102dd, callerid=SIP/701, callerIdName=88776655 , calleddevice=0404609818, lastRedirection=, userData= 03/24/11 16:51:09 TRACE LINECALLSTATE_CONNECTED htapicall=0x102dd 03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => link 03/24/11 16:51:09 TRACE OnLinkEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; m_pxCstaProvider->OnLinkEvent(ev); 03/24/11 16:51:09 TRACE set {1300945855.48}.lastRedirectionDevice= 03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => unlink 03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => link 03/24/11 16:51:09 TRACE OnLinkEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; m_pxCstaProvider->OnLinkEvent(ev); 03/24/11 16:51:09 TRACE set {1300945855.48}.lastRedirectionDevice= 03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________ 03/24/11 16:51:13 TRACE ASTProvider::HandleEvent => unlink
Log in to post a comment.
Gentlefolk,
we have just installed Activa 1.6 on a Windows 7 64bit system running Outlook 2010 32 bit.
Outlook dials fine but when we hangup the call (from the handset) outlook generates the following error:
'An internal error occured in the automatic phone dialer. Close the Dial Phone dialog box, and then open it again.
The following Activa logs were generated. Any ideas on where to start looking?
03/24/11 16:49:50 TRACE TSPI_lineGetDevCaps() dwDeviceIdBase=0x5 dwDeviceID=0x5
03/24/11 16:49:50 TRACE TSPI_lineGetAddressCaps() dwDeviceID=0x5 dwAddressID=0x0
03/24/11 16:50:53 TRACE TSPI_lineOpen() dwDeviceID=0x5 htLine=0x102ff
03/24/11 16:50:53 INFO Connect(test2.noojee.com.au,5038,click,kaiy5Bei)
03/24/11 16:50:53 TRACE ASTProvider::SendCommand =>
Action: Login
UserName: click
Secret: kaiy5Bei
ActionID: 3
03/24/11 16:50:53 TRACE ASTProvider::ReaderProc
03/24/11 16:50:53 TRACE Asterisk protocol header: Asterisk Call Manager/1.0
03/24/11 16:50:53 TRACE <== asterisk protocol response__________________________
03/24/11 16:50:53 TRACE Connect() returns ok
03/24/11 16:50:53 TRACE ASTCstaProvider::OpenMonitor device = SIP/701 => 701
03/24/11 16:50:53 TRACE first monitor for this device: {701,2}
03/24/11 16:50:53 TRACE AddTapiLine xref=2 htapiLine=0x102ff pTapiLine=0x3f88630
03/24/11 16:50:53 TRACE TSPI_lineGetNumAddressIDs hdLine=0000000000000002
03/24/11 16:50:53 TRACE TSPI_lineMakeCall() hdLine=0x2, htCall=0x102dd, phdCall=0x3a45d38, calledDevice=T0404609818
03/24/11 16:50:53 TRACE filtered calledDevice T0404609818 => 0404609818
03/24/11 16:50:53 TRACE Reading NOANSWERTIMEOUT 0
03/24/11 16:50:53 TRACE UserData not found
03/24/11 16:50:53 TRACE creating TapiCall 0x3f88040 with htCall 0x102dd
03/24/11 16:50:53 TRACE ASTTapiProvider::AddTapiCall pNewTapiCall=0x3f88040 callId=5, htapicall=0x102dd, callerId=SIP/701, calledId=0404609818 userData=
03/24/11 16:50:53 TRACE first TapiCall for this callid: => 0x3f88040
03/24/11 16:50:53 TRACE Executing MakeCall
03/24/11 16:50:53 TRACE AddConnectionToCallModel {5, SIP/701} => INITIALIZED
03/24/11 16:50:53 TRACE done, now CALL map has 1 items
03/24/11 16:50:53 TRACE ASTProvider::Originate call 5 channel:SIP/701 callerId:SIP/701 to calledExtension:0404609818 in context:default, callerIdName=88776655 timeout=30
03/24/11 16:50:53 TRACE ASTProvider::SendCommand =>
Action: Originate
Channel: SIP/701
Exten: 0404609818
Priority: 1
Callerid: 88776655 #CallId=5 <SIP/701>
Context: default
Variable: originating=SIP/701
ActionID: 4
Timeout: 30000
Async: 1
03/24/11 16:50:53 TRACE <== asterisk protocol response__________________________
03/24/11 16:50:53 TRACE Originate success
03/24/11 16:50:53 TRACE makecall or makepredictivecall success
03/24/11 16:50:53 TRACE NEW callId=5 for htapicall=0x102dd and hdcall=0x3a45d38
03/24/11 16:50:53 TRACE returning dwRequestId 66286
03/24/11 16:50:53 TRACE TSPI_lineGetCallInfo: changing callerIDName= by callerId=SIP/701 because is unknown
03/24/11 16:50:53 TRACE TSPI_lineGetCallInfo hdCall=0x3f88040 callerId=SIP/701, callerIdName=SIP/701, calledId=0404609818, calledIdName=0404609818, connectedId=, connectedIdName=, redirectingId=, redirectingIdName=, redirectionId=, userData=, isPredictive=0, dwOrigin=0x10
03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newchannel
03/24/11 16:50:53 TRACE OnNewChannelEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="<unknown>"; ev.m_callerIdName="<unknown>"; ev.m_state="Down"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewChannelEvent(ev);
03/24/11 16:50:53 TRACE Create AsteriskCall with uniqueId = 1300945849.47 and callid =6
03/24/11 16:50:53 TRACE setting {1300945849.47}.callerID=, callerIdName=
03/24/11 16:50:53 TRACE setting {1300945849.47}.channel=SIP/701-00000007, address=701
03/24/11 16:50:53 TRACE state = DOWN => do nothing
03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newcallerid
03/24/11 16:50:53 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="SIP/701"; ev.m_callerIdName="88776655 #CallId=5"; m_pxCstaProvider->OnNewCallerIdEvent(ev);
03/24/11 16:50:53 TRACE overriding {1300945849.47}. to new callerId SIP/701
03/24/11 16:50:53 TRACE Call does not contains UserData
03/24/11 16:50:53 TRACE Call containsCallId(5)
03/24/11 16:50:53 TRACE overriding {1300945849.47}.6 to new callId 5
03/24/11 16:50:53 TRACE Call is NOT predictive
03/24/11 16:50:53 TRACE overriding {1300945849.47}. to new calleridname 88776655
03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:53 TRACE ASTProvider::HandleEvent => newcallerid
03/24/11 16:50:53 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerId="SIP/701"; ev.m_callerIdName="88776655 #CallId=5"; m_pxCstaProvider->OnNewCallerIdEvent(ev);
03/24/11 16:50:53 TRACE Call does not contains UserData
03/24/11 16:50:53 TRACE Call containsCallId(5)
03/24/11 16:50:53 TRACE overriding {1300945849.47}.5 to new callId 5
03/24/11 16:50:53 TRACE Call is NOT predictive
03/24/11 16:50:53 TRACE overriding {1300945849.47}.88776655 to new calleridname 88776655
03/24/11 16:50:53 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:54 TRACE ASTProvider::HandleEvent => newstate
03/24/11 16:50:54 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Ringing"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewStateEvent(ev);
03/24/11 16:50:54 TRACE Call does not contains UserData
03/24/11 16:50:54 TRACE Call containsCallId(5)
03/24/11 16:50:54 TRACE overriding {1300945849.47}.5 to new callId 5
03/24/11 16:50:54 TRACE Call is NOT predictive
03/24/11 16:50:54 TRACE setting {1300945849.47}.callerID=SIP/701, callerIdName=88776655
03/24/11 16:50:54 TRACE state = RINGING => Send ALERTING to {1300945849.47}.address=701
03/24/11 16:50:54 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945849.47, address=701
03/24/11 16:50:54 TRACE ASTCallMap::GetSourceCall(0x3f8a080)
03/24/11 16:50:54 TRACE SourceCall is 1300945849.47
03/24/11 16:50:54 TRACE GetCIDInfoFromSourceCall(1300945849.47)
03/24/11 16:50:54 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 /
03/24/11 16:50:54 TRACE AddConnectionToCallModel {5, 701} => ALERTING
03/24/11 16:50:54 TRACE done, now CALL map has 1 items
03/24/11 16:50:54 TRACE event minimization RULE-4: (callerid = SIP/701) == (called = SIP/701) == (address = 701)
03/24/11 16:50:54 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:58 TRACE ASTProvider::HandleEvent => newstate
03/24/11 16:50:58 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945849.47"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Up"; ev.m_channel="SIP/701-00000007"; m_pxCstaProvider->OnNewStateEvent(ev);
03/24/11 16:50:58 TRACE Call does not contains UserData
03/24/11 16:50:58 TRACE Call containsCallId(5)
03/24/11 16:50:58 TRACE Call is NOT predictive
03/24/11 16:50:58 TRACE setting {1300945849.47}.callerID=SIP/701, callerIdName=88776655
03/24/11 16:50:58 TRACE state = UP => Send ESTABLISHED to {1300945849.47}.address=701
03/24/11 16:50:58 TRACE ASTCstaProvider::handleConnectionConnected uniqueId=1300945849.47, address=701
03/24/11 16:50:58 TRACE ASTCallMap::GetSourceCall(0x3f8a080)
03/24/11 16:50:58 TRACE SourceCall is 1300945849.47
03/24/11 16:50:58 TRACE GetCIDInfoFromSourceCall(1300945849.47)
03/24/11 16:50:58 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 /
03/24/11 16:50:58 TRACE AddConnectionToCallModel {5, 701} => CONNECTED
03/24/11 16:50:58 TRACE done, now CALL map has 1 items
03/24/11 16:50:58 TRACE event minimization RULE-4: (callerid = SIP/701) == (called = SIP/701) == (address = 701)
03/24/11 16:50:58 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:58 TRACE ASTProvider::HandleEvent => originateresponse
03/24/11 16:50:58 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newchannel
03/24/11 16:50:59 TRACE OnNewChannelEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerId="<unknown>"; ev.m_callerIdName="<unknown>"; ev.m_state="Down"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewChannelEvent(ev);
03/24/11 16:50:59 TRACE Create AsteriskCall with uniqueId = 1300945855.48 and callid =7
03/24/11 16:50:59 TRACE setting {1300945855.48}.callerID=, callerIdName=
03/24/11 16:50:59 TRACE setting {1300945855.48}.channel=IAX2/test2-6463, address=test2
03/24/11 16:50:59 TRACE state = DOWN => do nothing
03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newstate
03/24/11 16:50:59 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_state="Ringing"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewStateEvent(ev);
03/24/11 16:50:59 TRACE Call does not contains UserData
03/24/11 16:50:59 TRACE Call containsCallId(5)
03/24/11 16:50:59 TRACE overriding {1300945855.48}.7 to new callId 5
03/24/11 16:50:59 TRACE Call is NOT predictive
03/24/11 16:50:59 TRACE setting {1300945855.48}.callerID=, callerIdName=88776655
03/24/11 16:50:59 TRACE state = RINGING => Send ALERTING to {1300945855.48}.address=test2
03/24/11 16:50:59 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945855.48, address=test2
03/24/11 16:50:59 TRACE ASTCallMap::GetSourceCall(0x3f87430)
03/24/11 16:50:59 TRACE SourceCall is 1300945855.48
03/24/11 16:50:59 TRACE GetCIDInfoFromSourceCall(1300945855.48)
03/24/11 16:50:59 TRACE Exiting with cid/cidname/userData=//
03/24/11 16:50:59 TRACE AddConnectionToCallModel {5, test2} => ALERTING
03/24/11 16:50:59 TRACE done, now CALL map has 1 items
03/24/11 16:50:59 TRACE event minimization RULE-4: (callerid = ) == (called = ) == (address = test2)
03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => dial
03/24/11 16:50:59 TRACE OnDialEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; ev.m_callerIdName="88776655 #CallId=5"; ev.m_callerId="SIP/701"; m_pxCstaProvider->OnDialEvent(ev);
03/24/11 16:50:59 TRACE linked calls {1300945849.47} <- {1300945855.48}
03/24/11 16:50:59 TRACE Call does not contains UserData
03/24/11 16:50:59 TRACE Call containsCallId(5)
03/24/11 16:50:59 TRACE overriding {1300945849.47}.5 to new callId 5
03/24/11 16:50:59 TRACE overriding {1300945855.48}.5 to new callId 5
03/24/11 16:50:59 TRACE Call is NOT predictive
03/24/11 16:50:59 TRACE overriding {1300945849.47}. to new callerid SIP/701
03/24/11 16:50:59 TRACE state = RINGING => Send ALERTING to {1300945855.48}.address=test2
03/24/11 16:50:59 TRACE ASTCstaProvider::handleConnectionAlerting uniqueId=1300945855.48, address=test2
03/24/11 16:50:59 TRACE ASTCallMap::GetSourceCall(0x3f87430)
03/24/11 16:50:59 TRACE SourceCall is 1300945849.47
03/24/11 16:50:59 TRACE GetCIDInfoFromSourceCall(1300945855.48)
03/24/11 16:50:59 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 /
03/24/11 16:50:59 TRACE AddConnectionToCallModel {5, test2} => ALERTING
03/24/11 16:50:59 TRACE done, now CALL map has 1 items
03/24/11 16:50:59 TRACE overriding calledNumber from =>
03/24/11 16:50:59 TRACE source call is {1300945849.47} and lastRedirection=
03/24/11 16:50:59 TRACE GetMonitorSetFromCallModel = 5
03/24/11 16:50:59 TRACE num_monitors = 1
03/24/11 16:50:59 TRACE sending event:CFTE:ALERTING to xref=2, address=test2, callid=5, lastRedirection=, userData=
03/24/11 16:50:59 TRACE OnAlerting xref=2 callId=5, alertingDevice=test2, callerId=SIP/701, callerIdName=88776655 , calledID= userData= cause=0
03/24/11 16:50:59 TRACE searching a TapiCall with callId 5 in tapiLine 0x3f88630
03/24/11 16:50:59 TRACE pTapiCall found with callId 5 and htCall 0x102dd
03/24/11 16:50:59 TRACE alertingDevice callerid = 701
03/24/11 16:50:59 TRACE htapiline=0x102ff, deviceID=SIP/701, htapicall=0x102dd, callerid=SIP/701, callerIdName=88776655 , calleddevice=0404609818, lastRedirection= userData=
03/24/11 16:50:59 TRACE LINECALLSTATE_RINGBACK htapicall=0x102dd
03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________
03/24/11 16:50:59 TRACE ASTProvider::HandleEvent => newcallerid
03/24/11 16:50:59 TRACE OnNewCallerIdEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerId="0404609818"; ev.m_callerIdName="<Unknown>"; m_pxCstaProvider->OnNewCallerIdEvent(ev);
03/24/11 16:50:59 TRACE overriding {1300945855.48}. to new callerId 0404609818
03/24/11 16:50:59 TRACE <== asterisk protocol event _______________________________
03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => newstate
03/24/11 16:51:09 TRACE OnNewStateEvent:CFT: ev.m_uniqueId="1300945855.48"; ev.m_callerIdName="<unknown>"; ev.m_state="Up"; ev.m_channel="IAX2/test2-6463"; m_pxCstaProvider->OnNewStateEvent(ev);
03/24/11 16:51:09 TRACE state = UP => Send ESTABLISHED to {1300945855.48}.address=test2
03/24/11 16:51:09 TRACE ASTCstaProvider::handleConnectionConnected uniqueId=1300945855.48, address=test2
03/24/11 16:51:09 TRACE ASTCallMap::GetSourceCall(0x3f87430)
03/24/11 16:51:09 TRACE SourceCall is 1300945849.47
03/24/11 16:51:09 TRACE GetCIDInfoFromSourceCall(1300945855.48)
03/24/11 16:51:09 TRACE Exiting with cid/cidname/userData=SIP/701/88776655 /
03/24/11 16:51:09 TRACE AddConnectionToCallModel {5, test2} => CONNECTED
03/24/11 16:51:09 TRACE done, now CALL map has 1 items
03/24/11 16:51:09 TRACE source call is {1300945849.47} and lastRedirection=
03/24/11 16:51:09 TRACE GetMonitorSetFromCallModel = 5
03/24/11 16:51:09 TRACE num_monitors = 1
03/24/11 16:51:09 TRACE sending event:CFTE:ESTABLISHED to xref=2, address=test2, callid=5, lastRedirection=, userData=
03/24/11 16:51:09 TRACE OnEstablished xref=2 callId=5, establishedDevice=test2, callerId=SIP/701, callerIdName=88776655 , calledDevice =0404609818, lastRedirectingDevice=, userData= cause=0
03/24/11 16:51:09 TRACE searching a TapiCall with callId 5 in tapiLine 0x3f88630
03/24/11 16:51:09 TRACE htapiline=0x102ff, deviceID=SIP/701, htapicall=0x102dd, callerid=SIP/701, callerIdName=88776655 , calleddevice=0404609818, lastRedirection=, userData=
03/24/11 16:51:09 TRACE LINECALLSTATE_CONNECTED htapicall=0x102dd
03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________
03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => link
03/24/11 16:51:09 TRACE OnLinkEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; m_pxCstaProvider->OnLinkEvent(ev);
03/24/11 16:51:09 TRACE set {1300945855.48}.lastRedirectionDevice=
03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________
03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => unlink
03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________
03/24/11 16:51:09 TRACE ASTProvider::HandleEvent => link
03/24/11 16:51:09 TRACE OnLinkEvent:CFT: ev.m_srcUniqueId="1300945849.47"; ev.m_destUniqueId="1300945855.48"; m_pxCstaProvider->OnLinkEvent(ev);
03/24/11 16:51:09 TRACE set {1300945855.48}.lastRedirectionDevice=
03/24/11 16:51:09 TRACE <== asterisk protocol event _______________________________
03/24/11 16:51:13 TRACE ASTProvider::HandleEvent => unlink