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
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->
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
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->
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
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
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
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
Glad to help! Have a great weekend!
-Mark