IVR을 사용하는 VoIP

다운로드 옵션

  • PDF     (256.2 KB)
    다양한 디바이스에서 Adobe Reader로 보기
  • ePub     (114.0 KB)
    iPhone, iPad, Android, Sony Reader 또는 Windows Phone의 다양한 앱에서 보기
  • Mobi (Kindle)     (127.7 KB)
    Kindle 디바이스에서 보기 또는 다양한 디바이스의 Kindle 앱에서 보기
업데이트:2007년 1월 17일
문서 ID:12163

편견 없는 언어

본 제품에 대한 문서 세트는 편견 없는 언어를 사용하기 위해 노력합니다. 본 설명서 세트의 목적상, 편견 없는 언어는 나이, 장애, 성별, 인종 정체성, 민족 정체성, 성적 지향성, 사회 경제적 지위 및 교차성에 기초한 차별을 의미하지 않는 언어로 정의됩니다. 제품 소프트웨어의 사용자 인터페이스에서 하드코딩된 언어, RFP 설명서에 기초한 언어 또는 참조된 서드파티 제품에서 사용하는 언어로 인해 설명서에 예외가 있을 수 있습니다. 시스코에서 어떤 방식으로 포용적인 언어를 사용하고 있는지 자세히 알아보세요.

이 번역에 관하여

Cisco는 전 세계 사용자에게 다양한 언어로 지원 콘텐츠를 제공하기 위해 기계 번역 기술과 수작업 번역을 병행하여 이 문서를 번역했습니다. 아무리 품질이 높은 기계 번역이라도 전문 번역가의 번역 결과물만큼 정확하지는 않습니다. Cisco Systems, Inc.는 이 같은 번역에 대해 어떠한 책임도 지지 않으며 항상 원본 영문 문서(링크 제공됨)를 참조할 것을 권장합니다.

소개

이 문서에서는 게이트웨이가 있는 VoIP 네트워크에서 기본 IVR(interactive voice response)을 구성하고 문제를 해결하는 방법을 보여 줍니다.IVR은 사용자 입력에 대한 응답으로 음성 단어 또는 DTMF(dual tone multifrequency) 신호(더 일반적인) 형태로 전화 회선을 통해 녹음된 메시지 형태로 정보를 제공하는 시스템을 설명하는 데 사용되는 용어입니다. 예를 들면 모든 전화 및 자동 주식 견적 시스템에서 잔액을 확인할 수 있는 은행이 있습니다.

사전 요구 사항

요구 사항

이 문서에 대한 특정 요건이 없습니다.

사용되는 구성 요소

이 문서의 정보는 다음 소프트웨어 및 하드웨어 버전을 기반으로 합니다.

  • 라우터에서 실행되는 Cisco IOS® Software 릴리스 12.1(1)입니다.

이 문서의 정보는 특정 랩 환경의 디바이스를 토대로 작성되었습니다.이 문서에 사용된 모든 디바이스는 초기화된(기본) 컨피그레이션으로 시작되었습니다.라이브 네트워크에서 작업하는 경우, 사용하기 전에 모든 명령의 잠재적인 영향을 이해해야 합니다.

표기 규칙

문서 규칙에 대한 자세한 내용은 Cisco 기술 팁 표기 규칙을 참고하십시오.

구성

이 섹션에는 이 문서에서 설명하는 기능을 구성하기 위한 정보가 표시됩니다.

참고: 명령 조회 도구(등록된 고객만 해당)를 사용하여 이 문서에 사용된 명령에 대한 자세한 내용을 확인하십시오.

네트워크 다이어그램

이 문서에서는 다음 다이어그램에 표시된 네트워크 설정을 사용합니다.

voip_gw_gk.gif

이 네트워크 다이어그램의 Raleigh5300은 clid_authen_collect 내부 스크립트를 사용하여 IVR을 실행합니다.이 스크립트는 수신 통화의 ANI(자동 번호 식별)를 확인합니다. 이렇게 하면 전화를 받는 통화가 발신자 번호 및 DNIS(Dialed Number Identification Service) 및 특정 디바이스에 도달하기 위해 호출된 번호를 식별하는 데 도움이 됩니다.그런 다음 사용자 이름 및 비밀번호와 일치시키려고 시도합니다.스크립트에서 사용자 이름과 비밀번호와 일치하지 않을 경우, 음성 프롬프트를 사용하여 계정 번호와 비밀번호(종종 "PIN"이라고도 함)를 가져옵니다. 발신자가 사용자 이름 및 비밀번호를 입력하고 인증되면 스크립트는 호출할 대상 번호를 묻는 메시지를 표시합니다.

여기서 IVR 컨피그레이션은 매우 기본적인 컨피그레이션입니다.실제 환경에서는 사용자 이름과 암호가 RADIUS(Remote Authentication Dial-In User Service) 서버에 저장됩니다.AAA(Authentication, Authorization, and Accounting) 인증 로그인 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]를 입력합니다.자세한 내용은 Cisco Access Platform에 대한 대화형 음성 응답 구성을 참조하십시오.

구성

이 문서에서는 다음 구성을 사용합니다.

롤리 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

다음을 확인합니다.

이 섹션에서는 컨피그레이션이 제대로 작동하는지 확인하는 정보를 제공합니다.

Output Interpreter 도구(등록된 고객만 해당)(OIT)는 특정 show 명령을 지원합니다.OIT를 사용하여 show 명령 출력의 분석을 봅니다.

이 문서에 표시된 컨피그레이션을 라우터에 입력한 후 네트워크가 올바르게 작동하는지 확인합니다.이러한 명령 및 각 출력은 이 문서의 구성을 성공적으로 구현한 것을 보여줍니다.

IVR의 가장 일반적인 문제는 다음과 같습니다.

  • 일반 POTS(전화 서비스) 다이얼 피어에서 애플리케이션이 제대로 구성되지 않았습니다.

    • 수신 전화 통화가 POTS 다이얼 피어와 응용 프로그램과 일치하는지 확인합니다.POTS 다이얼 피어는 컨피그레이션에서 일치하는 port 명령을 사용하여 첫 번째 다이얼 피어와 일치하거나, 호출된 번호가 수신의 dial-peer 하위 명령과 일치하는 경우 called-number <number>와 일치합니다.

    • 애플리케이션이 실제로 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

문제 해결

이 섹션에서는 컨피그레이션 트러블슈팅을 위한 정보를 제공합니다.

문제 해결 명령

Output Interpreter 도구(등록된 고객만 해당)(OIT)는 특정 show 명령을 지원합니다.OIT를 사용하여 show 명령 출력의 분석을 봅니다.

참고: 디버그 명령을 실행하기 전에 디버그 명령에 대한 중요 정보를 참조하십시오.

  • debug voip aaa - 게이트웨이 aaa에 대한 디버깅 메시지를 시스템 콘솔에 출력할 수 있도록 합니다.

  • debug isdn q931 - 로컬 라우터(사용자측)와 네트워크 간의 ISDN 네트워크 연결(레이어 3)의 통화 설정 및 해제 정보를 표시합니다.

  • debug voip ccapi inout - 호출 제어 API를 디버깅합니다.

  • debug voip ivr - IVR 응용 프로그램을 디버깅합니다.

  • 디버그 ras - 전송 및 수신된 RAS(Registration, Admission 및 Status Protocol) 메시지의 유형 및 주소를 표시합니다.

관련 정보

이 문서가 도움이 되셨습니까?

Feedback피드백

지원 문의

이 문서가 적용되는 제품