08-17-2012 08:03 AM - edited 03-16-2019 12:45 PM
Hi Cisco Community!
I am new to inbound/outbound SIP being used with the CME on a 2951 router. I've made good headway, and have configured the trunk and voice peers. I see the inbound calls coming to the CME via the debug ccsip calls command, but the results are still not right. here is the error I see when using the debug command:
Aug 17 14:41:13.665: //3707/6D6766A483ED/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x34A6890
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : CID of caller
Called Number : CID of number called
Source IP Address (Sig ): <IP of SIP SOURCE>
Destn SIP Req Addr:Port : <MY CME IP>:5060
Destn SIP Resp Addr:Port :<MY CME IP>:5060
Destination Name : <MY CME IP>
My_CME#
Aug 17 14:41:13.665: //3707/6D6766A483ED/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g729r8
Negotiated Codec Bytes : 20
Nego. Codec payload : 18 (tx), 18 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 124.6.138.18
Source IP Port (Media): 16414
Destn IP Address (Media): <MY CME IP>
Destn IP Port (Media): 22346
Orig Destn IP Address:Port (Media): [ - ]:0
Aug 17 14:41:13.665: //3707/6D6766A483ED/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 1
Disconnect Cause (SIP) : 404
So the trunk is appearing to attempt to come up. Where I think my problem is, in trying to route the incoming call from the SIP trunk to an extension. I am having trouble determining how to build the translations to route the call. I want to route to an internal extension # 8030. Here is the voice translation section that I built:
voice translation-rule 2
rule 2 /CID of number called/ /8030/
!
!
!
voice translation-profile SKYPE_INBOUND
translate called 2
So, shouldn't the incoming number be translated to extension 8030? Or am I missing something?
Any help/guidance is appreciated!
Ken
08-17-2012 08:08 AM
Where is the profile applied to?
Can you not use dialplan-pattern?
Chris
08-17-2012 11:57 AM
I have not tried using a dialplan-pattern, and I don't see a correct format for that command in any of the reference material I'm looking at for these types of calls.
The profile is applied to:
dial-peer voice 200 voip
description *** INCOMING CALLS FROM SKYPE NETWORK ***
translation-profile incoming SKYPE_INBOUND
session protocol sipv2
session target ipv4:
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
dtmf-relay rtp-nte
no vad
!
Is that what you are asking?
08-17-2012 12:51 PM
How do you figure this is the dial-peer being matched on incoming side?
There is different ways of doing that, i recommend you add "incoming called-number ." to it.
The to confirm it is being matched use "debug voice dial peer"
To confirm if your translations are working do "debug voice translation"
HTH, please rate all useful posts!
Chris
08-17-2012 12:57 PM
I don't figure, or else I wouldn't be hanging around asking how to do this mess
So, I added the incoming called-number.
I turned on dialpeer logging
Nothing appears in the logs, call still never gets anywhere, and am presented with the following in the logs:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddDateHeaderString:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGetCallHeaderString:
Aug 17 19:55:11.779: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone MNL to SIP default timezone = GMT
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGenerateAndAppendFromTag:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPICreateInitialRequestUri:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddLocalVia:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddViaExtParam:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddChanInfoExtParam:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddChanInfoExtParam:exit@11458:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddViaParams:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddStdHeaders:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAppendTagParam:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGetCallHeaderString:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGetCallHeaderString:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sip_gw_add_register_contact:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIAddAuthorizationHeader:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x1E8DA5C0 to Register
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGetSwitchTransportFlag:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIGetSwitchTransportFlag:exit@4085:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/ccsip_profile_application:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/ccsip_profile_application:exit@19297:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sentRegister:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPISetRegisterTimer:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPISetTimer:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIChangeState:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/sipSPIPopInboundContainerFromCCB:
Aug 17 19:55:11.779: //9037/000000000000/SIP/Function/force_internal_queue_lookup:
Aug 17 19:55:11.987: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [63.209.144.201]:5060, local_address:[ - ]
Aug 17 19:55:11.987: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Aug 17 19:55:11.987: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sipSPIMatchRespToReqTran:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sippmh_match_resp_header_from:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sippmh_match_resp_header_to:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sipSPICopyToTagToCCB:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK21F921E from via branch list
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sipSPIPushInboundContainerIntoCCB:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/ccsipDisplayMsg:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/ccsipDisplayMsg:exit@10844:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/act_register_new_message:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sipSPICheckResponseExt:
Aug 17 19:55:11.987: //9037/000000000000/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Aug 17 19:55:11.987: //9037/000000000000/SIP/Function/sipSPIPreProcessRegisterResponse:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIStopTimer:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIPopInboundContainerFromCCB:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_api_register_result_ind:
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDecrementOverloadCount: Count:Local 0 Global 64
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/ccsipGetCCBFromRCB: Retrived CCB pointer [0x0]
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_spi_register_free_rcb: Freeing rcb [0x1D1C98A8]
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_register_reset_dns_cache: CCSIP_REGISTER:: registrar 0 DNS resolved addr reset
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 403
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetRPCBFromRCB: Retreiving RCB [0x1EB1E0C0] from RPCB [0x0]
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartRCBTimer: Starting timer for pattern 8144 for 180 seconds
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_delete_ccb:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIRemoveChildren:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIUfreeOneCCB:
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[8888] removed.
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIUdeleteCcbFromUACTable:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIUdeleteCcbFromTable:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x3440F78 key=29CBEC0-E7CD11E1-84999991-F7653EF4
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIFreeSessionTargetInfo:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIFreeHistoryInfo:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPICleanupContainerBlock:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_platform_delete_ccb:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_platform_stop_timers:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIDropRtpSessions:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIGetFirstStream:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIGetVideoStream:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIGetFirstStream:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_qos_cleanup:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIGetFirstStream:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_platform_terminate_implicit_subscriptions:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_free_kpml_info:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/ccsip_free_kpml_info:exit@1403:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIFlushKpmlDigitBuffer:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIFlushKpmlDigitBuffer:exit@1900:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIClearKPMLClientContext:exit@2120:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPI_ipip_free_dialpeer_video_caps:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPI_ipip_free_codec_profile:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/ccsip_offer_ans_delete:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/ccsip_iwf_delete:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPICleanupOverlapInfo:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIBwCacReleaseAccountedBw:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Function/sipSPIBwCacReleaseAccountedBw:exit@19207:
Aug 17 19:55:11.991: //9037/000000000000/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x3440F78
Aug 17 19:55:11.991: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[8888]
So I don't know what is causing the error on the last step...
08-18-2012 07:39 AM
Can you please post your entire config and IOS version?
Chris
08-20-2012 08:07 AM
Chris, we were able to work through Cisco TAC and come to a resolution. It appeared the INVITE part of the message was never making it to our CME due to SKYPE having a small configuration issue. Once that was resolved, INVITE came in and we had to reconfigure our dial peers to direct teh call.
The dial-peer had to have an inbound called rule, and also a translation rule to convert to the internal extension.
Thank you for your help!
Ken
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: