cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
4224
Views
0
Helpful
1
Replies

SIP Outbound Calls Failing

zorric.sia
Level 1
Level 1

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

1 Reply 1

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.