5 Replies Latest reply on Sep 11, 2015 11:18 AM by markfreeman

    Debugging Caller ID from analog trunks?

    jamesb New Member

      I have a 7100 configure with 4 FXO lines. Right now, I'm not seeing any caller ID information when I receive a call on one of those lines. I do have the 'caller-id' command under the trunk config. How can I debug the incoming calls to see if the caller id information is being received from the telco? I tried debug voice toneservices, it just shows this:

       

      18:48:31.856 TONESERVICES.EVENTS fxo 2/1 - empty - Caller-ID Detection: Request resource

      18:48:31.856 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: DSP channel allocated for the resource

      18:48:31.856 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: constructed

      18:48:31.856 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: starting

      18:48:31.857 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: TDM map

      18:48:37.859 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: stopping

      18:48:37.860 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: TDM unmap

      18:48:37.860 TONESERVICES.EVENTS fxo 2/1 - dsp 0/1.1 - Caller-ID Detection: release

       

      thanks,

      James

        • Re: Debugging Caller ID from analog trunks?
          markfreeman Employee

          James,

          Run a debug voice verbose and then you should see where it collected the caller-id is displayed in the debug. If you look at my debug below you see call come in at 8:33:02, scroll down to near bottom at 8:33:05 and you will see a VXMLInterpreter vxml. Debug output. I made it bold and in red color. There is where it shows what was actually collected. On my FXO Trunk Account, I have the Trunk Number set to my AA 8200.

           

          Let me know if you have any questions.

           

           

          UCAS15#debug voice ver

          08:33:02.056 TM.T01 01 Idle - Tism_seize()

          08:33:02.056 TM.T01 01 Idle -> Active

          08:33:02.056 TA.T01 01 TAIdle               rcvd: appearance on from TM

          08:33:02.056 TA.T01 01 State change      >> TAIdle->TAAppearance (TAS_Active)

          08:33:02.057 TM.T01 01 Active - tachg_Active

          08:33:02.058 TM.T01 01 Active -> GetCID

          08:33:02.058 TONESERVICES.EVENTS FxoTrunk 0/1 - empty - Caller-ID Detection: Request resource

          08:33:02.058 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: DSP channel allocated for the resource

          08:33:02.058 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: constructed

          08:33:02.059 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: starting

          08:33:02.059 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: TDM map

          08:33:02.060 TRKPORT.00:01 00:01  Ringing

          08:33:04.165 TRKPORT.00:01 00:01  Loop Current Feed

          08:33:05.125 TM.T01 01 GetCID - Tism_getCIDResponse(PASS)

          08:33:05.125 TM.T01 01 GetCID -> Calling

          08:33:05.125 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: received caller-id string

          08:33:05.125 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: stopping

          08:33:05.126 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: TDM unmap

          08:33:05.126 RTP.CHANNEL FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: releasing RTP resource

          08:33:05.126 TONESERVICES.EVENTS FxoTrunk 0/1 - Dsp 0/1.1 - Caller-ID Detection: release

          08:33:05.127 TA.T01 01 TAAppearance         rcvd: inboundCall from TM

          08:33:05.127 TA.T01 01 State change      >> TAAppearance->TAInboundCall (TAS_Calling)

          08:33:05.127 TA.T01 01 DID not enabled - using trunk-number: 8200

          08:33:05.127 TA.T01 01 TAAppearance         sent: call to SB

          08:33:05.128 SB.CALL 1 Idle                 Called the call routine with 8200

          08:33:05.128 SB.CCM isMappable:

          08:33:05.128 SB.CCM  :  Call Struct 0x441bc10 :   Call-ID = 1

          08:33:05.128 SB.CCM  :  Org Acct = T01    Dst Acct = AutoAttendantAcct

          08:33:05.129 SB.CCM  :  Org Port ID = FxoTrunk 0/1   Dst Port ID = unknown 0/0

          08:33:05.129 SB.CCM isMappable: Call Connection Type is TDM_TO_RTP

          08:33:05.129 SB.CCM isMappable: Reserving RTP Channel 0/1.1

          08:33:05.130 SB.CCM isMappable: Creating SDP Offer

          08:33:05.131 SB.CCM updateOfferWithEndpointConfig: DTMF(NTE 101), VAD(off), ptime(0)

          08:33:05.132 SB.CCM translateOffer: offer codec list: PCMU G729

          08:33:05.132 SB.CCM translateOffer: revised offer codec list: PCMU G729

          08:33:05.133 SB.CCM translateOffer: codec list after answerer: G729

          08:33:05.133 SB.CCM translateOffer: codec list after transcode add: G729

          08:33:05.134 SB.CCM translateOffer: compatible DTMF signaling: answerer restriction(NTE 101), offer(NTE 101): DTMF transcoding unnecessary, using offer

          08:33:05.134 SB.CCM translateOffer: success

          08:33:05.134 MEDIA.MANAGER Allocating media port.

          08:33:05.135 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 1

          08:33:05.135 MEDIA.MANAGER Call ID map : Added new entry : call ID 1 : session -1441978385INIP4127.0.0.3 : version 2 : index 0

          08:33:05.135 MEDIA.MANAGER New media entry : type(0), callID(1), sessionID(-1441978385INIP4127.0.0.3), original IP(127.0.0.3) ports(10000-10001), substitute IP(::) ports(10000-10001), RtpChannel(0/1.1), connection(0x4080d10), sdpOverride(0), me(0x4080610). RtpChannel 0/1.1

          08:33:05.136 SB.CALL 1 Idle                 Call sent from T01 to AutoAttendantAcct (8200)

          08:33:05.136 SB.CALL 1 State change      >> Idle->Delivering

          08:33:05.136 RTP.MANAGER FxoTrunk 0/1 - empty - RTP: Request resource

          08:33:05.136 RTP.MANAGER FxoTrunk 0/1 - Dsp 0/1.1 - RTP: DSP channel allocated for the resource

          08:33:05.136 RTP.PROVIDER FxoTrunk 0/1 - Dsp 0/1.1 - RTP: providing already allocated RTP channel

          08:33:05.137 TA.T01 01 TAInboundCall        CallResp event accepted

          08:33:05.137 TA.T01 01 State change      >> TAInboundCall->TAConnectWaitIn (TAS_Calling)

          08:33:05.137 AUTOATTENDANT.CA:0 Idle - updateSdpInActiveCallStruct: Creating SDP Answer based on SDP Offer

          08:33:05.138 MEDIA.MANAGER Allocating media port.

          08:33:05.139 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 1 sessionId -1441978386INIP4127.0.0.1 remote port 0

          08:33:05.139 MEDIA.MANAGER getSubstitutePort: No matching sessionPortMap entry found for session -1441978386INIP4127.0.0.1

          08:33:05.139 MEDIA.MANAGER Call ID map : Added new session ID : call ID 1 : session -1441978386INIP4127.0.0.1 : version 1 : index 2

          08:33:05.139 MEDIA.MANAGER New media entry : type(0), callID(1), sessionID(-1441978386INIP4127.0.0.1), original IP(127.0.0.1) ports(10002-10003), substitute IP(::) ports(10002-10003), RtpChannel(NULL), connection(0x4080010), sdpOverride(0), me(0x4080f10). No RtpChannel

          08:33:05.140 AUTOATTENDANT.CA:0 Idle - State Change: Idle to DigitGathering

          08:33:05.140 SB.CALL 1 Delivering           Called the connect routine

          08:33:05.140 SB.CCM isResponseMappable:

          08:33:05.141 SB.CCM  :  Call Struct 0x441bc10 :   Call-ID = 1

          08:33:05.141 SB.CCM  :  Org Acct = T01    Dst Acct = AutoAttendantAcct

          08:33:05.141 SB.CCM  :  Org Port ID = FxoTrunk 0/1   Dst Port ID = InternalRtpApp 0/0

          08:33:05.141 SB.CCM  :  SDP Transaction = CallID: 1

          08:33:05.142 SB.CCM  :  SDP Offer = 0x040ba610, (127.0.0.3:10000)

          08:33:05.142 SB.CCM  :  SDP Answer = 0x04080a10, (127.0.0.1:10002)

          08:33:05.142 SB.CCM  :  RTP Channel = 0/1.1

          08:33:05.142 SB.CCM isResponseMappable: reversing call connection type to compensate for event originator direction

          08:33:05.143 SB.CCM isResponseMappable: Call Connection Type is RTP_TO_TDM

          08:33:05.143 SB.CCM isResponseMappable: Modifying SDP Answer

          08:33:05.143 SB.CCM translateAnswer: offer  codec list: PCMU G729

          08:33:05.144 SB.CCM                : answer codec list: G729

          08:33:05.144 SB.CCM translateAnswer: CODEC transcoding is not required

          08:33:05.144 SB.CCM translateAnswer: offer / answer DTMF signaling identical: DTMF transcoding not required

          08:33:05.145 SB.CCM translateAnswer: success

          08:33:05.145 MEDIA.MANAGER Allocating media port.

          08:33:05.145 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 1 sessionId -1441978386INIP4127.0.0.1 remote port 10002

          08:33:05.145 MEDIA.MANAGER getSubstitutePort: Matching sessionPortMap entry found for session

          08:33:05.146 MEDIA.MANAGER getSubstitutePort: Session port count (1)  Returning port (10002)

          08:33:05.146 MEDIA.MANAGER Existing entry found for port reuse of SDP port 10002 and sub port 10002.

          08:33:05.146 MEDIA.MANAGER Call ID map : Replacing with newer version : call ID 1 : session -1441978386INIP4127.0.0.1 : version 2 : index 2

          08:33:05.146 MEDIA.MANAGER Reuse anchor entry with same SDP : call 1 : session -1441978386INIP4127.0.0.1 : IP :: ports 10002 - 10003 : remote IP 127.0.0.1 ports 10002 - 10003.

          08:33:05.146 SB.CALL 1 Delivering           Connect sent from AutoAttendantAcct to T01

          08:33:05.147 SB.CALL 1 State change      >> Delivering->Connecting

          08:33:05.147 TA.T01 01 TAConnectWaitIn      connect event accepted

          08:33:05.147 TA.T01 01 State change      >> TAConnectWaitIn->TAConnectPending (TAS_Connected)

          08:33:05.147 TM.T01 01 Calling - tachg_Connected

          08:33:05.147 TM.T01 01 Calling - inbound loopstart call - dialtone defeat enabled

          08:33:05.148 TM.T01 01 Calling -> Connecting

          08:33:05.148 TRKPORT.00:01 00:01  CID-Detected

          08:33:05.367 TRKPORT.00:01 00:01  Loop Current Feed

          08:33:05.449 TM.T01 01 Connecting - OffhookResponse(PASS)

          08:33:05.449 TM.T01 01 Connecting -> Connected

          08:33:05.450 TA.T01 01 TAConnectPending     rcvd: connect from TM

          08:33:05.450 TA.T01 01 State change      >> TAConnectPending->TAConnected (TAS_Connected)

          08:33:05.450 SB.CALL 1 Connecting           Called the connectResponse routine

          08:33:05.450 SB.CCM connect:

          08:33:05.450 SB.CCM  :  Call Struct 0x441bc10 :   Call-ID = 1

          08:33:05.451 SB.CCM  :  Org Acct = T01    Dst Acct = AutoAttendantAcct

          08:33:05.451 SB.CCM  :  Org Port ID = FxoTrunk 0/1   Dst Port ID = InternalRtpApp 0/0

          08:33:05.451 SB.CCM  :  SDP Transaction = CallID: 1

          08:33:05.452 SB.CCM  :  SDP Offer = 0x040ba610, (127.0.0.3:10000)

          08:33:05.452 SB.CCM  :  SDP Answer = 0x04080a10, (127.0.0.1:10002)

          08:33:05.452 SB.CCM  :  RTP Channel = 0/1.1

          08:33:05.452 MOH.APP printCSHoldStates, connect_: towOrig 0, towDest 0 origHold 0 destHold 0 isHold 0

          08:33:05.452 SB.CCM connect: Call Connection Type is TDM_TO_RTP

          08:33:05.453 SB.CCM SDP offer is 127.0.0.3:10000, SDP answer is 127.0.0.1:10002

          08:33:05.453 MEDIA.MANAGER Trying to connect call ID 1 : SDP sessions -1441978385INIP4127.0.0.3 and -1441978386INIP4127.0.0.1

          08:33:05.453 MEDIA.MANAGER Found 1 ports for session -1441978385INIP4127.0.0.3

          08:33:05.453 MEDIA.MANAGER Found 1 ports for session -1441978386INIP4127.0.0.1

          08:33:05.454 MEDIA.MANAGER Connecting Disconnected Local :10000 : Remote 127.0.0.3:10000

          08:33:05.454 MEDIA.MANAGER    and     Disconnected Local :10002 : Remote 127.0.0.1:10002

          08:33:05.454 MEDIA.MANAGER Setting up DSP Media Connection 1 for entry(type(0), callID(1), sessionID(-1441978385INIP4127.0.0.3), original IP(127.0.0.3) ports(10000-10001), substitute IP(::) ports(10000-10001), RtpChannel(0/1.1), connection(0x4080d10), sdpOverride(0), me(0x4080610))

          08:33:05.454 MEDIA.MANAGER Setting up Internal Media Connection 1 for entry(type(0), callID(1), sessionID(-1441978386INIP4127.0.0.1), original IP(127.0.0.1) ports(10002-10003), substitute IP(::) ports(10002-10003), RtpChannel(0/1.1), connection(0x4080010), sdpOverride(0), me(0x4080f10))

          08:33:05.455 MEDIA.MANAGER Connection Fixup 1 Internal Port 10002

          08:33:05.455 MEDIA.MANAGER   Local :10002 : Remote 127.0.0.1:10002

          08:33:05.455 MEDIA.MANAGER Connection Fixup 2 DSP Port 10000

          08:33:05.455 MEDIA.MANAGER   Local :10000 : Remote 127.0.0.3:10000

          08:33:05.455 MEDIA.MANAGER connectionFixup : Internal media : setup internal connection to DSP

          08:33:05.456 MEDIA.MANAGER connectionFixup : DSP media : Change entry 10000 remote from 127.0.0.3:10000 to 127.0.0.1:10002

          08:33:05.456 MEDIA.MANAGER Setup RTP Channel true for 0/1.1

          08:33:05.456 MEDIA.MANAGER Connection Result 1 Internal Port 10002

          08:33:05.456 MEDIA.MANAGER   Local :10002 : Remote 127.0.0.1:10000

          08:33:05.456 MEDIA.MANAGER Connection Result 2 DSP Port 10000

          08:33:05.456 MEDIA.MANAGER   Local :10000 : Remote 127.0.0.1:10002

          08:33:05.457 MEDIA.MANAGER connectionFixup success for port 10002 and 10000

          08:33:05.457 MEDIA.MANAGER Marking setup complete for port 10002

          08:33:05.457 MEDIA.MANAGER Marking setup complete for port 10000

          08:33:05.457 MEDIA.MANAGER Connection Fixup 1 Internal Port 10003

          08:33:05.457 MEDIA.MANAGER   Local :10003 : Remote 127.0.0.1:10003

          08:33:05.458 MEDIA.MANAGER Connection Fixup 2 DSP Port 10001

          08:33:05.458 MEDIA.MANAGER   Local :10001 : Remote 127.0.0.3:10001

          08:33:05.458 MEDIA.MANAGER connectionFixup : Internal media : setup internal connection to DSP

          08:33:05.458 MEDIA.MANAGER connectionFixup : DSP media : Change entry 10001 remote from 127.0.0.3:10001 to 127.0.0.1:10003

          08:33:05.458 MEDIA.MANAGER Connection Result 1 Internal Port 10003

          08:33:05.458 MEDIA.MANAGER   Local :10003 : Remote 127.0.0.1:10001

          08:33:05.459 MEDIA.MANAGER Connection Result 2 DSP Port 10001

          08:33:05.459 MEDIA.MANAGER   Local :10001 : Remote 127.0.0.1:10003

          08:33:05.459 MEDIA.MANAGER connectionFixup success for port 10003 and 10001

          08:33:05.459 MEDIA.MANAGER Marking setup complete for port 10003

          08:33:05.459 MEDIA.MANAGER Marking setup complete for port 10001

          08:33:05.459 MEDIA.MANAGER Connected Internal Port 10002 - DSP Port 10000

          08:33:05.460 MEDIA.MANAGER   First:   Local :10002 : Remote 127.0.0.1:10002

          08:33:05.460 MEDIA.MANAGER   Second:  Local :10000 : Remote 127.0.0.1:10000

          08:33:05.460 MEDIA.MANAGER Connected associations Entry not activated

          08:33:05.460 SB.CCM connect: Connected RTP/TDM via MCM

          08:33:05.460 MEDIA.MANAGER Setup RTP Channel true for 0/1.1

          08:33:05.461 SB.CCM handleMediaConnectionRtpChannel: No RTP channel to set up

          08:33:05.461 SB.CCM setupRtpChannel, source 2, silence 0

          08:33:05.461 SB.CCM setupRtpChannel: setup using media connection

          08:33:05.462 SB.CCM Looking up source address for destination 127.0.0.1

          08:33:05.462 SB.CCM setupRtpChannel: Source IP addr = 127.0.0.1, port = 10000

          08:33:05.462 SB.CCM setupRtpChannel: Target IP addr = 127.0.0.1, port = 10002

          08:33:05.462 SB.CCM setupRtpChannel: Undo of previous operation not required

          08:33:05.463 SB.CCM getFinalCodec: G729

          08:33:05.463 SB.CCM getFinalCodec: G729

          08:33:05.463 SB.CCM setupRtpChannel: Configuring RTP Channel 0/1.1 to Src 127.0.0.1:10000 Trg 127.0.0.1:10002 via G729 Rx G729

          08:33:05.464 SB.CCM setupRtpChannel: fpp=2 echo=on dtmf=101/101 dscp=46 vad=off isOffer yes

          08:33:05.464 SB.CCM setupRtpChannel: Starting RTP Channel

          08:33:05.464 RTP.CHANNEL Channel 0/1.1 session statistics cleared.

          08:33:05.464 RTP.CHANNEL Channel 0/1.1 started successfully.

          08:33:05.465 SB.CCM firewallConnectCall: Set up firewall from media connections

          08:33:05.465 SB.CCM sdpFirewall: invoked with offer - :10002, answer - 127.0.0.1:10002

          08:33:05.465 SB.CCM sdpFirewall: 'any' address detected, no action taken

          08:33:05.465 SB.CCM sdpFirewall: invoked with offer - 127.0.0.1:10000, answer - :10000

          08:33:05.465 SB.CCM sdpFirewall: 'any' address detected, no action taken

          08:33:05.466 SB.CCM connect: TDM streams: port(FxoTrunk 0/1) to port(InternalRtpApp 0/1.1)

          08:33:05.466 SB.CALL 1 Connecting           ConnectResponse sent from T01 to AutoAttendantAcct

          08:33:05.467 RTP.CHANNEL FxoTrunk 0/1 - Dsp 0/1.1 - RTP: starting

          08:33:05.467 AUTOATTENDANT.CA:0 DigitGathering - rcvd: connectResponse from SB

          08:33:05.467 AUTOATTENDANT.8200 Ca:0 activate: New session initiated.

          08:33:05.467 SB.CALL 1 Connecting           Called the finalizeConnect routine

          08:33:05.467 SB.CCM finalizeConnect: connection already finalized(2)

          08:33:05.468 SB.CALL 1 State change      >> Connecting->Connected

          08:33:05 VXMLInterpreter vxml. Ca:0 # Platform Event: activate

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'SystemLanguage' to '0'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'SystemDefaultPrompts' to 'CFLASH:/SystemDefaultPrompts'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'RootForXML' to 'CFLASH:/AA/'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'RootForWAV' to 'CFLASH:/AA/Prompts/'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'operator' to '0'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'internalError' to 'tel:@operator@'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'collect' to ''

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'disconnect' to 'disconnect:'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'repeat' to 'previous.0'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CallerName' to 'UNKNOWN'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CallerNumber' to 'UNKNOWN'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CalleeName' to 'UNKNOWN'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CalleeNumber' to 'UNKNOWN'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CallerNumber' to '2569631234'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CalleeName' to 'AutoAttendantAccount'

          08:33:05 VXMLInterpreter vxml. Ca:0 # Set 'CalleeNumber' to '8200'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'CalledOriginalNumber' to '8200'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'FullOptions' to 'Yes'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0   CallerName:UNKNOWN CallerNumber:2569631234 CalleeName:AutoAttendantAccount CalleeNumber:8200 CalledOriginalNumber:8200

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'RootDocument' to 'defaultAA.xml'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 Process Action: 'defaultAA.xml' (defaultAA.xml)

          08:33:05 VXMLInterpreter vxml.8200 Ca:0   Loaded XML from 'CFLASH:/AA/defaultAA.xml'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'LoopCount.DefaultAA' to '1'

          08:33:05 VXMLInterpreter vxml.8200 Ca:0 Entering state: '

          '
          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Entering File: 'CFLASH:/AA/defaultAA.xml' Offset: 1363'
          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'operator' to '0'
          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Set 'collect' to ''
          08:33:05 VXMLInterpreter vxml.8200 Ca:0 # Started prompt 'CFLASH:/AA/Prompts/Main.wav'
          08:33:05.951 TONESERVICES.EVENTS FxoTrunk 0/1 - empty - Bridged Dialtone Detector: Request resource
          08:33:05.951 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: DSP channel allocated for the resource
          08:33:05.952 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: constructed
          08:33:05.952 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: starting
          08:33:05.952 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: TDM map
          08:33:09.846 TRKPORT.00:01 00:01 Loop Current Feed Open
          08:33:09.953 TM.T01 01 Connected - dialtone defeat timed out normally
          08:33:09.954 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: stopping
          08:33:09.954 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: TDM unmap
          08:33:09.954 RTP.CHANNEL FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: releasing RTP resource
          08:33:09.955 TONESERVICES.EVENTS FxoTrunk 0/1 - FxoTrunk 0/1.1 - Bridged Dialtone Detector: release
          08:33:09.955 TRKPORT.00:01 00:01 Dialtone detection stopped
          08:33:10.346 TM.T01 01 Connected - Tism_clear()
          08:33:10.346 TM.T01 01 Connected -> Clearing
          08:33:10.347 TA.T01 01 TAConnected rcvd: clear from TM
          08:33:10.347 TA.T01 01 State change >> TAConnected->TATrunkClearing (TAS_Clearing)
          08:33:10.347 TM.T01 01 Clearing - tachg_Clearing
          08:33:10.347 SB.CALL 1 Connected Called the clearCall routine
          08:33:10.348 SB.CALL 1 Connected ClearCall sent from T01 to AutoAttendantAcct
          08:33:10.348 SB.CALL 1 State change >> Connected->Clearing
          08:33:10.348 AUTOATTENDANT.CA:0 DigitGathering - rcvd: clearCall from SB
          08:33:10.348 AUTOATTENDANT.CA:0 DigitGathering - State Change: DigitGathering to Idle
          08:33:10.349 SB.CALL 1 Clearing Called the clearResponse routine
          08:33:10.349 SB.CALL 1 State change >> Clearing->CallIdlePending
          08:33:10.349 SB.CCM disconnect:
          08:33:10.349 SB.CCM : Call Struct 0x441bc10 : Call-ID = 1
          08:33:10.349 SB.CCM : Org Acct = T01 Dst Acct = AutoAttendantAcct
          08:33:10.350 SB.CCM : Org Port ID = FxoTrunk 0/1 Dst Port ID = InternalRtpApp 0/1.1
          08:33:10.350 SB.CCM : RTP Channel = 0/1.1
          08:33:10.350 MOH.APP printCSHoldStates, disconnect: towOrig 0, towDest 0 origHold 0 destHold 0 isHold 0
          08:33:10.350 SB.CCM disconnect: Call Connection Type is TDM_TO_RTP
          08:33:10.350 SB.CCM disconnect: Stopping RTP Channel 0/1.1
          08:33:10.351 RTP.CHANNEL Channel 0/1.1 stopped successfully.
          08:33:10.351 SB.CCM disconnect: Disconnecting TDM streams
          08:33:10.351 SB.CCM release:
          08:33:10.351 SB.CCM : Call Struct 0x441bc10 : Call-ID = 1
          08:33:10.351 SB.CCM : Org Acct = T01 Dst Acct = AutoAttendantAcct
          08:33:10.352 SB.CCM : Org Port ID = FxoTrunk 0/1 Dst Port ID = InternalRtpApp 0/1.1
          08:33:10.352 SB.CCM : RTP Channel = 0/1.1
          08:33:10.352 SB.CCM release: Call Connection Type is TDM_TO_RTP
          08:33:10.352 SB.CCM release: Releasing RTP Channel 0/1.1
          08:33:10.353 RTP.CHANNEL Channel 0/1.1 released successfully.
          08:33:10.353 SB.CALL 1 CallIdlePending ClearResponse sent from AutoAttendantAcct to T01
          08:33:10.353 TA.T01 01 TATrunkClearing clearResponse event accepted
          08:33:10.354 TA.T01 01 State change >> TATrunkClearing->TAClearingComplete (TAS_Clearing)
          08:33:10.354 RTP.CHANNEL FxoTrunk 0/1 - Dsp 0/1.1 - RTP: stopping
          08:33:10 VXMLInterpreter vxml.8200 Ca:0 # Platform Event: deactivate
          08:33:10 VXMLInterpreter vxml.8200 Ca:0 Leaving state: '

          '
          08:33:10 VXMLInterpreter vxml.8200 Ca:0 Stopping current prompt.
          08:33:10.355 RTP.CHANNEL FxoTrunk 0/1 - Dsp 0/1.1 - RTP: releasing RTP resource
          08:33:10.355 RTP.CHANNEL FxoTrunk 0/1 - Dsp 0/1.1 - RTP: releasing
          08:33:10.358 TRKPORT.00:01 00:01 LCFO - far end disconnect
          08:33:10.407 TRKPORT.00:01 00:01 Loop Current Feed
          08:33:10.947 TM.T01 01 Clearing - Tism_clearResponse(PASS)
          08:33:10.947 TM.T01 01 Clearing -> Idle
          08:33:10.948 TM.T01 01 Idle - Tism_idleResponse(PASS)
          08:33:10.948 TA.T01 01 TAClearingComplete rcvd: appearance off from TM
          08:33:10.948 TA.T01 01 TAClearingComplete Clear Local Variables
          08:33:10.948 TA.T01 01 State change >> TAClearingComplete->

          TAIdle (TAS_Idle)

          08:33:10.948 TM.T01 01 Idle - tachg_Idle

          2015.09.11 08:33:11 SMDR 1          09/11/2015 08:33:05      0.1 0    E  00/01                 2569631234      00/01 AutoAttendantAc 8200            0 N

          UCAS15#

           

          -Mark

            • Re: Debugging Caller ID from analog trunks?
              jamesb New Member

              Thanks, Mark. So can I conclude from this that I'm not receiving Caller ID?

               

              06:37:11 VXMLInterpreter vxml. Ca:0 # Platform Event: activate

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'SystemLanguage' to '0'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'SystemDefaultPrompts' to 'CFLASH:/SystemDefaultPrompts'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'RootForXML' to 'CFLASH:/AA/'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'RootForWAV' to 'CFLASH:/AA/Prompts/'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'operator' to '0'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'internalError' to 'tel:@operator@'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'collect' to ''

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'disconnect' to 'disconnect:'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'repeat' to 'previous.0'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CallerName' to 'UNKNOWN'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CallerNumber' to 'UNKNOWN'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CalleeName' to 'UNKNOWN'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CalleeNumber' to 'UNKNOWN'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CalleeName' to 'AutoAttendantAccount'

              06:37:11 VXMLInterpreter vxml. Ca:0 # Set 'CalleeNumber' to '820'

              06:37:11 VXMLInterpreter vxml.820 Ca:0 # Set 'CalledOriginalNumber' to '820'

              06:37:11 VXMLInterpreter vxml.820 Ca:0 # Set 'FullOptions' to 'Yes'

              06:37:11 VXMLInterpreter vxml.820 Ca:0   CallerName:UNKNOWN CallerNumber:UNKNOWN CalleeName:AutoAttendantAccount CalleeNumber:820 CalledOriginalNumber:820

               

              This is my trunk config, btw:

               

              voice trunk T01 type analog supervision loop-start

                blind-dial

                caller-id

                trunk-number 299

                connect fxo 2/1

                rtp delay-mode adaptive

                codec-list g711_first

               

              Thanks a bunch,

              James