10-19-2011 10:34 AM
Hello All,
I'm setting up a test router for a new site and outbound calls to our SIP provider are failing. When calls are placed I get a fast busy. Inbound calls work fine. Here's the config and some debugs. Is this a problem with my provider or my config? Thanks in advance.
Router#sh run
Building configuration...
Current configuration : 4899 bytes
!
! Last configuration change at 12:09:01 UTC Mon Sep 19 2011
! NVRAM config last updated at 11:41:36 UTC Mon Sep 19 2011
! NVRAM config last updated at 11:41:36 UTC Mon Sep 19 2011
version 15.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Router
!
boot-start-marker
boot system flash:c2800nm-adventerprisek9-mz.151-4.M2.bin
boot-end-marker
!
!
! card type command needed for slot/vwic-slot 0/0
logging buffered 4096
enable password cisco
!
no aaa new-model
!
!
dot11 syslog
ip source-route
!
!
ip cef
!
ip dhcp excluded-address 192.168.10.1 192.168.10.10
!
ip dhcp pool ALL
network 192.168.10.0 255.255.255.0
default-router 192.168.10.1 255.255.255.0
option 150 ip 192.168.10.1
!
!
no ip domain lookup
no ipv6 cef
!
multilink bundle-name authenticated
!
!
!
!
!
!
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
signaling forward unconditional
h323
modem passthrough nse codec g711ulaw
sip
bind control source-interface FastEthernet0/0
bind media source-interface FastEthernet0/0
rel1xx disable
min-se 360 session-expires 360
header-passing
registrar server expires max 250 min 200
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8
!
!
!
!
!
voice-card 0
!
crypto pki token default removal timeout 0
!
!
!
!
license udi pid CISCO2811 sn FTX1045A4PK
archive
log config
hidekeys
username cisco password 0 cisco
!
redundancy
!
!
!
!
!
!
!
!
!
!
interface FastEthernet0/0
ip address 10.30.210.6 255.255.255.0
duplex auto
speed auto
!
interface FastEthernet0/1
ip address 192.168.10.1 255.255.255.0
duplex auto
speed auto
!
ip forward-protocol nd
ip http server
ip http authentication local
no ip http secure-server
ip http path flash:/cme/cme/cmegui
!
!
ip route 0.0.0.0 0.0.0.0 10.30.210.1
!
!
!
!
!
!
tftp-server flash:/cme/cme/Analog2.raw
tftp-server flash:/cme/cme/AreYouThere.raw
tftp-server flash:/cme/cme/AreYouThereF.raw
tftp-server flash:/cme/cme/Bass.raw
tftp-server flash:/cme/cme/CallBack.raw
tftp-server flash:/cme/cme/Chime.raw
tftp-server flash:/cme/cme/Classic1.raw
tftp-server flash:/cme/cme/Classic2.raw
tftp-server flash:/cme/cme/ClockShop.raw
tftp-server flash:/cme/cme/Drums1.raw
tftp-server flash:/cme/cme/Drums2.raw
tftp-server flash:/cme/cme/FilmScore.raw
tftp-server flash:/cme/cme/HarpSynth.raw
tftp-server flash:/cme/cme/Jamaica.raw
tftp-server flash:/cme/cme/KotoEffect.raw
tftp-server flash:/cme/cme/MusicBox.raw
tftp-server flash:/cme/cme/Piano1.raw
tftp-server flash:/cme/cme/Piano2.raw
tftp-server flash:/cme/cme/Pop.raw
tftp-server flash:/cme/cme/Pulse1.raw
tftp-server flash:/cme/cme/Ring1.raw
tftp-server flash:/cme/cme/Ring2.raw
tftp-server flash:/cme/cme/Ring3.raw
tftp-server flash:/cme/cme/Ring4.raw
tftp-server flash:/cme/cme/Ring5.raw
tftp-server flash:/cme/cme/Ring6.raw
tftp-server flash:/cme/cme/Ring7.raw
tftp-server flash:/cme/cme/Sax1.raw
tftp-server flash:/cme/cme/Sax2.raw
tftp-server flash:/cme/cme/Vibe.raw
tftp-server flash:/cme/cme/Analog1.raw
tftp-server flash:/term61.default.loads alias term61.default.loads
tftp-server flash:/term41.default.loads alias term41.default.loads
!
control-plane
!
!
voice-port 0/1/0
!
voice-port 0/1/1
!
!
mgcp fax t38 ecm
!
mgcp profile default
!
!
dial-peer voice 1000 voip
description Outbound LD Airespring
destination-pattern 1..........
session protocol sipv2
session target ipv4:64.211.41.115
incoming called-number .T
voice-class codec 1
dtmf-relay rtp-nte
no vad
!
dial-peer voice 10 voip
destination-pattern 8882811405
session protocol sipv2
session target ipv4:192.168.10.12
dtmf-relay rtp-nte
codec g711ulaw
!
!
sip-ua
no remote-party-id
retry invite 4
retry response 3
retry bye 2
retry cancel 2
retry register 5
timers register 250
!
!
!
telephony-service
em logout 0:0 0:0 0:0
max-ephones 20
max-dn 20
ip source-address 192.168.10.1 port 2000
system message SaveMore.com
cnf-file location flash:
cnf-file perphone
load 7941 SCCP41.9-1-1SR1S.loads
time-zone 8
dialplan-pattern 1 8882840569 extension-length 4 extension-pattern 1001
max-conferences 8 gain -6
moh flash:/cme/cme/music-on-hold.au
web admin system name cisco password cisco
dn-webedit
time-webedit
transfer-system full-consult
create cnf-files version-stamp Jan 01 2002 00:00:00
!
!
ephone-dn 1 dual-line
number 1001
description Test 1001
name Test 1001
!
!
ephone-dn 2 dual-line
number 1002
description Test1 1002
name Test1 1002
!
!
ephone 1
device-security-mode none
mac-address 001E.7A24.6AC2
type 7941
button 1:1
!
!
!
ephone 2
device-security-mode none
mac-address 001E.7A26.472B
type 7941
button 1:2
!
!
!
!
line con 0
line aux 0
line vty 0 4
login
transport input all
!
scheduler allocate 20000 1000
end
Router#
outer#debug ccsip even
Router#debug ccsip events
SIP Call events tracing is enabled
Router#debug ccsip ?
all Enable all SIP debugging traces
calls Enable CCSIP SPI calls debugging trace
dhcp Enable SIP-DHCP debugging trace
error Enable SIP error debugging trace
events Enable SIP events debugging trace
function Enable SIP function debugging trace
info Enable SIP info debugging trace
media Enable SIP media debugging trace
messages Enable CCSIP SPI messages debugging trace
preauth Enable SIP preauth debugging traces
states Enable CCSIP SPI states debugging trace
translate Enable SIP translation debugging trace
transport Enable SIP transport debugging traces
verbose Enable verbose mode
Router#debug ccsip call
Router#debug ccsip calls
SIP Call statistics tracing is enabled
Router#debug ccsip error
SIP Call error tracing is enabled
Router#debug ccsip preauth
SIP Call preauth tracing is enabled
Router#
Sep 19 12:31:50.821: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x4B555880) with key=[84] to table
Sep 19 12:31:50.821: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
Sep 19 12:31:50.821: //-1/000000000000/SIP/Info/ccsip_iwf_init:
Sep 19 12:31:50.821: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
Sep 19 12:31:50.821: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
Sep 19 12:31:50.821: //143/000000000000/SIP/State/sipSPIChangeState: 0x4B555880 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Sep 19 12:31:50.821: //143/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 174
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_process_event:
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, ev
ent:E_SIP_IWF_EV_SET_MODE
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_sip_md:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_sip_ed:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sccp_early_dialog_container
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_iwf_process_event:
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event
:E_SIP_IWF_EV_SET_FLOW_MODE
Sep 19 12:31:50.825: //143/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
Sep 19 12:31:50.825: //143/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Sep 19 12:31:50.829: //143/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
Sep 19 12:31:50.829: //143/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
Sep 19 12:31:50.829: //143/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
Sep 19 12:31:50.829: //143/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200
Sep 19 12:31:50.829: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 64.211.41.115 target_port : 5060
Sep 19 12:31:50.829: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
Sep 19 12:31:50.829: //143/2D12CE9181D3/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [1000]
Sep 19 12:31:50.833: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 8F to table
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallConfig: Media forking disabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=142
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetModemConfig:
From Config: Modem NSE payload = 100, Passthrough = 4, Modem relay = 0, Gw-Xid = 1
SPRT latency 200, SPRT Retries = 12, Dict Size = 1024
String Len = 32, Compress dir = 3
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallConfig: Media forking disabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/preprocessSetup:
This is a not a SIGO Call -, could be DM call
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/Info/ccsip_iwf_process_event:
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event
:E_SIP_IWF_EV_INIT_CALL_SETUP
Sep 19 12:31:50.833: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.30.210.6
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 10.30.210.6
Sep 19 12:31:50.837: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18552 for stream 1
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
Sep 19 12:31:50.837: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
Sep 19 12:31:50.837: //143/2D12CE9181D3/SIP/Media/sipSPIProcessRtpSessions: No active streams.
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPICheckRawMsg: Have raw msg from ccapi for type: 102
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 10.30.210.6
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 10.30.210.6
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
Sep 19 12:31:50.841: //143/2D12CE9181D3/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x4B555880 key=2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
Sep 19 12:31:50.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 64.211.41.115,Port 5060, Transport 1, SentBy Port 5060
Sep 19 12:31:50.845: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:4C567948
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:360, Configured Min-SE:360
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_handle_sent_sdp:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_process_event:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:
E_SIP_INVITE_SDP_SENT
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_is_invite_offer_valid: - 1
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_iwf_handle_network_event:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/ccsip_iwf_process_event:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event
:E_SIP_IWF_EV_SENT_SDP
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/is_sent_sccp_do_video_inactive:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/is_mode_sip_sccp_do_video:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/is_mode_sip_sccp_do_video:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr:
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
Sep 19 12:31:50.845: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
Sep 19 12:31:50.845: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured
SIP: (143) Group (a= group line) attribute, level 65535 instance 1 not found.
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Info/sipSPISendInvite: Associated container=0x4C567948 to Invite
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipSPITransportSendMessage: msg=0x4CB87D54, addr=64.211.41.115, port=5060, sentBy_port=0, local_addr=10.30
.210.6, is_req=1, transport=1, switch=0, callBack=0x41C07858
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Sep 19 12:31:50.849: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:64.211.41.115, rport:5060 with laddr:10.30
.210.6
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x4CB87D54
Sep 19 12:31:50.849: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4CB87D54, addr=64.211.41.115, port=5060, local_addr=10.
30.210.6, connId=3 for UDP
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
Sep 19 12:31:50.849: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/State/sipSPIChangeState: 0x4B555880 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE
_NONE)
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 143) to the VOIP RTP library
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.30.210.6
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 10.30.210.6, lport = 18552, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
src_callid = 143, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY
media_ip_addr = - , vrf tableid = 0 media_addr_type = 1
Sep 19 12:31:50.849: //143/2D12CE9181D3/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
Sep 19 12:31:50.853: //143/2D12CE9181D3/SIP/Info/sipSPICreateRtpSession: sess: 4C7B6F90 do_rtcp:0
Sep 19 12:31:50.853: //143/2D12CE9181D3/SIP/Media/sipSPICreateRtpSession: stun is disabled
Sep 19 12:31:50.853: //143/2D12CE9181D3/SIP/State/sipSPIChangeStreamState: Stream (callid = 143) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
Sep 19 12:31:50.853: //143/2D12CE9181D3/SIP/Info/sipSPIUpdateCallEntry:
Call 143 set InfoType to SPEECH
Sep 19 12:31:50.857: //143/2D12CE9181D3/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:19726586200@64.211.41.115:5060 SIP/2.0
Via: SIP/2.0/UDP 10.30.210.6:5060;branch=z9hG4bK372102
From: "Test 1001" <sip:8882840569@10.30.210.6>;tag=A587AB8-E7
To: <sip:19726586200@64.211.41.115>
Date: Mon, 19 Sep 2011 12:31:50 GMT
Call-ID: 2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
Supported: timer,resource-priority,replaces,sdp-anat
Min-SE: 360
Cisco-Guid: 0756207249-3790737888-2178135292-2531601294
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1316435510
Contact: <sip:8882840569@10.30.210.6:5060>
Expires: 180
Allow-Events: telephone-event
Content-Type: multipart/mixed;boundary=uniqueBoundary
Mime-Version: 1.0
Content-Length: 595
--uniqueBoundary
Content-Type: application/sdp
Content-Disposition: session;handling=required
v=0
o=CiscoSystemsSIP-GW-UserAgent 5837 8674 IN IP4 10.30.210.6
s=SIP Call
c=IN IP4 10.30.210.6
t=0 0
m=audio 18552 RTP/AVP 0 18 100 101
c=IN IP4 10.30.210.6
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
--uniqueBoundary
Content-Type: application/gtd
Content-Disposition: signal;handling=optional
IAM,
GCI,2d12ce91e1f211e081d3b4fc96e52b8e
--uniqueBoundary--
Sep 19 12:31:50.901: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [64.211.41.115]:5060, local_address:[10.30.210.6]
Sep 19 12:31:50.901: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 19 12:31:50.901: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Sep 19 12:31:50.901: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 19 12:31:50.905: //143/2D12CE9181D3/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.30.210.6:5060;branch=z9hG4bK372102
To: <sip:19726586200@64.211.41.115>
From: "Test 1001" <sip:8882840569@10.30.210.6>;tag=A587AB8-E7
Call-ID: 2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
CSeq: 101 INVITE
Server: Sansay-SIP/8.0
Content-Length: 0
Sep 19 12:31:50.905: //143/2D12CE9181D3/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Sep 19 12:31:50.905: //143/2D12CE9181D3/SIP/State/sipSPIChangeState: 0x4B555880 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDIN
G, SUBSTATE_PROCEEDING_PROCEEDING)
Sep 19 12:31:50.921: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [64.211.41.115]:5060, local_address:[10.30.210.6]
Sep 19 12:31:50.921: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 19 12:31:50.921: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Sep 19 12:31:50.921: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 19 12:31:50.921: //143/2D12CE9181D3/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 503 NoCircuitChannelAvailable
Via: SIP/2.0/UDP 10.30.210.6:5060;branch=z9hG4bK372102
To: <sip:19726586200@64.211.41.115>;tag=403698493-tdb12456
From: "Test 1001" <sip:8882840569@10.30.210.6>;tag=A587AB8-E7
Call-ID: 2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
CSeq: 101 INVITE
Server: Sansay-SIP/8.0
Content-Length: 0
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Sep 19 12:31:50.925: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_handle_call_active:
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_process_event:
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT,
event:E_SIP_DIALOG_ESTD
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/sipSPISendAck: Associated container=0x4C567B00 to Ack
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipSPITransportSendMessage: msg=0x4CB87D54, addr=64.211.41.115, port=5060, sentBy_port=0, local_addr=10.30
.210.6, is_req=1, transport=1, switch=0, callBack=0x0
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Sep 19 12:31:50.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:64.211.41.115, rport:5060 with laddr:10.30
.210.6
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x4CB87D54
Sep 19 12:31:50.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4CB87D54, addr=64.211.41.115, port=5060, local_addr=10.
30.210.6, connId=3 for UDP
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 503
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[143], src[4]
Sep 19 12:31:50.925: //143/2D12CE9181D3/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(63) for outgoing call
Sep 19 12:31:50.929: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Sep 19 12:31:50.929: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/State/sipSPIChangeState: 0x4B555880 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to
(STATE_DISCONNECTING, SUBSTATE_NONE)
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/ccsip_call_statistics: Requesting stats for callid=143
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/ccsip_call_statistics: Stats request failed for callid=143, dstCallID=-1, rc=-7
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
Sep 19 12:31:50.929: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured
Sep 19 12:31:50.929: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Sep 19 12:31:50.929: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:360, Configured Min-SE:360
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:17357083 ConnTime 0
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Media/sipSPIDestroyRtpSession: stream:4CB09A8C
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/State/sipSPIChangeState: 0x4B555880 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTA
TE_NONE)
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x4B555880
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 8882840569
Called Number : 19726586200
Source IP Address (Sig ): 10.30.210.6
Destn SIP Req Addr:Port : 64.211.41.115:5060
Destn SIP Resp Addr:Port : 64.211.41.115:5060
Destination Name : 64.211.41.115
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : No Codec
Negotiated Codec Bytes : 0
Nego. Codec payload : 255 (tx), 255 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 10.30.210.6
Source IP Port (Media): 18552
Destn IP Address (Media): -
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): [ - ]:0
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 63
Disconnect Cause (SIP) : 503
Sep 19 12:31:50.933: //143/2D12CE9181D3/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 8F
Sep 19 12:31:50.941: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:19726586200@64.211.41.115:5060 SIP/2.0
Via: SIP/2.0/UDP 10.30.210.6:5060;branch=z9hG4bK372102
From: "Test 1001" <sip:8882840569@10.30.210.6>;tag=A587AB8-E7
To: <sip:19726586200@64.211.41.115>;tag=403698493-tdb12456
Date: Mon, 19 Sep 2011 12:31:50 GMT
Call-ID: 2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.210.6
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0
Sep 19 12:32:22.933: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[84] removed.
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x4B555880 key=2F06AECC-E1F211E0-81D8B4FC-96E52B8E@10.30.21
0.6
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/ccsip_offer_ans_delete:
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/ccsip_iwf_delete:
Sep 19 12:32:22.933: //143/2D12CE9181D3/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 4B555880
Sep 19 12:32:27.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing: Holder=0x4CB0DFDC Connection=0x4A263B70, addr=64.211.41.115, port=5060, connid
=3 has been REFRESHED
Sep 19 12:32:27.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x4CB0DFDC,addr=64.211.41.115
10-21-2011 11:52 AM
I think that the problem is in your outgoing INVITE message.
Your SDP body contains the "Content-Type: application/gtd" and some gateways don't understand these options.
To prevent the insertion of gtd options try to remove the "signaling forward unconditional" under "voice service voip".
signaling forward {none| unconditional}
Specifies whether or not the originating gateway (OGW) forwards the signaling payload to the terminating gateway (TGW). Keywords are as follows:
•
none—Prevent the gateway from passing the signaling payload to the TGW.
•
unconditional—Forward the signaling payload received in the OGW to the TGW, even if the attached external route server has modified the GTD payload.
Regards.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide