No incoming caller id passed for screen pop up
Brought to you by:
activa
We have configured Activa . When we make a incoming call to the extension which is being monitored , the extension get the calledid But a tapi client like MS Phone dialer shows caller id as unknown .
Both the screen shot of phone dialer and the log of activa is attached .
This is the screen shot
11/18/11 20:31:35 [2676] TRACE [ASTProvider] Connect() returns ok
11/18/11 20:31:35 [2676] TRACE [ASTCstaProvider] ASTCstaProvider::OpenMonitor device = 101 => 101
11/18/11 20:31:35 [2676] TRACE [ASTCallMap] first monitor for this device: {101,1}
11/18/11 20:31:35 [2676] TRACE [TAPIProvider] AddTapiLine xref=1 htapiLine=0x5e27e78 pTapiLine=0x2af8ad0
11/18/11 20:31:35 [2676] TRACE [TSP] TSPI_lineGetNumAddressIDs hdLine=00000001
11/18/11 20:31:35 [1880] TRACE0[ASTProvider] HEADER: response DATA: Success
11/18/11 20:31:35 [1880] TRACE0[ASTProvider] HEADER: actionid DATA: 1
11/18/11 20:31:35 [1880] TRACE0[ASTProvider] HEADER: message DATA: Authentication accepted
11/18/11 20:31:35 [1880] TRACE [ASTProvider] <== asterisk protocol response__________________________
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: Newchannel
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: DAHDI/1-1
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstate DATA: 4
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstatedesc DATA: Ring
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 9871588990
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: accountcode DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: exten DATA: 01
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: context DATA: default
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.0
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => newchannel
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnNewChannelEvent:CFT: ev.m_uniqueId="1321628514.0"; ev.m_callerId="9871588990"; ev.m_callerIdName=""; ev.m_state="Ring"; ev.m_channel="DAHDI/1-1"; m_pxCstaProvider->OnNewChannelEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] Create AsteriskCall with uniqueId = 1321628514.0 and callid =2
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] setting {1321628514.0}.callerID=9871588990, callerIdName=
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] setting {1321628514.0}.channel=DAHDI/1-1, address=1
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] state = RING => Send ORIGINATED to {1321628514.0}.address=1
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] ASTCstaProvider::handleConnectionOriginated uniqueId=1321628514.0, address=1
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= start callmap dump...
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF ASTERISK CALLS MAP: {UNIQUEID} => call info:
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] {1321628514.0}.1 numDevice=1 callID=2 state=Ring chann=DAHDI/1-1 CID=9871588990 CIDName= ConLineNum= ConLineName= lastRedir= source= userData= cause=0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF MONITOR CALLS MAP: {CALL_ID} => DEVICE: => STATE
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= end callmap dump...
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] AddConnectionToCallModel {2, 1} => INITIALIZED
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] done, now CALL map has 1 items
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 2
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] num_monitors = 0
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] do not send EVENTS because not exist any monitors in call 2
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: Newchannel
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: SIP/101-00000000
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstate DATA: 0
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstatedesc DATA: Down
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: accountcode DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: exten DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: context DATA: default
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.1
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => newchannel
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnNewChannelEvent:CFT: ev.m_uniqueId="1321628514.1"; ev.m_callerId=""; ev.m_callerIdName=""; ev.m_state="Down"; ev.m_channel="SIP/101-00000000"; m_pxCstaProvider->OnNewChannelEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] Create AsteriskCall with uniqueId = 1321628514.1 and callid =3
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] setting {1321628514.1}.callerID=, callerIdName=
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] setting {1321628514.1}.channel=SIP/101-00000000, address=101
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] state = DOWN => do nothing
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: Dial
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: subevent DATA: Begin
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: DAHDI/1-1
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: destination DATA: SIP/101-00000000
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 9871588990
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA: <unknown>
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.0
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: destuniqueid DATA: 1321628514.1
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: dialstring DATA: 101
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => dial
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnDialEvent:CFT: ev.m_srcUniqueId="1321628514.0"; ev.m_destUniqueId="1321628514.1"; ev.m_callerIdName="<unknown>"; ev.m_callerId="9871588990"; m_pxCstaProvider->OnDialEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] linked calls {1321628514.0} <- {1321628514.1}
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] Dial event don't contain callid, we try to get it using GetLogicCallId
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afa230)
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] Call logicCallId(): 2
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 3
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] callerId not found
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding {1321628514.1}.3 to new callId 2
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] RemoveCall {3}
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] {3} NOT IN CALL-MONITOR MAP
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding {1321628514.1}. to new calleridname <unknown>
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding {1321628514.0}. to new calleridname <unknown>
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding {1321628514.0}. to new callerid 9871588990
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: NewCallerid
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: SIP/101-00000000
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 01
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.1
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: cid-callingpres DATA: 3 (Presentation Allowed, Network Number)
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => newcallerid
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnNewCallerIdEvent:CFT: ev.m_uniqueId="1321628514.1"; ev.m_callerId="01"; ev.m_callerIdName=""; m_pxCstaProvider->OnNewCallerIdEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding {1321628514.1}. to new callerId 01
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: Newstate
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: SIP/101-00000000
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstate DATA: 5
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstatedesc DATA: Ringing
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 01
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.1
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => newstate
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnNewStateEvent:CFT: ev.m_uniqueId="1321628514.1"; ev.m_callerIdName=""; ev.m_state="Ringing"; ev.m_channel="SIP/101-00000000"; m_pxCstaProvider->OnNewStateEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] state = RINGING => Send ALERTING to {1321628514.1}.address=101
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] ASTCstaProvider::handleConnectionAlerting uniqueId=1321628514.1, address=101
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] ASTCallMap::GetSourceCall(0x2afb8e0)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] SourceCall is 1321628514.0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afb8e0)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] GetCIDInfoFromSourceCall(1321628514.1)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] Exiting with cid/cidname/userData=9871588990/<unknown>/
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= start callmap dump...
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF ASTERISK CALLS MAP: {UNIQUEID} => call info:
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] {1321628514.0}.1 numDevice=1 callID=2 state=Ring chann=DAHDI/1-1 CID=9871588990 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source= userData= cause=0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] {1321628514.1}.101 numDevice=101 callID=2 state=Ringing chann=SIP/101-00000000 CID=01 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source=1321628514.0 userData= cause=0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF MONITOR CALLS MAP: {CALL_ID} => DEVICE: => STATE
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] [2] => 1 => INITIALIZED
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= end callmap dump...
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] AddConnectionToCallModel {2, 101} => ALERTING
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] done, now CALL map has 1 items
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] overriding calledNumber from 01 => 01
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] source call is {1321628514.0} and lastRedirection=[]
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 2
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] num_monitors = 1
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] sending event:CFTE:ALERTING to xref=1, address=101, callid=2, lastRedirection=, userData=
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] OnAlerting xref=1 callId=2, alertingDevice=101, callerId=9871588990, callerIdName=<unknown>, calledID=01 userData= cause=0
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] searching a TapiCall with callId 2 in tapiLine 0x2af8ad0
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] LINE_NEWCALL
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] ASTTapiProvider::AddTapiCall pNewTapiCall=0x2af9b10 callId=2, htapicall=0x2686a58, callerId=9871588990, calledId=01 userData=
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] first TapiCall for this callid: [2] => 0x2af9b10
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] ================================================================= start callmap dump...
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] DUMP OF TSP CALLS MAP: [HDRVCALL] => call info:
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] [0x2af9b10] TapiLine=0x02AF8AD0 hdrivercall=0x02AF9B10 htapicall=0x02686A58 callID=2 address=101ate= CID=9871588990 CIDName=<unknown> lastRedir= called=01 userData= isPredictive=0 cause=0
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] DUMP OF CALL-ID MAP: {CALL_ID} => hdrivercall
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] [2] => TapiLine=0x02AF8AD0 hdrivercall=0x02AF9B10 htapicall=0x02686A58 callID=2 address=101ate= CID=9871588990 CIDName=<unknown> lastRedir= called=01 userData= isPredictive=0 cause=0
11/18/11 20:31:57 [1880] TRACE0[TAPIProvider] ================================================================= end callmap dump...
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] alertingDevice callerid = 101
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] htapiline=0x5e27e78, deviceID=101, htapicall=0x2686a58, callerid=9871588990, callerIdName=<unknown>, calleddevice=01, lastRedirection= userData=
11/18/11 20:31:57 [1880] TRACE [TAPIProvider] LINECALLSTATE_OFFERING htapicall=0x2686a58
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [3092] TRACE [TAPIProvider] TSPI_lineGetCallInfo: changing callerIDName=<unknown> by callerId=9871588990 because is unknown
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: event DATA: Newstate
11/18/11 20:31:57 [3092] TRACE [TAPIProvider] TSPI_lineGetCallInfo hdCall=0x2af9b10 callerId=9871588990, callerIdName=9871588990, calledId=01, calledIdName=01, connectedId=9871588990, connectedIdName=<unknown>, redirectingId=, redirectingIdName=, redirectionId=101, userData=, isPredictive=0, dwOrigin=0x80
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channel DATA: DAHDI/1-1
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstate DATA: 5
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: channelstatedesc DATA: Ringing
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 9871588990
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA:
11/18/11 20:31:57 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.0
11/18/11 20:31:57 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => newstate
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] OnNewStateEvent:CFT: ev.m_uniqueId="1321628514.0"; ev.m_callerIdName=""; ev.m_state="Ringing"; ev.m_channel="DAHDI/1-1"; m_pxCstaProvider->OnNewStateEvent(ev);
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] state = RINGING => Send ALERTING to {1321628514.0}.address=1
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] ASTCstaProvider::handleConnectionAlerting uniqueId=1321628514.0, address=1
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] ASTCallMap::GetSourceCall(0x2afa230)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] SourceCall is 1321628514.0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afa230)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] GetCIDInfoFromSourceCall(1321628514.0)
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] Exiting with cid/cidname/userData=9871588990/<unknown>/
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= start callmap dump...
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF ASTERISK CALLS MAP: {UNIQUEID} => call info:
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] {1321628514.0}.1 numDevice=1 callID=2 state=Ringing chann=DAHDI/1-1 CID=9871588990 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source= userData= cause=0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] {1321628514.1}.101 numDevice=101 callID=2 state=Ringing chann=SIP/101-00000000 CID=01 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source=1321628514.0 userData= cause=0
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] DUMP OF MONITOR CALLS MAP: {CALL_ID} => DEVICE: => STATE
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] [2] => 1 => INITIALIZED
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] [2] => 101 => ALERTING
11/18/11 20:31:57 [1880] TRACE0[ASTCallMap] ================================================================= end callmap dump...
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] AddConnectionToCallModel {2, 1} => ALERTING
11/18/11 20:31:57 [1880] TRACE [ASTCallMap] done, now CALL map has 1 items
11/18/11 20:31:57 [1880] TRACE [ASTCstaProvider] event minimization RULE-4: (callerid = 9871588990) == (called = 9871588990) == (address = 1)
11/18/11 20:31:57 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:31:57 [1428] TRACE [TAPIProvider] TSPI_lineGetCallInfo: changing callerIDName=<unknown> by callerId=9871588990 because is unknown
11/18/11 20:31:57 [1428] TRACE [TAPIProvider] TSPI_lineGetCallInfo hdCall=0x2af9b10 callerId=9871588990, callerIdName=9871588990, calledId=01, calledIdName=01, connectedId=9871588990, connectedIdName=<unknown>, redirectingId=, redirectingIdName=, redirectionId=101, userData=, isPredictive=0, dwOrigin=0x80
11/18/11 20:31:57 [1428] TRACE [TSP] TSPI_lineAccept hdCall=02AF9B10
11/18/11 20:31:57 [2676] TRACE [TAPIProvider] TSPI_lineGetCallInfo: changing callerIDName=<unknown> by callerId=9871588990 because is unknown
11/18/11 20:31:57 [2676] TRACE [TAPIProvider] TSPI_lineGetCallInfo hdCall=0x2af9b10 callerId=9871588990, callerIdName=9871588990, calledId=01, calledIdName=01, connectedId=9871588990, connectedIdName=<unknown>, redirectingId=, redirectingIdName=, redirectionId=101, userData=, isPredictive=0, dwOrigin=0x80
11/18/11 20:31:57 [2676] TRACE [TAPIProvider] TSPI_lineGetDevCaps() dwDeviceIdBase=0x5 dwDeviceID=0x5
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: event DATA: Hangup
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: channel DATA: SIP/101-00000000
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.1
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 01
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA: <unknown>
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: cause DATA: 16
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: cause-txt DATA: Normal Clearing
11/18/11 20:32:07 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => hangup
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] OnHangup:CFT: ev.m_uniqueId="1321628514.1"; ev.m_cause="16"; m_pxCstaProvider->OnHangupEvent(ev);
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afb8e0)
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] GetStateOfDevice {2, 1}
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] deviceState = ALERTING
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] callMap.size == 2
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] handleConnectionDisconnected {1321628514.1} to 101
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] ASTCallMap::GetLogicAddress(0x2afb8e0)
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] ASTCstaProvider::handleConnectionDisconnected uniqueId=1321628514.1, logicaddress=101, cause=0
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afb8e0)
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] ================================================================= start callmap dump...
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] DUMP OF ASTERISK CALLS MAP: {UNIQUEID} => call info:
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] {1321628514.0}.1 numDevice=1 callID=2 state=Ringing chann=DAHDI/1-1 CID=9871588990 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source= userData= cause=0
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] {1321628514.1}.101 numDevice=101 callID=2 state=Ringing chann=SIP/101-00000000 CID=01 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source=1321628514.0 userData= cause=0
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] DUMP OF MONITOR CALLS MAP: {CALL_ID} => DEVICE: => STATE
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] [2] => 1 => ALERTING
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] [2] => 101 => ALERTING
11/18/11 20:32:07 [1880] TRACE0[ASTCallMap] ================================================================= end callmap dump...
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 2
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] num_monitors = 1
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] sending event:CFTE:CONNCLEARED to xref=1, address=101, callid=2
11/18/11 20:32:07 [1880] TRACE [TAPIProvider] OnConnectionCleared xref=1 callId=2, releasingDevice=101 cause=0
11/18/11 20:32:07 [1880] TRACE [TAPIProvider] searching a TapiCall with callId 2 in tapiLine 0x2af8ad0
11/18/11 20:32:07 [1880] TRACE [TAPIProvider] htapiline=0x5e27e78, deviceID=101, htapicall=0x2686a58, releasingDevice=101, callerid=N/A, callerIdName=N/A, calleddevice=N/A, lastRedirection=N/A
11/18/11 20:32:07 [1880] TRACE [TAPIProvider] LINECALLSTATE_IDLE NORMAL htapicall=0x2686a58
11/18/11 20:32:07 [1880] TRACE [ASTCallMap] RemoveConnection {2}=> 101
11/18/11 20:32:07 [1880] TRACE [ASTCstaProvider] removed uniqueId=1321628514.1 call from MAP
11/18/11 20:32:07 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: event DATA: Dial
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: subevent DATA: End
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: channel DATA: DAHDI/1-1
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.0
11/18/11 20:32:07 [1880] TRACE0[ASTProvider] HEADER: dialstatus DATA: CANCEL
11/18/11 20:32:07 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => dial
11/18/11 20:32:07 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: event DATA: Hangup
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: privilege DATA: call,all
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: channel DATA: DAHDI/1-1
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: uniqueid DATA: 1321628514.0
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: calleridnum DATA: 9871588990
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: calleridname DATA: <unknown>
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: cause DATA: 16
11/18/11 20:32:08 [1880] TRACE0[ASTProvider] HEADER: cause-txt DATA: Normal Clearing
11/18/11 20:32:08 [1880] TRACE [ASTProvider] ASTProvider::HandleEvent => hangup
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] OnHangup:CFT: ev.m_uniqueId="1321628514.0"; ev.m_cause="16"; m_pxCstaProvider->OnHangupEvent(ev);
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afa230)
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] GetStateOfDevice {2, 1}
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] deviceState = ALERTING
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] handleConnectionDisconnected {1321628514.0} to 1
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] ASTCallMap::GetLogicAddress(0x2afa230)
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] ASTCstaProvider::handleConnectionDisconnected uniqueId=1321628514.0, logicaddress=1, cause=0
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] ASTCallMap::GetLogicCallId(0x2afa230)
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] ================================================================= start callmap dump...
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] DUMP OF ASTERISK CALLS MAP: {UNIQUEID} => call info:
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] {1321628514.0}.1 numDevice=1 callID=2 state=Ringing chann=DAHDI/1-1 CID=9871588990 CIDName=<unknown> ConLineNum= ConLineName= lastRedir= source= userData= cause=0
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] DUMP OF MONITOR CALLS MAP: {CALL_ID} => DEVICE: => STATE
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] [2] => 1 => ALERTING
11/18/11 20:32:08 [1880] TRACE0[ASTCallMap] ================================================================= end callmap dump...
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 2
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] num_monitors = 0
11/18/11 20:32:08 [1880] WARN [ASTCstaProvider] do not send EVENTS because not exist any monitors in call 2
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] RemoveConnection {2}=> 1
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] removed uniqueId=1321628514.0 call from MAP
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] handleCallDisconnected {2} was the last one
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] GetMonitorSetFromCallModel = 2
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] callerId not found
11/18/11 20:32:08 [1880] WARN [ASTCstaProvider] do not send EVENTS because not exist any monitors in call 2
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] RemoveCall {2}
11/18/11 20:32:08 [1880] TRACE [ASTCallMap] {2} NOT IN CALL-MONITOR MAP
11/18/11 20:32:08 [1880] TRACE [ASTCstaProvider] removed entry with callId=2 from MAP
11/18/11 20:32:08 [1880] TRACE [ASTProvider] <== asterisk protocol event _______________________________
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] TSPI_lineCloseCall() hdCall=0x2af9b10
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] ASTTapiProvider::RemoveTapiCall hdrivercall=0x2af9b10
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] ================================================================= start callmap dump...
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] DUMP OF TSP CALLS MAP: [HDRVCALL] => call info:
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] [0x2af9b10] TapiLine=0x02AF8AD0 hdrivercall=0x02AF9B10 htapicall=0x02686A58 callID=2 address=101state= CID=9871588990 CIDName=<unknown> lastRedir=101 called=01 userData= isPredictive=0 cause=0
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] DUMP OF CALL-ID MAP: {CALL_ID} => hdrivercall
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] [2] => TapiLine=0x02AF8AD0 hdrivercall=0x02AF9B10 htapicall=0x02686A58 callID=2 address=101state= CID=9871588990 CIDName=<unknown> lastRedir=101 called=01 userData= isPredictive=0 cause=0
11/18/11 20:32:08 [2676] TRACE0[TAPIProvider] ================================================================= end callmap dump...
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] call had callid=2
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] ...with 1 associated hdrvrcalls
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] delete htcall 0x2686a58 and hdrvCall 0x2af9b10
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] deleted the last htapicall and the set of callid 2
11/18/11 20:32:08 [2676] TRACE [TAPIProvider] deleting pTapiCall addrs=101 htapiCall=0x2686a58
11/18/11 20:32:11 [1880] TRACE0[ASTProvider] HEADER: event DATA: PeerStatus