Debugging Caller ID from analog trunks?

[jamesb]

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

[Anonymous · Accepted 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

[jamesb]

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

[Anonymous]

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

[jamesb]

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

[Anonymous]

Glad to help! Have a great weekend!

-Mark