Brett - 2011-03-24

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