cancel
Showing results for 
Show  only  | Search instead for 
Did you mean: 
Highlighted
New Contributor

Debugging Caller ID from analog trunks?

Jump to solution

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

Labels (1)
0 Kudos
1 Solution

Accepted Solutions
Highlighted
Valued Contributor II
Valued Contributor II

Re: Debugging Caller ID from analog trunks?

Jump to solution

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

View solution in original post

0 Kudos
5 Replies
Highlighted
Valued Contributor II
Valued Contributor II

Re: Debugging Caller ID from analog trunks?

Jump to solution

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

View solution in original post

0 Kudos
Highlighted
New Contributor

Re: Debugging Caller ID from analog trunks?

Jump to solution

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

Highlighted
Valued Contributor II
Valued Contributor II

Re: Debugging Caller ID from analog trunks?

Jump to solution

You are correct, this line states that you are not receiving Caller-ID:

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

Your trunk account config was the same as mine except different trunk number.

-Mark

Highlighted
New Contributor

Re: Debugging Caller ID from analog trunks?

Jump to solution

Thanks again, Mark. I was able to get the facility to hook up an analog phone and confirm that it isn't working. Looks like I'll be talking to the telco.

James

Highlighted
Valued Contributor II
Valued Contributor II

Re: Debugging Caller ID from analog trunks?

Jump to solution

Glad to help! Have a great weekend!

-Mark