Solved: H323 Call Routing - 911

ggerhard · ‎02-17-2014

I'm missing something here in this config....

If we dial 10 digits the call goes out the FXO port just fine, 911 gives us a fast-busy.

configs below along w/ the debugs.. weird thing is on the 911 call it appears to be changing the called number and calling number....

config:

Current configuration : 6529 bytes

!

version 12.4

no service pad

service tcp-keepalives-in

service tcp-keepalives-out

service timestamps debug datetime msec localtime show-timezone

service timestamps log datetime msec localtime show-timezone

no service password-encryption

service sequence-numbers

!

hostname Ball

!

boot-start-marker

boot-end-marker

!

logging message-counter syslog

logging buffered 300000

logging console critical

enable secret 5 $1$jQUY$SdW8OgOkgDnXH5Au5GHhQ1

!

no aaa new-model

!

crypto pki trustpoint tti

revocation-check crl

rsakeypair tti

!

crypto pki trustpoint TP-self-signed-1275929792

enrollment selfsigned

subject-name cn=IOS-Self-Signed-Certificate-1275929792

revocation-check none

rsakeypair TP-self-signed-1275929792

!

crypto pki certificate chain tti

crypto pki certificate chain TP-self-signed-1275929792

certificate self-signed 01

3082024C 308201B5 A0030201 02020101 300D0609 2A864886 F70D0101 04050030

31312F30 2D060355 04031326 494F532D 53656C66 2D536967 6E65642D 43657274

69666963 6174652D 31323735 39323937 3932301E 170D3131 30373132 31343435

30305A17 0D323030 31303130 30303030 305A3031 312F302D 06035504 03132649

4F532D53 656C662D 5369676E 65642D43 65727469 66696361 74652D31 32373539

32393739 3230819F 300D0609 2A864886 F70D0101 01050003 818D0030 81890281

8100DC3D CA074470 922DDB00 BFB8479C C2C985E5 E7E607BC 29E29426 DAC68DEF

4B1106F7 0F22EA07 F78BCF41 5F5CA342 BB764BDE 1A1B7EB8 7AF1FD1F BD0BF0B9

96B23428 2D6C8E45 11E70E92 D45B3728 00F7082F 508FC058 04AF32A8 7E3D6EB4

F9270627 B9C42F20 9416C6A9 E8991EC7 CBE2A237 3F676A05 E326EBBC 9C215598

830F0203 010001A3 74307230 0F060355 1D130101 FF040530 030101FF 301F0603

551D1104 18301682 1442616C 6C2E6462 69736572 76696365 732E636F 6D301F06

03551D23 04183016 8014A288 03701404 59072011 4EB37AE7 96DFDF08 38DB301D

0603551D 0E041604 14A28803 70140459 0720114E B37AE796 DFDF0838 DB300D06

092A8648 86F70D01 01040500 03818100 470493CF 9EA0F433 DC15C2D9 EA288D43

1F6CFAB6 373286EA 40E9166E C394604B 9A283227 B0A53F55 F5854EB7 5FB7285B

EB2CEBFB 5C6160C0 CD488F10 BF46BE7C 995B10F9 1A510C8D 97DE8E6F 65843DEA

61E84252 45980509 318F8398 3800C9FF B448A42C 44A4AB42 A4B05A1A E44CB646

43886F38 D09EE75A DA1082A9 C0CBEC43

quit

no ip source-route

!

no ip dhcp use vrf connected

ip dhcp excluded-address 10.4.3.250 10.4.3.254

!

ip dhcp pool Ball-Voice

network 10.4.3.128 255.255.255.128

default-router 10.4.3.129

dns-server 10.0.1.52 10.0.1.39

option 150 ip 10.0.100.13 10.0.100.12

domain-name dbiservices.com

!

ip cef

no ip bootp server

no ip domain lookup

ip domain name dbiservices.com

!

no ipv6 cef

!

multilink bundle-name authenticated

!

voice service voip

h323

!

voice class codec 1

codec preference 1 g711ulaw

codec preference 2 g729r8

!

voice-card 0

codec complexity high

!

crypto ipsec client ezvpn EzVPN

connect auto

group RemoteOffice key dBiVPN$123

mode network-extension

peer 64.111.143.34

username Pineville password dbi$vpn@cc355

xauth userid mode local

!

archive

log config

hidekeys

!

ip tcp synwait-time 10

ip ftp source-interface Vlan40

ip ftp username cisco

ip ftp password cisco

!

class-map match-any voice-signaling

match dscp cs3

match dscp af31

class-map match-any voice-traffic

match dscp ef

match dscp cs5

!

policy-map LAN-QOS

class voice-traffic

set dscp ef

priority percent 10

class voice-signaling

set dscp cs3

policy-map WAN-QOS

class voice-traffic

priority percent 25

set dscp ef

class voice-signaling

bandwidth 64

set dscp cs3

!

interface FastEthernet0

switchport access vlan 40

switchport voice vlan 41

!

interface FastEthernet1

switchport access vlan 40

switchport voice vlan 41

spanning-tree portfast

!

interface FastEthernet2

switchport access vlan 40

switchport voice vlan 41

!

interface FastEthernet3

switchport access vlan 40

switchport voice vlan 41

!

interface FastEthernet4

description $ES_WAN$

ip address dhcp

no ip redirects

no ip unreachables

no ip proxy-arp

ip flow ingress

ip nat outside

ip virtual-reassembly

duplex auto

speed auto

crypto ipsec client ezvpn EzVPN

!

interface Vlan1

no ip address

!

interface Vlan40

ip address 10.4.3.1 255.255.255.128

ip helper-address 10.0.1.20

no ip redirects

no ip unreachables

no ip proxy-arp

ip flow ingress

ip nat inside

ip virtual-reassembly

ip tcp adjust-mss 1452

crypto ipsec client ezvpn EzVPN inside

!

interface Vlan41

ip address 10.4.3.129 255.255.255.128

ip nat inside

ip virtual-reassembly

crypto ipsec client ezvpn EzVPN inside

!

ip forward-protocol nd

ip route 0.0.0.0 0.0.0.0 192.168.99.254

ip route 0.0.0.0 0.0.0.0 dhcp

ip http server

ip http access-class 23

ip http authentication local

ip http secure-server

ip http timeout-policy idle 60 life 86400 requests 10000

!

logging trap debugging

access-list 23 permit 10.10.10.0 0.0.0.7

access-list 23 permit 10.0.0.0 0.255.255.255

snmp-server community D3Ang3loSnMp_Pub RO 61

snmp-server community D3Ang3loSnMp_Priv RW 61

snmp-server location DeAngelo Brothers Ball LA

snmp-server contact DeAngelo Brothers IT Dept.

snmp-server enable traps tty

!

control-plane

!

voice-port 0

!

voice-port 1

!

voice-port 2

!

voice-port 3

!

voice-port 4

connection plar 4300

caller-id enable

!

ccm-manager fallback-mgcp

no ccm-manager fax protocol cisco

!

no mgcp package-capability res-package

no mgcp package-capability fxr-package

no mgcp timer receive-rtcp

!

sccp local Vlan41

sccp ccm 10.0.100.12 identifier 2 priority 2 version 5.0.1

sccp ccm 10.0.100.13 identifier 1 priority 1 version 5.0.1

sccp

!

sccp ccm group 1

bind interface Vlan41

associate ccm 1 priority 1

associate profile 1 register Ball881

!

dial-peer voice 201010 voip

preference 1

destination-pattern 4300

voice-class codec 1

session target ipv4:10.0.100.13

dtmf-relay h245-alphanumeric

!

dial-peer voice 200010 voip

preference 2

destination-pattern 4300

voice-class codec 1

session target ipv4:10.0.100.12

dtmf-relay h245-alphanumeric

!

dial-peer voice 100 pots

preference 1

destination-pattern .T

port 4

!

Call attempts, to a 10 digit # and to 9,911

Ball#debug voip dialpeer

voip dialpeer default debugging is on

Ball#

Ball#debug h225 q931

H.225 Q931 IE Details debugging is on

Ball#

Ball#term mon

Ball#

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x012C

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020 002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F 43616C6C4D616E61676572003100010100C44000229CAF3D6621302C01B2020A04038200D50D800007000A00640D 06B8110000229CAF3D6621302C01B2020A040382010001000100010010A00100140140B50000120D820400200400 01030003000103

000131: *Feb 17 19:44:13.092 UTC: //-1/00229CAF2C01/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000132: *Feb 17 19:44:13.092 UTC: //-1/00229CAF2C01/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000133: *Feb 17 19:44:13.092 UTC: //-1/00229CAF2C01/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000134: *Feb 17 19:44:13.092 UTC: //-1/00229CAF2C01/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000135: *Feb 17 19:44:13.096 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

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

000136: *Feb 17 19:44:13.096 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000137: *Feb 17 19:44:13.096 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000138: *Feb 17 19:44:13.096 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000139: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000140: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000141: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000142: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000143: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000144: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000145: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000146: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000147: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

Peer Info Type=DIALPEER_INFO_SPEECH

000148: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000149: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000150: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000151: *Feb 17 19:44:13.096 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000152: *Feb 17 19:44:13.100 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000153: *Feb 17 19:44:13.100 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

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

000154: *Feb 17 19:44:13.100 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000155: *Feb 17 19:44:13.100 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000156: *Feb 17 19:44:13.100 UTC: //-1/00229CAF2C01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812C

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC5634E5110C110000229CAF3D6621302C01 B2020A0403820100010010800100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812C

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A00041100110000229CAF3D6621302C01B2020A04038210800100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x012D

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020 002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F 43616C6C4D616E61676572003100010100C4400030C3B6496621302D01B3020A04038200D50D800007000A00640D 06B811000030C3B6496621302D01B3020A040382010001000100010010A00100140140B50000120D820400200400 01030003000103

000157: *Feb 17 19:44:25.124 UTC: //-1/0030C3B62D01/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000158: *Feb 17 19:44:25.124 UTC: //-1/0030C3B62D01/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000159: *Feb 17 19:44:25.124 UTC: //-1/0030C3B62D01/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000160: *Feb 17 19:44:25.124 UTC: //-1/0030C3B62D01/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000161: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

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

000162: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000163: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000164: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000165: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000166: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000167: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000168: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000169: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000170: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000171: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000172: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000173: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

Peer Info Type=DIALPEER_INFO_SPEECH

000174: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000175: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000176: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000177: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000178: *Feb 17 19:44:25.128 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000179: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

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

000180: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

000181: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000182: *Feb 17 19:44:25.128 UTC: //-1/0030C3B62D01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812D

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC56B2EA110C11000030C3B6496621302D01 B3020A0403820100010010800100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812D

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A0004110011000030C3B6496621302D01B3020A04038210800100

Ball#

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x012E

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=6

Data 008134333030

Called Party Number: Length Of IE=12

Data 803133313838343036363337

User-User: Length Of IE=192

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010500464BB73996A0004955D9836621302E01B4020A04038200D50D800007000A00640D06B81100004955D9836621302E01B4020A040382010001000100010010A00100140140B50000120D82040020040001030003000103

000183: *Feb 17 19:45:23.436 UTC: //-1/004955D92E01/DPM/dpAssociateIncomingPeerCore:

Calling Number=4300, Called Number=13188406637, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000184: *Feb 17 19:45:23.436 UTC: //-1/004955D92E01/DPM/dpAssociateIncomingPeerCore:

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

000185: *Feb 17 19:45:23.436 UTC: //-1/004955D92E01/DPM/dpAssociateIncomingPeerCore:

Calling Number=4300, Called Number=13188406637, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

000186: *Feb 17 19:45:23.436 UTC: //-1/004955D92E01/DPM/dpAssociateIncomingPeerCore:

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

000187: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

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

000188: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=13188406637

000189: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000190: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000191: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000192: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=13188406637

000193: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000194: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000195: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000196: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=13188406637

000197: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000198: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000199: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

Peer Info Type=DIALPEER_INFO_SPEECH

000200: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

000201: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

000202: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=13188406637

000203: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000204: *Feb 17 19:45:23.440 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

000205: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

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

000206: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=13188406637

000207: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

000208: *Feb 17 19:45:23.440 UTC: //-1/004955D92E01/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812E

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC56E28E110C1100004955D9836621302E01B4020A0403820100010010800100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812E

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A000411001100004955D9836621302E01B4020A04038210800100

Ball#

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x012F

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010100C440801C31FF266F21302F01BF020A04038200D50D800007000A00640D06B81100801C31FF266F21302F01BF020A040382010001000100010010A00100140140B50000120D82040020040001030003000103

000244: *Feb 17 20:22:14.148 UTC: //-1/801C31FF2F01/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

000245: *Feb 17 20:22:14.148 UTC: //-1/801C31FF2F01/CCAPI/cc_api_call_setup_ind_common:

Interface=0x867581A8, Call Info(

Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=725

000246: *Feb 17 20:22:14.148 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000247: *Feb 17 20:22:14.148 UTC: :cc_get_feature_vsa malloc success

000248: *Feb 17 20:22:14.148 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000249: *Feb 17 20:22:14.148 UTC: cc_get_feature_vsa count is 1

000250: *Feb 17 20:22:14.148 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000251: *Feb 17 20:22:14.148 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2275987968,feature_id:693

000252: *Feb 17 20:22:14.148 UTC: //725/801C31FF2F01/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown))

000253: *Feb 17 20:22:14.148 UTC: //725/801C31FF2F01/CCAPI/cc_process_call_setup_ind:

Event=0x86DCD638

000254: *Feb 17 20:22:14.148 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 911

000255: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCallSetContext:

Context=0x8634FFF4

000256: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 725 with tag 0 to app "_ManagedAppProcess_Default"

000257: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

000258: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=100, Params=0x8634F184, Progress Indication=NULL(0)

000259: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCheckClipClir:

In: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

000260: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCheckClipClir:

Out: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

000261: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCallSetupRequest:

Destination Pattern=.T, Called Number=911, Digit Strip=TRUE

000262: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccCallSetupRequest:

Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=Ball Reception - 4300, Final Destination Flag=TRUE,

Guid=801C31FF-266F-2130-2F01-BF020A040382, Outgoing Dial-peer=100

000263: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

000264: *Feb 17 20:22:14.152 UTC: //725/801C31FF2F01/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x86E100A0, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=100, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

000265: *Feb 17 20:22:14.152 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000266: *Feb 17 20:22:14.152 UTC: :cc_get_feature_vsa malloc success

000267: *Feb 17 20:22:14.152 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000268: *Feb 17 20:22:14.152 UTC: cc_get_feature_vsa count is 2

000269: *Feb 17 20:22:14.152 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000270: *Feb 17 20:22:14.152 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2275987744,feature_id:694

000271: *Feb 17 20:22:14.156 UTC: //726/801C31FF2F01/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

000272: *Feb 17 20:22:14.156 UTC: //726/801C31FF2F01/CCAPI/ccCallSetContext:

Context=0x8634F134

000273: *Feb 17 20:22:14.156 UTC: //725/801C31FF2F01/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=100

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812F

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC56BD32110C1100801C31FF266F21302F01BF020A0403820100010010800100

000274: *Feb 17 20:22:14.156 UTC: //726/801C31FF2F01/CCAPI/cc_api_call_proceeding:

Interface=0x86E100A0, Progress Indication=NULL(0)

000275: *Feb 17 20:22:15.408 UTC: //726/801C31FF2F01/CCAPI/cc_api_call_disconnected:

Cause Value=34, Interface=0x86E100A0, Call Id=726

000276: *Feb 17 20:22:15.408 UTC: //726/801C31FF2F01/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)

000277: *Feb 17 20:22:15.408 UTC: //725/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

000278: *Feb 17 20:22:15.408 UTC: //726/801C31FF2F01/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=726

000279: *Feb 17 20:22:15.408 UTC: //726/801C31FF2F01/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)

000280: *Feb 17 20:22:15.408 UTC: //726/801C31FF2F01/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

000281: *Feb 17 20:22:15.412 UTC: //726/801C31FF2F01/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x86E100A0, Tag=0x0, Call Id=726,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

000282: *Feb 17 20:22:15.412 UTC: //726/801C31FF2F01/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

000283: *Feb 17 20:22:15.412 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000284: *Feb 17 20:22:15.412 UTC: :cc_free_feature_vsa freeing 87A8D118

000285: *Feb 17 20:22:15.412 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000286: *Feb 17 20:22:15.412 UTC: vsacount in free is 1

000287: *Feb 17 20:22:15.412 UTC: //725/801C31FF2F01/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

000288: *Feb 17 20:22:15.412 UTC: //725/801C31FF2F01/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

000289: *Feb 17 20:22:15.412 UTC: //725/801C31FF2F01/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x812F

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A000411001100801C31FF266F21302F01BF020A04038210800100

000290: *Feb 17 20:22:15.416 UTC: //725/801C31FF2F01/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x867581A8, Tag=0x0, Call Id=725,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

000291: *Feb 17 20:22:15.416 UTC: //725/801C31FF2F01/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

000292: *Feb 17 20:22:15.416 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000293: *Feb 17 20:22:15.416 UTC: :cc_free_feature_vsa freeing 87A8D1F8

000294: *Feb 17 20:22:15.416 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

000295: *Feb 17 20:22:15.416 UTC: vsacount in free is 0

acampbell · ‎02-24-2014

Hi,

Can you have a look at post

https://supportforums.cisco.com/thread/2213848

Suffering from the same isue as you.

Looks to be a cabling/Service provider issue.

I cant understand why your other call works when the voice port is reporting "fxols_power_denial_detected"

because this means it is not seeing the correct voltage on the telephone line.

Regards,
Alex.
Please rate useful posts.

Regards, Alex. Please rate useful posts.

View solution in original post

acampbell · ‎02-17-2014

Hi

This looks to be due digit stripping on
your dial peer 100.

!
dial-peer voice 100 pots
preference 1
destination-pattern .T
port 4
!

You have .T as your patern

Looking at you long dialled number 13188406637
that is 11 digits so the first digit (1) is being dropped
resulting in 10 digits to line 3188406637

When we look at the 911 call only 11 will be sent to line
This will be an incomplete number for the service provider

In my opinion as 911 is so important you should build
a dial peer just for the emergency service.

!
dial-peer voice 911 pots
description *** EMERGENCY SERVICE ***
preference 1
incoming called-number .
destination-pattern 911
no digit-strip
port 4
!

If you are actually sending 9911 to the gateway
then use this instead

!
dial-peer voice 911 pots
description *** EMERGENCY SERVICE ***
preference 1
incoming called-number .
destination-pattern 9911
forward-digits 3
port 4
!

Regards,
Alex.
Please rate useful posts.

Regards, Alex. Please rate useful posts.

ggerhard · ‎02-18-2014

Will try this as soon as they are in the office, thanks ALEX.

ggerhard · ‎02-18-2014

Alex,

Seems the calls are not hitting the 911 dial-peer, they are still hitting dial-peer 100 according to the debugs.

User is dialing 9911 from an IP phone.

dial-peer voice 100 pots

preference 1

destination-pattern .T

port 4

!

dial-peer voice 911 pots

preference 1

destination-pattern 9.911

incoming called-number .

port 4

FAST BUSY

006003: *Feb 18 14:42:53.012 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 0 Event 0x1

006004: *Feb 18 14:42:53.016 UTC: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x87BF6B7C) is in use

006005: *Feb 18 14:42:53.016 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

006006: *Feb 18 14:42:53.016 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x87A9F0AC, len=221, msgPtr=0x87ABA6D0

006007: *Feb 18 14:42:53.016 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x0135

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010100C44000FFB1C21F7131303501FF020A04038200D50D800007000A00640D06B8110000FFB1C21F7131303501FF020A040382010001000100010010A00100140140B50000120D82040020040001030003000103

006008: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

006009: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID

006010: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl

006011: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006012: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number

006013: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006014: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern

006015: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006016: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006017: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006018: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/common_idle_setupInd_hdlr: ccb ccm bits = system[0x0028]

006019: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006020: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006021: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006022: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

006023: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

006024: *Feb 18 14:42:53.020 UTC: //-1/00FFB1C23501/CCAPI/cc_api_call_setup_ind_common:

Interface=0x867581A8, Call Info(

Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=943

006025: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006026: *Feb 18 14:42:53.020 UTC: :cc_get_feature_vsa malloc success

006027: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006028: *Feb 18 14:42:53.020 UTC: cc_get_feature_vsa count is 1

006029: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006030: *Feb 18 14:42:53.020 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2270469880,feature_id:906

006031: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown))

006032: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating callInfoType container for callID = 943

006033: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0

006034: *Feb 18 14:42:53.020 UTC: //943/00FFB1C23501/CCAPI/cc_process_call_setup_ind:

Event=0x86DCD638

006035: *Feb 18 14:42:53.020 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 911

006036: *Feb 18 14:42:53.024 UTC: //943/00FFB1C23501/CCAPI/ccCallSetContext:

Context=0x8634C4F4

006037: *Feb 18 14:42:53.024 UTC: //943/00FFB1C23501/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 943 with tag 0 to app "_ManagedAppProcess_Default"

006038: *Feb 18 14:42:53.024 UTC: //943/00FFB1C23501/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

006039: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

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

006040: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006041: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006042: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006043: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006044: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006045: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006046: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006047: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006048: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006049: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006050: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006051: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

Peer Info Type=DIALPEER_INFO_SPEECH

006052: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006053: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006054: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006055: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006056: *Feb 18 14:42:53.024 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006057: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

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

006058: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006059: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006060: *Feb 18 14:42:53.024 UTC: //-1/00FFB1C23501/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006061: *Feb 18 14:42:53.024 UTC: //943/00FFB1C23501/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=100, Params=0x8634E2C4, Progress Indication=NULL(0)

006062: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccCheckClipClir:

In: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

006063: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccCheckClipClir:

Out: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

006064: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccCallSetupRequest:

Destination Pattern=.T, Called Number=911, Digit Strip=TRUE

006065: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccCallSetupRequest:

Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=Ball Reception - 4300, Final Destination Flag=TRUE,

Guid=00FFB1C2-1F71-3130-3501-FF020A040382, Outgoing Dial-peer=100

006066: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

006067: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x86E100A0, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=100, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

006068: *Feb 18 14:42:53.028 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006069: *Feb 18 14:42:53.028 UTC: :cc_get_feature_vsa malloc success

006070: *Feb 18 14:42:53.028 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006071: *Feb 18 14:42:53.028 UTC: cc_get_feature_vsa count is 2

006072: *Feb 18 14:42:53.028 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006073: *Feb 18 14:42:53.028 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2270469656,feature_id:907

006074: *Feb 18 14:42:53.028 UTC: //944/00FFB1C23501/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

006075: *Feb 18 14:42:53.028 UTC: //944/00FFB1C23501/CCAPI/ccCallSetContext:

Context=0x8634E274

006076: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=100

006077: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/H323/cch323_do_set_dialpeer_tag: gw_id=1

006078: *Feb 18 14:42:53.028 UTC: //944/00FFB1C23501/CCAPI/cc_api_call_proceeding:

Interface=0x86E100A0, Progress Indication=NULL(0)

006079: *Feb 18 14:42:53.028 UTC: //943/00FFB1C23501/H323/cch323_do_call_proceeding: gw_id=1

006080: *Feb 18 14:42:53.032 UTC: //943/00FFB1C23501/H323/cch323_do_call_proceeding: gw_id=1

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x8135

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC56F832110C110000FFB1C21F7131303501FF020A0403820100010010800100

006081: *Feb 18 14:42:54.280 UTC: //944/00FFB1C23501/CCAPI/cc_api_call_disconnected:

Cause Value=34, Interface=0x86E100A0, Call Id=944

006082: *Feb 18 14:42:54.280 UTC: //944/00FFB1C23501/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)

006083: *Feb 18 14:42:54.280 UTC: //943/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

006084: *Feb 18 14:42:54.280 UTC: //944/00FFB1C23501/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=944

006085: *Feb 18 14:42:54.280 UTC: //944/00FFB1C23501/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)

006086: *Feb 18 14:42:54.280 UTC: //944/00FFB1C23501/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

006087: *Feb 18 14:42:54.284 UTC: //944/00FFB1C23501/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x86E100A0, Tag=0x0, Call Id=944,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

006088: *Feb 18 14:42:54.284 UTC: //944/00FFB1C23501/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

006089: *Feb 18 14:42:54.284 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006090: *Feb 18 14:42:54.284 UTC: :cc_free_feature_vsa freeing 87549E10

006091: *Feb 18 14:42:54.284 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006092: *Feb 18 14:42:54.284 UTC: vsacount in free is 1

006093: *Feb 18 14:42:54.284 UTC: //-1/xxxxxxxxxxxx/H323/cch323_post_call_statistics: callID=943

006094: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

006095: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

006096: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

006097: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/H323/cch323_do_call_disconnect: gw_id=1, discCause=34

006098: *Feb 18 14:42:54.284 UTC: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x8135

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A00041100110000FFB1C21F7131303501FF020A04038210800100

006099: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/H323/cch323_call_generic_cleanup: Disconnected flag 1

006100: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/H323/cch323_store_call_history_entry: Call Active Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0

006101: *Feb 18 14:42:54.284 UTC: //943/00FFB1C23501/H323/cch323_store_call_history_entry: Call History Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0

006102: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 2

006103: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: hashDestroy for TcpFDTbl

006104: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 3

006105: *Feb 18 14:42:54.288 UTC: //943/00FFB1C23501/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x867581A8, Tag=0x0, Call Id=943,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

006106: *Feb 18 14:42:54.288 UTC: //943/00FFB1C23501/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

006107: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006108: *Feb 18 14:42:54.288 UTC: :cc_free_feature_vsa freeing 87549EF0

006109: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006110: *Feb 18 14:42:54.288 UTC: vsacount in free is 0

006111: *Feb 18 14:42:54.288 UTC: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x87BF6B7C)

NEW CONFIG

FAST BUSY

dial-peer voice 100 pots

preference 1

destination-pattern .T

port 4

!

dial-peer voice 911 pots

preference 1

destination-pattern 9911

incoming called-number .

port 4

forward-digits 3

!

line con 0

login local

no modem enable

line aux 0

line vty 0 4

access-class 101 in

login local

length 0

transport input telnet ssh

!

scheduler max-task-time 5000

scheduler allocate 4000 1000

scheduler interval 500

end

Ball#

006114: *Feb 18 14:54:59.287 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 0 Event 0x1

006115: *Feb 18 14:54:59.287 UTC: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x87BF6B7C) is in use

006116: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

006117: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x87A9EDF4, len=221, msgPtr=0x86695D1C

006118: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x0136

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010100C44000CE6C73F5733130360101020A04038200D50D800007000A00640D06B8110000CE6C73F5733130360101020A040382010001000100010010A00100140140B50000120D82040020040001030003000103

006119: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

006120: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID

006121: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl

006122: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006123: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number

006124: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006125: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern

006126: *Feb 18 14:54:59.291 UTC: //-1/00CE6C733601/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006127: *Feb 18 14:54:59.291 UTC: //-1/00CE6C733601/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006128: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006129: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/common_idle_setupInd_hdlr: ccb ccm bits = system[0x0028]

006130: *Feb 18 14:54:59.291 UTC: //-1/00CE6C733601/DPM/dpAssociateIncomingPeerCore:

Calling Number=3186411542, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006131: *Feb 18 14:54:59.291 UTC: //-1/00CE6C733601/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006132: *Feb 18 14:54:59.291 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006133: *Feb 18 14:54:59.291 UTC: //945/00CE6C733601/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

006134: *Feb 18 14:54:59.291 UTC: //-1/00CE6C733601/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

006135: *Feb 18 14:54:59.295 UTC: //-1/00CE6C733601/CCAPI/cc_api_call_setup_ind_common:

Interface=0x867581A8, Call Info(

Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=945

006136: *Feb 18 14:54:59.295 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006137: *Feb 18 14:54:59.295 UTC: :cc_get_feature_vsa malloc success

006138: *Feb 18 14:54:59.295 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006139: *Feb 18 14:54:59.295 UTC: cc_get_feature_vsa count is 1

006140: *Feb 18 14:54:59.295 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006141: *Feb 18 14:54:59.295 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2275987968,feature_id:908

006142: *Feb 18 14:54:59.295 UTC: //945/00CE6C733601/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown))

006143: *Feb 18 14:54:59.295 UTC: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating callInfoType container for callID = 945

006144: *Feb 18 14:54:59.295 UTC: //945/00CE6C733601/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0

006145: *Feb 18 14:54:59.295 UTC: //945/00CE6C733601/CCAPI/cc_process_call_setup_ind:

Event=0x86DCD638

006146: *Feb 18 14:54:59.295 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 911

006147: *Feb 18 14:54:59.295 UTC: //945/00CE6C733601/CCAPI/ccCallSetContext:

Context=0x8634B634

006148: *Feb 18 14:54:59.295 UTC: //945/00CE6C733601/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 945 with tag 0 to app "_ManagedAppProcess_Default"

006149: *Feb 18 14:54:59.299 UTC: //945/00CE6C733601/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

006150: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

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

006151: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006152: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006153: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006154: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006155: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006156: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006157: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006158: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006159: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006160: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006161: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006162: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

Peer Info Type=DIALPEER_INFO_SPEECH

006163: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006164: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

006165: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006166: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006167: *Feb 18 14:54:59.299 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006168: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

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

006169: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006170: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006171: *Feb 18 14:54:59.299 UTC: //-1/00CE6C733601/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=100

006172: *Feb 18 14:54:59.299 UTC: //945/00CE6C733601/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=100, Params=0x8634A7C4, Progress Indication=NULL(0)

006173: *Feb 18 14:54:59.299 UTC: //945/00CE6C733601/CCAPI/ccCheckClipClir:

In: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

006174: *Feb 18 14:54:59.299 UTC: //945/00CE6C733601/CCAPI/ccCheckClipClir:

Out: Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

006175: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/CCAPI/ccCallSetupRequest:

Destination Pattern=.T, Called Number=911, Digit Strip=TRUE

006176: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/CCAPI/ccCallSetupRequest:

Calling Number=3186411542(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=Ball Reception - 4300, Final Destination Flag=TRUE,

Guid=00CE6C73-F573-3130-3601-01020A040382, Outgoing Dial-peer=100

006177: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

006178: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x86E100A0, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=3186411542,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=100, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

006179: *Feb 18 14:54:59.303 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006180: *Feb 18 14:54:59.303 UTC: :cc_get_feature_vsa malloc success

006181: *Feb 18 14:54:59.303 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006182: *Feb 18 14:54:59.303 UTC: cc_get_feature_vsa count is 2

006183: *Feb 18 14:54:59.303 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006184: *Feb 18 14:54:59.303 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:2275987744,feature_id:909

006185: *Feb 18 14:54:59.303 UTC: //946/00CE6C733601/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

006186: *Feb 18 14:54:59.303 UTC: //946/00CE6C733601/CCAPI/ccCallSetContext:

Context=0x8634A774

006187: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=100

006188: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/H323/cch323_do_set_dialpeer_tag: gw_id=1

006189: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/H323/cch323_do_call_proceeding: gw_id=1

006190: *Feb 18 14:54:59.303 UTC: //945/00CE6C733601/H323/cch323_do_call_proceeding: gw_id=1

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x8136

Message Type : 0x02: CALL_PROC

User-User: Length Of IE=57

Data 0521C0060008914A00042800B500001240013C05010000ADD9FC56BECB110C110000CE6C73F5733130360101020A0403820100010010800100

006191: *Feb 18 14:54:59.307 UTC: //946/00CE6C733601/CCAPI/cc_api_call_proceeding:

Interface=0x86E100A0, Progress Indication=NULL(0)

006192: *Feb 18 14:55:00.559 UTC: //946/00CE6C733601/CCAPI/cc_api_call_disconnected:

Cause Value=34, Interface=0x86E100A0, Call Id=946

006193: *Feb 18 14:55:00.559 UTC: //946/00CE6C733601/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)

006194: *Feb 18 14:55:00.559 UTC: //945/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

006195: *Feb 18 14:55:00.559 UTC: //946/00CE6C733601/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=946

006196: *Feb 18 14:55:00.559 UTC: //946/00CE6C733601/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)

006197: *Feb 18 14:55:00.559 UTC: //946/00CE6C733601/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

006198: *Feb 18 14:55:00.563 UTC: //946/00CE6C733601/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x86E100A0, Tag=0x0, Call Id=946,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

006199: *Feb 18 14:55:00.563 UTC: //946/00CE6C733601/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

006200: *Feb 18 14:55:00.563 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006201: *Feb 18 14:55:00.563 UTC: :cc_free_feature_vsa freeing 87A8D118

006202: *Feb 18 14:55:00.563 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006203: *Feb 18 14:55:00.563 UTC: vsacount in free is 1

006204: *Feb 18 14:55:00.563 UTC: //-1/xxxxxxxxxxxx/H323/cch323_post_call_statistics: callID=945

006205: *Feb 18 14:55:00.563 UTC: //945/00CE6C733601/CCAPI/ccCallDisconnect:

Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

006206: *Feb 18 14:55:00.563 UTC: //945/00CE6C733601/CCAPI/ccCallDisconnect:

Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

006207: *Feb 18 14:55:00.563 UTC: //945/00CE6C733601/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

006208: *Feb 18 14:55:00.563 UTC: //945/00CE6C733601/H323/cch323_do_call_disconnect: gw_id=1, discCause=34

006209: *Feb 18 14:55:00.563 UTC: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x8136

Message Type : 0x5A: RELEASE_COMP

Cause: Length Of IE=2

Data 80A2

User-User: Length Of IE=34

Data 052580060008914A00041100110000CE6C73F5733130360101020A04038210800100

006210: *Feb 18 14:55:00.567 UTC: //945/00CE6C733601/H323/cch323_call_generic_cleanup: Disconnected flag 1

006211: *Feb 18 14:55:00.567 UTC: //945/00CE6C733601/H323/cch323_store_call_history_entry: Call Active Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0

006212: *Feb 18 14:55:00.567 UTC: //945/00CE6C733601/H323/cch323_store_call_history_entry: Call History Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0

006213: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 2

006214: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: hashDestroy for TcpFDTbl

006215: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 3

006216: *Feb 18 14:55:00.567 UTC: //945/00CE6C733601/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x867581A8, Tag=0x0, Call Id=945,

Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

006217: *Feb 18 14:55:00.567 UTC: //945/00CE6C733601/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

006218: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006219: *Feb 18 14:55:00.567 UTC: :cc_free_feature_vsa freeing 87A8D1F8

006220: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

006221: *Feb 18 14:55:00.567 UTC: vsacount in free is 0

006222: *Feb 18 14:55:00.567 UTC: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x87BF6B7C)

acampbell · ‎02-18-2014

Hi,

Can you fix your dial peer

!

dial-peer voice 911 pots

preference 1

destination-pattern 9.911

incoming called-number .

port 4

Change this to 2 dial peers as below:-

!

no dial-peer voice 9911 pots

!

dial-peer voice 9911 pots

preference 1

destination-pattern 9911

incoming called-number .

forward-digits 3

port 4

!

dial-peer voice 911 pots

preference 1

destination-pattern 911

incoming called-number .

forward-digits 3

port 4

!

Regards,
Alex.
Please rate useful posts.

Regards, Alex. Please rate useful posts.

ggerhard · ‎02-19-2014

Alex, same, but seems to be hitting the correct dial-peer now...

also not sure if this is normal operation:

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=911

2: Dial-peer Tag=100

006664: *Feb 18 15:47:48.395 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

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

why does it say calling and called is 911???

Ball#sh run | b dial-peer

dial-peer voice 201010 voip

preference 1

destination-pattern 4300

voice-class codec 1

session target ipv4:10.0.100.13

dtmf-relay h245-alphanumeric

!

dial-peer voice 200010 voip

preference 2

destination-pattern 4300

voice-class codec 1

session target ipv4:10.0.100.12

dtmf-relay h245-alphanumeric

!

dial-peer voice 100 pots

preference 1

destination-pattern .T

port 4

!

dial-peer voice 9911 pots

preference 1

destination-pattern 9911

incoming called-number .

port 4

forward-digits 3

!

dial-peer voice 911 pots

preference 1

destination-pattern 911

incoming called-number .

port 4

forward-digits 3

!

line con 0

login local

no modem enable

line aux 0

line vty 0 4

access-class 101 in

login local

length 0

transport input telnet ssh

!

scheduler max-task-time 5000

scheduler allocate 4000 1000

scheduler interval 500

end

Ball#

Ball#term mon

Ball#

006620: *Feb 18 15:47:48.387 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 0 Event 0x1

006621: *Feb 18 15:47:48.387 UTC: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x87BF6B7C) is in use

006622: *Feb 18 15:47:48.387 UTC: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

006623: *Feb 18 15:47:48.387 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x87A9EF50, len=221, msgPtr=0x8772F388

006624: *Feb 18 15:47:48.387 UTC: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

Q931 Message IE Decodes

Protocol Discriminator : 0x08

CRV Length : 2

CRV Value : 0x0138

Message Type : 0x05: SETUP

Bearer Capability: Length Of IE=3

Data 8090A2

Calling Party Number: Length Of IE=12

Data 008133313836343131353432

Called Party Number: Length Of IE=4

Data 80393131

User-User: Length Of IE=188

Data 0520B0060008914A000501401F00420061006C006C00200052006500630065007000740069006F006E0020002D002000340033003000300000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010100C440006BE4D457803130380115020A04038200D50D800007000A00640D06B81100006BE4D457803130380115020A040382010001000100010010A00100140140B50000120D82040020040001030003000103

006625: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

006626: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID

006627: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl

006628: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006629: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number

006630: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

006631: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern

006632: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/DPM/dpAssociateIncomingPeerCore:

Calling Number=

3186411542

, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006633: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006634: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006635: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/common_idle_setupInd_hdlr: ccb ccm bits = system[0x0028]

006636: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/DPM/dpAssociateIncomingPeerCore:

Calling Number=

3186411542

, Called Number=911, Voice-Interface=0x0,

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

Peer Info Type=DIALPEER_INFO_SPEECH

006637: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

006638: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

006639: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

006640: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=Ball Reception - 4300

----- ccCallInfo IE subfields -----

cisco-ani=

3186411542

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=911

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

006641: *Feb 18 15:47:48.391 UTC: //-1/006BE4D43801/CCAPI/cc_api_call_setup_ind_common:

Interface=0x867581A8, Call Info(

Calling Number=

3186411542

,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

Incoming Dial-peer=0, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=956

006642: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006643: *Feb 18 15:47:48.391 UTC: :cc_get_feature_vsa malloc success

006644: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006645: *Feb 18 15:47:48.391 UTC: cc_get_feature_vsa count is 1

006646: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

006647: *Feb 18 15:47:48.391 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:

2272984256

,feature_id:919

006648: *Feb 18 15:47:48.391 UTC: //956/006BE4D43801/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=

3186411542

(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

Called Number=911(TON=Unknown, NPI=Unknown))

006649: *Feb 18 15:47:48.391 UTC: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating callInfoType container for callID = 956

006650: *Feb 18 15:47:48.395 UTC: //956/006BE4D43801/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0

006651: *Feb 18 15:47:48.395 UTC: //956/006BE4D43801/CCAPI/cc_process_call_setup_ind:

Event=0x86DCD638

006652: *Feb 18 15:47:48.395 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 911

006653: *Feb 18 15:47:48.395 UTC: //956/006BE4D43801/CCAPI/ccCallSetContext:

Context=0x86350EB4

006654: *Feb 18 15:47:48.395 UTC: //956/006BE4D43801/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 956 with tag 0 to app "_ManagedAppProcess_Default"

006655: *Feb 18 15:47:48.395 UTC: //956/006BE4D43801/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

006656: *Feb 18 15:47:48.395 UTC: //-1/006BE4D43801/DPM/dpMatchPeersCore:

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

006657: *Feb 18 15:47:48.395 UTC: //-1/006BE4D43801/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=911

006658: *Feb 18 15:47:48.395 UTC: //-1/006BE4D43801/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

006659: *Feb 18 15:47:48.395 UTC: //-1/006BE4D43801/DPM/dpMatchPeersMoreArg: