Problem with sip trunk

Hi I'm bussy studying for ccnp voice and I am stuck with this sip trunk.

I have a sip trunk setup but cant get incomming calls to come through, outbound calls work fine though...

Can somebody please see what I am doing wrong, I tested the sip trunk with a soft phone and incomming calls work fine.

Here is my configs along with debug info and wireshark captures, please someone help I'm going nuts with this.

https://dl.dropbox.com/u/86943751/Cisco Debug.zip

Comments

  • Hi, check out this part from the debug:

     

      Result=Success(0); Incoming Dial-peer=1000 Is Matched

    145468: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/MatchNextPeer:

       Result=Success(0); Incoming Dial-peer=6 Is Matched

    145469: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/MatchNextPeer:

       Result=Success(0); Incoming Dial-peer=3000 Is Matched

    145470: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/dpMatchPeertype:[email protected] 

    145471: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/dpAssociateIncomingPeerCore:

       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000

    145472: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/dpMatchSafModulePlugin:

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

    145473: Jun 30 16:30:19.532: //-1/63C3F9938F4E/DPM/dpAssociateIncomingPeerSPI:[email protected] 

    145474: Jun 30 16:30:19.532: //3757/63C3F9938F4E/SIP/Info/sipSPIGetCallConfig: Peer tag 3000 matched for incoming call

    145475: Jun 30 16:30:19.532: //3757/63C3F9938F4E/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header

    145476: Jun 30 16:30:19.532: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()

    145477: Jun 30 16:30:19.532: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()

    145478: Jun 30 16:30:19.532: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 41.132.55.14

    145479: Jun 30 16:30:19.532: //3757/63C3F9938F4E/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header

    145480: Jun 30 16:30:19.532: //3757/63C3F9938F4E/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled

    145481: Jun 30 16:30:19.532: //3757/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

       Call Entry Is Not Found

     

     

    If you check your dial-peer 3000, a TP is applied there named: SIP-OP_Called_4

    Here is the TP detail:


    !

    voice translation-profile SIP-OP_Called_4

     translate calling 3265

     translate called 4

    !


    voice translation-rule 4

     rule 1 /0877339727/ /201/

    !

    It means, the incoming called number is being replaced by 201 so it's not matching the DN 0877339727. To confirm this issue the command 'debug voice translation' and check the debug.

    If you want to ring your phone either you have to revoke the Translation Profile from Dial-Peer 3000 or let the call in through Dial-Peer 1000.

    HTH.

  • Hi, thanks for your reply...here is the "debug voip translation"

    =~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2012.07.01 18:14:38 =~=~=~=~=~=~=~=~=~=~=~=
    Cisco Configuration Assistant. Version: 3.2 (1.33). Sat Jun 30 11:51:31 CAT 2012

    User Access Verification

    Username: cisco
    Password:

    UC540>en
    Password:
    UC540#no d al
    All possible debugging has been turned off
    UC540#conf t
    Enter configuration commands, one per line.  End with CNTL/Z.
    UC540(config)#no logging buffered 10000000 debug
    UC540(config)#!
    UC540(config)# service sequence-numbers
    UC540(config)# service timestamps debug datetime localtime msec
    UC540(config)# logging buffered 10000000 debug
    UC540(config)# no logging console
    UC540(config)# no logging monitor
    UC540(config)# default logging rate-limit
    UC540(config)# default logging queue-limit
    UC540(config)# voice iec syslog
    UC540(config)#exit
    UC540#debu
    UC540#debug voip sh run
    Building configuration...


    Current configuration : 30688 bytes
    !
    ! Last configuration change at 09:16:33 PST Sun Jul 1 2012 by cisco
    version 15.1
    parser config cache interface
    no service pad
    service timestamps debug datetime msec localtime
    service timestamps log datetime msec
    service password-encryption
    service internal
    service compress-config
    service sequence-numbers
    !
    hostname UC540
    !
    boot-start-marker
    boot-end-marker
    !
    !
    logging buffered 10000000
    no logging console
    no logging monitor
    enable secret 4 tnhtc92DXBhelxjYk8LWJrPV36S2i4ntXrpb4RFmfqY

    aaa new-model
    !
    !
    aaa authentication login default local
    !
    !
    !
    !
    !
    aaa session-id common
    !
    clock timezone PST -8 0
    clock summer-time PST recurring
    crypto pki token default removal timeout 0
    !
    crypto pki trustpoint TP-self-signed-1039311643
     enrollment selfsigned
     subject-name cn=IOS-Self-Signed-Certificate-1039311643
     revocation-check none
     rsakeypair TP-self-signed-1039311643
    !
    !
    crypto pki certificate chain TP-self-signed-1039311643
     --More--         certificate self-signed 01
      3082022B 30820194 A0030201 02020101 300D0609 2A864886 F70D0101 05050030
      31312F30 2D060355 04031326 494F532D 53656C66 2D536967 6E65642D 43657274
      69666963 6174652D 31303339 33313136 3433301E 170D3132 30363236 30393438
      32325A17 0D323030 31303130 30303030 305A3031 312F302D 06035504 03132649
      4F532D53 656C662D 5369676E 65642D43 65727469 66696361 74652D31 30333933
      31313634 3330819F 300D0609 2A864886 F70D0101 01050003 818D0030 81890281
      8100A282 4F10143E 28BD6AEE ACFC7A7B 2607588B 1C873A05 7DF2DEF5 7AD6F517
      3F1CBB97 4E3E34EC 78A64B79 0BE25D3F 2D31F07F 1822B66A 96A41823 2E60697F
      1F971E9E 4F3D6326 00899F0B 5FE5E55B EDF841A5 11512582 6A88423E 7AE7FA80
      EB531E89 0B12AE0B D35E8DA1 A8FBDD16 26614CA6 88F8A7C5 CDA5C01D 618579BC
      C4730203 010001A3 53305130 0F060355 1D130101 FF040530 030101FF 301F0603
      551D2304 18301680 14D49961 5CAED7CB 2A18B48F AB9E2990 E5684D91 4A301D06
      03551D0E 04160414 D499615C AED7CB2A 18B48FAB 9E2990E5 684D914A 300D0609
      2A864886 F70D0101 05050003 81810092 8D6CF263 8016C995 6D29628F 8D62E7DC
      48DEFF74 DDA8D889 4C9B5D25 66A6BD8D 004066DA 0DDF7287 4F0E0C39 D97964A1
      0FA7B0A4 640E390F 0DD6DB7C EB3B9A61 23F88255 D748CBFD EA1FFB5C C9A4C796
      02620CB6 4C6D9CAA 2C480FA8 622D440E E84151B6 A1770FAD B8766084 9CB21E9E
      AC293D80 13C24E2B 42837CA7 7CC7EB
          quit
    dot11 syslog
    !
    dot11 ssid cisco-data
     vlan 1
     --More--         authentication open
     authentication key-management wpa
     guest-mode
     wpa-psk ascii 7 022B575C0A1000284F4B
    !
    dot11 ssid cisco-voice
     vlan 100
     authentication open
     authentication key-management wpa
     wpa-psk ascii 7 153F580B053C242D2B36
    !
    ip source-route
    ip cef
    !
    !
    ip dhcp relay information trust-all
    ip dhcp excluded-address 10.1.1.1 10.1.1.10
    ip dhcp excluded-address 192.168.10.1 192.168.10.10
    !
    ip dhcp pool phone
     network 10.1.1.0 255.255.255.0
     default-router 10.1.1.1
     option 150 ip 10.1.1.1
    !
     --More--         ip dhcp pool data
     import all
     network 192.168.10.0 255.255.255.0
     default-router 192.168.10.1
    !
    !
    !
    ip domain name voice.fnbconnect.co.za
    ip name-server 4.2.2.2
    ip inspect WAAS flush-timeout 10
    ip inspect name SDM_LOW tcp router-traffic
    ip ddns update method sdm_ddns1
     HTTP
      add http://megavoice:[email protected]/nic/update?system=dyndns&hostname=<h>&myip=<a&gt;
      remove http://megavoice:[email protected]/nic/update?system=dyndns&hostname=<h>&myip=<a&gt;
     interval maximum 2 0 0 0
     interval minimum 1 0 0 0
    !
    no ipv6 cef
    !
    multilink bundle-name authenticated
    !
     --More--         !
    stcapp ccm-group 1
    stcapp
    !
    !
    !
    !
    voice call send-alert
    voice rtp send-recv
    !
    voice service voip
     ip address trusted list
      ipv4 0.0.0.0 0.0.0.0
      ipv4 41.183.0.13
      ipv4 41.183.0.14
     allow-connections sip to sip
     no supplementary-service sip moved-temporarily
     no supplementary-service sip refer
     no supplementary-service sip handle-replaces
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     sip
      bind control source-interface Dialer0
      bind media source-interface Dialer0
     --More--           registrar server expires max 3600 min 3600
      localhost dns:voice.fnbconnect.co.za
      no update-callerid
    !
    voice class codec 1
     codec preference 1 g729r8
    !
    !
    voice class cause-code 1
     no-circuit
    !
    voice iec syslog
    voice register global
     mode cme
     source-address 10.1.1.1 port 5060
     load 9971 sip9971.9-2-2
     load 9951 sip9951.9-2-2
     load 8961 sip8961.9-2-2
    !
    !
    !
    voice translation-rule 4
     rule 1 /0877339727/ /201/
    !
     --More--         voice translation-rule 410
     rule 1 /^0(.*)/ /1/
     rule 15 /^...$/ /0877339727/
    !
    voice translation-rule 411
     rule 1 /^0(.*)/ /ABCD01/
    !
    voice translation-rule 412
     rule 1 /^ABCD(.*)/ /1/
    !
    voice translation-rule 422
     rule 15 /^ABCD(.*)/ /1/
    !
    voice translation-rule 1000
     rule 1 /.*/ //
    !
    voice translation-rule 1111
     rule 15 /^...$/ /0877339727/
    !
    voice translation-rule 1112
     rule 1 /^0/ //
    !
    voice translation-rule 2222
    !
     --More--         !
    voice translation-profile CALLER_ID_TRANSLATION_PROFILE
     translate calling 1111
    !
    voice translation-profile CallBlocking
     translate called 2222
    !
    voice translation-profile OUTGOING_TRANSLATION_PROFILE
     translate called 1112
    !
    voice translation-profile PSTN_CallForwarding
     translate redirect-target 410
     translate redirect-called 410
    !
    voice translation-profile PSTN_Outgoing
     translate calling 1111
     translate called 1112
     translate redirect-target 410
     translate redirect-called 410
    !
    voice translation-profile SIP-OP_Called_4
     translate calling 3265
     translate called 4
    !
     --More--         voice translation-profile SIP_Incoming
     translate called 411
    !
    voice translation-profile SIP_Passthrough
     translate called 412
    !
    voice translation-profile SIP_Passthrough_CallBlocking
     translate called 422
    !
    voice translation-profile nondialable
     translate called 1000
    !
    !
    voice-card 0
     dspfarm
     dsp services dspfarm
    !
    fax interface-type fax-mail
    !
    !
    license udi pid UC540W-FXO-K9 sn FGL1548256Z
    archive
     log config
      logging enable
     --More--           logging size 600
      hidekeys
    username cisco privilege 15 secret 4 tnhtc92DXBhelxjYk8LWJrPV36S2i4ntXrpb4RFmfqY
    !
    !
    ip tftp source-interface Loopback0
    !
    class-map match-all _class_Voice0
     match ip dscp ef
    class-map match-all _class_Voice1
     match ip dscp cs3
    class-map match-any media
     match  dscp ef
    class-map match-any signaling
     match  dscp cs3
     match  dscp af31
    !
    !
    policy-map Voice
     class _class_Voice0
      set cos 6
     class _class_Voice1
      set cos 3
    policy-map queue
     --More--         class signaling
      bandwidth percent 5
     class media
      priority percent 50
     class class-default
      fair-queue
    policy-map shape
     class class-default
      shape average 512000
      service-policy queue
    !
    !
    !
    !
    bridge irb
    !
    !
    !
    !
    interface Loopback0
     ip address 10.1.10.2 255.255.255.252
     ip nat inside
     ip virtual-reassembly in
    !
     --More--         interface FastEthernet0/0
     description $ETH-WAN$
     no ip address
     ip virtual-reassembly in
     load-interval 30
     duplex auto
     speed auto
     pppoe enable group global
     pppoe-client dial-pool-number 1
    !
    interface Integrated-Service-Engine0/0
     description cue is initialized with default IMAP group
     ip unnumbered Loopback0
     ip nat inside
     ip virtual-reassembly in
     service-module ip address 10.1.10.1 255.255.255.252
     service-module ip default-gateway 10.1.10.2
    !
    interface FastEthernet0/1/0
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
     --More--         interface FastEthernet0/1/1
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/2
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/3
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/4
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
     --More--         interface FastEthernet0/1/5
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/6
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/7
     switchport voice vlan 100
     no ip address
     macro description cisco-phone
     spanning-tree portfast
    !
    interface FastEthernet0/1/8
     switchport mode trunk
     switchport voice vlan 100
     no ip address
     macro description cisco-switch
    !
     --More--         interface Dot11Radio0/5/0
     no ip address
     !
     encryption vlan 1 mode ciphers aes-ccm
     !
     encryption vlan 100 mode ciphers aes-ccm
     !
     ssid cisco-data
     !
     ssid cisco-voice
     !
     speed basic-1.0 basic-2.0 basic-5.5 6.0 9.0 basic-11.0 12.0 18.0 24.0 36.0 48.0 54.0
     station-role root
     antenna receive right
     antenna transmit right
     service-policy output Voice
    !
    interface Dot11Radio0/5/0.1
     encapsulation dot1Q 1 native
     bridge-group 1
     bridge-group 1 subscriber-loop-control
     bridge-group 1 spanning-disabled
     bridge-group 1 block-unknown-source
     --More--         no bridge-group 1 source-learning
     no bridge-group 1 unicast-flooding
    !
    interface Dot11Radio0/5/0.100
     encapsulation dot1Q 100
     bridge-group 100
     bridge-group 100 subscriber-loop-control
     bridge-group 100 spanning-disabled
     bridge-group 100 block-unknown-source
     no bridge-group 100 source-learning
     no bridge-group 100 unicast-flooding
    !
    interface Vlan1
     no ip address
     bridge-group 1
     bridge-group 1 spanning-disabled
    !
    interface Vlan100
     no ip address
     bridge-group 100
     bridge-group 100 spanning-disabled
    !
    interface Dialer0
     bandwidth 512
     --More--         ip ddns update hostname megavox.dyndns.org
     ip ddns update sdm_ddns1
     ip address negotiated
     ip mtu 1452
     ip nat outside
     ip virtual-reassembly in
     encapsulation ppp
     dialer pool 1
     dialer-group 1
     ppp authentication chap pap callin
     ppp chap hostname [email protected]
     ppp chap password 7 03520E5F555D70
     ppp pap sent-username [email protected] password 7 03520E5F555D70
     ppp ipcp dns request
     service-policy output shape
    !
    interface BVI1
     ip address 192.168.10.1 255.255.255.0
     ip nat inside
     ip virtual-reassembly in
     ip tcp adjust-mss 1412
    !
    interface BVI100
     ip address 10.1.1.1 255.255.255.0
     --More--         ip nat inside
     ip virtual-reassembly in
     ip tcp adjust-mss 1412
    !
    ip forward-protocol nd
    ip http server
    ip http authentication local
    ip http secure-server
    ip http path flash:/gui
    !
    ip dns server
    ip nat inside source list 1 interface Dialer0 overload
    ip route 0.0.0.0 0.0.0.0 Dialer0
    ip route 10.1.10.1 255.255.255.255 Integrated-Service-Engine0/0
    !
    access-list 1 remark SDM_ACL Category=2
    access-list 1 permit 10.1.1.0 0.0.0.255
    access-list 1 permit 192.168.10.0 0.0.0.255
    access-list 1 permit 10.1.10.0 0.0.0.3
    access-list 100 remark auto generated by SDM firewall configuration
    access-list 100 remark SDM_ACL Category=1
    access-list 100 deny   ip 192.168.10.0 0.0.0.255 any
    access-list 100 deny   ip host 255.255.255.255 any
    access-list 100 deny   ip 127.0.0.0 0.255.255.255 any
     --More--         access-list 100 permit ip any any
    dialer-list 1 protocol ip permit
    !
    !
    !
    !
    snmp-server community public RO
    tftp-server flash:/phones/521_524/cp524g-8-1-17.bin alias cp524g-8-1-17.bin
    tftp-server flash:/ringtones/Analog1.raw alias Analog1.raw
    tftp-server flash:/ringtones/Analog2.raw alias Analog2.raw
    tftp-server flash:/ringtones/AreYouThere.raw alias AreYouThere.raw
    tftp-server flash:/ringtones/DistinctiveRingList.xml alias DistinctiveRingList.xml
    tftp-server flash:/ringtones/RingList.xml alias RingList.xml
    tftp-server flash:/ringtones/AreYouThereF.raw alias AreYouThereF.raw
    tftp-server flash:/ringtones/Bass.raw alias Bass.raw
    tftp-server flash:/ringtones/CallBack.raw alias CallBack.raw
    tftp-server flash:/ringtones/Chime.raw alias Chime.raw
    tftp-server flash:/ringtones/Classic1.raw alias Classic1.raw
    tftp-server flash:/ringtones/Classic2.raw alias Classic2.raw
    tftp-server flash:/ringtones/ClockShop.raw alias ClockShop.raw
    tftp-server flash:/ringtones/Drums1.raw alias Drums1.raw
    tftp-server flash:/ringtones/Drums2.raw alias Drums2.raw
    tftp-server flash:/ringtones/FilmScore.raw alias FilmScore.raw
     --More--         tftp-server flash:/ringtones/HarpSynth.raw alias HarpSynth.raw
    tftp-server flash:/ringtones/Jamaica.raw alias Jamaica.raw
    tftp-server flash:/ringtones/KotoEffect.raw alias KotoEffect.raw
    tftp-server flash:/ringtones/MusicBox.raw alias MusicBox.raw
    tftp-server flash:/ringtones/Piano1.raw alias Piano1.raw
    tftp-server flash:/ringtones/Piano2.raw alias Piano2.raw
    tftp-server flash:/ringtones/Pop.raw alias Pop.raw
    tftp-server flash:/ringtones/Pulse1.raw alias Pulse1.raw
    tftp-server flash:/ringtones/Ring1.raw alias Ring1.raw
    tftp-server flash:/ringtones/Ring2.raw alias Ring2.raw
    tftp-server flash:/ringtones/Ring3.raw alias Ring3.raw
    tftp-server flash:/ringtones/Ring4.raw alias Ring4.raw
    tftp-server flash:/ringtones/Ring5.raw alias Ring5.raw
    tftp-server flash:/ringtones/Ring6.raw alias Ring6.raw
    tftp-server flash:/ringtones/Ring7.raw alias Ring7.raw
    tftp-server flash:/ringtones/Sax1.raw alias Sax1.raw
    tftp-server flash:/ringtones/Sax2.raw alias Sax2.raw
    tftp-server flash:/ringtones/Vibe.raw alias Vibe.raw
    tftp-server flash:/Desktops/CampusNight.png
    tftp-server flash:/Desktops/TN-CampusNight.png
    tftp-server flash:/Desktops/CiscoFountain.png
    tftp-server flash:/Desktops/TN-CiscoFountain.png
    tftp-server flash:/Desktops/CiscoLogo.png
    tftp-server flash:/Desktops/TN-CiscoLogo.png
     --More--         tftp-server flash:/Desktops/Fountain.png
    tftp-server flash:/Desktops/TN-Fountain.png
    tftp-server flash:/Desktops/MorroRock.png
    tftp-server flash:/Desktops/TN-MorroRock.png
    tftp-server flash:/Desktops/NantucketFlowers.png
    tftp-server flash:/Desktops/TN-NantucketFlowers.png
    tftp-server flash:Desktops/320x212x16/List.xml
    tftp-server flash:Desktops/320x212x12/List.xml
    tftp-server flash:Desktops/320x216x16/List.xml
    tftp-server flash:/bacdprompts/en_bacd_allagentsbusy.au alias en_bacd_allagentsbusy.au
    tftp-server flash:/bacdprompts/en_bacd_disconnect.au alias en_bacd_disconnect.au
    tftp-server flash:/bacdprompts/en_bacd_enter_dest.au alias en_bacd_enter_dest.au
    tftp-server flash:/bacdprompts/en_bacd_invalidoption.au alias en_bacd_invalidoption.au
    tftp-server flash:/bacdprompts/en_bacd_music_on_hold.au alias en_bacd_music_on_hold.au
    tftp-server flash:/bacdprompts/en_bacd_options_menu.au alias en_bacd_options_menu.au
    tftp-server flash:/bacdprompts/en_bacd_welcome.au alias en_bacd_welcome.au
    tftp-server flash:/bacdprompts/en_bacd_xferto_operator.au alias en_bacd_xferto_operator.au
    radius-server attribute 31 send nas-port-detail
    !
     --More--         !
    !
    control-plane
    !
    bridge 1 route ip
    bridge 100 route ip
    call threshold interface FastEthernet0/0 int-calls low 1 high 1
    !
    voice-port 0/0/0
     caller-id enable
    !
    voice-port 0/0/1
     caller-id enable
    !
    voice-port 0/0/2
     caller-id enable
    !
    voice-port 0/0/3
     caller-id enable
    !
    voice-port 0/1/0
     connection plar opx 201
     description Configured by CCA 4FXO-0/1/0-Custom-OP
     caller-id enable
     --More--         !
    voice-port 0/1/1
     connection plar opx 201
     description Configured by CCA 4 FXO-0/1/1-Custom-OP
     caller-id enable
    !
    voice-port 0/1/2
     connection plar opx 201
     description Configured by CCA 4 FXO-0/1/2-Custom-OP
     caller-id enable
    !
    voice-port 0/1/3
     connection plar opx 201
     description Configured by CCA 4 FXO-0/1/3-Custom-OP
     caller-id enable
    !
    voice-port 0/4/0
     auto-cut-through
     signal immediate
     input gain auto-control -15
     description Music On Hold Port
    !
    sccp local Loopback0
    sccp ccm 10.1.1.1 identifier 1 version 4.0
     --More--         sccp
    !
    sccp ccm group 1
     associate ccm 1 priority 1
     associate profile 2 register mtp708105e75fc0
    !
    dspfarm profile 2 transcode 
     description CCA transcoding for SIP Trunk FNB Connect
     codec g729abr8
     codec g729ar8
     codec g711ulaw
     maximum sessions 10
     associate application SCCP
    !
    dial-peer cor custom
     name internal
     name local
     name local-plus
     name international
     name national
     name national-plus
     name emergency
     name toll-free
    !
     --More--         !
    dial-peer cor list call-internal
     member internal
    !
    dial-peer cor list call-local
     member local
    !
    dial-peer cor list call-local-plus
     member local-plus
    !
    dial-peer cor list call-national
     member national
    !
    dial-peer cor list call-national-plus
     member national-plus
    !
    dial-peer cor list call-international
     member international
    !
    dial-peer cor list call-emergency
     member emergency
    !
    dial-peer cor list call-toll-free
     member toll-free
     --More--         !
    dial-peer cor list user-internal
     member internal
     member emergency
    !
    dial-peer cor list user-local
     member internal
     member local
     member emergency
     member toll-free
    !
    dial-peer cor list user-local-plus
     member internal
     member local
     member local-plus
     member emergency
     member toll-free
    !
    dial-peer cor list user-national
     member internal
     member local
     member local-plus
     member national
     member emergency
     --More--         member toll-free
    !
    dial-peer cor list user-national-plus
     member internal
     member local
     member local-plus
     member national
     member national-plus
     member emergency
     member toll-free
    !
    dial-peer cor list user-international
     member internal
     member local
     member local-plus
     member international
     member national
     member national-plus
     member emergency
     member toll-free
    !
    !
    dial-peer voice 50 pots
     description ** incoming dial peer **
     --More--         incoming called-number ^AAAA$
     port 0/1/0
    !
    dial-peer voice 1000 voip
     permission term
     description ** Incoming call from SIP trunk (FNB Connect) **
     session protocol sipv2
     session target sip-server
     incoming called-number .%
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    !
    dial-peer voice 1001 voip
     corlist outgoing call-local
     description ** star code to SIP trunk (FNB Connect) **
     destination-pattern *..
     session protocol sipv2
     --More--         session target sip-server
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    !
    dial-peer voice 1003 voip
     description ** Passthrough Inbound Calls for PSTN from CUE **
     translation-profile incoming SIP_Passthrough
     b2bua
     session protocol sipv2
     session target ipv4:10.1.10.1
     incoming called-number ABCDT
     dtmf-relay rtp-nte
     codec g711ulaw
     no vad
    !
    dial-peer voice 1005 voip
     description ** Passthrough Inbound Calls for MWI from CUE **
     --More--         b2bua
     session protocol sipv2
     session target ipv4:10.1.10.1
     incoming called-number A80T
     dtmf-relay rtp-nte
     codec g711ulaw
     no vad
    !
    dial-peer voice 1009 voip
     description ** Passthrough Inbound Calls for Internal Extensions from CUE **
     b2bua
     session protocol sipv2
     session target ipv4:10.1.10.1
     incoming called-number ^...$
     dtmf-relay rtp-nte
     codec g711ulaw
     no vad
    !
    dial-peer voice 51 pots
     description ** incoming dial peer **
     incoming called-number ^AAAA$
     port 0/1/1
    !
    dial-peer voice 52 pots
     --More--         description ** incoming dial peer **
     incoming called-number ^AAAA$
     port 0/1/2
    !
    dial-peer voice 53 pots
     description ** incoming dial peer **
     incoming called-number ^AAAA$
     port 0/1/3
    !
    dial-peer voice 3000 voip
     description SIP-OP
     translation-profile incoming SIP-OP_Called_4
     session protocol sipv2
     session target sip-server
     incoming called-number 0877339727
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
     --More--         !
    dial-peer voice 1020 voip
     corlist outgoing call-emergency
     description **CCA*South Africa*Emergencies**
     translation-profile outgoing PSTN_Outgoing
     preference 1
     destination-pattern 010177
     session protocol sipv2
     session target sip-server
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    !
    dial-peer voice 1021 voip
     corlist outgoing call-emergency
     description **CCA*South Africa*Emergencies**
     translation-profile outgoing CALLER_ID_TRANSLATION_PROFILE
     preference 1
     --More--         destination-pattern 10177
     session protocol sipv2
     --More--        
    UC540#debu
    UC540#debug voip
    UC540#debug voip tr
    UC540#debug voip translation ?
      <cr>

    UC540#debug voip translation
    VoIP Translation Rule debugging is enabled
    UC540#sh logg
    UC540#sh logging
    Syslog logging: enabled (0 messages dropped, 3 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)

    No Active Message Discriminator.



    No Inactive Message Discriminator.


        Console logging: disabled
        Monitor logging: disabled
        Buffer logging:  level debugging, 22 messages logged, xml disabled,
                        filtering disabled
        Exception Logging: size (4096 bytes)
        Count and timestamp logging messages: disabled
        Persistent logging: disabled

    No active filter modules.

        Trap logging: level informational, 618 message lines logged
            Logging Source-Interface:       VRF Name:
     --More--        
    Log Buffer (10000000 bytes):

    120335: Jul  1 16:16:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2103 GUID=F526AA22C2CE11E18E27CE588177C75E
    120336: Jul  1 16:16:33.382: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    120337: Jul  1 16:17:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2108 GUID=07084D22C2CF11E18E30CE588177C75E
    120338: Jul  1 16:17:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2112 GUID=18E9F022C2CF11E18E37CE588177C75E
    120339: Jul  1 16:18:02.994: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2116 GUID=2ACB9322C2CF11E18E3ECE588177C75E
    120340: Jul  1 16:18:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2121 GUID=3CAD3622C2CF11E18E46CE588177C75E
    120341: Jul  1 16:19:02.994: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2124 GUID=4E8ED889C2CF11E18E4CCE588177C75E
    120342: Jul  1 16:19:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2128 GUID=60707B89C2CF11E18E53CE588177C75E
     --More--         120343: Jul  1 09:20:02.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120344: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B67C4; count=1
    120345: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2CA8
    120346: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B67C4; count=1
    120347: Jul  1 16:20:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2132 GUID=72521E89C2CF11E18E5ACE588177C75E
    120348: Jul  1 09:20:03.010: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B67C4; count=1
    120349: Jul  1 09:20:03.010: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2CA8
    120350: Jul  1 09:20:32.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120351: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5E7C; count=1
    120352: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2084
    120353: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B5E7C; count=1
     --More--         120354: Jul  1 16:20:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2137 GUID=8433C189C2CF11E18E62CE588177C75E
    120355: Jul  1 09:20:33.010: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5E7C; count=1
    120356: Jul  1 09:20:33.010: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2084
    UC540#sh logging
    Syslog logging: enabled (0 messages dropped, 3 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)

    No Active Message Discriminator.



    No Inactive Message Discriminator.


        Console logging: disabled
        Monitor logging: disabled
        Buffer logging:  level debugging, 43 messages logged, xml disabled,
                        filtering disabled
        Exception Logging: size (4096 bytes)
        Count and timestamp logging messages: disabled
        Persistent logging: disabled

    No active filter modules.

        Trap logging: level informational, 621 message lines logged
            Logging Source-Interface:       VRF Name:

    Log Buffer (10000000 bytes):

    120335: Jul  1 16:16:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2103 GUID=F526AA22C2CE11E18E27CE588177C75E
    120336: Jul  1 16:16:33.382: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    120337: Jul  1 16:17:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2108 GUID=07084D22C2CF11E18E30CE588177C75E
    120338: Jul  1 16:17:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2112 GUID=18E9F022C2CF11E18E37CE588177C75E
    120339: Jul  1 16:18:02.994: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2116 GUID=2ACB9322C2CF11E18E3ECE588177C75E
    120340: Jul  1 16:18:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2121 GUID=3CAD3622C2CF11E18E46CE588177C75E
    120341: Jul  1 16:19:02.994: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2124 GUID=4E8ED889C2CF11E18E4CCE588177C75E
    120342: Jul  1 16:19:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2128 GUID=60707B89C2CF11E18E53CE588177C75E
    120343: Jul  1 09:20:02.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120344: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B67C4; count=1
    120345: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2CA8
    120346: Jul  1 09:20:02.986: //-1/72521E898E5A/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B67C4; count=1
    120347: Jul  1 16:20:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2132 GUID=72521E89C2CF11E18E5ACE588177C75E
    120348: Jul  1 09:20:03.010: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B67C4; count=1
    120349: Jul  1 09:20:03.010: //-1/72521E898E5A/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2CA8
    120350: Jul  1 09:20:32.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120351: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5E7C; count=1
    120352: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2084
    120353: Jul  1 09:20:32.986: //-1/8433C1898E62/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B5E7C; count=1
    120354: Jul  1 16:20:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2137 GUID=8433C189C2CF11E18E62CE588177C75E
    120355: Jul  1 09:20:33.010: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5E7C; count=1
    120356: Jul  1 09:20:33.010: //-1/8433C1898E62/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2084
    120357: Jul  1 09:21:02.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120358: Jul  1 09:21:02.986: //-1/961564898E69/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5D1C; count=1
    120359: Jul  1 09:21:02.986: //-1/961564898E69/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2524
    120360: Jul  1 09:21:02.986: //-1/961564898E69/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B5D1C; count=1
    120361: Jul  1 16:21:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2141 GUID=96156489C2CF11E18E69CE588177C75E
    120362: Jul  1 09:21:03.010: //-1/961564898E69/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B5D1C; count=1
    120363: Jul  1 09:21:03.010: //-1/961564898E69/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E2524
    120364: Jul  1 09:21:32.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
      Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120365: Jul  1 09:21:32.986: //-1/A7F707898E70/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B681C; count=1
    120366: Jul  1 09:21:32.986: //-1/A7F707898E70/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E3270
    120367: Jul  1 09:21:32.986: //-1/A7F707898E70/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B681C; count=1
    120368: Jul  1 16:21:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2145 GUID=A7F70789C2CF11E18E70CE588177C75E
    12036

  • Hi, thanks for replying. Here is the debug "debug voice translation"

    120466: Jul  1 16:30:02.194: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    120467: Jul  1 16:30:02.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2217 GUID=D7F2D8C0C2D011E18EEBCE588177C75E
    120468: Jul  1 09:30:32.982: //-1/xxxxxxxxxxxx/RXRULE/regxrule_get_profile_from_trunkgroup_internal:
       Voice port 0x89354CF8 dsl=-1 timeslot=0 does not belong to any trunk group
    120469: Jul  1 09:30:32.986: //-1/E9D47BC08EF3/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B629C; count=1
    120470: Jul  1 09:30:32.986: //-1/E9D47BC08EF3/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E1994
    120471: Jul  1 09:30:32.986: //-1/E9D47BC08EF3/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x8B2B629C; count=1
    120472: Jul  1 16:30:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2222 GUID=E9D47BC0C2D011E18EF3CE588177C75E
    120473: Jul  1 09:30:33.010: //-1/E9D47BC08EF3/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x8B2B629C; count=1
    120474: Jul  1 09:30:33.010: //-1/E9D47BC08EF3/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x898E1994

  • I removed the translation profile from the dialpeer 3000 and this is the debug that I got...

     

    123003: Jul  1 17:02:26.214: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    123004: Jul  1 17:02:32.998: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2489 GUID=623D36FBC2D511E190BDCE588177C75E
    123005: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [41.183.0.13]:5060, local_address:[ - ]
    123006: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
    123007: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
    123008: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
    Received:
    OPTIONS sip:[email protected]:5060 SIP/2.0
    Via: SIP/2.0/UDP 41.183.0.14:5060;branch=z9hG4bK70b98e7a;rport
    From: "asterisk" <sip:[email protected]>;tag=as1caefa33
    To: <sip:[email protected]:5060>
    Contact: <sip:[email protected]>
    Call-ID: [email protected]
    CSeq: 102 OPTIONS
    User-Agent: FNB Connect
    Max-Forwards: 70
    Date: Sun, 01 Jul 2012 17:03:25 GMT
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
    Supported: replaces
    Content-Length: 0


    123009: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
    123010: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x88C71058) with key=[1891] to table
    123011: Jul  1 10:02:39.358: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
    123012: Jul  1 10:02:39.358: //-1/000000000000/SIP/Info/ccsip_iwf_init: 
    123013: Jul  1 10:02:39.358: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
    123014: Jul  1 10:02:39.358: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...
    123015: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port 5060
    123016: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
    123017: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
    123018: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 41.132.55.14
    123019: Jul  1 10:02:39.358: //-1/660A1E6C90C1/SIP/State/sipSPIChangeState: 0x88C71058 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
    123020: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port 5060
    123021: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone PST to SIP default timezone = GMT
    123022: Jul  1 10:02:39.358: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port 5060
    123023: Jul  1 10:02:39.358: //2490/660A1E6C90C1/SIP/Info/ccsipInitPldCallingInfo: non-numeric calling number: asterisk
    123024: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetViaHostInURLFormat: VIA URL:sip:41.183.0.14:5060, Host:41.183.0.14
    123025: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : asterisk
    123026: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetFromCalledPartyId: P-Called-Party-ID header not found
    123027: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetPeerByCalledPartyId: P-Called-Party-ID not found or parse error
    123028: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: No match found for P-Called-Party-ID
    123029: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Peer tag 3000 matched for incoming call
    123030: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
    123031: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
    123032: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
    123033: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 41.132.55.14
    123034: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
    123035: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
    123036: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
    123037: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
    123038: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
    123039: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
    123040: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
    123041: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=0
    123042: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIGetCallConfig: Media forking disabled
    123043: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
    123044: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
    123045: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
    123046: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
    123047: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
    123048: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Error/resolve_media_ip_address_to_bind: bind interface address not available
    123049: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling reg_invoke_ip_first_hop()
    123050: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling ip_best_local_address()
    123051: Jul  1 10:02:39.362: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return addr 41.132.55.14
    123052: Jul  1 10:02:39.362: //2490/660A1E6C90C1/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 41.132.55.14
    123053: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
    123054: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling ip_best_local_address()
    123055: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 41.132.55.14
    123056: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
    123057: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
    123058: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/State/sipSPIChangeState: 0x88C71058 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
    123059: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
    123060: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x88C71058 [email protected]
    123061: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 9BA to table
    123062: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
    123063: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Info/sipSPISendOptionsResponse: Associated container=0x8B2B6A64 to Options Response
    123064: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Transport/sipSPITransportSendMessage: msg=0x8B0CE240, addr=41.183.0.13, port=5060, sentBy_port=5060, local_addr=, is_req=0, transport=1, switch=0, callBack=0x814AD178
    123065: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
    123066: Jul  1 10:02:39.366: //2490/660A1E6C90C1/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
    123067: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 0
    123068: Jul  1 10:02:39.366: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x8B0CE240, addr=41.183.0.13, port=5060, local_addr=, connId=0 for UDP
    123069: Jul  1 10:02:39.370: //2490/660A1E6C90C1/SIP/Msg/ccsipDisplayMsg:
    Sent:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 41.183.0.14:5060;branch=z9hG4bK70b98e7a;rport;received=41.183.0.13
    From: "asterisk" <sip:[email protected]>;tag=as1caefa33
    To: <sip:[email protected]:5060>;tag=112A8A8-31A
    Date: Sun, 01 Jul 2012 17:02:39 GMT
    Call-ID: [email protected]
    Server: Cisco-SIPGateway/IOS-12.x
    CSeq: 102 OPTIONS
    Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
    Allow-Events: telephone-event
    Accept: application/sdp
    Supported: 100rel,timer,resource-priority,replaces,sdp-anat
    Content-Type: application/sdp
    Content-Length: 453

  • If you want to look for yourself you can logon with ssh at megavox.dyndns.org

    I feel like just giving up...

  • Hi,

    Can you just debug the dial-peers matching with the command 'debug voice dialpeer'? I just curious to check which dial-peer it's coming through.

    Nothing to give up - things are getting really interesting, let's solve it. :) Your UC box seems configured on full features, let's diagnose more to find out what's the reason behind.

  • Ok thanks....

     

    UC540#sh run | s dial-peer voice 3000 1000
    dial-peer voice 1000 voip
     permission term
     description ** Incoming call from SIP trunk (FNB Connect) **
     session protocol sipv2
     session target sip-server
     incoming called-number .%
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    UC540#sh run | s dial-peer voice 1000
    dial-peer voice 1000 voip
     permission term
     description ** Incoming call from SIP trunk (FNB Connect) **
     session protocol sipv2
     session target sip-server
     incoming called-number .%
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    UC540#sh run | s dial-peer voice 10003000
    dial-peer voice 3000 voip
     description SIP-OP
     session protocol sipv2
     session target sip-server
     incoming called-number 0877339727
     voice-class codec 1 
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad
    UC540#sh logg
    UC540#sh logging
    Syslog logging: enabled (0 messages dropped, 3 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)

    No Active Message Discriminator.



    No Inactive Message Discriminator.


        Console logging: disabled
        Monitor logging: disabled
        Buffer logging:  level debugging, 489 messages logged, xml disabled,
                        filtering disabled
        Exception Logging: size (4096 bytes)
        Count and timestamp logging messages: disabled
        Persistent logging: disabled

    No active filter modules.

        Trap logging: level informational, 1264 message lines logged
            Logging Source-Interface:       VRF Name:

    Log Buffer (10000000 bytes):

    125163: Jul  1 21:18:50.989: %SYS-5-CONFIG_I: Configured from console by cisco on vty1 (192.168.10.11)
    125164: Jul  1 21:19:02.997: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4616 GUID=3764B042C2F911E19F0FCE588177C75E
    125165: Jul  1 14:19:29.877: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125166: Jul  1 14:19:29.881: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125167: Jul  1 14:19:29.881: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125168: Jul  1 14:19:29.881: //-1/476CB1519F15/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125169: Jul  1 14:19:29.881: //-1/476CB1519F15/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125170: Jul  1 14:19:29.881: //-1/476CB1519F15/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125171: Jul  1 14:19:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125172: Jul  1 14:19:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125173: Jul  1 14:19:32.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125174: Jul  1 14:19:32.993: //-1/494653429F16/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125175: Jul  1 14:19:32.993: //-1/494653429F16/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125176: Jul  1 14:19:32.993: //-1/494653429F16/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125177: Jul  1 14:19:32.993: //-1/494653429F16/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125178: Jul  1 14:19:32.993: //-1/494653429F16/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125179: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125180: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125181: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125182: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125183: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125184: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125185: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125186: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125187: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125188: Jul  1 14:19:32.997: //-1/494653429F16/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125189: Jul  1 21:19:33.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4620 GUID=49465342C2F911E19F16CE588177C75E
    125190: Jul  1 14:19:40.477: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125191: Jul  1 14:19:40.477: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125192: Jul  1 14:19:40.477: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125193: Jul  1 14:19:40.477: //-1/4DBE20DD9F1A/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125194: Jul  1 14:19:40.477: //-1/4DBE20DD9F1A/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125195: Jul  1 14:19:40.477: //-1/4DBE20DD9F1A/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125196: Jul  1 14:19:51.077: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125197: Jul  1 14:19:51.077: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125198: Jul  1 14:19:51.077: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125199: Jul  1 14:19:51.077: //-1/540F8FD09F1B/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125200: Jul  1 14:19:51.077: //-1/540F8FD09F1B/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125201: Jul  1 14:19:51.077: //-1/540F8FD09F1B/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125202: Jul  1 14:20:01.673: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125203: Jul  1 14:20:01.673: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125204: Jul  1 14:20:01.677: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125205: Jul  1 14:20:01.677: //-1/5A60612F9F1C/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125206: Jul  1 14:20:01.677: //-1/5A60612F9F1C/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125207: Jul  1 14:20:01.677: //-1/5A60612F9F1C/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125208: Jul  1 14:20:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125209: Jul  1 14:20:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125210: Jul  1 14:20:02.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125211: Jul  1 14:20:02.993: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125212: Jul  1 14:20:02.993: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125213: Jul  1 14:20:02.993: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125214: Jul  1 14:20:02.993: //-1/5B27F6429F1D/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125215: Jul  1 14:20:02.993: //-1/5B27F6429F1D/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125216: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125217: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125218: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125219: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125220: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125221: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125222: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125223: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125224: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125225: Jul  1 14:20:02.997: //-1/5B27F6429F1D/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125226: Jul  1 21:20:03.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4624 GUID=5B27F642C2F911E19F1DCE588177C75E
    125227: Jul  1 14:20:12.273: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125228: Jul  1 14:20:12.273: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125229: Jul  1 14:20:12.273: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125230: Jul  1 14:20:12.273: //-1/60B1D0229F21/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125231: Jul  1 14:20:12.277: //-1/60B1D0229F21/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125232: Jul  1 14:20:12.277: //-1/60B1D0229F21/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125233: Jul  1 14:20:22.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125234: Jul  1 14:20:22.873: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125235: Jul  1 14:20:22.873: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125236: Jul  1 14:20:22.873: //-1/67033DA99F22/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125237: Jul  1 14:20:22.873: //-1/67033DA99F22/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125238: Jul  1 14:20:22.873: //-1/67033DA99F22/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125239: Jul  1 14:20:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125240: Jul  1 14:20:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125241: Jul  1 14:20:32.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125242: Jul  1 14:20:32.993: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125243: Jul  1 14:20:32.993: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125244: Jul  1 14:20:32.993: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125245: Jul  1 14:20:32.993: //-1/6D0999429F24/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125246: Jul  1 14:20:32.993: //-1/6D0999429F24/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125247: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125248: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125249: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125250: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125251: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125252: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125253: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125254: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125255: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125256: Jul  1 14:20:32.997: //-1/6D0999429F24/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125257: Jul  1 21:20:33.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4628 GUID=6D099942C2F911E19F24CE588177C75E
    125258: Jul  1 14:20:33.473: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125259: Jul  1 14:20:33.473: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125260: Jul  1 14:20:33.473: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125261: Jul  1 14:20:33.473: //-1/6D5410749F28/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125262: Jul  1 14:20:33.473: //-1/6D5410749F28/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125263: Jul  1 14:20:33.473: //-1/6D5410749F28/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125264: Jul  1 14:20:44.069: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125265: Jul  1 14:20:44.069: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125266: Jul  1 14:20:44.069: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125267: Jul  1 14:20:44.073: //-1/73A580009F29/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125268: Jul  1 14:20:44.073: //-1/73A580009F29/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125269: Jul  1 14:20:44.073: //-1/73A580009F29/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125270: Jul  1 14:20:54.669: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125271: Jul  1 14:20:54.669: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125272: Jul  1 14:20:54.669: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125273: Jul  1 14:20:54.669: //-1/79F6ECEF9F2A/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125274: Jul  1 14:20:54.673: //-1/79F6ECEF9F2A/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125275: Jul  1 14:20:54.673: //-1/79F6ECEF9F2A/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125276: Jul  1 14:21:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125277: Jul  1 14:21:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125278: Jul  1 14:21:02.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125279: Jul  1 14:21:02.993: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125280: Jul  1 14:21:02.993: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125281: Jul  1 14:21:02.993: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125282: Jul  1 14:21:02.993: //-1/7EEB3C429F2B/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125283: Jul  1 14:21:02.993: //-1/7EEB3C429F2B/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125284: Jul  1 14:21:02.997: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125285: Jul  1 14:21:02.997: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125286: Jul  1 14:21:02.997: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125287: Jul  1 14:21:02.997: //-1/7EEB3C429F2B/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125288: Jul  1 14:21:02.997: //-1/7EEB3C429F2B/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125289: Jul  1 14:21:03.001: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125290: Jul  1 14:21:03.001: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125291: Jul  1 14:21:03.001: //-1/7EEB3C429F2B/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125292: Jul  1 14:21:03.001: //-1/7EEB3C429F2B/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125293: Jul  1 14:21:03.001: //-1/7EEB3C429F2B/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125294: Jul  1 21:21:03.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4632 GUID=7EEB3C42C2F911E19F2BCE588177C75E
    125295: Jul  1 14:21:05.269: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125296: Jul  1 14:21:05.269: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125297: Jul  1 14:21:05.269: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125298: Jul  1 14:21:05.269: //-1/80485C7A9F2F/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125299: Jul  1 14:21:05.269: //-1/80485C7A9F2F/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125300: Jul  1 14:21:05.269: //-1/80485C7A9F2F/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125301: Jul  1 14:21:15.869: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125302: Jul  1 14:21:15.869: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125303: Jul  1 14:21:15.869: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125304: Jul  1 14:21:15.869: //-1/86992D419F30/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125305: Jul  1 14:21:15.869: //-1/86992D419F30/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125306: Jul  1 14:21:15.869: //-1/86992D419F30/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125307: Jul  1 14:21:26.465: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125308: Jul  1 14:21:26.469: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125309: Jul  1 14:21:26.469: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125310: Jul  1 14:21:26.469: //-1/8CEA9CCD9F31/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125311: Jul  1 14:21:26.469: //-1/8CEA9CCD9F31/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125312: Jul  1 14:21:26.469: //-1/8CEA9CCD9F31/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125313: Jul  1 14:21:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125314: Jul  1 14:21:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125315: Jul  1 14:21:32.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125316: Jul  1 14:21:32.993: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125317: Jul  1 14:21:32.993: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125318: Jul  1 14:21:32.993: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125319: Jul  1 14:21:32.993: //-1/90CCDEAA9F32/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125320: Jul  1 14:21:32.993: //-1/90CCDEAA9F32/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125321: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125322: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125323: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125324: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125325: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125326: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125327: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125328: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125329: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125330: Jul  1 14:21:32.997: //-1/90CCDEAA9F32/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125331: Jul  1 21:21:33.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4636 GUID=90CCDEAAC2F911E19F32CE588177C75E
    125332: Jul  1 14:21:37.065: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125333: Jul  1 14:21:37.065: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125334: Jul  1 14:21:37.065: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125335: Jul  1 14:21:37.065: //-1/933C0BC09F36/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125336: Jul  1 14:21:37.065: //-1/933C0BC09F36/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125337: Jul  1 14:21:37.065: //-1/933C0BC09F36/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125338: Jul  1 14:21:47.665: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125339: Jul  1 14:21:47.665: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125340: Jul  1 14:21:47.665: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125341: Jul  1 14:21:47.665: //-1/998CDD1F9F37/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125342: Jul  1 14:21:47.665: //-1/998CDD1F9F37/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125343: Jul  1 14:21:47.665: //-1/998CDD1F9F37/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125344: Jul  1 14:21:58.261: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125345: Jul  1 14:21:58.261: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125346: Jul  1 14:21:58.261: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125347: Jul  1 14:21:58.261: //-1/9FDE4C129F38/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125348: Jul  1 14:21:58.261: //-1/9FDE4C129F38/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125349: Jul  1 14:21:58.261: //-1/9FDE4C129F38/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125350: Jul  1 14:22:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125351: Jul  1 14:22:02.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125352: Jul  1 14:22:02.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125353: Jul  1 14:22:02.993: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125354: Jul  1 14:22:02.993: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125355: Jul  1 14:22:02.993: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125356: Jul  1 14:22:02.993: //-1/A2AE81AA9F39/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125357: Jul  1 14:22:02.993: //-1/A2AE81AA9F39/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125358: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125359: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125360: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125361: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125362: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125363: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125364: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125365: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125366: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125367: Jul  1 14:22:02.997: //-1/A2AE81AA9F39/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125368: Jul  1 21:22:03.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 4640 GUID=A2AE81AAC2F911E19F39CE588177C75E
    125369: Jul  1 14:22:08.861: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125370: Jul  1 14:22:08.861: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125371: Jul  1 14:22:08.861: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125372: Jul  1 14:22:08.861: //-1/A62F1D719F3E/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125373: Jul  1 14:22:08.861: //-1/A62F1D719F3E/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125374: Jul  1 14:22:08.861: //-1/A62F1D719F3E/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125375: Jul  1 14:22:19.457: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125376: Jul  1 14:22:19.461: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125377: Jul  1 14:22:19.461: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125378: Jul  1 14:22:19.461: //-1/AC808C649F3F/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125379: Jul  1 14:22:19.461: //-1/AC808C649F3F/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125380: Jul  1 14:22:19.461: //-1/AC808C649F3F/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125381: Jul  1 14:22:30.057: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125382: Jul  1 14:22:30.057: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    125383: Jul  1 14:22:30.057: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    125384: Jul  1 14:22:30.057: //-1/B2D1FBF09F40/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125385: Jul  1 14:22:30.057: //-1/B2D1FBF09F40/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    125386: Jul  1 14:22:30.057: //-1/B2D1FBF09F40/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125387: Jul  1 14:22:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    125388: Jul  1 14:22:32.981: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    125389: Jul  1 14:22:32.981: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    125390: Jul  1 14:22:32.993: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    125391: Jul  1 14:22:32.993: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    125392: Jul  1 14:22:32.993: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    125393: Jul  1 14:22:32.993: //-1/B49024AA9F41/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    125394: Jul  1 14:22:32.993: //-1/B49024AA9F41/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    125395: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    125396: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    125397: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125398: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125399: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125400: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    125401: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    125402: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    125403: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    125404: Jul  1 14:22:32.997: //-1/B49024AA9F41/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    125405: Jul  1 21:22:33.001: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed

  • Hi,

    Also please post for the output of 'show dial-peer voice summary'. And sure we can find out the issue but no more tonight, time to take some rest. :)

    See you tomorrow.

  • Here is the info you requested

    Ok thank you very much for your help so far...

     

     

    UC540#sh dial-peer voice summary
    dial-peer hunt 0
                 AD                                    PRE PASS                OUT
    TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT    KEEPALIVE
    50     pots  up   up                                0                      down 0/1/0
    1000   voip  up   up                                0  syst sip-server
    1001   voip  up   up             *..                0  syst sip-server
    1003   voip  up   up                                0  syst ipv4:10.1.10.1
    1005   voip  up   up                                0  syst ipv4:10.1.10.1
    1009   voip  up   up                                0  syst ipv4:10.1.10.1
    51     pots  up   up                                0                      down 0/1/1
    52     pots  up   up                                0                      down 0/1/2
    53     pots  up   up                                0                      down 0/1/3
    3000   voip  up   up                                0  syst sip-server
    1020   voip  up   up             010177             1  syst sip-server
    1021   voip  up   up             10177              1  syst sip-server
    1022   voip  up   up             0112               1  syst sip-server
    1023   voip  up   up             112                1  syst sip-server
    1024   voip  up   up             010111             1  syst sip-server
    1025   voip  up   up             10111              1  syst sip-server
    1026   voip  up   up             00[12345].......-  1  syst sip-server
                                     .
    1027   voip  up   up             0086[01]......     1  syst sip-server
    1028   voip  up   up             0087.......        1  syst sip-server
    1029   voip  up   up             0080.......        1  syst sip-server
    1030   voip  up   up             00[78]........     1  syst sip-server
    1031   voip  up   up             010[12]..          1  syst sip-server
    1032   voip  up   up             00152[89].....     1  syst sip-server
    1033   voip  up   up             000T               1  syst sip-server
    20001  pots  up   up             BCD$               0                           50/0/9
    20002  pots  up   up             201$               0                           50/0/201
    20003  pots  up   up             0877339727$       10                           50/0/298
    20004  pots  up   up             A801...            0                           50/0/299
    20005  pots  up   up             A800...            0                           50/0/300
    20006  pots  up   up             0877339727$        9                           50/0/201

  • Ok removed dial-peer 1000

    dial-peer voice 1000 voip
     permission term
     description ** Incoming call from SIP trunk (FNB Connect) **
     session protocol sipv2
     session target sip-server
     incoming called-number .%
     voice-class codec 1
     voice-class sip dtmf-relay force rtp-nte
     dtmf-relay rtp-nte
     fax rate 14400
     fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711ulaw
     ip qos dscp cs5 media
     ip qos dscp cs4 signaling
     no vad

    Removed

    ephone-dn  298
     number 0877339727
     description SIP Main Number registration
     preference 10

    modified

    ephone-dn  201  octo-line
     number 201 secondary 0877339727 no-reg primary
     label 201
     description Sybrand Struwig
     name sybrand struwig
     hold-alert 15 originator

    UC540#sh sip-ua register status
    Line                             peer       expires(sec) registered P-Associ-URI
    ================================ ========== ============ ========== ============
    0877339727                       20006      2347         yes

    UC540#sh dial-peer voice summary
    dial-peer hunt 0
                 AD                                    PRE PASS                OUT
    TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT    KEEPALIVE
    50     pots  up   up                                0                      down 0/1/0
    1001   voip  up   up             *..                0  syst sip-server
    1003   voip  up   up                                0  syst ipv4:10.1.10.1
    1005   voip  up   up                                0  syst ipv4:10.1.10.1
    1009   voip  up   up                                0  syst ipv4:10.1.10.1
    51     pots  up   up                                0                      down 0/1/1
    52     pots  up   up                                0                      down 0/1/2
    53     pots  up   up                                0                      down 0/1/3
    3000   voip  up   up                                1  syst sip-server
    1020   voip  up   up             010177             1  syst sip-server
    1021   voip  up   up             10177              1  syst sip-server
    1022   voip  up   up             0112               1  syst sip-server
    1023   voip  up   up             112                1  syst sip-server
    1024   voip  up   up             010111             1  syst sip-server
    1025   voip  up   up             10111              1  syst sip-server
    1026   voip  up   up             00[12345].......-  1  syst sip-server
                                     .
    1027   voip  up   up             0086[01]......     1  syst sip-server
    1028   voip  up   up             0087.......        1  syst sip-server
    1029   voip  up   up             0080.......        1  syst sip-server
    1030   voip  up   up             00[78]........     1  syst sip-server
    1031   voip  up   up             010[12]..          1  syst sip-server
    1032   voip  up   up             00152[89].....     1  syst sip-server
    1033   voip  up   up             000T               1  syst sip-server
    20001  pots  up   up             BCD$               0                           50/0/9
    20002  pots  up   up             201$               0                           50/0/201
    20004  pots  up   up             A801...            0                           50/0/299
    20005  pots  up   up             A800...            0                           50/0/300
    20006  pots  up   up             0877339727$        9                           50/0/201

     

    135119: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135120: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135121: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135122: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135123: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135124: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135125: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135126: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135127: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135128: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135129: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135130: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135131: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135132: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135133: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135134: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135135: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135136: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135137: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135138: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135139: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:[email protected]:5060
    135140: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135141: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135142: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135143: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135144: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135145: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135146: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135147: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135148: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135149: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135150: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135151: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135152: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135153: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135154: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135155: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135156: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135157: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135158: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135159: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135160: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135161: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135162: Jul  1 22:49:07.383: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    135163: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135164: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135165: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135166: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135167: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135168: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135169: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135170: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135171: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135172: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135173: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135174: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135175: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135176: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135177: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135178: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135179: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135180: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135181: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135182: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135183: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:[email protected]:5060
    135184: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135185: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135186: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135187: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135188: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135189: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135190: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135191: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135192: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135193: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135194: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135195: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135196: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135197: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135198: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135199: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135200: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135201: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135202: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135203: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135204: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135205: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135206: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135207: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
    135208: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135209: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=BCDT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135210: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135211: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135212: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
    135213: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135214: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=BCDT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135215: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=20001 Is Matched
    135216: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135217: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    135218: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135219: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135220: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    135221: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    135222: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=A, Expanded String=A, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135223: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/MatchNextPeer:
       Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=20004
    135224: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=1
    135225: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    135226: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    135227: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    135228: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    135229: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    135230: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=AB, Expanded String=AB, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135231: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=-1
    135232: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    135233: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    135234: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    135235: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    135236: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    135237: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=ABC, Expanded String=ABC, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135238: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=-1
    135239: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    135240: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    135241: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    135242: Jul  1 22:49:21.999: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 5369 GUID=D55D924BC30511E1A415CE588177C75E
    135243: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135244: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135245: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135246: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135247: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135248: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135249: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135250: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135251: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135252: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135253: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135254: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135255: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135256: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135257: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135258: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135259: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135260: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135261: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Result=-1
    135262: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:[email protected]
    135263: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:[email protected]:5060
    135264: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135265: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135266: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Result=-1
    135267: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:[email protected]
    135268: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135269: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135270: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135271: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Result=-1
    135272: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:[email protected]
    135273: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135274: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135275: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135276: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Result=-1
    135277: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:[email protected]
    135278: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135279: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135280: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135281: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135282: Jul  2 01:49:27.635: //-1/D8BBFFF7A419/DPM/dpMatchPeertype:[email protected]
    135283: Jul  2 01:49:27.635: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135284: Jul  2 01:49:27.635: //-1/D8BBFFF7A419/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135285: Jul  2 01:49:27.635: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135286: Jul  2 01:49:38.227: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135287: Jul  2 01:49:38.227: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135288: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135289: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135290: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135291: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135292: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135293: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135294: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135295: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135296: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135297: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135298: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135299: Jul  2 01:49:38.231: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135300: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135301: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135302: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135303: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135304: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Result=-1
    135305: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:[email protected]
    135306: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:0877339[email protected]:5060
    135307: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135308: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135309: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Result=-1
    135310: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:[email protected]
    135311: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135312: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135313: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135314: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Result=-1
    135315: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:[email protected]
    135316: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135317: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135318: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135319: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Result=-1
    135320: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:[email protected]
    135321: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135322: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135323: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135324: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135325: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchPeertype:[email protected]
    135326: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135327: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135328: Jul  2 01:49:38.231: //-1/DF0D6F83A41A/DPM/dpAssociateIncomingPeerSPI:[email protected]
    UC540#sh
    UC540#show logg
    UC540#show logging
    Syslog logging: enabled (0 messages dropped, 3 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled)

    No Active Message Discriminator.



    No Inactive Message Discriminator.


        Console logging: disabled
        Monitor logging: disabled
        Buffer logging:  level debugging, 906 messages logged, xml disabled,
                        filtering disabled
        Exception Logging: size (4096 bytes)
        Count and timestamp logging messages: disabled
        Persistent logging: disabled

    No active filter modules.

        Trap logging: level informational, 1511 message lines logged
            Logging Source-Interface:       VRF Name:

    Log Buffer (10000000 bytes):

    135119: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135120: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135121: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135122: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135123: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135124: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135125: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135126: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135127: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135128: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135129: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135130: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135131: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135132: Jul  2 01:49:06.435: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135133: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135134: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135135: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135136: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135137: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135138: Jul  2 01:49:06.435: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135139: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:[email protected]:5060
    135140: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135141: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135142: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135143: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135144: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135145: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135146: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135147: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135148: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135149: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135150: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135151: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135152: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Result=-1
    135153: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135154: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135155: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135156: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135157: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135158: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchPeertype:[email protected]
    135159: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135160: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135161: Jul  2 01:49:06.439: //-1/CC1A5C65A413/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135162: Jul  1 22:49:07.383: %SYS-5-CONFIG_I: Configured from console by cisco on vty0 (192.168.10.11)
    135163: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135164: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135165: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135166: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135167: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135168: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135169: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135170: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135171: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135172: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135173: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135174: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135175: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135176: Jul  2 01:49:17.031: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135177: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135178: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135179: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135180: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135181: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135182: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135183: Jul  2 01:49:17.031: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_REQUEST_URI; URI=sip:[email protected]:5060
    135184: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135185: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135186: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135187: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135188: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_TO_URI; URI=sip:[email protected]:5060
    135189: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135190: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135191: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135192: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135193: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_FROM_URI; URI=sip:[email protected]
    135194: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135195: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135196: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Result=-1
    135197: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135198: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=0877339727
    135199: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135200: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchCore:
       Dial String=0877339727, Expanded String=0877339727, Calling Number=
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135201: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=3000 Is Matched
    135202: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchPeertype:[email protected]
    135203: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3000
    135204: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135205: Jul  2 01:49:17.035: //-1/D26B2F31A414/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135206: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=BCD, Called Number=, Voice-Interface=0x89354CF8,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135207: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=BCD
    135208: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135209: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=BCDT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135210: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135211: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135212: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=BCD
    135213: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135214: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=BCDT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135215: Jul  2 01:49:21.971: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
       Result=Success(0); Incoming Dial-peer=20001 Is Matched
    135216: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135217: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20001
    135218: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0
    135219: Jul  2 01:49:21.975: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:[email protected]
    135220: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=A, Peer Info Type=DIALPEER_INFO_SPEECH
    135221: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=A
    135222: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=A, Expanded String=A, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135223: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/MatchNextPeer:
       Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=20004
    135224: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=1
    135225: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Result=Partial Matches(1) after DP_MATCH_DEST
    135226: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=A, saf_enabled=1, saf_dndb_lookup=0, dp_result=1
    135227: Jul  2 01:49:21.991: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=MORE_DIGITS_NEEDED(1)
    135228: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=AB, Peer Info Type=DIALPEER_INFO_SPEECH
    135229: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=AB
    135230: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=AB, Expanded String=AB, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135231: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=-1
    135232: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    135233: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=AB, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    135234: Jul  2 01:49:21.995: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    135235: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Calling Number=, Called Number=ABC, Peer Info Type=DIALPEER_INFO_SPEECH
    135236: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       Match Rule=DP_MATCH_DEST; Called Number=ABC
    135237: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchCore:
       Dial String=ABC, Expanded String=ABC, Calling Number=
       Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
    135238: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchCore:
       Result=-1
    135239: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersCore:
       No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)
    135240: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchSafModulePlugin:
       dialstring=ABC, saf_enabled=1, saf_dndb_lookup=0, dp_result=-1
    135241: Jul  2 01:49:21.999: //-1/D55D924BA415/DPM/dpMatchPeersMoreArg:
       Result=NO_MATCH(-1)
    135242: Jul  1 22:49:21.999: %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 5369 GUID=D55D924BC30511E1A415CE588177C75E
    135243: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135244: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ANSWER; Calling Number=asterisk
    135245: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135246: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135247: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135248: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135249: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_ORIGINATE; Calling Number=asterisk
    135250: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
       Is Incoming=TRUE, Number Expansion=FALSE
    135251: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Dial String=, Expanded String=, Calling Number=asteriskT
       Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
    135252: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
       Result=-1
    135253: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:[email protected]
    135254: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
       Result=NO_MATCH(-1) After All Match Rules Attempt
    135255: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:
       dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1
    135256: Jul  2 01:49:27.631: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:[email protected]
    135257: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Calling Number=asterisk, Called Number=, Voice-Interface=0x0,
       Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
       Peer Info Type=DIALPEER_INFO_SPEECH
    135258: Jul  2 01:49:27.631: //-1/D8BBFFF7A419/DPM/dpAssociateIncomingPeerCore:
       Match Rule=DP_MATCH_VIA_URI; URI=sip:41.183.0.14:5060
    135259: Jul  2 01:49:27.631: //-1/D8BBFFF7

  • This debug was taken withot me even making a call to the sip number...

    I have an idea that there is no invites comming in from the ITSP

  • Some more information

    SIP UAS CALL INFO
    Call 1
    SIP Call ID                : [email protected]
       State of the call       : SIP_STATE_OPTIONS_WAIT (27)
       Substate of the call    : SUBSTATE_NONE (0)
       Calling Number          : asterisk
       Called Number           : 0877339727
       Bit Flags               : 0x40000C 0x10000104 0x10
       CC Call ID              : 5685
       Source IP Address (Sig ): 41.132.55.14
       Destn SIP Req Addr:Port : [41.183.0.13]:0
       Destn SIP Resp Addr:Port: [41.183.0.13]:5060
       Destination Name        : 41.183.0.13
       Number of Media Streams : 1
       Number of Active Streams: 0
       RTP Fork Object         : 0x0
       Media Mode              : flow-through
       Media Stream 1
         State of the stream      : STREAM_IDLE
         Stream Call ID           : -1
         Stream Type              : voice+dtmf (1)
         Stream Media Addr Type   : 1
         Negotiated Codec         : No Codec    (0 bytes)
         Codec Payload Type       : 255 (None)
         Negotiated Dtmf-relay    : inband-voice
         Dtmf-relay Payload Type  : 0
         QoS ID                   : -1
         Local QoS Strength       : BestEffort
         Negotiated QoS Strength  : BestEffort
         Negotiated QoS Direction : None
         Local QoS Status         : None
         Media Source IP Addr:Port: [41.132.55.14]:0


    Options-Ping    ENABLED:NO    ACTIVE:NO
    Call 2
    SIP Call ID                : [email protected]
       State of the call       : SIP_STATE_OPTIONS_WAIT (27)
       Substate of the call    : SUBSTATE_NONE (0)
       Calling Number          : asterisk
       Called Number           : 0877339727
       Bit Flags               : 0x40000C 0x10000104 0x10
       CC Call ID              : 5686
       Source IP Address (Sig ): 41.132.55.14
       Destn SIP Req Addr:Port : [41.183.0.13]:0
       Destn SIP Resp Addr:Port: [41.183.0.13]:5060
       Destination Name        : 41.183.0.13
       Number of Media Streams : 1
       Number of Active Streams: 0
       RTP Fork Object         : 0x0
       Media Mode              : flow-through
       Media Stream 1
         State of the stream      : STREAM_IDLE
         Stream Call ID           : -1
         Stream Type              : voice+dtmf (1)
         Stream Media Addr Type   : 1
         Negotiated Codec         : No Codec    (0 bytes)
         Codec Payload Type       : 255 (None)
         Negotiated Dtmf-relay    : inband-voice
         Dtmf-relay Payload Type  : 0
         QoS ID                   : -1
         Local QoS Strength       : BestEffort
         Negotiated QoS Strength  : BestEffort
         Negotiated QoS Direction : None
         Local QoS Status         : None
         Media Source IP Addr:Port: [41.132.55.14]:0


    Options-Ping    ENABLED:NO    ACTIVE:NO
    Call 3
    SIP Call ID                : [email protected]
       State of the call       : SIP_STATE_OPTIONS_WAIT (27)
       Substate of the call    : SUBSTATE_NONE (0)
       Calling Number          : asterisk
       Called Number           : 0877339727
       Bit Flags               : 0x40000C 0x10000104 0x10
       CC Call ID              : 5684
       Source IP Address (Sig ): 41.132.55.14
       Destn SIP Req Addr:Port : [41.183.0.13]:0
       Destn SIP Resp Addr:Port: [41.183.0.13]:5060
       Destination Name        : 41.183.0.13
       Number of Media Streams : 1
       Number of Active Streams: 0
       RTP Fork Object         : 0x0
       Media Mode              : flow-through
       Media Stream 1
         State of the stream      : STREAM_IDLE
         Stream Call ID           : -1
         Stream Type              : voice+dtmf (1)
         Stream Media Addr Type   : 1
         Negotiated Codec         : No Codec    (0 bytes)
         Codec Payload Type       : 255 (None)
         Negotiated Dtmf-relay    : inband-voice
         Dtmf-relay Payload Type  : 0
         QoS ID                   : -1
         Local QoS Strength       : BestEffort
         Negotiated QoS Strength  : BestEffort
         Negotiated QoS Direction : None
         Local QoS Status         : None
         Media Source IP Addr:Port: [41.132.55.14]:0


    Options-Ping    ENABLED:NO    ACTIVE:NO
       Number of SIP User Agent Server(UAS) calls: 3

  • Hi, 

    Tell me one thing, did incoming calls succeed ever thru this SIP trunk? The incoming call is matching well and coming through the expected dial-peer. But for any reason the call is getting dropped. Is it possible to get the disconnect reason from the ITSP? If not, can you test the incoming call through any FXO ports by matching the same incoming dial-peer?

  • Hi, this is the response I'm getting from mythe ITSP

    Please
    advise the client he is not replying to the OPTIONS these
    are used to gauge if the remote UAs up. He wont get any
    invite's until he replies to the OPTIONS.



    Kindly quote the below RFC extract to the (section 11):



    http://www.ietf.org/rfc/rfc3261.txt



    --

       As is the case for general UA behavior, the transaction layer can
       return a timeout error if the OPTIONS yields no response.  This may
       indicate that the target is unreachable and hence unavailable.
       ...


    --





    Thanks,

    Chris G

  • This is the debug I sent to them...

     

    000632: Jun 29 08:36:58.427:

    //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

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

    Peer Info Type=DIALPEER_INFO_SPEECH

    000633: Jun 29 08:36:58.427:

    //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

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

    000634: Jun 29 08:36:58.427:
    //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

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

    000635: Jun 29 08:36:58.431:

    //-1/6C9BDD43805B/DPM/dpAssociateIncomingPeerCore:

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

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

    Peer Info Type=DIALPEER_INFO_SPEECH

    000636: Jun 29 08:36:58.431:

    //-1/6C9BDD43805B/DPM/dpAssociateIncomingPeerCore:

    Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming
    Dial-peer=3000

    000637: Jun 29 08:36:58.431:
    //-1/6C9BDD43805B/DPM/dpMatchSafModulePlugin:

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

  • OPTIONS sip:[email protected]:5060
    SIP/2.0

    Via: SIP/2.0/UDP
    41.183.0.14:5060;branch=z9hG4bK331d034a;rport

    From: "asterisk"
    <sip:[email protected]>;tag=as39c634e7

    To: <sip:[email protected]:5060>

    Contact: <sip:[email protected]>

    Call-ID:
    [email protected]

    CSeq: 102 OPTIONS

    User-Agent: FNB Connect

    Max-Forwards: 70

    Date: Fri, 29 Jun 2012 11:02:41 GMT

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

    Supported: replaces

    Content-Length: 0

     

     

    000099: Jun 29 11:01:55.543:
    //40/AC7D5A8C802F/SIP/Msg/ccsipDisplayMsg:

    Sent:

    SIP/2.0 200 OK

    Via: SIP/2.0/UDP
    41.183.0.14:5060;branch=z9hG4bK331d034a;rport;received=41.183.0.13

    From: "asterisk"
    <sip:[email protected]183.0.14>;tag=as39c634e7

    To:
    <sip:[email protected]:5060>;tag=55FDC-1F

    Date: Fri, 29 Jun 2012 11:01:55 GMT

    Call-ID:
    [email protected]

    Server: Cisco-SIPGateway/IOS-12.x

    CSeq: 102 OPTIONS

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

    Allow-Events: telephone-event

    Accept: application/sdp

    Supported:
    100rel,timer,resource-priority,replaces,sdp-anat

    Content-Type: application/sdp

    Content-Length: 453

     

    v=0

    o=CiscoSystemsSIP-GW-UserAgent 8345 8787 IN
    IP4 41.132.55.14

    s=SIP Call

    c=IN IP4 41.132.55.14

    t=0 0

    m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3

    c=IN IP4 41.132.55.14

    m=image 0 udptl t38

    c=IN IP4 41.132.55.14

    a=T38FaxVersion:0

    a=T38MaxBitRate:14400

    a=T38FaxFillBitRemoval:0

    a=T38FaxTranscodingMMR:0

    a=T38FaxTranscodingJBIG:0

    a=T38FaxRateManagement:transferredTCF

    a=T38FaxMaxBuffer:200

    a=T38FaxMaxDatagram:320


    a=T38FaxUdpEC:t38UDPRedundancy

  • The ITSP also confirmed that they can't reach my device....

  • Does the ITSP supports G.729 codec in call? I can see your voice class codec contains G.729 codec only. Test once using the G.711 codec, or hardcode the codec command inside the dial-peer.

     

    Have you noticed this error?

    %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect failed): IEC=1.1.179.11.28.6 on callID 2217

    Though the error description into the Cisco VoIP Internal Error Codes says something different but I'm also curious to it - will be searching more for this error.

    Last thing, I have seen your FXO ports are configured - make an inbound thru any FXO port and match the dial-peer 3000 and let's see what happens.

     






  • Hi, I tried to post on the forums but for some reason my posts don't
    want to go through.

    Here is the response I got from the SP after I sent the debug
    below...

    Does it make any sense and is there a work around?



    Thanks again for ll your help so far...



    Good day Mr Struwig

     

     

    This is the responds I got from the engineer.

     

    We

    use OPTIONS as an application layer "ping" as well as to gauge
    remote capability, the vast majority of FNB Connect user's are
    mobile. We require this to keep state within the REGISTER
    timeout. If you don't answer we consider you lagged and take
    voicemail. This is to keep up our customer expectation as mobile
    networks are a best effort service and don't always perform as
    they should.




    We officially only support a reply to a OPTIONs packet as a
    keep-alive. We do an aggressive register timeout to accommodate
    our mobile user's (20 seconds). You can perhaps try to re-reg
    every 15 seconds to prevent the need for an OPTIONs "ping". I
    have not tested this in our environment, first prize would be to
    reply to our OPTIONs packet's as per RFC.

    Kind regards



    Nkosana Mabena

    Technical Support

    FNB Connect

    Personal Banking



    7th Floor, 3 First Place

    Cnr Simmonds and Prichard streets

    Bank city

    Johannesburg





    Tel (087) 5750001,

    e-mail
    [email protected]

    www.fnbconnect.co.za



    First National Bank - a division of FirstRand Bank Limited.

    An Authorised Financial Services and Credit Provider (NCRCP20).



    ‘Consider the effect on the environment before
    printing this email.’

     

     


    From: Sybrand Struwig
    [mailto:[email protected]]


    Sent: 03 July 2012 01:33 AM

    To: FNBConnect Support

    Cc: Mariyam Hachikyan

    Subject: Fwd: FW: Att Nkosona


     

    Nkosana,



    Inbound calls from the ITSP is not being routed to my UC540, the
    only thing that is getting trough is 102 OPTIONS messages and my
    UC540 is sending SIP/2.0 200 OK messages back.

    Incoming calls from the SIP trunk was working on the 2012/02/16
    12:31 PM and as I can recall they just had a server crash the week
    end before...

    Do you see the OK answer of my device to  your Option message and
    do you not accept it or are you even receiving it?.



    Here is the debug for one 102 OPTIONS Message that came through...



    Received:

    OPTIONS sip:[email protected]:5060
    SIP/2.0

    Via: SIP/2.0/UDP 41.183.0.14:5060;branch=z9hG4bK368371e6;rport

    From: "asterisk" <sip:[email protected]>;tag=as1843c0de

    To: <sip:[email protected]:5060>

    Contact: <sip:[email protected]>

    Call-ID: [email protected]

    CSeq: 102 OPTIONS

    User-Agent: FNB Connect

    Max-Forwards: 70

    Date: Mon, 02 Jul 2012 22:28:57 GMT

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

    Supported: replaces

    Content-Length: 0





    619633: Jul  3 01:28:57.129:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking
    Invite Dialog

    619634: Jul  3 01:28:57.129:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added
    context(0x88BD3A18) with key=[12618] to table

    619635: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

    619636: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_iwf_init: 

    619637: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:


    619638: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

    619639: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port
    5060

    619640: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619641: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619642: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14


    619643: Jul  3 01:28:57.133:
    //-1/25B72A23B227/SIP/State/sipSPIChangeState: 0x88BD3A18 : State
    change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE,
    SUBSTATE_NONE)

    619644: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy
    Port 5060


    619645: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone
    EET to SIP default timezone = GMT

    619646: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port
    5060

    619647: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/ccsipInitPldCallingInfo: non-numeric
    calling number: asterisk


    619648: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetViaHostInURLFormat: VIA
    URL:sip:41.183.0.14:5060,
    Host:41.183.0.14

    619649: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetShrlPeer: Try match
    incoming dialpeer for Calling number: : asterisk


    619650: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetFromCalledPartyId:
    P-Called-Party-ID header not found

    619651: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetPeerByCalledPartyId:
    P-Called-Party-ID not found or parse error

    619652: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    No match found for P-Called-Party-ID


    619653: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    Peer tag 3000 matched for incoming call


    619654: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    Precondition tag absent in Require/Supported header


    619655: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619656: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619657: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14

    619658: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Precondition tag
    absent in Require/Supported header

    619659: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Media
    Antitrombone disabled

    619660: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: Storing the
    configured mode as FLOW-THROUGH

    619661: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: xcoder
    high-density disabled

    619662: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set
    to FLOW_THROUGH

    619663: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Using Voice Class
    Codec, tag = 1 and offer-all is = FALSE

    619664: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Media/sipSPICopyStunConfigFromPeerToCCB:
    Firewall traversal is not enabled

    619665: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetModemInfoPerCall:
    peer_callID=0

    619666: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Media forking
    disabled

    619667: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetOverlapConfiguration: Overlap
    signaling: FALSE: Endpt: SIP Trunk

    619668: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr
    passthrough config:1 tag:0

    619669: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list
    config:2 tag:0

    619670: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_build_consolidated_header_list:

    Both passthru and copylist are disabled

    619671: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIValidateStreamAddrType:
    stream:1, Mode : 1

    619672: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619673: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling
    ip_best_local_address()

    619674: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return
    addr 41.132.55.14

    619675: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Media/sipSPISetMediaSrcAddr: Media src addr
    for stream 1 = 41.132.55.14

    619676: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619677: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619678: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14

    619679: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP
    SPI : SIPSPI_EV_CC_OPTIONS_RESP

    619680: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sact_idle_new_message_options:
    ccsip_api_options_ind returned: SIP_SUCCESS

    619681: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/State/sipSPIChangeState: 0x88BD3A18 : State
    change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT,
    SUBSTATE_NONE)

    619682: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding
    to UAS Request table.

    619683: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddCcbToTable: Added to table.
    ccb=0x88BD3A18
    [email protected]

    619684: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddccCallIdToTable: Adding call
    id 4133 to table

    619685: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
    ccsip_spi_get_msg_type returned: 3 for event 38

    619686: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPISendOptionsResponse: Associated
    container=0x8B2B275C to Options Response

    619687: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipSPITransportSendMessage:
    msg=0x885B5AC4, addr=41.183.0.13, port=5060, sentBy_port=5060,
    local_addr=, is_req=0, transport=1, switch=0, callBack=0x814AD178

    619688: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipSPITransportSendMessage:
    Proceedable for sending msg immediately

    619689: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg: switch
    transport is 0

    619690: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg: Trying
    to send resp=0x885B5AC4 to default port=5060

    619691: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection:
    connection required for raddr:41.183.0.13, rport:5060 with laddr:

    619692: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg:
    Connection obtained...sending msg=0x885B5AC4

    619693: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
    send for msg=0x885B5AC4, addr=41.183.0.13, port=5060, local_addr=,
    connId=2 for UDP

    619694: Jul  3 01:28:57.145:
    //16691/25B72A23B227/SIP/Msg/ccsipDisplayMsg:

    Sent:

    SIP/2.0 200 OK

    Via: SIP/2.0/UDP
    41.183.0.14:5060;branch=z9hG4bK368371e6;rport;received=41.183.0.13

    From: "asterisk" <sip:[email protected]>;tag=as1843c0de

    To: <sip:[email protected]:5060>;tag=762FDA4-2655

    Date: Mon, 02 Jul 2012 22:28:57 GMT

    Call-ID: [email protected]

    Server: Cisco-SIPGateway/IOS-12.x

    CSeq: 102 OPTIONS

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

    Allow-Events: telephone-event

    Accept: application/sdp

    Supported: 100rel,timer,resource-priority,replaces,sdp-anat

    Content-Type: application/sdp

    Content-Length: 453



    v=0

    o=CiscoSystemsSIP-GW-UserAgent 9774 6330 IN IP4 41.132.55.14

    s=SIP Call

    c=IN IP4 41.132.55.14

    t=0 0

    m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3

    c=IN IP4 41.132.55.14

    m=image 0 udptl t38

    c=IN IP4 41.132.55.14

    a=T38FaxVersion:0

    a=T38MaxBitRate:14400

    a=T38FaxFillBitRemoval:0

    a=T38FaxTranscodingMMR:0

    a=T38FaxTranscodingJBIG:0

    a=T38FaxRateManagement:transferredTCF

    a=T38FaxMaxBuffer:200

    a=T38FaxMaxDatagram:320

    a=T38FaxUdpEC:t38UDPRedundancy



    619695: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteccCallIdFromTable:
    Removing call id 412E

    619696: Jul  3 01:28:57.345:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for
    key=[12614] removed.

    619697: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromUASReqTable:
    ****Deleting from UAS Request table.

    619698: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting
    from table. ccb=0x88BCE028
    [email protected]

    619699: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromUASRespTable:
    ****Deleting from UAS Response table.

    619700: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIFlushEventBufferQueue: There are
    0 events on the internal queue that are going to be free'd

    619701: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPI_ipip_free_codec_profile: Codec
    Profiles Freed

    619702: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/ccsip_offer_ans_delete:


    619703: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/ccsip_iwf_delete: 

    619704: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb
    88BCE028

    619705: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued
    for SPI with IP addr: [41.183.0.13]:5060, local_address:[ - ]

    619706: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
    ccsip_spi_get_msg_type returned: 2 for event 1

    619707: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
    context=0x87B7255C

    619708: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg:
    gConnTab=0x87B7255C, addr=41.183.0.13, port=5060, local_addr=,
    connid=2, transport=UDP

    619709: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:





    On 2012/07/03 07:32 AM, MijanurRahman wrote:

    Does the ITSP supports G.729 codec in call? I can see your
    voice class codec contains G.729 codec only. Test once using the
    G.711 codec, or hardcode the codec command inside the dial-peer.

     

    Have you noticed this error?

    %VOICE_IEC-3-GW: C SCRIPTS: Internal Error (Digit collect
    failed): IEC=1.1.179.11.28.6 on callID 2217

    Though the error description into the Cisco
    VoIP Internal Error Codes
     says something different but
    I'm also curious to it - will be searching more for this
    error.

    Last thing, I have seen your FXO ports are configured - make an
    inbound thru any FXO port and match the dial-peer 3000 and let's
    see what happens.

     







    INE - The Industry Leader in CCIE Preparation

    http://www.INE.com



    Subscription information may be found at:

    http://www.ieoc.com/forums/ForumSubscriptions.aspx


  • Hi,


    Here is the response I got from the SP after I sent the debug
    below...

    Does it make any sense and is there a work around?



    Thanks again for ll your help so far...

     

    We
    use OPTIONS as an application layer "ping" as well as to gauge
    remote capability, the vast majority of FNB Connect user's are
    mobile. We require this to keep state within the REGISTER
    timeout. If you don't answer we consider you lagged and take
    voicemail. This is to keep up our customer expectation as mobile
    networks are a best effort service and don't always perform as
    they should.




    We officially only support a reply to a OPTIONs packet as a
    keep-alive. We do an aggressive register timeout to accommodate
    our mobile user's (20 seconds). You can perhaps try to re-reg
    every 15 seconds to prevent the need for an OPTIONs "ping". I
    have not tested this in our environment, first prize would be to
    reply to our OPTIONs packet's as per RFC.




    Received:

    OPTIONS sip:[email protected]:5060
    SIP/2.0

    Via: SIP/2.0/UDP 41.183.0.14:5060;branch=z9hG4bK368371e6;rport

    From: "asterisk" <sip:[email protected]>;tag=as1843c0de

    To: <sip:[email protected]:5060>

    Contact: <sip:[email protected]>

    Call-ID: [email protected]

    CSeq: 102 OPTIONS

    User-Agent: FNB Connect

    Max-Forwards: 70

    Date: Mon, 02 Jul 2012 22:28:57 GMT

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

    Supported: replaces

    Content-Length: 0





    619633: Jul  3 01:28:57.129:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking
    Invite Dialog

    619634: Jul  3 01:28:57.129:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added
    context(0x88BD3A18) with key=[12618] to table

    619635: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_offer_ans_init:

    619636: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_iwf_init: 

    619637: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:


    619638: Jul  3 01:28:57.133:
    //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...

    619639: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port
    5060

    619640: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619641: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619642: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14


    619643: Jul  3 01:28:57.133:
    //-1/25B72A23B227/SIP/State/sipSPIChangeState: 0x88BD3A18 : State
    change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE,
    SUBSTATE_NONE)

    619644: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy
    Port 5060


    619645: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone
    EET to SIP default timezone = GMT

    619646: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
    Transaction Address 41.183.0.13,Port 5060, Transport 1, SentBy Port
    5060

    619647: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/ccsipInitPldCallingInfo: non-numeric
    calling number: asterisk


    619648: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetViaHostInURLFormat: VIA
    URL:sip:41.183.0.14:5060,
    Host:41.183.0.14

    619649: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetShrlPeer: Try match
    incoming dialpeer for Calling number: : asterisk


    619650: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetFromCalledPartyId:
    P-Called-Party-ID header not found

    619651: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetPeerByCalledPartyId:
    P-Called-Party-ID not found or parse error

    619652: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    No match found for P-Called-Party-ID


    619653: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    Peer tag 3000 matched for incoming call


    619654: Jul  3 01:28:57.133:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig:
    Precondition tag absent in Require/Supported header


    619655: Jul  3 01:28:57.133:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619656: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619657: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14

    619658: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Precondition tag
    absent in Require/Supported header

    619659: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Media
    Antitrombone disabled

    619660: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: Storing the
    configured mode as FLOW-THROUGH

    619661: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: xcoder
    high-density disabled

    619662: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set
    to FLOW_THROUGH

    619663: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Using Voice Class
    Codec, tag = 1 and offer-all is = FALSE

    619664: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Media/sipSPICopyStunConfigFromPeerToCCB:
    Firewall traversal is not enabled

    619665: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetModemInfoPerCall:
    peer_callID=0

    619666: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIGetCallConfig: Media forking
    disabled

    619667: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPISetOverlapConfiguration: Overlap
    signaling: FALSE: Endpt: SIP Trunk

    619668: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr
    passthrough config:1 tag:0

    619669: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list
    config:2 tag:0

    619670: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPI_ipip_build_consolidated_header_list:
    Both passthru and copylist are disabled

    619671: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sipSPIValidateStreamAddrType:
    stream:1, Mode : 1

    619672: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619673: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling
    ip_best_local_address()

    619674: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return
    addr 41.132.55.14

    619675: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Media/sipSPISetMediaSrcAddr: Media src addr
    for stream 1 = 41.132.55.14

    619676: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    reg_invoke_ip_first_hop()

    619677: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling
    ip_best_local_address()

    619678: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return
    addr 41.132.55.14

    619679: Jul  3 01:28:57.137:
    //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP
    SPI : SIPSPI_EV_CC_OPTIONS_RESP

    619680: Jul  3 01:28:57.137:
    //16691/25B72A23B227/SIP/Info/sact_idle_new_message_options:
    ccsip_api_options_ind returned: SIP_SUCCESS

    619681: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/State/sipSPIChangeState: 0x88BD3A18 : State
    change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT,
    SUBSTATE_NONE)

    619682: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding
    to UAS Request table.

    619683: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddCcbToTable: Added to table.
    ccb=0x88BD3A18
    [email protected]

    619684: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPIUaddccCallIdToTable: Adding call
    id 4133 to table

    619685: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
    ccsip_spi_get_msg_type returned: 3 for event 38

    619686: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Info/sipSPISendOptionsResponse: Associated
    container=0x8B2B275C to Options Response

    619687: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipSPITransportSendMessage:
    msg=0x885B5AC4, addr=41.183.0.13, port=5060, sentBy_port=5060,
    local_addr=, is_req=0, transport=1, switch=0, callBack=0x814AD178

    619688: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipSPITransportSendMessage:
    Proceedable for sending msg immediately

    619689: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg: switch
    transport is 0

    619690: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg: Trying
    to send resp=0x885B5AC4 to default port=5060

    619691: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection:
    connection required for raddr:41.183.0.13, rport:5060 with laddr:

    619692: Jul  3 01:28:57.141:
    //16691/25B72A23B227/SIP/Transport/sipTransportLogicSendMsg:
    Connection obtained...sending msg=0x885B5AC4

    619693: Jul  3 01:28:57.141:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
    send for msg=0x885B5AC4, addr=41.183.0.13, port=5060, local_addr=,
    connId=2 for UDP

    619694: Jul  3 01:28:57.145:
    //16691/25B72A23B227/SIP/Msg/ccsipDisplayMsg:

    Sent:

    SIP/2.0 200 OK

    Via: SIP/2.0/UDP
    41.183.0.14:5060;branch=z9hG4bK368371e6;rport;received=41.183.0.13

    From: "asterisk" <sip:[email protected]>;tag=as1843c0de

    To: <sip:[email protected]:5060>;tag=762FDA4-2655

    Date: Mon, 02 Jul 2012 22:28:57 GMT

    Call-ID: [email protected]

    Server: Cisco-SIPGateway/IOS-12.x

    CSeq: 102 OPTIONS

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

    Allow-Events: telephone-event

    Accept: application/sdp

    Supported: 100rel,timer,resource-priority,replaces,sdp-anat

    Content-Type: application/sdp

    Content-Length: 453



    v=0

    o=CiscoSystemsSIP-GW-UserAgent 9774 6330 IN IP4 41.132.55.14

    s=SIP Call

    c=IN IP4 41.132.55.14

    t=0 0

    m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3

    c=IN IP4 41.132.55.14

    m=image 0 udptl t38

    c=IN IP4 41.132.55.14

    a=T38FaxVersion:0

    a=T38MaxBitRate:14400

    a=T38FaxFillBitRemoval:0

    a=T38FaxTranscodingMMR:0

    a=T38FaxTranscodingJBIG:0

    a=T38FaxRateManagement:transferredTCF

    a=T38FaxMaxBuffer:200

    a=T38FaxMaxDatagram:320

    a=T38FaxUdpEC:t38UDPRedundancy



    619695: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteccCallIdFromTable:
    Removing call id 412E

    619696: Jul  3 01:28:57.345:
    //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for
    key=[12614] removed.

    619697: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromUASReqTable:
    ****Deleting from UAS Request table.

    619698: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting
    from table. ccb=0x88BCE028
    [email protected]

    619699: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUdeleteCcbFromUASRespTable:
    ****Deleting from UAS Response table.

    619700: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIFlushEventBufferQueue: There are
    0 events on the internal queue that are going to be free'd

    619701: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPI_ipip_free_codec_profile: Codec
    Profiles Freed

    619702: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/ccsip_offer_ans_delete:


    619703: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/ccsip_iwf_delete: 

    619704: Jul  3 01:28:57.345:
    //16686/12C36F8BB21F/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb
    88BCE028

    619705: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued
    for SPI with IP addr: [41.183.0.13]:5060, local_address:[ - ]

    619706: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
    ccsip_spi_get_msg_type returned: 2 for event 1

    619707: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
    context=0x87B7255C

    619708: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg:
    gConnTab=0x87B7255C, addr=41.183.0.13, port=5060, local_addr=,
    connid=2, transport=UDP

    619709: Jul  3 01:29:07.729:
    //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sign In or Register to comment.