voip dialpeer match problem with cme 8.6

Umit AYDINLI · ‎05-08-2012

Hi,

I have a cisco 2821 router with cme version 8.6. I'm using ios c2800nm-ipvoicek9-mz.151-4.M3.bin

I have dialpeer match problem. debug voice dialpeer output is below.

014310: May 8 09:14:47.373: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=7743, Peer Info Type=DIALPEER_INFO_SPEECH

014311: May 8 09:14:47.373: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=7743

014312: May 8 09:14:47.373: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

014313: May 8 09:14:47.373: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=7743, saf_enabled=1, saf_dndb_lookup=1, dp_result=0

014314: May 8 09:14:47.373: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=7700

CME_8.6#show dialplan number 7743 timeout

Macro Exp.: 7743

VoiceOverIpPeer7700

peer type = voice, system default peer = FALSE, information type = voice,

description = `',

tag = 7700, destination-pattern = `77..',

voice reg type = 0, corresponding tag = 0,

allow watch = FALSE

answer-address = `', preference=0,

CLID Restriction = None

CLID Network Number = `'

CLID Second Number sent

CLID Override RDNIS = disabled,

rtp-ssrc mux = system

source carrier-id = `', target carrier-id = `',

source trunk-group-label = `', target trunk-group-label = `',

numbering Type = `unknown'

group = 7700, Admin state is up, Operation state is up,

incoming called-number = `', connections/maximum = 0/unlimited,

DTMF Relay = disabled,

modem transport = system,

URI classes:

Incoming (Called) =

Incoming (Calling) =

Destination =

huntstop = disabled,

in bound application associated: 'DEFAULT'

out bound application associated: ''

dnis-map =

permission :both

incoming COR list:maximum capability

outgoing COR list:minimum requirement

outgoing LPCOR:

Translation profile (Incoming):

Translation profile (Outgoing):

incoming call blocking:

translation-profile = `'

disconnect-cause = `no-service'

mailbox selection policy: none

type = voip, session-target = `ipv4:192.168.23.251',

technology prefix:

settle-call = disabled

ip media DSCP = ef, ip media rsvp-pass DSCP = ef

ip media rsvp-fail DSCP = ef, ip signaling DSCP = af31,

ip video rsvp-none DSCP = af41,ip video rsvp-pass DSCP = af41

ip video rsvp-fail DSCP = af41,

ip defending Priority = 0, ip preemption priority = 0

ip policy locator voice:

ip policy locator video:

UDP checksum = disabled,

session-protocol = cisco, session-transport = system,

req-qos = best-effort, acc-qos = best-effort,

req-qos video = best-effort, acc-qos video = best-effort,

req-qos audio def bandwidth = 64, req-qos audio max bandwidth = 0,

req-qos video def bandwidth = 384, req-qos video max bandwidth = 0,

RTP dynamic payload type values: NTE = 101

Cisco: NSE=100, fax=96, fax-ack=97, dtmf=121, fax-relay=122

CAS=123, TTY=119, ClearChan=125, PCM switch over u-law=0,

A-law=8, GSMAMR-NB=117 iLBC=116, AAC-ld=114, iSAC=124

lmr_tone=0, nte_tone=0

h263+=118, h264=119

G726r16 using static payload

G726r24 using static payload

RTP comfort noise payload type = 19

fax rate = voice, payload size = 20 bytes

fax protocol = system

fax-relay ecm enable

Fax Relay ans enabled

Fax Relay SG3-to-G3 Enabled (by system configuration)

fax NSF = 0xAD0051 (default)

codec = g711ulaw, payload size = 160 bytes,

video codec = None

voice class codec = `'

voice class sip session refresh system

voice class sip rsvp-fail-policy voice post-alert mandatory keep-alive interval 30

voice class sip rsvp-fail-policy voice post-alert optional keep-alive interval 30

voice class sip rsvp-fail-policy video post-alert mandatory keep-alive interval 30

voice class sip rsvp-fail-policy video post-alert optional keep-alive interval 30

text relay = disabled

Media Setting = forking (disabled) flow-through (global)

Expect factor = 10, Icpif = 20,

Playout Mode is set to adaptive,

Initial 60 ms, Max 1000 ms

Playout-delay Minimum mode is set to default, value 40 ms

Fax nominal 300 ms

Max Redirects = 1, signaling-type = cas,

VAD = disabled, Poor QOV Trap = disabled,

Source Interface = NONE

voice class sip url = system,

voice class sip tel-config = system,

voice class sip rel1xx = system,

tvoice class sip outbound-proxy = system,

voice class sip asserted-id = system,

voice class sip privacy = system,

voice class sip e911 = system,

voice class sip history-info = system,

voice class sip pass-thru headers = system,

voice class sip pass-thru content unsupp = system,

voice class sip pass-thru content sdp = system,

voice class sip copy-list = system,

voice class sip anat = system,

voice class sip g729 annexb-all = system,

voice class sip early-offer forced = system,

voice class sip negotiate cisco = system,

voice class sip reset timer expires 183 = system,

voice class sip block 180 = system,

voice class sip block 181 = system,

voice class sip block 183 = system,

voice class sip preloaded-route = system,

voice class sip random-contact = system,

voice class sip random-request-uri validate = system,

voice class sip call-route p-called-party-id = system,

voice class sip call-route history-info = system,

voice class sip privacy-policy send-always = system,

voice class sip privacy-policy passthru = system,

voice class sip privacy-policy strip history-info = system,

voice class sip privacy-policy strip diversion = system,

voice class sip bandwidth audio = system,

voice class sip bandwidth video = system,

voice class sip error-code-override options-keepalive failure = system,

voice class sip encap clear-channel = system,

voice class sip map resp-code 181 = system,

voice class sip bind control = system,

voice class sip bind media = system,

voice class sip registration passthrough = System

voice class sip authenticate redirecting-number = system,

redirect ip2ip = disabled

local peer = false

probe disabled,

Secure RTP: system (use the global setting)

voice class perm tag = `'

Time elapsed since last clearing of voice call statistics never

Connect Time = 0, Charged Units = 0,

Successful Calls = 0, Failed Calls = 0, Incomplete Calls = 0

Accepted Calls = 0, Refused Calls = 0,

Last Disconnect Cause is "",

Last Disconnect Text is "",

Last Setup Time = 0.

Last Disconnect Time = 0.

Matched: 7743 Digits: 2

Target: ipv4:192.168.23.251

Runing config is below.

!

interface GigabitEthernet0/0.11

encapsulation dot1Q 11

ip address 192.168.25.190 255.255.255.192

h323-gateway voip interface

h323-gateway voip bind srcaddr 192.168.25.190

!

voice service voip

ip address trusted list

ipv4 0.0.0.0 0.0.0.0

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to h323

allow-connections sip to sip

modem passthrough nse codec g711ulaw redundancy

sip

bind control source-interface GigabitEthernet0/0.11

bind media source-interface GigabitEthernet0/0.11

!

voice class codec 5

codec preference 1 g729br8

codec preference 2 g729r8

!

voice register global

mode cme

source-address 192.168.25.190 port 5060

bandwidth video tias-modifier 100000 negotiate end-to-end

max-dn 18

max-pool 9

load 9971 sip9971.9-2-2SR1-9

timezone 48

time-format 24

date-format D/M/Y

tftp-path flash:

file text

create profile sync 005432543004659A

network-locale 1 U1

user-locale U1 TR

camera

video

!

voice register dn 4

number 7716

name Tel2

label 7716

!

voice register template 1

softkeys connected Endcall Hold Trnsfer

!

voice register pool 2

busy-trigger-per-button 2

id mac xxxx.xxxx.xxxx

type 9971

number 1 dn 4

template 1

dtmf-relay cisco-rtp sip-notify

description Tel2

!

dial-peer voice 7700 voip

destination-pattern 77..

session target ipv4:192.168.23.251

codec g711ulaw

no vad

!

telephony-service

max-conferences 8 gain -6

transfer-system full-consult

!

What did I see error? Could you advice me please.

Chris Deren · ‎05-08-2012

What is the problem here?

Dial-peer 7700 is matched just fine as expected.

Chris

Umit AYDINLI · ‎07-19-2012

Hi, This problem still going. I don't understand where is the problem?

Log are below.

CME_8.6#

042435: Jul 19 07:16:02.650: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

INVITE sip:6134846@192.168.23.251;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK74f71bd1

From: "NOC2" <7792>;tag=ecc882b147c9008122d53908-04005126

To: <6134846>

Call-ID: ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130

Max-Forwards: 70

Date: Thu, 19 Jul 2012 07:16:01 GMT

CSeq: 101 INVITE

User-Agent: Cisco-CP9971/9.3.1

Contact: <7792>;video

Expires: 180

Accept: application/sdp

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

Remote-Party-ID: "NOC2" <7792>;party=calling;id-type=subscriber;privacy=off;screen=yes

Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-6.0.0,X-cisco-xsi-8.0.1

Allow-Events: kpml,dialog

Content-Length: 632

Content-Type: application/sdp

Content-Disposition: session;handling=optional

v=0

o=Cisco-SIPUA 11235 0 IN IP4 172.17.108.130

s=SIP Call

t=0 0

m=audio 27004 RTP/AVP 0 8 18 102 9 116 124 101

c=IN IP4 172.17.108.130

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:102 L16/16000

a=rtpmap:9 G722/8000

a=rtpmap:116 iLBC/8000

a=fmtp:116 mode=20

a=rtpmap:124 ISAC/16000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=sendrecv

m=video 26350 RTP/AVP 97

c=IN IP4 172.17.108.130

b=TIAS:1000000

a=rtpmap:97 H264/90000

a=fmtp:97 profile-level-id=42801E;packetization-mode=0;level-asymmetry-allowed=1

a=imageattr:* recv [x=640,y=480,q=0.50]

a=sendrecv

042436: Jul 19 07:16:02.650: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=6134846, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042437: Jul 19 07:16:02.650: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042438: Jul 19 07:16:02.650: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042439: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=1, saf_dndb_lookup=1, dp_result=0

042440: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78871

2: Dial-peer Tag=78873

042441: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=7792, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

042442: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40007

042443: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

042444: Jul 19 07:16:02.654: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=7792, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

042445: Jul 19 07:16:02.658: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40007

042446: Jul 19 07:16:02.658: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

042447: Jul 19 07:16:02.658: //-1/6E9A27879781/DPM/dpAssociateIncomingPeerCore:

Calling Number=7792, Called Number=6134846, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

042448: Jul 19 07:16:02.658: //-1/6E9A27879781/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40007

042449: Jul 19 07:16:02.658: //-1/6E9A27879781/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

042450: Jul 19 07:16:02.658: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ

042451: Jul 19 07:16:02.658: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:4B746504

042452: Jul 19 07:16:02.658: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_LEG_BY_LEG, SE Value:0, SE Refresher:none, Min-SE Value:1800, flags:2000

SIP: (27876) Attribute mid, level 1 instance 1 not found.

SIP: (27876) Attribute mid, level 2 instance 1 not found.

042453: Jul 19 07:16:02.662: //27876/6E9A27879781/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:

failed to update call entry

042454: Jul 19 07:16:02.662: //27876/6E9A27879781/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:

failed to update call entry

042455: Jul 19 07:16:02.662: //27876/6E9A27879781/SIP/Error/sipSPI_ipip_update_call_entry:

failed to update call entry

042456: Jul 19 07:16:02.662: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_ipip_set_channel_count: Unable to set CHANNEL_COUNT for callid 27876

042457: Jul 19 07:16:02.662: //27876/6E9A27879781/SIP/Error/sip_iwf_sip_copy_sdp_to_channelInfo: Channel count is not set at this point. Not SIP-SIP or SET_MODE is not done.

042458: Jul 19 07:16:02.666: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP

042459: Jul 19 07:16:02.666: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:4B743014

042460: Jul 19 07:16:02.666: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042461: Jul 19 07:16:02.666: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042462: Jul 19 07:16:02.670: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042463: Jul 19 07:16:02.670: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

042464: Jul 19 07:16:02.670: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78871

2: Dial-peer Tag=78873

042465: Jul 19 07:16:02.670: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042466: Jul 19 07:16:02.670: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042467: Jul 19 07:16:02.670: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Result=Partial Matches(1) after DP_MATCH_DEST

042468: Jul 19 07:16:02.670: //-1/6E9A27879781/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=1, saf_dndb_lookup=0, dp_result=1

042469: Jul 19 07:16:02.670: //-1/6E9A27879781/DPM/dpMatchPeersMoreArg:

Result=MORE_DIGITS_NEEDED(1)

042470: Jul 19 07:16:02.670: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_spi_subscribe_client: Queued event from SIP SPI : SIPSPI_EV_CC_SUBSCRIBE

042471: Jul 19 07:16:02.674: //27876/6E9A27879781/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK74f71bd1

From: "NOC2" <7792>;tag=ecc882b147c9008122d53908-04005126

To: <6134846>

Date: Thu, 19 Jul 2012 07:16:02 GMT

Call-ID: ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0

042472: Jul 19 07:16:02.674: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SUBSCRIBE sip:7792@172.17.108.130:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.23.251:5060;branch=z9hG4bK1BE6D6F

From: <6134846>;tag=4CE48CD0-1976

To: <7792>

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

CSeq: 101 SUBSCRIBE

Max-Forwards: 70

Date: Thu, 19 Jul 2012 07:16:02 GMT

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

Event: kpml; call-id=ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130; from-tag=ecc882b147c9008122d53908-04005126

Expires: 7200

Contact: <6134846>

Content-Type: application/kpml-request+xml

Content-Length: 358

http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="urn:ietf:params:xml:ns:kpml-request kpml-request.xsd" version="1.0">[x#*]|bs

042473: Jul 19 07:16:02.694: //0/000000000000/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.23.251:5060;branch=z9hG4bK1BE6D6F

From: <6134846>;tag=4CE48CD0-1976

To: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

Date: Thu, 19 Jul 2012 07:16:01 GMT

CSeq: 101 SUBSCRIBE

Server: Cisco-CP9971/9.3.1

Contact: <7792>

Expires: 7200

Content-Length: 0

042474: Jul 19 07:16:02.698: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

NOTIFY sip:6134846@192.168.23.251:5060 SIP/2.0

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK50099890

To: <6134846>;tag=4CE48CD0-1976

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

Date: Thu, 19 Jul 2012 07:16:01 GMT

CSeq: 1000 NOTIFY

Event: kpml

Subscription-State: active; expires=7200

Max-Forwards: 70

Contact: <7792>

Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE

Content-Length: 0

042475: Jul 19 07:16:02.698: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_call_notify_response: Queued event from SIP SPI : SIPSPI_EV_CC_NOTIFY_RESP

042476: Jul 19 07:16:02.698: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK50099890

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

To: <6134846>;tag=4CE48CD0-1976

Date: Thu, 19 Jul 2012 07:16:02 GMT

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

CSeq: 1000 NOTIFY

Content-Length: 0

CME_8.6#

042477: Jul 19 07:16:12.686: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

NOTIFY sip:6134846@192.168.23.251:5060 SIP/2.0

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK6b9d0a9d

To: <6134846>;tag=4CE48CD0-1976

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

Date: Thu, 19 Jul 2012 07:16:11 GMT

CSeq: 1001 NOTIFY

Event: kpml

Subscription-State: active; expires=7200

Max-Forwards: 70

Contact: <7792>

Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE

Content-Length: 219

Content-Type: application/kpml-response+xml

Content-Disposition: session;handling=required

042478: Jul 19 07:16:12.690: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_call_notify_response: Queued event from SIP SPI : SIPSPI_EV_CC_NOTIFY_RESP

042479: Jul 19 07:16:12.690: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK6b9d0a9d

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

To: <6134846>;tag=4CE48CD0-1976

Date: Thu, 19 Jul 2012 07:16:12 GMT

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

CSeq: 1001 NOTIFY

Content-Length: 0

042480: Jul 19 07:16:12.694: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846T, Peer Info Type=DIALPEER_INFO_SPEECH

042481: Jul 19 07:16:12.694: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846T

042482: Jul 19 07:16:12.694: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042483: Jul 19 07:16:12.694: //-1/6E9A27879781/DPM/dpMatchSafModulePlugin:

dialstring=6134846T, saf_enabled=1, saf_dndb_lookup=0, dp_result=0

042484: Jul 19 07:16:12.694: //-1/6E9A27879781/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78871

2: Dial-peer Tag=78873

042485: Jul 19 07:16:12.694: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_spi_subscribe_client: Queued event from SIP SPI : SIPSPI_EV_CC_SUBSCRIBE

042486: Jul 19 07:16:12.698: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SUBSCRIBE sip:7792@172.17.108.130:5060;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 192.168.23.251:5060;branch=z9hG4bK1BE7246

From: <6134846>;tag=4CE48CD0-1976

To: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

CSeq: 102 SUBSCRIBE

Max-Forwards: 70

Date: Thu, 19 Jul 2012 07:16:12 GMT

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

Event: kpml; call-id=ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130; from-tag=ecc882b147c9008122d53908-04005126

Expires: 0

Contact: <6134846>

Content-Length: 0

042487: Jul 19 07:16:12.698: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING

042488: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042489: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042490: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042491: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

042492: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78873

2: Dial-peer Tag=78871

042493: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042494: Jul 19 07:16:12.698: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042495: Jul 19 07:16:12.702: //-1/6E9A27879781/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042496: Jul 19 07:16:12.702: //-1/6E9A27879781/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

042497: Jul 19 07:16:12.702: //-1/6E9A27879781/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78871

2: Dial-peer Tag=78873

042498: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=6134846, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042499: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042500: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042501: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

042502: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78873

2: Dial-peer Tag=78871

042503: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=6134846, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

042504: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=78871

042505: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

042506: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=6134846, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

042507: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=78871

042508: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

042509: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=6134846, Peer Info Type=DIALPEER_INFO_SPEECH

042510: Jul 19 07:16:12.702: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=6134846

042511: Jul 19 07:16:12.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

042512: Jul 19 07:16:12.706: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=6134846, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

042513: Jul 19 07:16:12.706: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=78873

2: Dial-peer Tag=78871

042514: Jul 19 07:16:12.706: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT

042515: Jul 19 07:16:12.706: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP

042516: Jul 19 07:16:12.706: //27876/6E9A27879781/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:500, container:4B74697C

042517: Jul 19 07:16:12.710: //27876/6E9A27879781/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 500 Internal Server Error

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK74f71bd1

From: "NOC2" <7792>;tag=ecc882b147c9008122d53908-04005126

To: <6134846>;tag=4CE4B400-10EA

Date: Thu, 19 Jul 2012 07:16:02 GMT

Call-ID: ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Reason: Q.850;cause=63

Content-Length: 0

042518: Jul 19 07:16:12.718: //0/000000000000/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.23.251:5060;branch=z9hG4bK1BE7246

From: <6134846>;tag=4CE48CD0-1976

To: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

Date: Thu, 19 Jul 2012 07:16:11 GMT

CSeq: 102 SUBSCRIBE

Server: Cisco-CP9971/9.3.1

Contact: <7792>

Expires: 0

Content-Length: 0

042519: Jul 19 07:16:12.718: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

NOTIFY sip:6134846@192.168.23.251:5060 SIP/2.0

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK5127c25d

To: <6134846>;tag=4CE48CD0-1976

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

Date: Thu, 19 Jul 2012 07:16:11 GMT

CSeq: 1002 NOTIFY

Event: kpml

Subscription-State: terminated; reason=timeout

Max-Forwards: 70

Contact: <7792>

Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE

Content-Length: 222

Content-Type: application/kpml-response+xml

Content-Disposition: session;handling=required

042520: Jul 19 07:16:12.718: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_call_notify_response: Queued event from SIP SPI : SIPSPI_EV_CC_NOTIFY_RESP

042521: Jul 19 07:16:12.718: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_api_initiate_subscription_termination: Queued event from SIP SPI : SIPSPI_EV_SUBSCRIPTION_TERMINATED

042522: Jul 19 07:16:12.722: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK5127c25d

From: <7792>;tag=ecc882b147c9008262b8c579-669abbe1

To: <6134846>;tag=4CE48CD0-1976

Date: Thu, 19 Jul 2012 07:16:12 GMT

Call-ID: 6E9DD110-D0A811E1-9786DABF-70B24351@192.168.23.251

CSeq: 1002 NOTIFY

Content-Length: 0

042523: Jul 19 07:16:12.722: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

ACK sip:6134846@192.168.23.251;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK74f71bd1

From: "NOC2" <7792>;tag=ecc882b147c9008122d53908-04005126

To: <6134846>;tag=4CE4B400-10EA

Call-ID: ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130

Max-Forwards: 70

Date: Thu, 19 Jul 2012 07:16:11 GMT

CSeq: 101 ACK

Content-Length: 0

042524: Jul 19 07:16:12.726: //27876/6E9A27879781/SIP/Call/sipSPICallInfo:

The Call Setup Information is:

Call Control Block (CCB) : 0x496E1990

State of The Call : STATE_DEAD

TCP Sockets Used : NO

Calling Number : 7792

Called Number : 6134846

Source IP Address (Sig ): 192.168.23.251

Destn SIP Req Addr:Port : 172.17.108.130:5060

Destn SIP Resp Addr:Port : 172.17.108.130:49158

Destination Name : 172.17.108.130

042525: Jul 19 07:16:12.726: //27876/6E9A27879781/SIP/Call/sipSPIMediaCallInfo:

Number of Media Streams: 2

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): 192.168.23.251

Source IP Port (Media): 18164

Destn IP Address (Media): 172.17.108.130

Destn IP Port (Media): 27004

Orig Destn IP Address:Port (Media): [ - ]:0

042526: Jul 19 07:16:12.726: //27876/6E9A27879781/SIP/Call/sipSPIMediaCallInfo:

Number of Media Streams: 2

Media Stream : 2

Negotiated Codec :

Negotiated Codec Bytes : 0

Nego. Codec payload : 97 (tx), 97 (rx)

Negotiated Dtmf-relay : 0

Dtmf-relay Payload : 0 (tx), 0 (rx)

Source IP Address (Media): 192.168.23.251

Source IP Port (Media): 16792

Destn IP Address (Media): 172.17.108.130

Destn IP Port (Media): 26350

Orig Destn IP Address:Port (Media): [ - ]:0

042527: Jul 19 07:16:12.726: //27876/6E9A27879781/SIP/Call/sipSPICallInfo:

Disconnect Cause (CC) : 63

Disconnect Cause (SIP) : 500

CME_8.6#

Chris Deren · ‎07-19-2012

Your issue is this:

042517: Jul 19 07:16:12.710: //27876/6E9A27879781/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 500 Internal Server Error

Via: SIP/2.0/UDP 172.17.108.130:5060;branch=z9hG4bK74f71bd1

From: "NOC2" <7792>;tag=ecc882b147c9008122d53908-04005126

To: <6134846>;tag=4CE4B400-10EA

Date: Thu, 19 Jul 2012 07:16:02 GMT

Call-ID: ecc882b1-47c90009-7a6bd1db-4e493eae@172.17.108.130

CSeq: 101 INVITE

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Reason: Q.850;cause=63

Content-Length: 0

Your SIP trunking does not appears to be setup properly. Can you post your config?

Chris

Umit AYDINLI · ‎07-20-2012

Hi, It's below. But this configrations runing with 79xx phones now. Remember It's not runing 9971 phones only.

!

voice service voip

ip address trusted list

ipv4 0.0.0.0 0.0.0.0

clid network-provided

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to h323

allow-connections sip to sip

supplementary-service h450.12

no supplementary-service sip moved-temporarily

redirect ip2ip

signaling forward rawmsg

fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw

cause-code legacy

h323

modem passthrough nse codec g711ulaw redundancy

sip

bind control source-interface GigabitEthernet0/0.102

bind media source-interface GigabitEthernet0/0.102

registrar server expires max 1200 min 300

early-offer forced

no call service stop

!

voice class codec 5

codec preference 1 g729br8

codec preference 2 g729r8

!

dial-peer voice 78871 voip

translation-profile outgoing voipswitch

preference 9

service session

max-conn 3

destination-pattern 0[2-9].T

session protocol sipv2

session target ipv4:172.17.127.5

voice-class codec 5

voice-class sip dtmf-relay force rtp-nte

dtmf-relay rtp-nte

no vad

!

dial-peer voice 78873 voip

translation-profile outgoing voipswitch

preference 9

service session

max-conn 3

destination-pattern 05.T

session protocol sipv2

session target ipv4:172.17.127.5

voice-class codec 5

voice-class sip dtmf-relay force rtp-nte

dtmf-relay rtp-nte

no vad

!