带有 IVR 的 VoIP

下载选项

  • PDF     (356.3 KB)
    在各种设备上使用 Adobe Reader 查看
  • ePub     (113.4 KB)
    在 iPhone、iPad、Android、Sony Reader 或 Windows Phone 上使用各种应用查看
  • Mobi (Kindle)     (126.1 KB)
    在 Kindle 设备上查看或在多个设备上使用 Kindle 应用查看
已更新: 2007 年 1 月 17 日
文档 ID:12163

非歧视性语言

此产品的文档集力求使用非歧视性语言。在本文档集中，非歧视性语言是指不隐含针对年龄、残障、性别、种族身份、族群身份、性取向、社会经济地位和交叉性的歧视的语言。由于产品软件的用户界面中使用的硬编码语言、基于 RFP 文档使用的语言或引用的第三方产品使用的语言，文档中可能无法确保完全使用非歧视性语言。 深入了解思科如何使用包容性语言。

关于此翻译

思科采用人工翻译与机器翻译相结合的方式将此文档翻译成不同语言，希望全球的用户都能通过各自的语言得到支持性的内容。 请注意：即使是最好的机器翻译，其准确度也不及专业翻译人员的水平。 Cisco Systems, Inc. 对于翻译的准确性不承担任何责任，并建议您总是参考英文原始文档（已提供链接）。

简介

本文档向您展示如何在带网关的VoIP网络中配置和排除基本交互式语音响应(IVR)故障。IVR是一个术语，用于描述以电话线以录制消息的形式提供信息以响应以口语或双音多频(DTMF)信令形式（更常见）的用户输入的系统。 例如，银行允许您通过任何电话和自动化股票报价系统检查余额。

先决条件

要求

本文档没有任何特定的要求。

使用的组件

本文档中的信息基于以下软件和硬件版本：

  • 在路由器中运行的Cisco IOS®软件版本12.1(1)。

本文档中的信息都是基于特定实验室环境中的设备创建的。本文档中使用的所有设备最初均采用原始（默认）配置。如果您是在真实网络上操作，请确保您在使用任何命令前已经了解其潜在影响。

规则

有关文档规则的详细信息，请参阅 Cisco 技术提示规则。

配置

本部分提供有关如何配置本文档所述功能的信息。

注意：使用命令查找工具(仅限注册客户)可查找有关本文档中使用的命令的详细信息。

网络图

本文档使用此图中所示的网络设置：

voip_gw_gk.gif

此网络图中的Raleigh5300使用clid_authen_collect内部脚本运行IVR。此脚本查看来电的自动号码标识(ANI)。 这有助于接收设备的呼叫识别主叫方号码和被叫号码识别服务(DNIS)，以及呼叫到达特定设备的号码。然后，它会尝试将这些与用户名和密码进行匹配。如果脚本与用户名和密码不匹配，则使用语音提示获取帐号和密码（通常称为“PIN”）。 在主叫方输入用户名和密码并通过身份验证后，脚本会提示要呼叫的目标号码。

IVR配置是非常基本的配置。在实际环境中，用户名和密码存储在远程身份验证拨入用户服务(RADIUS)服务器中。身份验证、授权和记帐(AAA)身份验证登录H.323指向RADIUS，而不是本地。在运行IVR的路由器配置中使用aaa authentication login h323 group radius命令来完成此操作。

在dial-peer voice 1命令下，IVR应用已配置。在本例中，它指向clid_authen_collect脚本。软件中有各种IVR配置。输入exec命令show call application voice [summary]查看此内容。有关详细信息，请参阅为思科接入平台配置交互式语音响应。

配置

本文档使用以下配置：

罗利5300A 
Raleigh5300A#show run
Building configuration...


Current configuration:
!
! Last configuration change at 00:42:21 UTC Tue Mar 28 2000
! NVRAM config last updated at 00:42:22 UTC Tue Mar 28 2000
!
version 12.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Raleigh5300A
!
logging buffered 50000 debugging
AAA new-model
AAA authentication login default none
AAA authentication login h323 local
enable secret  password  

!--- Choose a strong password with at least one capital letter, !--- one number, and one special character.

!
username 1234 password 0 5678
spe 1/0 1/7
 firmware location system:/ucode/mica_port_firmware
!
!
resource-pool disable
!
!
!
!
!
clock calendar-valid
ip subnet-zero
!
isdn switch-type primary-5ess
isdn voice-call-failure 0
mta receive maximum-recipients 0
!
!
controller T1 0
 framing esf
 clock source line primary
 linecode b8zs
 pri-group timeslots 1-24
!
controller T1 1
 clock source line secondary 1
!
controller T1 2
!
controller T1 3
!
!
voice-port 0:D
!
!
dial-peer voice 1 pots
 application clid_authen_collect
 incoming called-number 4085556400
 answer-address 9195552001
 destination-pattern 919#9195552...
 port 0:D
 prefix 919
!
dial-peer voice 2 voip
 destination-pattern 4085556400
 tech-prefix 408#
 session target ras
!
num-exp 6... 4085556...

!--- This command is used to configure a Cisco IOS® gateway to route the calls coming to a main direct inward dial (DID) number to an AutoAttendant.

gateway 
 
 !
 interface Ethernet0
 no ip address
 shutdown
!
interface Serial0:23
 no ip address
 ip mroute-cache
 isdn switch-type primary-5ess
 isdn incoming-voice modem
 fair-queue 64 256 0
 no cdp enable
!
interface FastEthernet0
 ip address 172.16.120.2 255.255.255.0
 duplex auto
 speed auto
 h323-gateway voip interface
 h323-gateway voip id RALgk1 ipaddr 172.16.120.1 1718
 h323-gateway voip h323-id RAL5300A@cisco.com
 h323-gateway voip tech-prefix 919#
!
ip classless
ip route 172.16.110.0 255.255.255.0 172.16.120.10
no ip http server
!
line con 0
 transport input none
line 1 48
 transport output lat pad telnet rlogin udptn v120 lapb-ta
line aux 0
line vty 0 4
 password cisco
!
ntp clock-period 17179842
ntp server 172.16.110.10
end

罗利3640A 
Raleigh3640A#show run
Building configuration...


Current configuration:
!
version 12.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Raleigh3640A
!
logging buffered 50000 debugging
enable secret  password  

!--- Choose a strong password with at least one capital letter, !--- one number, and one special character.

!
!
!
!
!
ip subnet-zero
!
ip dvmrp route-limit 20000
!
!
!
!
!
interface Ethernet1/0
 ip address 172.16.120.1 255.255.255.0
!
interface Serial1/0
 no ip address
 no ip mroute-cache
 no fair-queue
!
interface TokenRing1/0
 no ip address
 shutdown
 ring-speed 16
!
ip classless
ip route 172.16.110.0 255.255.255.0 172.16.120.10
no ip http server
!
!
gatekeeper
 zone local RALgk1 cisco.com
 zone remote SJgk1 cisco.com 172.16.110.1 1719
 zone prefix SJgk1 408.......
 gw-type-prefix 408#*
 no shutdown
!
!
line con 0
 transport input none
line aux 0
line vty 0 4
 password cisco
 login
!
ntp clock-period 17179856
ntp server 172.16.110.10
end

圣荷西5300A 
SanJose5300A#show run
Building configuration...


Current configuration:
!
! Last configuration change at 00:15:49 UTC Tue Mar 28 2000
! NVRAM config last updated at 00:15:50 UTC Tue Mar 28 2000
!
version 12.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname SanJose5300A
!
logging buffered 50000 debugging
enable secret  password  

!--- Choose a strong password with at least one capital letter, !--- one number, and one special character.

!
!
!
resource-pool disable
!
!
!
!
!
ip subnet-zero
!
isdn voice-call-failure 0
mta receive maximum-recipients 0
!
!
controller T1 0
 framing esf
 clock source line primary
 linecode b8zs
 ds0-group 1 timeslots 1-4 type e&m-immediate-start
!
controller T1 1
 clock source line secondary 1
!
controller T1 2
!
controller T1 3
!
!
voice-port 0:1
!
!
dial-peer voice 1 pots
 answer-address 4085556001
 destination-pattern 408#4085556...
 direct-inward-dial
 port 0:1
 prefix 6
!
dial-peer voice 2 voip
 destination-pattern 9195552...
 tech-prefix 919#
 session target ras
!
num-exp 2... 9195552...
gateway 
 
 !
 interface Ethernet0
 no ip address
!
interface FastEthernet0
 ip address 172.16.110.2 255.255.255.0
 duplex auto
 speed auto
 h323-gateway voip interface
 h323-gateway voip id SJgk1 ipaddr 172.16.110.1 1718
 h323-gateway voip h323-id SJ5300A@cisco.com
 h323-gateway voip tech-prefix 408#
!
ip classless
ip route 172.16.120.0 255.255.255.0 172.16.110.10
no ip http server
!
!
!
line con 0
 transport input none
line aux 0
line vty 0 4
 password cisco
 login
!
ntp clock-period 17179924
ntp server 172.16.110.10
end

圣荷西3640A 
SanJose3640A#show run
Building configuration...


Current configuration:
!
! NVRAM config last updated at 00:05:33 UTC Tue Mar 28 2000
!
version 12.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname SanJose3640A
!
boot system flash c3640-ix-mz.120-7.T
logging buffered 50000 debugging
enable secret password  

!--- Choose a strong password with at least one capital letter, !--- one number, and one special character.

!
!
!
!
!
ip subnet-zero
!
ip dvmrp route-limit 20000
!
!
interface Ethernet1/0
 ip address 172.16.110.1 255.255.255.0
!
interface Serial1/0
 no ip address
 no ip mroute-cache
 shutdown
 no fair-queue
!
interface Ethernet1/1
 no ip address
 shutdown
!
ip classless
ip route 172.16.120.0 255.255.255.0 172.16.110.10
no ip http server
!
tftp-server flash:c3640-ix-mz.121-1.bin
!
gatekeeper
 zone local SJgk1 cisco.com
 zone remote RALgk1 cisco.com 172.16.120.1 1719
 zone prefix RALgk1 919.......
 gw-type-prefix 919#*
 no shutdown
!
!
line con 0
 transport input none
line aux 0
line vty 0 4
 password cisco
 login
!
ntp clock-period 17179867
ntp server 172.16.110.10
end

验证

本部分提供的信息有助于确认配置是否正常运行。

命令输出解释程序（仅限注册用户）(OIT) 支持某些 show 命令。使用 OIT 可查看对 show 命令输出的分析。

在路由器中输入本文档中所示的配置后，检验网络是否运行正常。这些命令和相应的输出显示了本文档中配置的成功实施。

IVR最常见的问题包括：

  • 在普通旧电话服务(POTS)拨号对等体上未正确配置应用。

    • 确保传入电话呼叫与应用程序的POTS拨号对等体匹配。POTS拨号对等体在配置中使用matching port命令匹配第一个拨号对等体，或者如果被叫号码与dial-peer子命令incoming called-number <number>匹配，则POTS拨号对等体会与第一个拨号对等体匹配。

    • 通过show call application voice [summary]命令检查应用程序是否实际存在。确保应用程序名称不包含语法错误。

  • 确保在拨号对等体端口上未配置直接拨入。

  • 确保将正确的音频文件加载到闪存中。

  • 确保正确配置了用户名和密码的身份验证。

Raleigh5300A 输出 

Raleigh5300A#show gateway
 Gateway  RAL5300A@cisco.com  is registered to Gatekeeper RALgk1

Alias list (CLI configured) 
 H323-ID RAL5300A@cisco.com
Alias list (last RCF) 
 H323-ID RAL5300A@cisco.com


 H323 resource thresholding is Disabled

Raleigh5300A#show flash


System flash directory:
File  Length   Name/status
  1   7084904  c5300-is-mz.121-1.bin  
  2   23186    enter_account.au  
  3   38087    enter_destination.au  
  4   20414    enter_pin.au  
  5   17513    auth_failed.au  
[7184432 bytes used, 1204176 available, 8388608 total]
8192K bytes of processor board System flash (Read/Write)


Raleigh5300A#show call application voice clid_authen_collect
Idle call list has 1 calls on it.
Application clid_authen_collect
    The script is compiled into the image
    It has 0 calls active.

The TCL Script is:
------------------
# clid_authen_collect.tcl
#----------------------------------
# September 1998, David Ramsthaler
#
# Copyright (c) 1998, 1999 by cisco Systems, Inc.
# All rights reserved.
#----------------------------------
# Mimic the clid_authen_collect script in the SP1.0 release.
#
# It authenticates using (ani, dnis) for (account, password). If
# that fails, it collects account and pin number, then authenticates
# using (account, pin). 
#
# If authentication passes, it collects the destination number and
# places the call.
# 
# The main routine is at the bottom. Start reading the script there.
#


proc do_get_account {} {
    global state
    global account


    set prompt(url) flash:enter_account.au
    set prompt(interrupt) true
    set prompt(abortKey) *
    set prompt(terminationKey) #
    set patterns(account) .+
    set event [promptAndCollect prompt info patterns ]


    if {$event == "collect success"} {
        set state get_pin
        set account $info(digits)
        return 0
    }


    if {$event == "collect aborted"} {
        set state get_account
        return 0
    }


    if {$event == "collect fail" || $event == "collect timeout"} {
        set state get_account
        return 0
    }
    set state end
    return 0
}
    
proc do_get_pin {} {
    global state
    global pin


    set prompt(url) flash:enter_pin.au
    set prompt(interrupt) true
    set prompt(abortKey) *
    set prompt(terminationKey) #
    set patterns(account) .+
    set event [promptAndCollect prompt info patterns ]


    if {$event == "collect success"} {
        set state authenticate
        set pin $info(digits)
        return 0
    }


    if {$event == "collect aborted"} {
        set state get_account
        return 0
    }


    if {$event == "collect fail" || $event == "collect timeout"} {
        # timeout
        if {$info(code) == 102} {
            set state get_pin
            return 0
        }


        # invalid number
        if {$info(code) == 28} {
            set state get_pin
            return 0
        }
    }


    set state end
    return 0
}


proc do_authenticate {} {
    global state
    global pin
    global account


    set event [authenticate $account $pin info]


    if { $event == "authenticated" } {
        set state authen_pass
        return 0
    }


    if {$event == "authentication failed"} {
        set state authen_fail
        return 0
    }


    set state end
    return 0
}


proc do_get_dest {} {
    global state
    global destination


    set prompt(url) flash:enter_destination.au
    set prompt(interrupt) true
    set prompt(abortKey) *
    set prompt(terminationKey) #
    set prompt(dialPlan) true


    set event [promptAndCollect prompt info ]


    if {$event == "collect success"} {
        set state place_call
        set destination $info(digits)
        return 0
    }


    if {$event == "collect aborted"} {
        set state get_dest
        return 0
    }


    if {$event == "collect fail" || $event == "collect timeout"} {
        set state get_dest
        return 0
    }
    set state end
    return 0
}


proc do_authen_pass {} {
    global state
    global destination


    set dnislen [string len [dnis]]


    if { [did] && $dnislen } {
        set destination [dnis]
        set state place_call
    } else {
        set state get_dest
    }
    return 0
}


proc do_place_call {} {
    global state
    global destination
    
    set event [placeCall $destination callInfo info]


    if {$event == "active"} {
        set state active
        return 0
    }
    if {$event == "call fail"} {
        set state place_fail
        return 0
    }


    set state end
    return 0
}


proc do_active_notimer {} {
    global state


    set event [waitEvent]
    while { $event == "digit" } {
        set event [waitEvent]
    }
    set state end
    return 0
}


proc do_active_last_timer {} {
    global state


    set event [startTimer [creditTimeLeft] info]
    while { $event == "digit" } {
        set event [startTimer $info(timeLeft) info]
    }
    if { $event == "timeout" } {
        clearOutgoingLeg retInfo
        set state out_of_time
    } else {
        set state end
    }


    return 0
}


proc do_active_timer {} {
    global state


    if { [creditTimeLeft] < 10 } {
        do_active_last_timer
        return 0
    }
    set delay [expr [creditTimeLeft] - 10]
    set event [startTimer $delay info]
    while { $event == "digit" } {
        set event [startTimer $info(timeLeft) info]
    }
    if { $event == "timeout" } {
        insertMessage flash:beep.au retInfo
        do_active_last_timer
    } else {
        set state end
    }


    return 0
}


proc do_active {} {
    global state


    if { ( [creditTimeLeft] == "unlimited") || 
         ([creditTimeLeft] == "uninitialized") } {
             do_active_notimer
    } else {
             do_active_timer
    }
    return 0
}


proc do_out_of_time {} {
    global state


    set prompt(url) flash:out_of_time.au
    set prompt(playComplete) true
    set event [promptAndCollect prompt info ]
    set state end
    return 0
}


proc do_authen_fail {} {
    global state


    set prompt(url) flash:auth_failed.au
    set prompt(playComplete) true
    set event [promptAndCollect prompt info ]
    set state end
    return 0
}


proc do_place_fail {} {
    global state


    playFailureTone 5 retInfo
    set state end
    return 0
}



#---------------------------------------
# And here is the main loop
#


acceptCall


set event [authenticate [ani] [dnis] info]


if {$event != "authenticated"} {
    set state get_account
} else {
    set state authen_pass
}


while {$state != "end"} {
    puts "cid([callID]) running state $state"
    if {$state == "get_account"} {
        do_get_account
    } elseif {$state == "get_pin"} {
        do_get_pin
    } elseif {$state == "authenticate"} {
        do_authenticate
    } elseif {$state == "get_dest"} {
        do_get_dest
    } elseif {$state == "place_call"} {
        do_place_call
    } elseif {$state == "active"} {
        do_active
    } elseif {$state == "authen_fail" } {
        do_authen_fail
    } elseif {$state == "authen_pass" } {
        do_authen_pass
    } elseif {$state == "place_fail"} {
        do_place_fail
    } elseif {$state == "out_of_time"} {
        do_out_of_time
    } else {
        break
    }
}

Raleigh路由器启用了一些debug命令。show debug命令显示以下内容： 

Raleigh5300A#show debug
  General OS: AAA Authentication debugging is on 
  ISDN: ISDN Q931 packets debugging is on
  ISDN Q931 packets debug DSLs. (On/Off/No DSL:1/0/-) DSL 0 --> 7 1 - - - - - 
  - - 
  H.323 RAS: H.323 RAS Messages debugging is on
  voip AAA: voip AAA debugging is on 
  voip: voip ccAPI function enter/exit debugging is on
  ivr: ivr state transitions debugging is on

假设在启用调试时发出呼叫，显示调试。以下是此类呼叫的输出： 

Raleigh5300A#show log
Syslog logging: enabled (0 messages dropped, 0 flushes, 0 overruns)
    Console logging: level debugging, 5422 messages logged
    Monitor logging: level debugging, 0 messages logged
    Buffer logging: level debugging, 5422 messages logged
    Trap logging: level informational, 230 message lines logged


Log Buffer (50000 bytes):


Mar 28 00:44:50.741: RASlib::ras_sendto: msg length 76 
                     from 172.16.120.2:49831 to 172.16.120.1:1719
Mar 28 00:44:50.741: RASLib::RASSendRRQ: RRQ (seq# 12164) 
                     sent to 172.16.120.1
Mar 28 00:44:50.745: RASLib::RASRecvData: successfully 
                     rcvd message of length 52 from 172.16.120.1:1719
Mar 28 00:44:50.745: RASLib::RASRecvData: RCF (seq# 12164) 
                     rcvd from [172.16.120.1:1719] on sock [0x6179E6A4]
Mar 28 00:45:18.433: ISDN Se0:23: RX <-  SETUP pd = 8  callref = 0x02
Mar 28 00:45:18.433: Bearer Capability i = 0x8090A2
Mar 28 00:45:18.433: Channel ID i = 0xA98393
Mar 28 00:45:18.433: Calling Party Number i = 0x2180, 
                     '9195552010', Plan:ISDN, Type:National
Mar 28 00:45:18.433: Called Party Number i = 0xA1, 
                     '4085556400', Plan:ISDN, Type:National
Mar 28 00:45:18.437: ISDN Se0:23: TX ->  CALL_PROC pd = 8  callref = 0x8002
Mar 28 00:45:18.437: Channel ID i = 0xA98393
Mar 28 00:45:18.437: ISDN Se0:23: TX ->  ALERTING pd = 8  callref = 0x8002
Mar 28 00:45:18.437: cc_api_call_setup_ind (vdbPtr=0x61B9ADAC, 
                     callInfo={called=4085556400, 
                     calling=9195552010, fdest=0 peer_tag=1}, callID=0x61A088C4)
Mar 28 00:45:18.441: start_h323_accounting: 
Mar 28 00:45:18.441: start_h323_ccapi_accounting: Error: Null userp
Mar 28 00:45:18.441: cc_process_call_setup_ind (event=0x61BB59E8) 
                      handed call to app "clid_authen_collect"
Mar 28 00:45:18.441: App clid_authen_collect: Handling callID 52
Mar 28 00:45:18.441: callingNumber=9195552010, calledNumber=4085556400, 
                     redirectNumber=
Mar 28 00:45:18.441: accountNumber=, finalDestFlag=0,
                     guid=1acb.27d8.98f4.006b.0000.0000.2071.a5e8
Mar 28 00:45:18.441: peer_tag=1
Mar 28 00:45:18.441: ccCallHandoff (callID=0x34)
Mar 28 00:45:18.445: :/acceptCall/ 
Mar 28 00:45:18.445: Accepting CallID=52
Mar 28 00:45:18.445: ccCallSetupAck (callID=0x34)
Mar 28 00:45:18.445: ccCallProceeding (callID=0x34, prog_ind=0x0)
Mar 28 00:45:18.445: ccCallConnect (callID=0x34)
Mar 28 00:45:18.445: :/ani/ 
Mar 28 00:45:18.445: :/dnis/ 
Mar 28 00:45:18.445: :[authenticate] 
Mar 28 00:45:18.445: authenticate
Mar 28 00:45:18.445:     account=9195552010
Mar 28 00:45:18.445:     password=4085556400
Mar 28 00:45:18.445: start_authetication service: ivr tcl authentication 
Mar 28 00:45:18.445: AAA: parse name= idb type=-1 tty=-1
Mar 28 00:45:18.445: AAA/MEMORY: create_user (0x61EBED14) user='9195552010' 
                     ruser='' port='' rem_addr='' 
                     authen_type=ASCII service=LOGIN priv=0
Mar 28 00:45:18.445: AAA/AUTHEN/START (2776990538): 
                     port='' list='h323' action=LOGIN service=LOGIN
Mar 28 00:45:18.445: AAA/AUTHEN/START (2776990538): found list h323
Mar 28 00:45:18.445: AAA/AUTHEN/START (2776990538): Method=LOCAL
Mar 28 00:45:18.445: AAA/AUTHEN (2776990538): User not found, end of method list
Mar 28 00:45:18.445: AAA/AUTHEN (2776990538): status = FAIL
Mar 28 00:45:18.445: voip_authenticate: Authentication server ERROR: server MSG:  
Mar 28 00:45:18.445: AAA/MEMORY: free_user (0x61EBED14) 
                     user='9195552010' ruser='' port='' 
                     rem_addr='' authen_type=ASCII service=LOGIN priv=0
Mar 28 00:45:18.449: ISDN Se0:23: TX ->  CONNECT pd = 8  callref = 0x8002
Mar 28 00:45:18.449: cid(52) ta_get_event returning authentication failed
Mar 28 00:45:18.449: :[callID] 
Mar 28 00:45:18.449: :/puts/ 
Mar 28 00:45:18.449: cid( 52) running state get_account
Mar 28 00:45:18.453: TA_PromptCmd. CallID=52


Mar 28 00:45:18.453: ccCallApp (callID=0x34)
Mar 28 00:45:18.453: prompt and collect app got callID 52
Mar 28 00:45:18.453:     Playing prompt enter_account.au
Mar 28 00:45:18.453:     Prompt interrupt enabled
Mar 28 00:45:18.453:     No return on play complete
Mar 28 00:45:18.453:     Not matching against dial plan
Mar 28 00:45:18.453:     Abort key is *    Termination key is #
Mar 28 00:45:18.453:     Matching against 1 patterns.
Mar 28 00:45:18.453:         Pattern .+
Mar 28 00:45:18.453: ccCallSetContext (callID=0x34, context=0x61A3AAA4)
Mar 28 00:45:18.453: ms_create() Iniz ply_timer
Mar 28 00:45:18.453: ccAssociateStream (callID=0x34 coder=5 
                     vad=0 recordFunc=0x0 evQ=0x61BE4420)
Mar 28 00:45:18.453: cc_API_call_associated (vdbPtr=0x61B9ADAC, 
                     callID=0x34, disp=0)
Mar 28 00:45:18.453: ms_associateDone(): xmitFunc = playFunc 0x60B8B358
Mar 28 00:45:18.453: ms_associateDone(): CallID 52 First Buf Play 
                     at 6d07h of enter_account.au
Mar 28 00:45:18.453: ms_associateDone() 6d07h, Tstart(ply: iSndDly 1000, pSnce 0) 
Mar 28 00:45:18.545: ISDN Se0:23: RX <-  CONNECT_ACK pd = 8  callref = 0x02
Mar 28 00:45:18.545: ISDN Se0:23: CALL_PROGRESS: CALL_CONNECTED 
                     call id 0x1B, bchan -1, dsl 0
Mar 28 00:45:19.453: $ $ms_process() >>ms_start_play()
Mar 28 00:45:19.453: ms_start_play() 6d07h mgdTstop(ply)
Mar 28 00:45:20.241: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=1, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:20.245: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:20.353: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
  callID=0x34, digit=1, duration=160) digit=1
Mar 28 00:45:20.353: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:20.353: ms_stop_play(): Play Stopped at 6d07h
Mar 28 00:45:20.353: ccDisassociateStream (callID=0x34)
Mar 28 00:45:20.521: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=2, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:20.525: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:20.593: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=2, duration=120) digit=2
Mar 28 00:45:20.781: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=3, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:20.785: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:20.853: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=3, duration=120) digit=3
Mar 28 00:45:21.101: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=4, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:21.105: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:21.193: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=4, duration=140) digit=4
Mar 28 00:45:21.553: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=#,  
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:21.553: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:21.673: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=#, duration=170) digit=#
Mar 28 00:45:21.673: pcapp CallID 52 returning PCAPP_MATCHED. string=1234
Mar 28 00:45:21.673: ccCallAppReturn (callID=0x34)
Mar 28 00:45:21.673: ms_delete() invoking ms_stop_play() for just in case...
Mar 28 00:45:21.673: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:21.673: ms_delete(): mc_delete_dynamicS() mc_delete_read()
Mar 28 00:45:21.673: cid(52) ta_get_event returning collect success
Mar 28 00:45:21.677: :[callID] 
Mar 28 00:45:21.677: :/puts/ 
Mar 28 00:45:21.677: cid( 52) running state get_pin
Mar 28 00:45:21.677: ta_PromptCmd. CallID=52


Mar 28 00:45:21.677: ccCallApp (callID=0x34)
Mar 28 00:45:21.681: prompt and collect app got callID 52
Mar 28 00:45:21.681:     Playing prompt enter_pin.au
Mar 28 00:45:21.681:     Prompt interrupt enabled
Mar 28 00:45:21.681:     No return on play complete
Mar 28 00:45:21.681:     Not matching against dial plan
Mar 28 00:45:21.681:     Abort key is *    Termination key is #
Mar 28 00:45:21.681:     Matching against 1 patterns.
Mar 28 00:45:21.681:         Pattern .+
Mar 28 00:45:21.681: ccCallSetContext (callID=0x34, context=0x61A986B0)
Mar 28 00:45:21.681: ms_create() Iniz ply_timer
Mar 28 00:45:21.681: ccAssociateStream (callID=0x34 coder=5 vad=0 
                     recordFunc=0x0 evQ=0x61BE4420)
Mar 28 00:45:21.681: cc_api_call_associated (vdbPtr=0x61B9ADAC, 
                     callID=0x34, disp=0)
Mar 28 00:45:21.681: ms_associateDone(): xmitFunc = playFunc 0x60B8B358
Mar 28 00:45:21.681: ms_associateDone(): CallID 52 First Buf 
                     Play at 6d07h of enter_pin.au
Mar 28 00:45:21.681: ms_associateDone() 6d07h, Tstart(ply: 
                     iSndDly 1000, pSnce 0) 
Mar 28 00:45:22.681: $ $ms_process() >>ms_start_play()
Mar 28 00:45:22.681: ms_start_play() 6d07h mgdTstop(ply)
Mar 28 00:45:23.433: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, callID=0x34, 
                     digit=5, flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:23.433: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:23.533: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=5, duration=150) digit=5
Mar 28 00:45:23.533: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:23.533: ms_stop_play(): Play Stopped at 6d07h
Mar 28 00:45:23.533: ccDisassociateStream (callID=0x34)
Mar 28 00:45:23.693: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=6, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:23.693: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:23.793: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=6, duration=150) digit=6
Mar 28 00:45:24.041: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=7, flags=0x1, 
                     timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:24.045: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:24.121: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=7, duration=130) digit=7
Mar 28 00:45:24.321: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=8, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:24.325: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:24.421: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=8, duration=150) digit=8
Mar 28 00:45:24.653: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=#, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:24.653: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:24.741: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=#, duration=140) digit=#
Mar 28 00:45:24.745: pcapp CallID 52 returning PCAPP_MATCHED. string=5678
Mar 28 00:45:24.745: ccCallAppReturn (callID=0x34)
Mar 28 00:45:24.745: ms_delete() invoking ms_stop_play() for just in case...
Mar 28 00:45:24.745: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:24.745: ms_delete(): mc_delete_dynamicS() mc_delete_read()
Mar 28 00:45:24.745: cid(52) ta_get_event returning collect success
Mar 28 00:45:24.745: :[callID] 
Mar 28 00:45:24.745: :/puts/ 
Mar 28 00:45:24.745: cid( 52) running state authenticate
Mar 28 00:45:24.749: :[authenticate] 
Mar 28 00:45:24.749: authenticate
Mar 28 00:45:24.749:     account=1234
Mar 28 00:45:24.749:     password=5678
Mar 28 00:45:24.749: start_authetication service: ivr tcl authentication 
Mar 28 00:45:24.749: AAA: parse name= idb type=-1 tty=-1
Mar 28 00:45:24.749: AAA/MEMORY: create_user (0x61B0354C) user='1234' ruser='' 
                     port='' rem_addr='' authen_type=ASCII service=LOGIN priv=0
Mar 28 00:45:24.749: AAA/AUTHEN/START (3238629809): port='' 
                     list='h323' action=LOGIN service=LOGIN
Mar 28 00:45:24.749: AAA/AUTHEN/START (3238629809): found list h323
Mar 28 00:45:24.749: AAA/AUTHEN/START (3238629809): Method=LOCAL
Mar 28 00:45:24.749: AAA/AUTHEN (3238629809): status = GETPASS
Mar 28 00:45:24.749: AAA/AUTHEN/CONT (3238629809): continue_login (user='1234')
Mar 28 00:45:24.749: AAA/AUTHEN (3238629809): status = GETPASS
Mar 28 00:45:24.749: AAA/AUTHEN/CONT (3238629809): Method=LOCAL
Mar 28 00:45:24.749: AAA/AUTHEN (3238629809): status = PASS
Mar 28 00:45:24.749: AAA/MEMORY: free_user (0x61B0354C) user='1234' ruser='' 
                     port='' rem_addr='' authen_type=ASCII service=LOGIN priv=0
Mar 28 00:45:24.749: cid(52) ta_get_event returning authenticated
Mar 28 00:45:24.753: :[callID] 
Mar 28 00:45:24.753: :/puts/ 
Mar 28 00:45:24.753: cid( 52) running state authen_pass
Mar 28 00:45:24.753: :/dnis/ 
Mar 28 00:45:24.753: :/did/ 
Mar 28 00:45:24.757: :[callID] 
Mar 28 00:45:24.757: :/puts/ 
Mar 28 00:45:24.757: cid( 52) running state get_dest
Mar 28 00:45:24.757: ta_PromptCmd. CallID=52


Mar 28 00:45:24.757: ccCallApp (callID=0x34)
Mar 28 00:45:24.757: prompt and collect app got callID 52
Mar 28 00:45:24.757:     Playing prompt enter_destination.au
Mar 28 00:45:24.757:     Prompt interrupt enabled
Mar 28 00:45:24.757:     No return on play complete
Mar 28 00:45:24.757:     Matching against dial plan
Mar 28 00:45:24.757:     Abort key is *    Termination key is #
Mar 28 00:45:24.757:     Matching against 0 patterns.
Mar 28 00:45:24.757: ccCallSetContext (callID=0x34, context=0x61A3AAA4)
Mar 28 00:45:24.761: ms_create() Iniz ply_timer
Mar 28 00:45:24.761: ccAssociateStream (callID=0x34 coder=5 vad=0 
                     recordFunc=0x0 evQ=0x61BE4420)
Mar 28 00:45:24.761: cc_api_call_associated (vdbPtr=0x61B9ADAC, 
                     callID=0x34, disp=0)
Mar 28 00:45:24.761: ms_associateDone(): xmitFunc = playFunc 0x60B8B358
Mar 28 00:45:24.761: ms_associateDone(): CallID 52 First Buf Play 
                     at 6d07h of enter_destination.au
Mar 28 00:45:24.761: ms_associateDone() 6d07h, Tstart(ply: iSndDly 1000, pSnce 0) 
Mar 28 00:45:25.761: $ $ms_process() >>ms_start_play()
Mar 28 00:45:25.761: ms_start_play() 6d07h mgdTstop(ply)
Mar 28 00:45:29.393: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=6, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:29.393: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:29.573: cc_api_call_digit (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=6, duration=230) digit=6
Mar 28 00:45:29.573: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:29.573: ms_stop_play(): Play Stopped at 6d07h
Mar 28 00:45:29.573: ccDisassociateStream (callID=0x34)
Mar 28 00:45:29.801: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=4,
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:29.805: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:29.921: cc_api_call_digit (vdbPtr=0x61B9ADAC, callID=0x34, 
                     digit=4, duration=170) digit=4
Mar 28 00:45:30.181: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=0, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:30.185: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:30.281: cc_api_call_digit (vdbPtr=0x61B9ADAC, callID=0x34, 
                     digit=0, duration=150) digit=0
Mar 28 00:45:30.533: cc_api_call_digit_begin (vdbPtr=0x61B9ADAC, 
                     callID=0x34, digit=0, 
                     flags=0x1, timestamp=0xDB1154A6, expiration=0x0)
Mar 28 00:45:30.533: pcapp CallID 52 event CC_EV_CALL_DIGIT_BEGIN ignored
Mar 28 00:45:30.673: cc_api_call_digit (vdbPtr=0x61B9ADAC, callID=0x34, 
                     digit=0, duration=190) digit=0
Mar 28 00:45:30.673: pcapp CallID 52 returning PCAPP_MATCHED. string=6400
Mar 28 00:45:30.673: ccCallAppReturn (callID=0x34)
Mar 28 00:45:30.673: ms_delete() invoking ms_stop_play() for just in case...
Mar 28 00:45:30.673: ms_stop_play() call 52 mgdTstop at 6d07h
Mar 28 00:45:30.673: ms_delete(): mc_delete_dynamicS() mc_delete_read()
Mar 28 00:45:30.673: cid(52) ta_get_event returning collect success
Mar 28 00:45:30.677: :[callID] 
Mar 28 00:45:30.677: :/puts/ 
Mar 28 00:45:30.677: cid( 52) running state place_call
Mar 28 00:45:30.677: :[placeCall] 
Mar 28 00:45:30.677: Placing call for callID 52 to destination=6400
Mar 28 00:45:30.677: ccCallApp (callID=0x34)
Mar 28 00:45:30.681: placecall CallID 52 got event CC_EV_CALL_HANDOFF
Mar 28 00:45:30.681: ccCallSetContext (callID=0x34, context=0x61EBED14)
Mar 28 00:45:30.681: Matched peers(1)
Mar 28 00:45:30.681: placecall pc_setupPeer cid(52), destPat(4085556400), matched(10), 
                     prefix(), peer(61C088AC)
Mar 28 00:45:30.681: ccCallSetupRequest (Inbound call = 0x34, outbound peer =2, dest=, 
                     params=0x61A650F8 mode=0, *callID=0x61BC6EF0)
Mar 28 00:45:30.681: callingNumber=9195552010, calledNumber=4085556400, redirectNumber=
Mar 28 00:45:30.681: accountNumber=1234, finalDestFlag=0,
                     guid=1acb.27d8.98f4.006b.0000.0000.2071.a5e8
Mar 28 00:45:30.681: peer_tag=2
Mar 28 00:45:30.681: ccIFCallSetupRequest: (vdbPtr=0x6174EC64, dest=, 
                     callParams={called=4085556400, 
                     calling=9195552010, fdest=0, voice_peer_tag=2}, mode=0x0)
Mar 28 00:45:30.681: ccCallSetContext (callID=0x35, context=0x61C72B0C)
Mar 28 00:45:30.681: placecall cid(52) state change PC_CS_INIT to PC_CS_CALL_SETTING
Mar 28 00:45:30.681: RASlib::ras_sendto: msg length 115 from 172.16.120.2:49831 
                     to 172.16.120.1:1719
Mar 28 00:45:30.685: RASLib::RASSendARQ: ARQ (seq# 12165) sent to 172.16.120.1
Mar 28 00:45:30.685: start_h323_accounting: 
Mar 28 00:45:30.685: start_h323_ccapi_accounting: Error: Null userp
Mar 28 00:45:30.689: RASLib::RASRecvData: successfully rcvd message 
                     of length 7 from 172.16.120.1:1719
Mar 28 00:45:30.689: RASLib::RASRecvData: RIP (seq# 12165) rcvd 
                     from [172.16.120.1:1719] on sock[6179E6A4]
Mar 28 00:45:30.705: RASLib::RASRecvData: successfully rcvd message 
                     of length 24 from 172.16.120.1:1719
Mar 28 00:45:30.705: RASLib::RASRecvData: ACF (seq# 12165) rcvd from 
                     [172.16.120.1:1719] on sock [0x6179E6A4]
Mar 28 00:45:31.713: cc_api_call_alert(vdbPtr=0x6174EC64, callID=0x35, 
                     prog_ind=0x8, sig_ind=0x1)
Mar 28 00:45:31.713: placecall CallID 53 got event CC_EV_CALL_ALERT
Mar 28 00:45:31.713: ccConferenceCreate (confID=0x61BC6F40, callID1=0x34, 
                     callID2=0x35, tag=0x0)
Mar 28 00:45:31.713: cc_api_bridge_done (confID=0x14, srcIF=0x6174EC64, srcCallID=0x35, 
                     dstCallID=0x34, disposition=0, tag=0x0)
Mar 28 00:45:31.713: placecall cid(52) state change PC_CS_CALL_SETTING to 
                     PC_CS_CONFERENCING_ALERT
Mar 28 00:45:31.713: cc_api_bridge_done (confID=0x14, srcIF=0x61B9ADAC, srcCallID=0x34, 
                     dstCallID=0x35, disposition=0, tag=0x0)
Mar 28 00:45:31.713: cc_api_caps_ind (dstVdbPtr=0x6174EC64, dstCallId=0x35, 
                     srcCallId=0x34, caps={codec=0xEBF7, fax_rate=0xFF, 
                     vad=0x3, modem=0x3 codec_bytes=48, signal_type=2})
Mar 28 00:45:31.717: placecall CallID 52 got event CC_EV_CONF_CREATE_DONE
Mar 28 00:45:31.717: placecall cid(52) state change PC_CS_CONFERENCING_ALERT 
                     to PC_CS_CONFERENCED_ALERT
Mar 28 00:45:32.321: cc_api_caps_ind (dstVdbPtr=0x61B9ADAC, dstCallId=0x34, 
                     srcCallId=0x35, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:32.321: cc_api_caps_ack (dstVdbPtr=0x61B9ADAC, dstCallId=0x34, 
                     srcCallId=0x35,caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:32.325: cc_api_caps_ack (dstVdbPtr=0x6174EC64, dstCallId=0x35, 
                     srcCallId=0x34, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:35.745: RASlib::ras_sendto: msg length 76 from 172.16.120.2:49831 
                     to 172.16.120.1:1719
Mar 28 00:45:35.745: RASLib::RASSendRRQ: RRQ (seq# 12166) sent to 172.16.120.1
Mar 28 00:45:35.749: RASLib::RASRecvData: successfully rcvd message of 
                     length 52 from 172.16.120.1:1719
Mar 28 00:45:35.749: RASLib::RASRecvData: RCF (seq# 12166) rcvd from 
                     [172.16.120.1:1719] on sock [0x6179E6A4]
Mar 28 00:45:40.673: cc_api_call_digit (vdbPtr=0x61B9ADAC, callID=0x34, 
                     digit=T, duration=0)
Mar 28 00:45:40.673: placecall CallID 52 got event CC_EV_CALL_DIGIT
Mar 28 00:45:43.845: cc_api_call_connected(vdbPtr=0x6174EC64, callID=0x35)
Mar 28 00:45:43.845: placecall CallID 53 got event CC_EV_CALL_CONNECTED
Mar 28 00:45:43.845: placecall CallID 52 returning PLACECALL_ACTIVE.
Mar 28 00:45:43.845: ccCallAppReturn (callID=0x34)
Mar 28 00:45:43.845: pCall(0x61B00C24), settlement_credit_time=0
Mar 28 00:45:43.845: ccCallSetContext (callID=0x35, context=0x61B00C24)
Mar 28 00:45:43.845: cid(52) ta_get_event returning active
Mar 28 00:45:43.845: :[callID] 
Mar 28 00:45:43.845: :/puts/ 
Mar 28 00:45:43.845: cid( 52) running state active
Mar 28 00:45:43.849: :/creditTimeLeft/ 
Mar 28 00:45:43.849: :[waitEvent] 
Mar 28 00:45:43.849: Waiting Event for callID 52
Mar 28 00:45:51.269: cc_api_call_disconnected(vdbPtr=0x6174EC64, callID=0x35, cause=0x10)
Mar 28 00:45:51.273: ccConferenceDestroy (confID=0x14, tag=0x0)
Mar 28 00:45:51.273: cc_api_bridge_drop_done (confID=0x14, srcIF=0x6174EC64, 
                     srcCallID=0x35, dstCallID=0x34, disposition=0 tag=0x0)
Mar 28 00:45:51.273: cc_api_bridge_drop_done (confID=0x14, srcIF=0x61B9ADAC, 
                     srcCallID=0x34, 
                     dstCallID=0x35, disposition=0 tag=0x0)
Mar 28 00:45:51.273: ccCallDisconnect (callID=0x35, cause=0x10 tag=0x0)
Mar 28 00:45:51.273:       RASlib::ras_sendto: msg length 76 from 172.16.120.2:49831 
                           to 172.16.120.1:1719
Mar 28 00:45:51.273:       RASLib::RASSendDRQ: DRQ (seq# 12167) sent to 172.16.120.1
Mar 28 00:45:51.277:       RASLib::RASRecvData: successfully rcvd message of length 
                           3 from 172.16.120.1:1719
Mar 28 00:45:51.277:       RASLib::RASRecvData: DCF (seq# 12167) rcvd from 
                           [172.16.120.1:1719] on sock [0x6179E6A4]
Mar 28 00:45:51.277: stop_h323_ccapi_accounting: Error: Null userp
Mar 28 00:45:51.281: cc_api_call_disconnect_done(vdbPtr=0x6174EC64, 
                     callID=0x35, disp=0, tag=0x0)
Mar 28 00:45:51.281: cid(53) outgoing disconnected
Mar 28 00:45:51.281: cid(52) ta_get_event returning outgoing disconnected
Mar 28 00:45:51.281: ta_WaitEventCmd(): ta_get_event(event [outgoing disconnected]) 
Mar 28 00:45:51.281: TCL script eval for callID 52 completed. code=OK
Mar 28 00:45:51.281: ccCallDisconnect (callID=0x34, cause=0x10 tag=0x0)
Mar 28 00:45:51.297: stop_h323_ccapi_accounting: Error: Null userp
Mar 28 00:45:51.297: cc_api_call_disconnect_done(vdbPtr=0x61B9ADAC, 
                     callID=0x34, disp=0, tag=0x0)
Mar 28 00:45:51.305: %ISDN-6-DISCONNECT: Interface Serial0:18  disconnected 
                     from 9195552010 , call lasted 32 seconds
Mar 28 00:45:51.305: ISDN Se0:23: TX ->  DISCONNECT pd = 8  callref = 0x8002
Mar 28 00:45:51.305: Cause i = 0x8090 - Normal call clearing 
Mar 28 00:45:51.353: ISDN Se0:23: RX <-  RELEASE pd = 8  callref = 0x02
Mar 28 00:45:51.353: ISDN Se0:23: TX ->  RELEASE_COMP pd = 8  callref = 0x8002
Mar 28 00:45:51.365: %LINK-3-UPDOWN: Interface Serial0:18, changed state to down
Mar 28 00:46:20.748: RASlib::ras_sendto: msg length 76 from 172.16.120.2:49831 
                     to 172.16.120.1:1719
Mar 28 00:46:20.748: RASLib::RASSendRRQ: RRQ (seq# 12168) sent to 172.16.120.1
Mar 28 00:46:20.752: RASLib::RASRecvData: successfully rcvd message of 
                     length 52 from 172.16.120.1:1719
Mar 28 00:46:20.752: RASLib::RASRecvData: RCF (seq# 12168) rcvd from 
                     [172.16.120.1:1719] on sock [0x6179E6A4]
Raleigh5300A#
Raleigh5300A#
Raleigh5300A#show call active voice
 GENERIC:
SetupTime=54456184 ms
Index=1
PeerAddress=9195552010
PeerSubAddress=
PeerId=1
PeerIfIndex=56
LogicalIfIndex=26
ConnectTime=54456185
CallDuration=00:00:44
CallState=4
CallOrigin=2
ChargedUnits=0
InfoType=2
TransmitPackets=1475
TransmitBytes=75244
ReceivePackets=318
ReceiveBytes=10176
TELE:
ConnectionId=[0x1ACB27D8 0x98F4006F 0x0 0x20755AB8]
TxDuration=29800 ms
VoiceTxDuration=6360 ms
FaxTxDuration=0 ms
CoderTypeRate=g729r8
NoiseLevel=-55
ACOMLevel=0
OutSignalLevel=-42
InSignalLevel=-56
InfoActivity=2
ERLLevel=15
SessionTarget=
ImgPages=0
 GENERIC:
SetupTime=54457502 ms
Index=1
PeerAddress=4085556400
PeerSubAddress=
PeerId=2
PeerIfIndex=57
LogicalIfIndex=0
ConnectTime=54458807
CallDuration=00:00:18
CallState=4
CallOrigin=1
ChargedUnits=0
InfoType=2
TransmitPackets=318
TransmitBytes=6360
ReceivePackets=1121
ReceiveBytes=22420
VOIP:
ConnectionId[0x1ACB27D8 0x98F4006F 0x0 0x20755AB8]
RemoteIPAddress=172.16.110.2
RemoteUDPPort=17942
RoundTripDelay=2 ms
SelectedQoS=best-effort
tx_DtmfRelay=inband-voice
SessionProtocol=cisco
SessionTarget=ras
OnTimeRvPlayout=15900
GapFillWithSilence=0 ms
GapFillWithPrediction=0 ms
GapFillWithInterpolation=0 ms
GapFillWithRedundancy=0 ms
HiWaterPlayoutDelay=70 ms
LoWaterPlayoutDelay=50 ms
ReceiveDelay=50 ms
LostPackets=0
EarlyPackets=0
LatePackets=0
VAD = enabled
CoderTypeRate=g729r8
CodecBytes=20
SignalingType=cas
Raleigh5300A#
Raleigh5300A#show call active voice brief
<ID>: <start>hs.<index> +<connect> pid:<peer_id> <dir> <addr> <state> 
  dur hh:mm:ss tx:<packets>/<bytes> rx:<packets>/<bytes> <state>
 IP <ip>:<udp> rtt:<time>ms pl:<play>/<gap>ms lost:<lost>/<early>/<late>
  delay:<last>/<min>/<max>ms <codec>
 FR <protocol><y/n><y/n><y/n><on/off> [int dlci cid] vad: dtmf: seq:
  sig: <codec> (payload size)
 Tele <int>: tx:<tot>/<v>/<fax>ms <codec> noise:<l> acom:<l> i/o:<l>/<l> dBm

6F   : 54456184hs.1 +1 pid:1 Answer 9195552010 active
 dur 00:00:40 tx:1279/73076 rx:172/5504
 Tele 0:D:54: tx:25890/3440/0ms g729r8 noise:-55 acom:0  i/0:-55/-41 dBm


6F   : 54457502hs.1 +1305 pid:2 Originate 4085556400 active
 dur 00:00:14 tx:172/3440 rx:925/18500
 IP 172.16.110.2:17942 rtt:2ms pl:15900/0ms lost:0/0/0 delay:50/50/70ms g729r8

SanJose5300A 输出 

SanJose5300A#show gateway
 Gateway  SJ5300A@cisco.com  is registered to Gatekeeper SJgk1


Alias list (CLI configured) 
 H323-ID SJ5300A@cisco.com
Alias list (last RCF) 
 H323-ID SJ5300A@cisco.com


 H323 resource thresholding is Disabled
SanJose5300A#show log
Syslog logging: enabled (0 messages dropped, 0 flushes, 0 overruns)
    Console logging: level debugging, 1787 messages logged
    Monitor logging: level debugging, 0 messages logged
    Buffer logging: level debugging, 1787 messages logged
    Trap logging: level informational, 97 message lines logged


Log Buffer (50000 bytes):


Mar 28 00:45:25.585: RASlib::ras_sendto: msg length 74 from 
                     172.16.110.2:52521 to 172.16.110.1:1719
Mar 28 00:45:25.585: RASLib::RASSendRRQ: RRQ (seq# 12137) sent to 172.16.110.1
Mar 28 00:45:25.589: RASLib::RASRecvData: successfully rcvd message of 
                     length 52 from 172.16.110.1:1719
Mar 28 00:45:25.589: RASLib::RASRecvData: RCF (seq# 12137) rcvd from 
                     [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:45:30.705: RASlib::ras_sendto: msg length 122 from 
                     172.16.110.2:52521 to 172.16.110.1:1719
Mar 28 00:45:30.705: RASLib::RASSendARQ: ARQ (seq# 12138) sent to 172.16.110.1
Mar 28 00:45:30.709: RASLib::RASRecvData: successfully rcvd message 
                     of length 24 from 172.16.110.1:1719
Mar 28 00:45:30.713: RASLib::RASRecvData: ACF (seq# 12138) rcvd from 
                     [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:45:30.713: cc_api_call_setup_ind (vdbPtr=0x616F8D2C, 
                     callInfo={called=408#4085556400, 
                     calling=9195552010, fdest=1 peer_tag=2}, callID=0x6199B54C)
Mar 28 00:45:30.713: cc_process_call_setup_ind (event=0x619B2D6C) handed 
                     call to app "SESSION"
Mar 28 00:45:30.713: sess_appl: ev(23=CC_EV_CALL_SETUP_IND), cid(39), disp(0)
Mar 28 00:45:30.713: ccCallSetContext (callID=0x27, context=0x61A60F64)
Mar 28 00:45:30.713: ssaCallSetupInd finalDest cllng(9195552010), 
                     clled(408#4085556400)
Mar 28 00:45:30.713: ssaSetupPeer cid(39) peer list:  tag(1) called 
                     number (408#4085556400) 
Mar 28 00:45:30.713: ssaSetupPeer cid(39), destPat(408#4085556400), 
                     matched(11), prefix(6), peer(61A03B88)
Mar 28 00:45:30.713: ccCallProceeding (callID=0x27, prog_ind=0x0)
Mar 28 00:45:30.713: ccCallSetupRequest (Inbound call = 0x27, outbound peer =1, dest=, 
                     params=0x61A60F78 mode=0, *callID=0x619BB9F0)
Mar 28 00:45:30.713: callingNumber=9195552010, calledNumber=408#4085556400, 
                     redirectNumber=
Mar 28 00:45:30.713: accountNumber=, finalDestFlag=1,
guid=1acb.27d8.98f4.006b.0000.0000.2071.a5e8
Mar 28 00:45:30.713: peer_tag=1
Mar 28 00:45:30.713: ccIFCallSetupRequest: (vdbPtr=0x619AC884, dest=, 
                     callParams={called=408#4085556400, 
                     calling=9195552010, fdest=1, voice_peer_tag=1}, mode=0x0)
Mar 28 00:45:30.717: ccCallSetContext (callID=0x28, context=0x6194F3AC)
Mar 28 00:45:30.717: cc_api_call_proceeding(vdbPtr=0x619AC884, callID=0x28,
      prog_ind=0x0)
Mar 28 00:45:30.717: sess_appl: ev(20=CC_EV_CALL_PROCEEDING), cid(40), disp(0)
Mar 28 00:45:30.717: ssaTraceSct: cid(40)st(1)oldst(0)cfid(-1)csize(0)
                     in(0)fDest(0)-cid2(39)st2(1)oldst2(0)
Mar 28 00:45:30.717: ssaIgnore cid(40), st(1),oldst(1), ev(20)
Mar 28 00:45:31.701: cc_api_call_alert(vdbPtr=0x619AC884, callID=0x28, 
                     prog_ind=0x8, sig_ind=0x1)
Mar 28 00:45:31.701: sess_appl: ev(7=CC_EV_CALL_ALERT), cid(40), disp(0)
Mar 28 00:45:31.701: ssaTraceSct: cid(40)st(1)oldst(1)cfid(-1)csize(0)
                     in(0)fDest(0)-cid2(39)st2(1)oldst2(0)
Mar 28 00:45:31.701: ccCallAlert (callID=0x27, prog_ind=0x8, sig_ind=0x1)
Mar 28 00:45:31.701: ccConferenceCreate (confID=0x619BBA38, callID1=0x27, 
                     callID2=0x28, tag=0x0)
Mar 28 00:45:31.701: cc_api_bridge_done (confID=0x14, srcIF=0x616F8D2C, srcCallID=0x27, 
                     dstCallID=0x28, disposition=0, tag=0x0)
Mar 28 00:45:31.705: cc_api_bridge_done (confID=0x14, srcIF=0x619AC884, srcCallID=0x28, 
                     dstCallID=0x27, disposition=0, tag=0x0)
Mar 28 00:45:31.705: cc_api_caps_ind (dstVdbPtr=0x616F8D2C, dstCallId=0x27, 
                     srcCallId=0x28,caps={codec=0xEBF7, fax_rate=0xFF, 
                     vad=0x3, modem=0x3 codec_bytes=1637472312, signal_type=2})
Mar 28 00:45:31.705: sess_appl: ev(28=CC_EV_CONF_CREATE_DONE), cid(39), disp(0)
Mar 28 00:45:31.705: ssaTraceSct: cid(39)st(3)oldst(0)cfid(20)csize(0)
                     in(1)fDest(1)-cid2(40)st2(3)oldst2(1)
Mar 28 00:45:32.517: cc_api_caps_ind (dstVdbPtr=0x619AC884, dstCallId=0x28, 
                     srcCallId=0x27,caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:32.517: cc_api_caps_ack (dstVdbPtr=0x619AC884, dstCallId=0x28, 
                     srcCallId=0x27, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:32.521: cc_api_caps_ack (dstVdbPtr=0x616F8D2C, dstCallId=0x27, 
                     srcCallId=0x28,caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x1
                     codec_bytes=20, signal_type=0})
Mar 28 00:45:43.834: cc_api_call_connected(vdbPtr=0x619AC884, callID=0x28)
Mar 28 00:45:43.834: sess_appl: ev(8=CC_EV_CALL_CONNECTED), cid(40), disp(0)
Mar 28 00:45:43.834: ssaTraceSct: cid(40)st(4)oldst(1)cfid(20)csize(0)
                     in(0)fDest(0)-cid2(39)st2(4)oldst2(3)
Mar 28 00:45:43.834: ccCallConnect (callID=0x27)
Mar 28 00:45:43.834: ssaFlushPeerTagQueue cid(39) peer list: (empty)
Mar 28 00:45:51.258: cc_api_call_disconnected(vdbPtr=0x619AC884,  
                     callID=0x28, cause=0x10)
Mar 28 00:45:51.258: sess_appl: ev(12=CC_EV_CALL_DISCONNECTED), cid(40), disp(0)
Mar 28 00:45:51.258: ssaTraceSct: cid(40)st(5)oldst(4)cfid(20)csize(0)
                     in(0)fDest(0)-cid2(39)st2(5)oldst2(3)
Mar 28 00:45:51.258: ssa: Disconnected cid(40) state(5) cause(0x10)
Mar 28 00:45:51.258: ccConferenceDestroy (confID=0x14, tag=0x0)
Mar 28 00:45:51.258: cc_api_bridge_drop_done (confID=0x14, srcIF=0x616F8D2C, 
                     srcCallID=0x27, dstCallID=0x28, disposition=0 tag=0x0)
Mar 28 00:45:51.258: cc_api_bridge_drop_done (confID=0x14, srcIF=0x619AC884, 
                     srcCallID=0x28, dstCallID=0x27, disposition=0 tag=0x0)
Mar 28 00:45:51.258: sess_appl: ev(29=CC_EV_CONF_DESTROY_DONE), cid(39), disp(0)
Mar 28 00:45:51.258: ssaTraceSct: cid(39)st(6)oldst(3)cfid(20)csize(0)
                     in(1)fDest(1)-cid2(40)st2(6)oldst2(5)
Mar 28 00:45:51.258: ccCallDisconnect (callID=0x27, cause=0x10 tag=0x0)
Mar 28 00:45:51.258: ccCallDisconnect (callID=0x28, cause=0x10 tag=0x0)
Mar 28 00:45:51.262: RASlib::ras_sendto: msg length 76 from 172.16.110.2:52521 
                     to 172.16.110.1:1719
Mar 28 00:45:51.262: RASLib::RASSendDRQ: DRQ (seq# 12139) sent to 172.16.110.1
Mar 28 00:45:51.266: RASLib::RASRecvData: successfully rcvd message of 
                     length 3 from 172.16.110.1:1719
Mar 28 00:45:51.266: RASLib::RASRecvData: DCF (seq# 12139) rcvd from 
                     [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:45:51.266: cc_api_call_disconnect_done(vdbPtr=0x0, 
                     callID=0x27, disp=0, tag=0x0)
Mar 28 00:45:51.270: sess_appl: ev(13=CC_EV_CALL_DISCONNECT_DONE), cid(39), disp(0)
Mar 28 00:45:51.270: ssaTraceSct: cid(39)st(7)oldst(6)cfid(-1)csize(0)
                     in(1)fDest(1)-cid2(40)st2(7)oldst2(5)
Mar 28 00:45:51.278: cc_api_call_disconnect_done(vdbPtr=0x619AC884, 
                     callID=0x28, disp=0, tag=0x61726DDC)
Mar 28 00:45:51.278: sess_appl: ev(13=CC_EV_CALL_DISCONNECT_DONE), cid(40), disp(0)
Mar 28 00:45:51.278: ssaTraceSct: cid(40)st(7)oldst(5)cfid(-1)csize(1)in(0)fDest(0)
Mar 28 00:46:10.590: RASlib::ras_sendto: msg length 74 from 172.16.110.2:52521 
                     to 172.16.110.1:1719
Mar 28 00:46:10.590: RASLib::RASSendRRQ: RRQ (seq# 12140) sent to 172.16.110.1
Mar 28 00:46:10.594: RASLib::RASRecvData: successfully rcvd message of 
                     length 52 from 172.16.110.1:1719
Mar 28 00:46:10.594: RASLib::RASRecvData: RCF (seq# 12140) rcvd from 
                     [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:46:55.595: RASlib::ras_sendto: msg length 74 from 
                     172.16.110.2:52521 to 172.16.110.1:1719
Mar 28 00:46:55.595: RASLib::RASSendRRQ: RRQ (seq# 12141) sent to 172.16.110.1
Mar 28 00:46:55.599: RASLib::RASRecvData: successfully rcvd 
                     message of length 52 from 172.16.110.1:1719
Mar 28 00:46:55.599: RASLib::RASRecvData: RCF (seq# 12141) rcvd 
                     from [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:47:40.600: RASlib::ras_sendto: msg length 74 from 
                     172.16.110.2:52521 to 172.16.110.1:1719
Mar 28 00:47:40.600: RASLib::RASSendRRQ: RRQ (seq# 12142) sent to 172.16.110.1
Mar 28 00:47:40.604: RASLib::RASRecvData: successfully rcvd 
                     message of length 52 from 172.16.110.1:1719
Mar 28 00:47:40.604: RASLib::RASRecvData: RCF (seq# 12142) rcvd 
                     from [172.16.110.1:1719] on sock [0x61752218]
Mar 28 00:48:25.604: RASlib::ras_sendto: msg length 74 from 172.16.110.2:52521 
                     to 172.16.110.1:1719
Mar 28 00:48:25.604: RASLib::RASSendRRQ: RRQ (seq# 12143) sent to 172.16.110.1
Mar 28 00:48:25.608: RASLib::RASRecvData: successfully rcvd message 
                     of length 52 from 172.16.110.1:1719
Mar 28 00:48:25.608: RASLib::RASRecvData: RCF (seq# 12143) rcvd from 
                     [172.16.110.1:1719] on sock [0x61752218]
SanJose5300A#
SanJose5300A#
SanJose5300A#
SanJose5300A#show call active voice brief
<ID>: <start>hs.<index> +<connect> pid:<peer_id> <dir> <addr> <state> 
  dur hh:mm:ss tx:<packets>/<bytes> rx:<packets>/<bytes> <state>
 IP <ip>:<udp> rtt:<time>ms pl:<play>/<gap>ms lost:<lost>/<early>/<late>
  delay:<last>/<min>/<max>ms <codec>
 FR <protocol><y/n><y/n><y/n><on/off> [int dlci cid] vad: dtmf: seq:
  sig: <codec> (payload size)
 Tele <int>: tx:<tot>/<v>/<fax>ms <codec> noise:<l> acom:<l> i/o:<l>/<l> dBm


6F   : 54422877hs.1 +1301 pid:2 Answer 9195552010 active
 dur 00:00:38 tx:2122/42440 rx:836/16720
 IP 172.16.120.2:16938 rtt:2ms pl:16720/0ms lost:0/0/0 delay:30/30/70ms g729r8


6F   : 54422895hs.1 +1283 pid:1 Originate 408#4085556400 active
 dur 00:00:38 tx:836/-8996 rx:2143/68576
 Tele 0:1 (42): tx:50050/42860/0ms g729r8 noise:-66 acom:0  i/0:-40/-71 dBm
SanJose5300A#show call actvice voice
 GENERIC:
SetupTime=54422877 ms
Index=1
PeerAddress=9195552010
PeerSubAddress=
PeerId=2
PeerIfIndex=17
LogicalIfIndex=0
ConnectTime=54424178
CallDuration=00:00:44
CallState=4
CallOrigin=2
ChargedUnits=0
InfoType=2
TransmitPackets=2414
TransmitBytes=48280
ReceivePackets=836
ReceiveBytes=16720
VOIP:
ConnectionId[0x1ACB27D8 0x98F4006F 0x0 0x20755AB8]
RemoteIPAddress=172.16.120.2
RemoteUDPPort=16938
RoundTripDelay=2 ms
SelectedQoS=best-effort
tx_DtmfRelay=inband-voice
SessionProtocol=cisco
SessionTarget=
OnTimeRvPlayout=16720
GapFillWithSilence=0 ms
GapFillWithPrediction=0 ms
GapFillWithInterpolation=0 ms
GapFillWithRedundancy=0 ms
HiWaterPlayoutDelay=70 ms
LoWaterPlayoutDelay=30 ms
ReceiveDelay=30 ms
LostPackets=0
EarlyPackets=0
LatePackets=0
VAD = enabled
CoderTypeRate=g729r8
CodecBytes=20
SignalingType=cas
 GENERIC:
SetupTime=54422895 ms
Index=1
PeerAddress=408#4085556400
PeerSubAddress=
PeerId=1
PeerIfIndex=16
LogicalIfIndex=13
ConnectTime=54424178
CallDuration=00:00:44
CallState=4
CallOrigin=1
ChargedUnits=0
InfoType=2
TransmitPackets=836
TransmitBytes=-12488
ReceivePackets=2434
ReceiveBytes=77888
TELE:
ConnectionId=[0x1ACB27D8 0x98F4006F 0x0 0x20755AB8]
TxDuration=55880 ms
VoiceTxDuration=48690 ms
FaxTxDuration=0 ms
CoderTypeRate=g729r8
NoiseLevel=-66
ACOMLevel=0
OutSignalLevel=-70
InSignalLevel=-42
InfoActivity=2
ERLLevel=15
SessionTarget=
ImgPages=0
SanJose5300A#

Raleigh3600A 输出 

Raleigh3640A#show debug
H.323 RAS:
  H.323 RAS Messages debugging is on
Raleigh3640A#und all


Raleigh3640A#show gatekeeper endpoint
                    GATEKEEPER ENDPOINT REGISTRATION
                    ================================
CallSignalAddr  Port  RASSignalAddr   Port  Zone Name         Type    F 
--------------- ----- --------------- ----- ---------         ----    --
172.16.120.2    1720  172.16.120.2    49831 RALgk1            VOIP-GW  
    H323-ID: RAL5300A@cisco.com
Total number of active registrations = 1

Raleigh3640A#show gatekeeper gw-type-prefix
GATEWAY TYPE PREFIX TABLE
=========================
Prefix: 408#*


Prefix: 919#*
  Zone RALgk1 master gateway list:
    172.16.120.2:1720 RAL5300A 

Raleigh3640A#show log
Syslog logging: enabled (0 messages dropped, 0 flushes, 0 overruns)
    Console logging: level debugging, 277 messages logged
    Monitor logging: level debugging, 0 messages logged
    Buffer logging: level debugging, 277 messages logged
    Trap logging: level informational, 107 message lines logged


Log Buffer (50000 bytes):


Mar 28 00:44:50.742: RASLib::RASRecvData: successfully rcvd 
                     message of length 76 from 172.16.120.2:49831
Mar 28 00:44:50.742: RASLib::RASRecvData: RRQ (seq# 12164) 
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:44:50.746: RASlib::ras_sendto: msg length 52 
                     from 172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:44:50.746: RASLib::RASSendRCF: RCF (seq# 12164) 
                     sent to 172.16.120.2
Mar 28 00:45:30.682: RASLib::RASRecvData: successfully 
                     rcvd message of length 115 from 172.16.120.2:49831
Mar 28 00:45:30.686: RASLib::RASRecvData: ARQ (seq# 12165) 
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]      
                     RASLib::parse_arq_nonstd: ARQ Nonstd 
                     decode succeeded, remlen = 0
Mar 28 00:45:30.686: RASlib::ras_sendto: msg length 7 from 
                     172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:45:30.686: RASLib::RASSendRIP: RIP (seq# 12165) sent to 172.16.120.2
Mar 28 00:45:30.686: RASLib::RAS_WK_TInit: ipsock [0x60F0ED1C] setup successful
Mar 28 00:45:30.690: RASlib::ras_sendto: msg length 79 
                     from 172.16.120.1:55415 to 172.16.110.1:1719
Mar 28 00:45:30.690: RASLib::RASSendLRQ: LRQ (seq# 28) sent to 172.16.110.1
Mar 28 00:45:30.698: RASLib::RASRecvData: successfully 
                     rcvd message of length 128 from 172.16.110.1:1719
Mar 28 00:45:30.698: RASLib::RASRecvData: LCF (seq# 28) 
                     rcvd from [172.16.110.1:1719] on sock [0x60F0ED1C]     
                     RASLib::parse_lcf_nonstd: LCF Nonstd 
                     decode succeeded, remlen = 0
Mar 28 00:45:30.702: RASlib::ras_sendto: msg length 24 from 
                     172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:45:30.702: RASLib::RASSendACF: ACF (seq# 12165) sent to 172.16.120.2
Mar 28 00:45:35.746: RASLib::RASRecvData: successfully rcvd message of 
                     length 76 from 172.16.120.2:49831
Mar 28 00:45:35.746: RASLib::RASRecvData: RRQ (seq# 12166) rcvd 
                     from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:45:35.750: RASlib::ras_sendto: msg length 52 from 
                     172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:45:35.750: RASLib::RASSendRCF: RCF (seq# 12166) sent to 172.16.120.2
Mar 28 00:45:51.274: RASLib::RASRecvData: successfully rcvd 
                     message of length 76 from 172.16.120.2:49831
Mar 28 00:45:51.274: RASLib::RASRecvData: DRQ (seq# 12167) 
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:45:51.274: RASlib::ras_sendto: msg length 3 
                     from 172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:45:51.278: RASLib::RASSendDCF: DCF (seq# 12167) 
                     sent to 172.16.120.2
Mar 28 00:46:20.750: RASLib::RASRecvData: successfully 
                     rcvd message of length 76 from 172.16.120.2:49831
Mar 28 00:46:20.750: RASLib::RASRecvData: RRQ (seq# 12168) 
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:46:20.750: RASlib::ras_sendto: msg length 52 from 
                     172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:46:20.754: RASLib::RASSendRCF: RCF (seq# 12168) 
                     sent to 172.16.120.2
Mar 28 00:47:05.750: RASLib::RASRecvData: successfully 
                     rcvd message of length 76 from 172.16.120.2:49831
Mar 28 00:47:05.754: RASLib::RASRecvData: RRQ (seq# 12169) 
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:47:05.754: RASlib::ras_sendto: msg length 52 
                     from 172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:47:05.758: RASLib::RASSendRCF: RCF (seq# 12169) 
                     sent to 172.16.120.2
Mar 28 00:47:50.754: RASLib::RASRecvData: successfully 
                     rcvd message of length 76 from 172.16.120.2:49831
Mar 28 00:47:50.758: RASLib::RASRecvData: RRQ (seq# 12170)  
                     rcvd from [172.16.120.2:49831] on sock [0x60F2F9A0]
Mar 28 00:47:50.758: RASlib::ras_sendto: msg length 52 
                     from 172.16.120.1:1719 to 172.16.120.2:49831
Mar 28 00:47:50.758: RASLib::RASSendRCF: RCF (seq# 12170) sent to 172.16.120.2
Raleigh3640A#

Raleigh3640A#
Raleigh3640A#show gatekeeper call
Total number of active calls = 1.
                         GATEKEEPER CALL INFO
                         ====================
LocalCallID                        Age(secs)   BW
24-6872                            45          64(Kbps)
 Endpt(s): Alias        E.164Addr     CallSignalAddr  Port  RASSignalAddr   Port
   src EP: RAL5300A     9195552010    172.16.120.2    1720  172.16.120.2    49831
   dst EP:              408#408555640 172.16.110.2    1720  172.16.110.2    1720 


Raleigh3640A#

SanJose3640A 输出 

SanJose3640A#show debug
H.323 RAS:
  H.323 RAS Messages debugging is on


SanJose3640A#show gatekeeper endpoint
                    GATEKEEPER ENDPOINT REGISTRATION
                    ================================
CallSignalAddr  Port  RASSignalAddr   Port  Zone Name         Type    F 
--------------- ----- --------------- ----- ---------         ----    --
172.16.110.2    1720  172.16.110.2    52521 SJgk1             VOIP-GW  
    H323-ID: SJ5300A@cisco.com
Total number of active registrations = 1


SanJose3640A#show gatekeeper gw-type-prefix
GATEWAY TYPE PREFIX TABLE
=========================
Prefix: 919#*


Prefix: 408#*
  Zone SJgk1 master gateway list:
    172.16.110.2:1720 SJ5300A 

SanJose3640A#show log
Syslog logging: enabled (0 messages dropped, 0 flushes, 0 overruns)
    Console logging: level debugging, 1301 messages logged
    Monitor logging: level debugging, 0 messages logged
    Buffer logging: level debugging, 1293 messages logged
    Trap logging: level informational, 103 message lines logged


Log Buffer (50000 bytes):


Mar 28 00:45:25.592: RASLib::RASRecvData: successfully rcvd 
                     message of length 74 from 172.16.110.2:52521
Mar 28 00:45:25.592: RASLib::RASRecvData: RRQ (seq# 12137) rcvd 
                     from [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:45:25.596: RASlib::ras_sendto: msg length 52 from 
                     172.16.110.1:1719 to 172.16.110.2:52521
Mar 28 00:45:25.596: RASLib::RASSendRCF: RCF (seq# 12137) sent to 172.16.110.2
Mar 28 00:45:30.692: RASLib::RASRecvData: successfully rcvd message 
                     of length 79 from 172.16.120.1:55415
Mar 28 00:45:30.692: RASLib::RASRecvData: LRQ (seq# 28) rcvd from 
                     [172.16.120.1:55415] on sock [0x60FE9B04]     
                     RASLib::parse_lrq_nonstd: LRQ Nonstd decode succeeded, remlen = 0
Mar 28 00:45:30.696: RASlib::ras_sendto: msg length 128 from 
                     172.16.110.1:1719 to 172.16.120.1:55415
Mar 28 00:45:30.696: RASLib::RASSendLCF: LCF (seq# 28) sent to 172.16.120.1
Mar 28 00:45:30.712: RASLib::RASRecvData: successfully rcvd message 
                     of length 122 from 172.16.110.2:52521
Mar 28 00:45:30.712: RASLib::RASRecvData: ARQ (seq# 12138) rcvd from 
                     [172.16.110.2:52521] on sock [0x60FE9B04]      
                     RASLib::parse_arq_nonstd: ARQ Nonstd decode succeeded, remlen = 0
Mar 28 00:45:30.716: RASlib::ras_sendto: msg length 24 from 172.16.110.1:1719 
                     to 172.16.110.2:52521
Mar 28 00:45:30.716: RASLib::RASSendACF: ACF (seq# 12138) sent to 172.16.110.2
Mar 28 00:45:51.268: RASLib::RASRecvData: successfully rcvd message 
                     of length 76 from 172.16.110.2:52521
Mar 28 00:45:51.268: RASLib::RASRecvData: DRQ (seq# 12139) rcvd 
                     from [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:45:51.268: RASlib::ras_sendto: msg length 3 from 
                     172.16.110.1:1719 to 172.16.110.2:52521
Mar 28 00:45:51.268: RASLib::RASSendDCF: DCF (seq# 12139) sent to 172.16.110.2
Mar 28 00:46:10.596: RASLib::RASRecvData: successfully rcvd message of 
                     length 74 from 172.16.110.2:52521
Mar 28 00:46:10.596: RASLib::RASRecvData: RRQ (seq# 12140) rcvd 
                     from [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:46:10.600: RASlib::ras_sendto: msg length 52 from 172.16.110.1:1719 
                     to 172.16.110.2:52521
Mar 28 00:46:10.600: RASLib::RASSendRCF: RCF (seq# 12140) sent to 172.16.110.2
Mar 28 00:46:55.600: RASLib::RASRecvData: successfully rcvd message of 
                     length 74 from 172.16.110.2:52521
Mar 28 00:46:55.600: RASLib::RASRecvData: RRQ (seq# 12141) rcvd from 
                     [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:46:55.604: RASlib::ras_sendto: msg length 52 from 172.16.110.1:1719 
                     to 172.16.110.2:52521
Mar 28 00:46:55.604: RASLib::RASSendRCF: RCF (seq# 12141) sent to 172.16.110.2
Mar 28 00:47:40.604: RASLib::RASRecvData: successfully rcvd message of 
                     length 74 from 172.16.110.2:52521
Mar 28 00:47:40.608: RASLib::RASRecvData: RRQ (seq# 12142) rcvd from 
                     [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:47:40.608: RASlib::ras_sendto: msg length 52 from 172.16.110.1:1719 
                     to 172.16.110.2:52521
Mar 28 00:47:40.608: RASLib::RASSendRCF: RCF (seq# 12142) sent to 172.16.110.2
Mar 28 00:48:25.608: RASLib::RASRecvData: successfully rcvd message of 
                     length 74 from 172.16.110.2:52521
Mar 28 00:48:25.612: RASLib::RASRecvData: RRQ (seq# 12143) rcvd from 
                     [172.16.110.2:52521] on sock [0x60FE9B04]
Mar 28 00:48:25.612: RASlib::ras_sendto: msg length 52 from 172.16.110.1:1719 
                     to 172.16.110.2:52521
Mar 28 00:48:25.612: RASLib::RASSendRCF: RCF (seq# 12143) sent to 172.16.110.2
SanJose3640A#

SanJose3640A#
SanJose3640A#show gatekeeper calls
Total number of active calls = 1.
                         GATEKEEPER CALL INFO
                         ====================
LocalCallID                        Age(secs)   BW
21-6872                            63          64(Kbps)
 Endpt(s): Alias        E.164Addr     CallSignalAddr  Port  RASSignalAddr   Port
   src EP:              9195552010   
   dst EP: SJ5300A      408#408555640 172.16.110.2    1720  172.16.110.2    52521

故障排除

此部分提供信息故障排除您的配置。

故障排除命令

命令输出解释程序（仅限注册用户）(OIT) 支持某些 show 命令。使用 OIT 可查看对 show 命令输出的分析。

注意：在发出debug命令之前，请参阅有关Debug命令的重要信息。

  • debug voip aaa — 启用网关aaa的调试消息以输出到系统控制台。

  • debug isdn q931 — 显示有关本地路由器（用户端）和网络之间ISDN网络连接（第3层）的呼叫建立和拆卸的信息。

  • debug voip ccapi inout — 调试呼叫控制API。

  • debug voip ivr — 调试IVR应用。

  • debug ras — 显示发送和接收的注册、准入和状态协议(RAS)消息的类型和编址。

相关信息

此文档是否有帮助?

Feedback反馈

联系我们

本文档适用于以下产品