CME SIP Trunk Config debug?

[DrVoIP]

I am configuring a SIP trunk on a CME.   The configuration is very straight forward, but I am clearly doing something wrong.   Generally, if you enter "debug CCSIP all"  just before you enter the credentials for the SIP SERVER, you can watch the sip registration process. When I do it I get no action, nothing happens?

sip-ua

credentials username TEST password TEST realm cheapvoice.com

generally when you run debug you will see the registraiton attempt as soon as you type the carriage return in the credentials statement?

How do I force a registraiton attempt?  Here is full conifguration

Current configuration : 5448 bytes

!

version 12.4

service timestamps debug datetime msec

service timestamps log datetime msec

no service password-encryption

!

hostname LabCME

!

boot-start-marker

boot-end-marker

!

enable secret 5 $1$j9K.$l2bbD6kjVGPxXc.5uRa0q.

!

no aaa new-model

no network-clock-participate wic 0

!

!

ip cef

no ip dhcp use vrf connected

ip dhcp excluded-address 10.1.200.1 10.1.200.10

!

ip dhcp pool ITS

   network 10.1.200.0 255.255.255.0

   option 150 ip 10.1.200.1

   default-router 10.1.200.1

!

ip dhcp pool voice

   option 150 ip 10.1.200.1

   default-router 10.1.200.1

!

!

ip domain name DrVoIP.Local

ip host did.voip.les.net 64.34.181.47

multilink bundle-name authenticated

!

!

voice-card 0

no dspfarm

!

!

!

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

sip

  registrar server expires max 1200 min 300

!

!

voice class codec 1

codec preference 1 g711ulaw

!

voice register global

mode cme

source-address 10.1.200.1 port 5060

max-dn 20

max-pool 10

authenticate realm 172.16.0.0

dialplan-pattern 1 1.. extension-length 3

tftp-path flash:

create profile sync 0071835289771102

!

voice register dn  1

number 201

call-forward b2bua busy 100 

call-forward b2bua noan 100 timeout 20

name Peter

label Bria

!

voice register dn  2

number 200

call-forward b2bua busy 100 

call-forward b2bua noan 100 timeout 20

name Peter

label CIP

!

voice register template  1

!

voice register pool  1

id mac 0026.BB01.512A

number 1 dn 1

max registrations 36

username 7604442665 password 7289

!

voice register pool  2

id mac 0023.1247.A924

number 1 dn 2

max registrations 36

username bria password 7289

!

!

voice translation-rule 1

rule 1 /\(^9\)/ //

!

!

voice translation-profile DiscardDigit9

translate called 1

!

!

!

!

!

!        

username DrVoIP password 0 !@#$%^&

username user1 password 0 cisco

!

!

controller T1 0/0/0

framing esf

linecode b8zs

!

!

!

!

interface Loopback0

ip address 10.1.2.1 255.255.255.0

!

interface FastEthernet0/0

no ip address

duplex auto

speed auto

!

interface FastEthernet0/0.1

description datanetwork

encapsulation dot1Q 1 native

ip address 172.16.1.2 255.255.255.0

!

interface FastEthernet0/0.200

description voicenetwork

encapsulation dot1Q 200

ip address 10.1.200.1 255.255.255.0

!

interface FastEthernet0/1

no ip address

shutdown

duplex auto

speed auto

!

interface Service-Engine1/0

ip unnumbered Loopback0

service-module ip address 10.1.2.254 255.255.255.0

service-module ip default-gateway 10.1.2.1

!

ip default-gateway 172.16.1.1

ip route 0.0.0.0 0.0.0.0 172.16.1.1

ip route 10.1.2.254 255.255.255.255 Service-Engine1/0

ip route 172.16.0.0 255.255.0.0 172.16.1.1

!

!        

ip http server

no ip http secure-server

!

!

!

tftp-server flash:P00307020200.bin

tftp-server flash:P00307020200.loads

tftp-server flash:P00307020200.sb2

tftp-server flash:P00307020200.sbn

tftp-server flash:P00403020214.bin

tftp-server flash:P0030702T023.bin

tftp-server flash:P0030702T023.loads

tftp-server flash:P0030702T023.sb2

tftp-server flash:P0030702T023.sbn

tftp-server flash:P00405000700.bin

tftp-server flash:P00405000700.sbn

!

control-plane

!

!

!

!

!        

!

!

dial-peer voice 1 voip

description sip trunk to les.net

translation-profile outgoing DiscardDigit9

destination-pattern 9T

voice-class codec 1

session protocol sipv2

session target dns:did.voip.les.net

dtmf-relay rtp-nte sip-notify

no vad

!

dial-peer voice 2 voip

destination-pattern 600

b2bua

session protocol sipv2

session target ipv4:10.1.2.254

dtmf-relay sip-notify

codec g711ulaw

no vad

!

!

sip-ua   

credentials username 2316859993 password 112E4E574F4B realm did.voip.les.net

authentication username 2316859993 password 045C015257361A541D011016 realm 64.34.181.47

authentication username 2316859993 password 047C5C545778 realm did.voip.les.net

calling-info pstn-to-sip from number set 7025532706

no remote-party-id

retry options 0

registrar dns:did.voip.les.net expires 3600

sip-server dns:did.voip.les.net

!

!

telephony-service

load 7910 P00405000700

load 7960-7940 P0030702T023

max-ephones 5

max-dn 20

ip source-address 10.1.200.1 port 2000

auto assign 1 to 5

voicemail 600

max-conferences 8 gain -6

transfer-system full-consult

create cnf-files version-stamp Jan 01 2002 00:00:00

!        

!

ephone-dn  1  dual-line

number 100

call-forward busy 600

call-forward noan 600 timeout 18

!

!

ephone-dn  2  dual-line

number 101

call-forward busy 600

call-forward noan 600 timeout 18

!

!

ephone-dn  3  dual-line

number 102

call-forward busy 600

call-forward noan 600 timeout 18

!

!

ephone-dn  4  dual-line

number 103

call-forward busy 600

call-forward noan 600 timeout 18

!

!

ephone-dn  5  dual-line

number 104

call-forward busy 600

call-forward noan 600 timeout 18

!

!

ephone-dn  11

number 800

mwi on

!

!

ephone-dn  12

number 801

mwi off

!

!

ephone  1

no multicast-moh

mac-address 000C.29AE.9BAC

type CIPC

button  1:1

!

!

!

ephone  2

no multicast-moh

!

!

!

ephone  3

no multicast-moh

!

!

!

ephone  4

no multicast-moh

!

!

!

ephone  5

no multicast-moh

!

!

!        

line con 0

exec-timeout 0 0

password cisco

logging synchronous

login

line aux 0

line 66

no activation-character

no exec

transport preferred none

transport input all

transport output pad telnet rlogin lapb-ta mop udptn v120 ssh

line vty 0 4

password cisco

login

!

scheduler allocate 20000 1000

!

end

Peter Buswell (aka DrVoIP)
http://blog.drvoip.com       

[ADAM CRISP · Accepted solution]

Hi Peter,

the line:

registrar dns:did.voip.les.net expires 3600

means that the router will attempt to register every 3600 seconds (1hr) so my guess is that there's a previous registration attempt that's in the system that you missed on your debug.

your can check to see whether this is the case by typing

show sip-ua registration status

If you see your username 2316859993 in the table then this has happened.

I'm be inclined to change the registration interval to 120 seconds, to ease your debugging

i.e.

sip-ua

no registrar dns:did.voip.les.net

registrar dns:did.voip.les.net expires 120

Hope this helps

Adam

[DrVoIP]

Adam - Registration status alwasy says no.  Can you see from debug where it fails?

CMEsipLAB#sho sip-ua register st

Line          peer           expires(sec)  registered

============  =============  ============  ===========

2316858060    -1               28            no

CMEsipLAB#

*Jan  6 00:34:27.055: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x467C6EA4,addr=64.34.181.47

CMEsipLAB#sho sip-ua register

% Incomplete command.

CMEsipLAB#

*Jan  6 00:34:57.151: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x46153DC8) with key=[7] to table

*Jan  6 00:34:57.151: //-1/000000000000/SIP/Info/sipSPIGetOutboundHostAndDestHost: CCSIP: target_host : did.voip.les.net target_port : 5060

*Jan  6 00:34:57.151: //-1/000000000000/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host

*Jan  6 00:34:57.151: //-1/000000000000/SIP/State/sipSPIChangeState: 0x46153DC8 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)

*Jan  6 00:34:57.151: //-1/000000000000/SIP/Info/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires

*Jan  6 00:34:57.151: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER

*Jan  6 00:34:57.151: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 39

*Jan  6 00:34:57.151: //-1/000000000000/

CMEsipLAB#SIP/Info/act_idle_outgoing_register: In act_idle_outgoing_register

*Jan  6 00:34:57.151: //7/000000000000/SIP/Info/act_idle_outgoing_register:  Send REGISTER to did.voip.les.net:5060

*Jan  6 00:34:57.151: //7/000000000000/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.

*Jan  6 00:34:57.151: //7/000000000000/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x46153DC8 key=36DD10CC-56CD11E2-80029504-A735D9CA

*Jan  6 00:34:57.151: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE

*Jan  6 00:34:57.151: //7/000000000000/SIP/State/sipSPIChangeState: 0x46153DC8 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_SENT_DNS)

*Jan  6 00:34:57.151: //7/000000000000/SIP/State/sipSPIChangeState: 0x46153DC8 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS)  to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS)

*Jan  6 00:34:57.151: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: TYPE SRV query for _sip._udp.did.voip.les.net and type:1

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: Type A query for did.voip.les.net and type:1

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: TYPE A query successful for did.voip.les.net

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 42

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_register_set_dns_resolved_address: CCSIP_REGISTER:: Primary registrar DNS resolved addr set to 64.34.181.47:5060

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartExpiresTimer: Starting timer for pattern  for 3600 seconds

*Jan  6 00:34:57.175: //7/000000000000/SIP/State/sipSPIChangeState: 0x46153DC8 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS)  to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT

*Jan  6 00:34:57.175: //7/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x45D50F64 to Register

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x46072A7C, addr=64.34.181.47, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x4136C074

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0

*Jan  6 00:34:57.175: //7/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x46072A7C

*Jan  6 00:34:57.175: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x46072A7C, addr=64.34.181.47, port=5060, connId=1 for UDP

*Jan  6 00:34:57.179: //7/000000000000/SIP/State/sipSPIChangeState: 0x46153DC8 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)  to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)

*Jan  6 00:34:57.179: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

REGISTER sip:did.voip.les.net:5060 SIP/2.0

Via: SIP/2.0/UDP 172.16.1.2:5060;branch=z9hG4bK68CC

From: <>2316858060@did.voip.les.net>;tag=381184-E58

To: <>2316858060@did.voip.les.net>

Date: Sun, 06 Jan 2013 00:34:57 GMT

Call-ID: 36DD10CC-56CD11E2-80029504-A735D9CA

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1357432497

CSeq: 8 REGISTER

Contact: <2316858060>

Expires:  3600

Content-Length: 0

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 64.34.181.47:5060

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x466AEFDC

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 172.16.1.2:5060;branch=z9hG4bK68CC;received=76.79.152.2;rport=59232

From: <>2316858060@did.voip.les.net>;tag=381184-E58

To: <>2316858060@did.voip.les.net>;tag=as30db920d

Call-ID: 36DD10CC-56CD11E2-80029504-A735D9CA

CSeq: 8 REGISTER

Server: LES.NET VoIP

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

WWW-Authenticate: Digest algorithm=MD5, realm="64.34.181.47", nonce="19a7b086"

Content-Length: 0

*Jan  6 00:34:57.271: //7/000000000000/SIP/Error/sipSPIHandleAuthChallenge: Error getting credentials

*Jan  6 00:34:57.271: //7/000000000000/SIP/Error/ccsip_api_register_result_ind: Message Code Class 4xx Method Code 100 received for REGISTER

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_spi_register_free_rcb: Freeing rcb

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_register_reset_dns_cache: CCSIP_REGISTER:: Primary registrar DNS resolved addr reset

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartExpiresTimer: Starting timer for pattern 2316858060 for 180 seconds

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[7] removed.

*Jan  6 00:34:57.275: //7/000000000000/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.

*Jan  6 00:34:57.275: //7/000000000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x46153DC8 key=36DD10CC-56CD11E2-80029504-A735D9CA

*Jan  6 00:34:57.275: //7/000000000000/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd

*Jan  6 00:34:57.275: //7/000000000000/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 46153DC8

*Jan  6 00:34:57.275: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[7]

Peter Buswell (aka DrVoIP)
http://blog.drvoip.com

[Harmit Singh]

Hi Peter,

Here is what I see from the debugs:

*Jan  6 00:34:57.179: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

REGISTER sip:did.voip.les.net:5060 SIP/2.0

Via: SIP/2.0/UDP 172.16.1.2:5060;branch=z9hG4bK68CC

From: <>2316858060@did.voip.les.net>;tag=381184-E58

To: <>2316858060@did.voip.les.net>

Date: Sun, 06 Jan 2013 00:34:57 GMT

Call-ID: 36DD10CC-56CD11E2-80029504-A735D9CA

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1357432497

CSeq: 8 REGISTER

Contact: <2316858060>

Expires:  3600

Content-Length: 0

*Jan  6 00:34:57.271: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 172.16.1.2:5060;branch=z9hG4bK68CC;received=76.79.152.2;rport=59232

From: <>2316858060@did.voip.les.net>;tag=381184-E58

To: <>2316858060@did.voip.les.net>;tag=as30db920d

Call-ID: 36DD10CC-56CD11E2-80029504-A735D9CA

CSeq: 8 REGISTER

Server: LES.NET VoIP

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

WWW-Authenticate: Digest algorithm=MD5, realm="64.34.181.47", nonce="19a7b086"

Content-Length: 0

Looking at the SIP-UA config:

!

sip-ua  

credentials username 2316859993 password 112E4E574F4B realm did.voip.les.net

authentication username 2316859993 password 045C015257361A541D011016 realm 64.34.181.47

authentication username 2316859993 password 047C5C545778 realm did.voip.les.net

calling-info pstn-to-sip from number set 7025532706

no remote-party-id

retry options 0

registrar dns:did.voip.les.net expires 3600

sip-server dns:did.voip.les.net

!

It would be good to check with the ITSP why they are sending a 401 unauthorized for the register message we are sending. Possibly looks like an authentication issue but it would be good to confirm from the provider if your sip-ua config is accurate as per the config on their end.

HTH.

Regards,

Harmit.

[ADAM CRISP · Accepted solution]

Yes,

The Service Provider challenges your Register message:

Received:

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 172.16.1.2:5060;branch=z9hG4bK68CC;received=76.79.152.2;rport=59232

From: <>2316858060@did.voip.les.net>;tag=381184-E58

To: <>2316858060@did.voip.les.net>;tag=as30db920d

Call-ID: 36DD10CC-56CD11E2-80029504-A735D9CA

CSeq: 8 REGISTER

Server: LES.NET VoIP

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH

Supported: replaces, timer

WWW-Authenticate: Digest algorithm=MD5, realm="64.34.181.47", nonce="19a7b086"

Content-Length: 0

So the providers SIP server is programmed with the realm 64.34.181.47 and not "did.voip.les.net"

So program the credientials keywork on your cisco to match:

sip-ua

credentials username 2316859993 password realm 64.34.181.47