Guest

Gateway Protocols

H.323 Video Call Flow Across CUBE and Cisco Gatekeeper

Document ID: 107976

Updated: Sep 29, 2008

   Print

Introduction

The objective of this document is to provide configuration and troubleshooting information for H.323 video calls across Cisco Unified Border Element (CUBE) and Cisco Gatekeeper.

Network Topology Details:

There are two sites:

  • Site-1 uses Cisco Unified Communication Manager.

  • Site-2 uses Cisco Unified Communication Manager Express (CME).

Each site has a CUBE and Gatekeeper co-located on the same device. The Gatekeeper on Site-1 is configured as a remote gatekeeper in Site-2 and vice-versa. Inter-site calls are routed through the CUBE (flow-through mode) located in each site. Cisco Unified Communication Manager and CUBE at Site 1 use tech-prefix #2. CME and CUBE at Site 2 use tech-prefix #3.

Users use VT Advantage cameras and IP phone to make audio / video calls.

Prerequisites

Requirements

There are no specific requirements for this document.

Components Used

The information in this document is based on these software and hardware versions:

  • Cisco Unified CallManager—6.1.1.3000-2

  • CUBE and Gatekeeper—Cisco IOS Software Release 12.4(15)T6

  • Cisco CallManager Express—Cisco IOS Software Release 12.4(15)T6

The information in this document was created from the devices in a specific lab environment. All of the devices used in this document started with a cleared (default) configuration. If your network is live, make sure that you understand the potential impact of any command.

Conventions

Refer to Cisco Technical Tips Conventions for more information on document conventions.

Configure

In this section, you are presented with the information to configure the features described in this document.

Note: Use the Command Lookup Tool (registered customers only) to find more information on the commands used in this document.

Network Diagram

This document uses this network setup:

cube_call_flow_diag.gif

Configurations

This document uses these configurations:

  • CUBE and Gatekeeper Configuration in Site-1

  • CUBE and Gatekeeper Configuration in Site-2

  • CME Configuration

  • Cisco Unified Communication Manager Configuration

CUBE and Gatekeeper Configuration in Site-1

!---Enable H.323 – H.323 call connections


voice service voip 
 allow-connections h323 to h323



!--- Configure the CUBE to register with the local Gatekeeper zone CCM-CUBE 
!--- using tech-prefix 2# and CUBE-1 as the H323 ID

 
interface FastEthernet0/0
 ip address 14.50.201.17 255.255.255.0
 h323-gateway voip interface
 h323-gateway voip id CCM-CUBE ipaddr 14.50.201.17 1719
 h323-gateway voip h323-id CUBE-1
 h323-gateway voip tech-prefix 2#
 h323-gateway voip bind srcaddr 14.50.201.17
!


!--- Configure dial-peers to route calls with called numbers prefixed 
!--- with 2# and 3#


dial-peer voice 919 voip
 destination-pattern 2#T
 session target ras
 incoming called-number .
 dtmf-relay h245-alphanumeric
 codec g711ulaw
 no vad
!
dial-peer voice 408 voip
 destination-pattern 3#T
 session target ras
 dtmf-relay h245-alphanumeric
 codec g711ulaw
 no vad


!--- Configure local zones CCM, CCM-CUBE and remote zone CME-CUBE
!--- Configure a zone prefix to route 919* calls to CCM Zone
!--- Configure a hop-off prefix to route calls beginning with 3# to remote zone CME-CUBE

!--- Configure invia and outvia parameters such that calls coming in / going out CCM 
!--- zone are sent via the IP-IP Gateway registered in CCM-CUBE zone

!--- Configure invia and outvia parameters such that calls coming in / going out of 
!--- remote CME-CUBE zone are sent  via the IP-IP Gateway registered in CCM-CUBE zone



gatekeeper
zone local CCM cisco.com 14.50.201.17 invia CCM-CUBE outvia CCM-CUBE
zone local CCM-CUBE cisco.com
zone remote CME-CUBE cisco.com 14.1.123.95 1719 invia CCM-CUBE outvia CCM-CUBE
zone prefix CCM 919.......
gw-type-prefix 3#* hopoff CME-CUBE
no shutdown



!—-- Enable H.323 VoIP Gateway

gateway

CUBE and Gatekeeper Configuration in Site-2

!---Enable H.323 – H.323 call connections


voice service voip 
 allow-connections h323 to h323



!--- Configure the CUBE to register with the local Gatekeeper zone CME-CUBE 
!--- using tech-prefix 3# and CUBE-2 as the H323 ID

 
interface FastEthernet0/0
 ip address 14.1.123.95 255.255.255.0
 h323-gateway voip interface
 h323-gateway voip id CME-CUBE ipaddr 14.1.123.95 1719
 h323-gateway voip h323-id CUBE-2
 h323-gateway voip tech-prefix 3#
 h323-gateway voip bind srcaddr 14.1.123.95
!


!--- Configure dial-peers to route calls with called numbers prefixed with 2# and 3#
!--- using the Gatekeeper


dial-peer voice 919 voip
 destination-pattern 2#T
 session target ras
 incoming called-number .
 dtmf-relay h245-alphanumeric
 codec g711ulaw
 no vad
!
dial-peer voice 408 voip
 destination-pattern 3#T
 session target ras
 dtmf-relay h245-alphanumeric
 codec g711ulaw
 no vad


!--- Configure local zones CME, CME-CUBE and remote zone CCM-CUBE
!--- Configure a zone prefix to route 408* calls to CME Zone
!--- Configure a hop-off prefix to route calls beginning with 2# to remote zone CCM-CUBE

!--- Configure invia and outvia parameters such that calls coming in / going out 
!--- of CME zone are sent through the IP-IP Gateway registered in CME-CUBE zone.

!--- Configure invia and outvia parameters such that calls coming in / going out 
!--- of remote CCM-CUBE zone are sent  via the IP-IP Gateway registered in CME-CUBE zone


gatekeeper
 zone local CME cisco.com 14.1.123.95 invia CME-CUBE outvia CME-CUBE
 zone local CME-CUBE cisco.com
 zone remote CCM-CUBE cisco.com 14.50.201.17 1719 invia CME-CUBE outvia CME-CUBE
 zone prefix CME 4085252...
 gw-type-prefix 2#* hopoff CCM-CUBE
 no shutdown
!
 

!—--Enable H.323 VoIP Gateway

gateway

CME Configuration

!--- Configure the CME to register with the Gatekeeper zone CME  
!--- using tech-prefix 3# and CME-1 as the H323 ID

 
interface GigabitEthernet0/0
 ip address 14.1.103.74 255.255.255.0
 h323-gateway voip interface
 h323-gateway voip id CME ipaddr 14.1.123.95 1719
 h323-gateway voip h323-id CME-1
 h323-gateway voip tech-prefix 3#
 h323-gateway voip bind srcaddr 14.1.103.74


!--- Configure inbound  dial-peer with a translation profile to strip 3# 
!--- in  the called-number of incoming calls received by CME


!
voice translation-rule 1
 rule 1 /^3#\(.*$\)/ /\1/
!
!
voice translation-profile 1
 translate called 1
!

dial-peer voice 3 voip
 translation-profile incoming 1
 incoming called-number 3#.
 dtmf-relay h245-alphanumeric
 codec g711ulaw
 no vad
!


!--- Configure outbound  dial-peer to route calls to 919* via the Gatekeeper.
!--- Note that 2# is prefixed to the called number using the tech-prefix command


dial-peer voice 919 voip
 destination-pattern 9193922000
 session target ras
 tech-prefix 2#
 codec g711ulaw
 dtmf-relay h245-alphanumeric
 no vad
 

!—-- Enable H.323 VoIP Gateway

gateway

Cisco Unified Communication Manager Configuration

Complete these steps:

  1. Configure a Gatekeeper (Device > Gatekeeper) on the Cisco Unified Communication Manager Administration page.

    cube_call_flow1.gif

  2. Configure a H.225 Gatekeeper Controlled Trunk (Device → Trunk) on the Cisco Unified Communications Manager Administration page with Gatekeeper Name, Terminal Type, Technology prefix and Zone parameters.

    cube_call_flow2.gif

    cube_call_flow3.gif

  3. Configure a Route pattern to route calls to 4085252000 across the H.225 Trunk configured in Step 2. Note that the Prefix Digits (Outgoing calls) field is set to 3#.

    cube_call_flow4.gif

    cube_call_flow5.gif

  4. Configure a Translation Pattern in order to strip the 2# on inbound calls across the H225 Trunk.

    cube_call_flow6.gif

Verify

Use this section in order to confirm your configuration works properly.

The Output Interpreter Tool (registered customers only) (OIT) supports certain show commands. Use the OIT to view an analysis of show command output.

Gateway

Use this section to confirm that your configuration works properly at the Cisco IOS Gatekeeper.

These gatekeeper show commands were collected after enabling debug gatekeeper main 10:

  • Show gatekeeper endpoints

    Gatekeeper-1

                GATEKEEPER ENDPOINT REGISTRATION
                        ================================
    CallSignalAddr  Port  RASSignalAddr   Port  Zone Name    Type    Flags 
    --------------- ----- --------------- ----- ---------    ----    ----- 
    14.50.201.17    1720  14.50.201.17    62820 CCM-CUBE     H323-GW 
        ENDPOINT-ID: 83D872B800000001  VERSION: 4  AGE: 24 secs  SupportsAnnexE: FALSE
        g_supp_prots: 0x00000050
        H323-ID: CUBE-1
        Voice Capacity Max.=  Avail.=  Current.= 2
    14.50.201.81    39284 14.50.201.81    33580 CCM          
       VOIP-GW 
        ENDPOINT-ID: 849D11EC00000002  VERSION: 5  AGE: 8 secs  SupportsAnnexE: FALSE
        g_supp_prots: 0x00000050
        H323-ID: CCM-GK-Trunk_1
        Voice Capacity Max.=  Avail.=  Current.= 1
    Total number of active registrations = 2
    

    Gatekeeper-2

                GATEKEEPER ENDPOINT REGISTRATION
                        ================================
    CallSignalAddr  Port  RASSignalAddr   Port  Zone Name   Type    Flags 
    --------------- ----- --------------- ----- ---------   ----    ----- 
    14.1.123.95     1720  14.1.123.95     64422 CME-CUBE    H323-GW 
        ENDPOINT-ID: 8591ED9400000001  VERSION: 4  AGE: 10 secs  SupportsAnnexE: FALSE
        g_supp_prots: 0x00000050
        H323-ID: CUBE-2
        Voice Capacity Max.=  Avail.=  Current.= 2
    14.1.125.125    1720  14.1.125.125    56689 CME         
       VOIP-GW 
        ENDPOINT-ID: 860100E800000002  VERSION: 4  AGE: 6 secs  SupportsAnnexE: FALSE
        g_supp_prots: 0x00000050
        H323-ID: CME-1
        Voice Capacity Max.=  Avail.=  Current.= 1
    Total number of active registrations = 2
    
  • Show gatekeeper gw-type-prefix

    Gatekeeper-1

    GATEWAY TYPE PREFIX TABLE
    =========================
    Prefix: 3#*    (Hopoff zone CME-CUBE)
    
    Prefix: 2#*
      Zone CCM master gateway list:
        14.50.201.81:39284 CCM-GK-Trunk_1 
      Zone CCM-CUBE master gateway list:
        14.50.201.17:1720 CUBE-1
    

    Gatekeeper-2

    GATEWAY TYPE PREFIX TABLE
    =========================
    Prefix: 2#*    (Hopoff zone CCM-CUBE)
    
    Prefix: 3#*
      Zone CME master gateway list:
        14.1.125.125:1720 CME-1 
      Zone CME-CUBE master gateway list:
        14.1.123.95:1720 CUBE-2
    
  • Show gatekeeper calls

    Gatekeeper-1

    Total number of active calls = 2.
    
    largest hash bucket = 2
                                   GATEKEEPER CALL INFO
                                   ====================
    LocalCallID                        Age(secs)   BW
    7-196               760            26          832(Kbps)
    ConferenceID                         CallID                               SrcCRV
    006E38C4 3570518C 03000301 0E32CA1F  006E38C4 3570518C 03000301 0E32CA1F  3
     Endpt(s): Alias                 E.164Addr
       src EP: CCM-GK-Trunk_1        9193922000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.50.201.81    39284 14.50.201.81    33580
     Endpt(s): Alias                 E.164Addr
       dst EP: CUBE-1                3#4085252000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.50.201.17    1720  14.50.201.17    62820
            callstate: SEP, DEP, 
    LocalCallID                        Age(secs)   BW
    8-196               760            25          832(Kbps)
    ConferenceID                         CallID                               SrcCRV
    006E38C4 3570518C 03000301 0E32CA1F  006E38C4 3570518C 03000301 0E32CA1F  8
     Endpt(s): Alias                 E.164Addr
       src EP: CUBE-1                9193922000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.50.201.17    1720  14.50.201.17    62820
     Endpt(s): Alias                 E.164Addr
       dst EP:                       3#4085252000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.1.123.95     1720  14.1.123.95     1720 
            callstate: SEP,

    Gatekeeper-2

    Total number of active calls = 2.
    
    largest hash bucket = 2
                                   GATEKEEPER CALL INFO
                                   ====================
    LocalCallID                        Age(secs)   BW
    15-196              760            41          832(Kbps)
    ConferenceID                         CallID                               SrcCRV
    006E38C4 3570518C 03000301 0E32CA1F  006E38C4 3570518C 03000301 0E32CA1F  0
     Endpt(s): Alias                 E.164Addr
       src EP: CUBE-1                9193922000
     Endpt(s): Alias                 E.164Addr
       dst EP: CUBE-2                3#4085252000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.1.123.95     1720  14.1.123.95     64422
            callstate: DEP, 
    LocalCallID                        Age(secs)   BW
    16-196              760            41          832(Kbps)
    ConferenceID                         CallID                               SrcCRV
    006E38C4 3570518C 03000301 0E32CA1F  006E38C4 3570518C 03000301 0E32CA1F  16
     Endpt(s): Alias                 E.164Addr
       src EP: CUBE-2                9193922000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.1.123.95     1720  14.1.123.95     64422
     Endpt(s): Alias                 E.164Addr
       dst EP: CME-1                 3#4085252000
               CallSignalAddr  Port  RASSignalAddr   Port
               14.1.125.125    1720  14.1.125.125    56689
            callstate: SEP, DEP,

CUBE

Use this section in order to confirm that your configuration works properly at the CUBE.

  • Show gateway

    Cube-1

    H.323 ITU-T Version: 4.0   H323 Stack Version: 0.1 
    
     H.323 service is up
     Gateway  CUBE-1  is registered to Gatekeeper CCM-CUBE
    
    Alias list (CLI configured) 
     H323-ID CUBE-1
    Alias list (last RCF) 
     H323-ID CUBE-1

    Cube-2

    H.323 ITU-T Version: 4.0   H323 Stack Version: 0.1 
          
     H.323 service is up
     Gateway  CUBE-2  is registered to Gatekeeper CME-CUBE
    
    Alias list (CLI configured) 
     H323-ID CUBE-2
    Alias list (last RCF) 
     H323-ID CUBE-2
    
  • Show call active video brief

    Cube-1

    148C : 2153 192864460ms.1 +6560 pid:919 Answer 9193922000 active
     dur 00:00:23 tx:1714/557033 rx:1704/360129
     IP 14.50.201.81:5445 SRTP: off rtt:0ms pl:0/0ms lost:0/0/0 
        delay:0/0/0ms g711ulaw TextRelay: off
     media inactive detected:n media contrl rcvd:n/a timestamp:n/a
     long duration call detected:n long duration call duration:n/a timestamp:n/a
    
    148C : 2154 192864490ms.1 +6390 pid:408 Originate 3#4085252000 active
     dur 00:00:23 tx:1704/360129 rx:1714/557033
     IP 14.1.123.95:17180 SRTP: off rtt:0ms pl:0/0ms lost:0/0/0 
        delay:0/0/0ms g711ulaw TextRelay: off
     media inactive detected:n media contrl rcvd:n/a timestamp:n/a
     long duration call detected:n long duration call duration:n/a timestamp:n/a
    
    Telephony call-legs: 0
    SIP call-legs: 0
    H323 call-legs: 2
    Call agent controlled call-legs: 0
    SCCP call-legs: 0
    Multicast call-legs: 0
    Media call-legs: 0
    Total call-legs: 2

    Cube-2

    148C : 23 192861220ms.1 +5840 pid:919 Answer 9193922000 active
     dur 00:00:38 tx:2845/922239 rx:2824/571918
     IP 14.50.201.17:19332 SRTP: off rtt:0ms pl:0/0ms lost:0/0/0 
       delay:0/0/0ms g711ulaw 
         TextRelay: off
     media inactive detected:n media contrl rcvd:n/a timestamp:n/a
     long duration call detected:n long duration call duration:n/a timestamp:n/a
    
    148C : 24 192861250ms.1 +5640 pid:408 Originate 3#4085252000 active
     dur 00:00:39 tx:2825/572078 rx:2846/922898
     IP 14.1.125.125:17224 SRTP: off rtt:0ms pl:0/0ms lost:0/0/0 
       delay:0/0/0ms g711ulaw 
         TextRelay: off
     media inactive detected:n media contrl rcvd:n/a timestamp:n/a
     long duration call detected:n long duration call duration:n/a timestamp:n/a
    
    Telephony call-legs: 0
    SIP call-legs: 0
    H323 call-legs: 2
    Call agent controlled call-legs: 0
    SCCP call-legs: 0
    Multicast call-legs: 0
    Media call-legs: 0
    Total call-legs: 2
  • Show voip rtp connections

    Cube-1

    VoIP RTP active connections :
    No. CallId     dstCallId  LocalRTP RmtRTP LocalIP         RemoteIP       
    1   2153       2154       17782    18956  14.50.201.17    14.50.202.31   
    2   2154       2153       16418    19496  14.50.201.17    14.1.123.95    
    3   2155       2156       16564    5445   14.50.201.17    14.50.201.44   
    4   2156       2155       19332    17180  14.50.201.17    14.1.123.95    
    Found 4 active RTP connections

    Cube-2

    VoIP RTP active connections :
    No. CallId     dstCallId  LocalRTP RmtRTP LocalIP         RemoteIP       
    1   23         24         19496    16418  14.1.123.95     14.50.201.17   
    2   24         23         16772    16904  14.1.123.95     14.1.125.125   
    3   25         26         17180    19332  14.1.123.95     14.50.201.17   
    4   26         25         17338    17224  14.1.123.95     14.1.125.125   
    Found 4 active RTP connections

Troubleshoot

Use this section in order to troubleshoot your configuration.

Debug Commands

Configure the Cisco IOS Gateway to log the debugs in its logging buffer and disable logging console.

Note: Refer to Important Information on Debug Commands before you use debug commands.

Note: Show and Debug commands for common problems are available at Multiservice voice debug lookup tool.

These are the commands used to configure the gateway in order to store the debugs in the logging buffer of the gateway:

  • service timestamps debug datetime msec

  • service sequence

  • no logging console

  • logging buffered 5000000 debug

  • clear log

CUBE Debugs

  • debug voip ccapi inout

  • debug ras

  • debug h225 asn1

  • debug h245 asn1

  • debug cch323 h225

  • debug cch323 h245

  • debug voip ipipgw

Gatekeeper Debugs

  • debug ras

  • debug gatekeeper main 10

  • debug gatekeeper call 10

  • debug gatekeeper zone 10

Call Flow Example

This section describes the call flow that results from this configuration example.

  1. IP Phone (919-392-2000) makes a call to IP Phone (408-525-2000)

  2. Cisco Unified Communication Manager prefixes a 3# to the called-number and sends an ARQ request to the Gatekeeper in Site-1

  3. Gatekeeper-1 identifies that the call is inbound from CCM zone and checks whether there is an invia zone configured

  4. Gatekeeper-1 determines CCM-CUBE as the invia zone for CCM zone and tries to find an IP-IP gateway in CCM-CUBE zone

  5. Gatekeeper-1 finds the local IP-IP gateway (CUBE-1) and sends the IP address of the gateway (14.50.201.17) in the ACF response

  6. Cisco Unified Communication Manager sends a H225 setup message to CUBE-1

  7. CUBE-1 sends an ARQ request with “answerCall” set to TRUE to Gatekeeper-1

  8. Gatekeeper-1 sends an ACF response to CUBE-1

  9. CUBE-1 then matches inbound dial-peer 919 and outbound dial-peer 408 and sends an ARQ request for 3#4085252000 to Gatekeeper-1

  10. CUBE-1 sends H225 Call Proceeding message to Cisco Unified Communication Manager

  11. As there are no invia zones configured for CCM-CUBE zone, Gatekeeper-1 performs normal ARQ processing. It finds 3# tech-prefix in the destination number

  12. 3# is configured as a hopoff prefix for remote zone CME-CUBE. Hence, Gatekeeper-1 sends a LRQ (Location request) to Gatekeeper-2

  13. Gatekeeper-2 receives the LRQ and identifies that LRQ is from remote zone CCM-CUBE. It checks whether there is an invia zone configured for remote zone CCM-CUBE

  14. Gatekeeper-2 determines CME-CUBE as the invia zone for CCM-CUBE zone and tries to find an IP-IP gateway in CME-CUBE

  15. Gatekeeper-2 finds the local IP-IP gateway (CUBE-2) and sends the IP address of the gateway (14.1.123.95) in the LCF response

  16. Gatekeeper-1 receives the LCF response and sends an ACF response with the IP address of CUBE-2 to CUBE-1

  17. CUBE-1 sends a H225 setup message to CUBE-2

  18. CUBE-2 sends an ARQ request with “answerCall” set to TRUE to Gatekeeper-2

  19. Gatekeeper-2 sends an ACF response to CUBE-2

  20. CUBE-2 then matches inbound dial-peer 919 and outbound dial-peer 408 and sends an ARQ request for 3#4085252000 to Gatekeeper-2

  21. CUBE-2 sends an H225 Call Proceeding message to CUBE-1

  22. Because there are no invia zones configured for CCM-CUBE zone, Gatekeeper-2 performs normal ARQ processing. It finds 3# tech prefix in the destination number

  23. Gatekeeper-2 uses the remaining digits (4085252000) to find a zone prefix match. It determines that CME zone can handle this 408 prefix and tries to find a gateway that is registered in CME zone with a tech-prefix 3#

  24. Gatekeeper-2 selects CME as the destination gateway and sends its IP address (14.1.103.74) in the ACF response

  25. CUBE-2 receives the ACF response and sends a H225 setup message to CME

  26. Gatekeeper receives an ARQ request with “answerCall” set to TRUE from CME and sends an ACF response

  27. CUBE-2 receives H225 Call proceeding, Alerting and Connect messages from CME, which are then passed all the way back to Cisco Unified Communications Manager

  28. H.245 negotiation takes place. Audio and video RTP streams are established

  29. 4085252000 hangs up the call. CUBE-2 receives H225 Release-complete from CME

  30. After receiving / sending Release-complete, CCM, CUBE-1, CUBE-2 and CME send a Disengage Request (DRQ) to their respective Gatekeepers

  31. CUBE-2 sends Release-complete to CUBE-1, which then sends a corresponding Release-complete message to Cisco Unified Communications Manager and the call disconnects

Debug Outputs

This section provides debug outputs for the call flow discussed in this section.

Click these hyperlinks for complete debug output:

Step 1

IP Phone (919-392-2000) makes a call to IP Phone (408-525-2000).

Step 2

Cisco Unified Communication Manager prefixes a 3# to the called-number and sends an ARQ request to the Gatekeeper in Site-1.

(GK-CUBE-1.txt)

008874: *Jul 24 06:49:52.584: RAS INCOMING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 72
      callType pointToPoint : NULL
      endpointIdentifier {"849D11EC00000002"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000"
      }
      srcCallSignalAddress ipAddress : 
      {
        ip '0E32C951'H
        port 39284
      }
      bandWidth 7680
      callReferenceValue 3
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall FALSE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      gatekeeperIdentifier {"CCM"}
      willSupplyUUIEs FALSE
    }

Step 3

Gatekeeper-1 identifies that the call is inbound from CCM zone and checks whether there is an invia zone configured.

(GK-CUBE-1.txt)

008882: *Jul 24 06:49:52.600: //006E38C40300/006E38C40300/GK/rassrv_get_addrinfo:
   (3#4085252000) Matched tech-prefix 3#
008883: *Jul 24 06:49:52.600: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_get_ingress_network:
   returning default ingress network = 1
008884: *Jul 24 06:49:52.600: //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone:
   about to check the source side, src_zonep=0x8528AAE8
008885: *Jul 24 06:49:52.600: //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone:
   matched zone is CCM, and z_invianamelen=8

Step 4

Gatekeeper-1 determines CCM-CUBE as the invia zone for CCM zone and tries to find an IP-IP gateway in CCM-CUBE zone.

(GK-CUBE-1.txt)

008886: *Jul 24 06:49:52.600: //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone
    and z_invianamep=CCM-CUBE
008887: *Jul 24 06:49:52.600: zone_gkid_search_cluster: 
    searching for gkid CCM-CUBE
008888: *Jul 24 06:49:52.600: zone_gkid_search_cluster:
    searching local cluster for CCM-CUBE, z_gknamep: CCM z_flags: 0x3000017
008889: *Jul 24 06:49:52.600: //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone(CCM): 
    Terminating inbound call at the IPIPGW in zone CCM-CUBE

Step 5

Gatekeeper-1 finds the local IP-IP gateway (CUBE-1) and sends the IP address of the gateway (14.50.201.17) in the ACF response.

(GK-CUBE-1.txt)

008895: *Jul 24 06:49:52.604: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: Found an IPIPGW.
    tgwp: 0x84EA170C, endptsigIP: 14.50.201.17, 
    endptrasIP: 14.50.201.17, zone: CCM-CUBE
008896: *Jul 24 06:49:52.604: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: 
    Selected an IPIPGW.
008897: *Jul 24 06:49:52.604: //006E38C40300/006E38C40300/GK/rassrv_get_addrinfo:
    (3#4085252000) successfully resolved IPIPGW and returning with
    return code 0
008898: *Jul 24 06:49:52.608: H225 NONSTD OUTGOING PDU ::=

value ACFnonStandardInfo ::= 
    {
      srcTerminalAlias 
      {
        e164 : "9193922000"
      }
      dstTerminalAlias 
      {
        e164 : "3#4085252000"
      }
    }



008899: *Jul 24 06:49:52.608: H225 NONSTD OUTGOING ENCODE BUFFER::= 
    00010480C4C6C553330105806073B8585333
008900: *Jul 24 06:49:52.608: 
008901: *Jul 24 06:49:52.608: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 72
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E32C911'H
        port 1720
      }
      irrFrequency 240
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '00010480C4C6C553330105806073B8585333'H
      }
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
    }

Step 6

Cisco Unified Communication Manager sends a H225 setup message to CUBE-1.

(GK-CUBE-1.txt)

008913: *Jul 24 06:49:52.636: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body setup : 
        {
          protocolIdentifier { 0 0 8 2250 0 5 }
          sourceAddress 
          {
            dialedDigits : "9193922000",
            h323-ID : {"9193922000..."}
          }
          sourceInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F43616C6C4D616E61676572'H
              versionId '31'H
            }
            terminal 
            {
            }
            mc FALSE
            undefinedNode FALSE
          }
          destinationAddress 
          {
            dialedDigits : "3#4085252000"
          }
          activeMC FALSE
          conferenceID '006E38C43570518C030003010E32CA1F'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress : 
          {
            ip '0E32C951'H
            port 39284
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
        nonStandardControl 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '8144000400010300'H
          }
        }
      }
    }

008917: *Jul 24 06:49:52.664: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver:
    Received msg of type SETUPIND_CHOSEN
008918: *Jul 24 06:49:52.664: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry
008919: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind:
    callingNumber[9193922000] calledNumber[3#4085252000]
008920: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind: 
    ---- calling IE present
008921: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind: ====== PI = 0
008922: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind: 
    Receive: infoXCap 8
008923: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind: 
    Receive: infoXCap ccb 8
008924: *Jul 24 06:49:52.664: //2153/006E38C40300/H323/setup_ind: 
    Receive bearer cap infoXRate 24, rateMult 6
008925: *Jul 24 06:49:52.668: //2153/006E38C40300/H323/setup_ind: 
setup_ind: is_overlap = 0, info_complete = 0

Step 7

CUBE-1 sends an ARQ request with “answerCall” set to TRUE to Gatekeeper-1.

(GK-CUBE-1.txt)

008932: *Jul 24 06:49:52.672: H225 NONSTD OUTGOING ENCODE BUFFER::= 80000010800181
008933: *Jul 24 06:49:52.672: 
008934: *Jul 24 06:49:52.676: RAS OUTGOING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 4099
      callType pointToPoint : NULL
      callModel direct : NULL
      endpointIdentifier {"83D872B800000001"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000",
        dialedDigits : "9193922000",
        h323-ID : {"9193922000..."}
      }
      srcCallSignalAddress ipAddress : 
      {
        ip '0E32C951'H
        port 39284
      }
      bandWidth 7680
      callReferenceValue 7
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '80000010800181'H
      }
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall TRUE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      willSupplyUUIEs FALSE
    }

Step 8

Gatekeeper-1 sends an ACF response to CUBE-1.

(GK-CUBE-1.txt)

008950: *Jul 24 06:49:52.724: H225 NONSTD OUTGOING ENCODE BUFFER::= 40
008951: *Jul 24 06:49:52.724: 
008952: *Jul 24 06:49:52.724: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 4099
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E32C911'H
        port 1720
      }
      irrFrequency 240
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
      usageSpec 
      {

        {
          when 
          {
            end NULL
            inIrr NULL
          }
          callStartingPoint 
          {
            connect NULL
          }
          required 
          {
            nonStandardUsageTypes 
            {

              {
                nonStandardIdentifier h221NonStandard : 
                {
                  t35CountryCode 181
                  t35Extension 0
                  manufacturerCode 18
                }
                data '40'H
              }
            }
            startTime NULL
            endTime NULL
            terminationCause NULL
          }
        }
      }
    }

Step 9

CUBE-1 then matches inbound dial-peer 919 and outbound dial-peer 408 and sends an ARQ request for 3#4085252000 to Gatekeeper-1.

(GK-CUBE-1.txt)

008974: *Jul 24 06:49:52.772: //-1/006E38C40300/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x857AB698, Call Info(
   Calling Number=9193922000,(Calling Name=)(TON=Unknown, NPI=Unknown,
      Screening=User, Passed, Presentation=Allowed),
   Called Number=3#4085252000(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=919, Progress Indication=NULL(0),
      Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE),
      Call Id=2153


008995: *Jul 24 06:49:52.797: //2153/006E38C40300/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x857AB698, Interface Type=1, Destination=, Mode=0x0,
   Call Params(Calling Number=9193922000,(Calling Name=)(TON=Unknown, NPI=Unknown,
      Screening=User, Passed, Presentation=Allowed),
   Called Number=3#4085252000(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=408,
      Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, 
      Application Call Id=)

009019: *Jul 24 06:49:52.813: H225 NONSTD OUTGOING PDU ::=

value ARQnonStandardInfo ::= 
    {
      sourceAlias 
      {
      }
      sourceExtAlias 
      {
      }
      callingOctet3a 129
      gtd '49414D2C0D0A4745412C747273332C30302C312C...'H
      ingressNetwork h323 : NULL
    }



009020: *Jul 24 06:49:52.813: H225 NONSTD OUTGOING ENCODE BUFFER::= 
    800000108901812A002749414D2C0D0A4745412C747273332C30302C312C792
    C792C312C393139333932323030300D0A0D0A0120
009021: *Jul 24 06:49:52.817: 
009022: *Jul 24 06:49:52.817: RAS OUTGOING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 4100
      callType pointToPoint : NULL
      callModel direct : NULL
      endpointIdentifier {"83D872B800000001"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000",
        h323-ID : {"CUBE-1"}
      }
      bandWidth 7680
      callReferenceValue 8
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '800000108901812A002749414D2C0D0A4745412C...'H
      }
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall FALSE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      willSupplyUUIEs FALSE
    }

Step 10

CUBE-1 sends H225 Call Proceeding message to Cisco Unified Communication Manager.

009029: *Jul 24 06:49:52.833: //2153/006E38C40300/H323/run_h225_sm:
    Received event H225_EV_CALLPROC while at state H225_SETUP
009030: *Jul 24 06:49:52.833: //2153/006E38C40300/H323/cch323_h225_set_new_state:
    Changing from H225_SETUP state to H225_CALLPROC state
009031: *Jul 24 06:49:52.833: //2153/006E38C40300/H323/generic_send_callproc:
    ====== PI = 0
009032: *Jul 24 06:49:52.837: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body callProceeding : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          destinationInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F47617465776179'H
              versionId '32'H
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "2#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
      }
    }

Step 11

As there are no invia zones configured for CCM-CUBE zone, Gatekeeper-1 performs normal ARQ processing. It finds 3# tech-prefix in the destination number.

(GK-CUBE-1.txt)

009050: *Jul 24 06:49:52.881: //006E38C40300/006E38C40300/GK/rassrv_get_addrinfo:
    (3#4085252000) Matched tech-prefix 3#
009051: *Jul 24 06:49:52.881:
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_get_ingress_network:
    ARQ non-std ingress network = 2

Step 12

3# is configured as a hopoff prefix for remote zone CME-CUBE. Hence, Gatekeeper-1 sends a LRQ (Location request) to Gatekeeper-2.

(GK-CUBE-1.txt)

009053: *Jul 24 06:49:52.881: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone:
    matched zone is CME-CUBE, and z_outvianamelen=8
009054: *Jul 24 06:49:52.881: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone 
    and z_outvianamep=CCM-CUBE
009055: *Jul 24 06:49:52.885: zone_gkid_search_cluster: 
    searching for gkid CCM-CUBE
009056: *Jul 24 06:49:52.885: zone_gkid_search_cluster: 
    searching local cluster for CCM-CUBE, z_gknamep: CCM z_flags: 0x3000017
009057: *Jul 24 06:49:52.885: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone: 
    Received ARQ for a zone (CME-CUBE) that has an outviazone (CCM-CUBE) specified, 
    but I am that viazone.  Continue normal ARQ processing

009061: *Jul 24 06:49:52.885: H225 NONSTD OUTGOING PDU ::=

value LRQnonStandardInfo ::= 
    {
      ttl 6
      nonstd-callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      callingOctet3a 129
      gatewaySrcInfo 
      {
        e164 : "9193922000",
        h323-ID : {"CUBE-1"}
      }
      gtd '49414D2C0D0A4745412C747273332C30302C312C...'H
    }



009062: *Jul 24 06:49:52.889: H225 NONSTD OUTGOING ENCODE BUFFER::= 8289B100110000
    6E38C43570518C030003010E32CA1F018116020480C4C6C5533340050043005500420045002D00
    312A002749414D2C0D0A4745412C747273332C30302C312C792C792C312C393139333932323030
    300D0A0D0A
009063: *Jul 24 06:49:52.893: 
009064: *Jul 24 06:49:52.893: RAS OUTGOING PDU ::=

value RasMessage ::= locationRequest : 
    {
      requestSeqNum 2051
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '8289B1001100006E38C43570518C030003010E32...'H
      }
      replyAddress ipAddress : 
      {
        ip '0E32C911'H
        port 1719
      }
      sourceInfo 
      {
        h323-ID : {"CCM-CUBE"}
      }
      canMapAlias TRUE
      hopCount 6
    }

Step 13

Gatekeeper-2 receives the LRQ and identifies that LRQ is from remote zone CCM-CUBE. It checks whether there is an invia zone configured for remote zone CCM-CUBE.

(GK-CUBE-2.txt)

026307: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq:
    checking the source of the LRQ. source_endptp=0x0
026308: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq:
    srcvia found gkname of source zone. looking up CCM-CUBE in zone list
026309: *Sep 24 12:43:19.182: zone_gkid_search_cluster: searching for gkid CCM-CUBE
026310: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq:
    about to check the source side, src_zonep=0x86006BF0
026311: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq:
    matched zone is CCM-CUBE

Step 14

Gatekeeper-2 determines CME-CUBE as the invia zone for CCM-CUBE zone and tries to find an IP-IP gateway in CME-CUBE.

(GK-CUBE-2.txt)

026312: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq 
    and z_invianamelen=8
026313: *Sep 24 12:43:19.182: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq
    and z_invianamep=CME-CUBE
026314: *Sep 24 12:43:19.182: zone_gkid_search_cluster: searching for gkid CME-CUBE
026315: *Sep 24 12:43:19.186: zone_gkid_search_cluster: searching local cluster for
    CME-CUBE, z_gknamep: CME z_flags: 0x3000017
026316: *Sep 24 12:43:19.186: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_lrq(CCM-CUBE):
    Terminating inbound call at the IPIPGW in zone CME-CUBE
026317: *Sep 24 12:43:19.186: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: 
    zonep: 0x86006984, tpp: 0x854C57CC, current_endpt: 1
026318: *Sep 24 12:43:19.186: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: 
    Selecting IPIPGW based on tech prefix. qelemp.head=0x8606CA90, use_count=1, 
    current_endpt=1

Step 15

Gatekeeper-2 finds the local IP-IP gateway (CUBE-2) and sends the IP address of the gateway (14.1.123.95) in the LCF response.

(GK-CUBE-2.txt)

026322: *Sep 24 12:43:19.186: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: 
    Found an IPIPGW. tgwp: 0x84F7A7B4, endptsigIP: 14.1.123.95, 
    endptrasIP: 14.1.123.95, zone: CME-CUBE
026323: *Sep 24 12:43:19.186: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_gw_select_ipipgw_random: Selected an IPIPGW.
026324: *Sep 24 12:43:19.190: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_zone_get_proxy_usage: local zone= CME-CUBE, 
    remote zone= CCM-CUBE, call direction= 0, eptype= 67650 be_entry= 0 
026325: *Sep 24 12:43:19.190: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_zone_get_proxy_usage: returns proxied = 0
026326: *Sep 24 12:43:19.190: H225 NONSTD OUTGOING PDU ::=

value LCFnonStandardInfo ::= 
    {
      termAlias 
      {
        h323-ID : {"CUBE-2"}
      }
      gkID {"CME-CUBE"}
      gateways 
      {

        {
          gwType h320-gateway : NULL
          gwAlias 
          {
            h323-ID : {"CUBE-2"}
          }
          sigAddress 
          {
            ip '0E017B5F'H
            port 1720
          }
          resources 
          {
            maxDSPs 0
            inUseDSPs 0
            maxBChannels 0
            inUseBChannels 0
            activeCalls 0
            bandwidth 0
            inuseBandwidth 0
          }
        }
      }
      gtd gtdData : '49414D2C0D0A4745412C747273332C30302C312C...'H
    }



026327: *Sep 24 12:43:19.198: H225 NONSTD OUTGOING ENCODE BUFFER::= 80014005004300
5500420045002D00320E0043004D0045002D004300550042004501000140050043005500420045002
D0032000E017B5F06B80000000000000000000004802B00002749414D2C0D0A4745412C747273332C3
0302C312C792C792C312C393139333932323030300D0A0D0A
026328: *Sep 24 12:43:19.202: 
026329: *Sep 24 12:43:19.202: RAS OUTGOING PDU ::=

value RasMessage ::= locationConfirm : 
    {
      requestSeqNum 2051
      callSignalAddress ipAddress : 
      {
        ip '0E017B5F'H
        port 1720
      }
      rasAddress ipAddress : 
      {
        ip '0E017B5F'H
        port 64422
      }
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '800140050043005500420045002D00320E004300...'H
      }
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      destinationType 
      {
        gateway 
        {
        }
        mc FALSE
        undefinedNode FALSE
      }
    }

Step 16

Gatekeeper-1 receives the LCF response and sends an ACF response with the IP address of CUBE-2 to CUBE-1.

(GK-CUBE-1.txt)

009094: *Jul 24 06:49:52.993: H225 NONSTD OUTGOING PDU ::=

value ACFnonStandardInfo ::= 
    {
      srcTerminalAlias 
      {
        e164 : "9193922000",
        h323-ID : {"CUBE-1"}
      }
      dstTerminalAlias 
      {
        e164 : "3#4085252000"
      }
      srcInfo 
      {
        e164 : "9193922000",
        h323-ID : {"CUBE-1"}
      }
      gtd gtdData : '49414D2C0D0A4745412C747273332C30302C312C...'H
    }



009095: *Jul 24 06:49:52.997: H225 NONSTD OUTGOING ENCODE BUFFER::= 80020480C4C6
    C5533340050043005500420045002D00310105806073B8585333058016020480C4C6C55333400500
    43005500420045002D00312B00002749414D2C0D0A4745412C747273332C30302C312C792C792C31
    2C393139333932323030300D0A0D0A
009096: *Jul 24 06:49:53.001: 
009097: *Jul 24 06:49:53.001: H225 NONSTD OUTGOING PDU ::=

value RasnonStdUsageTypes ::= 
    {
      callModes NULL
    }



009098: *Jul 24 06:49:53.001: H225 NONSTD OUTGOING ENCODE BUFFER::= 40
009099: *Jul 24 06:49:53.001: 
009100: *Jul 24 06:49:53.001: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 4100
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E017B5F'H
        port 1720
      }
      irrFrequency 240
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '80020480C4C6C553334005004300550042004500...'H
      }
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
      usageSpec 
      {

        {
          when 
          {
            end NULL
            inIrr NULL
          }
          callStartingPoint 
          {
            connect NULL
          }
          required 
          {
            nonStandardUsageTypes 
            {

              {
                nonStandardIdentifier h221NonStandard : 
                {
                  t35CountryCode 181
                  t35Extension 0
                  manufacturerCode 18
                }
                data '40'H
              }
            }
            startTime NULL
            endTime NULL
            terminationCause NULL
          }
        }
      }
    }

Step 17

CUBE-1 sends a H225 setup message to CUBE-2.

(GK-CUBE-1.txt)

009141: *Jul 24 06:49:53.089: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body setup : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          sourceAddress 
          {
            h323-ID : {"CUBE-1"}
          }
          sourceInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F47617465776179'H
              versionId '32'H
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "2#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          destinationAddress 
          {
            dialedDigits : "3#4085252000"
          }
          activeMC FALSE
          conferenceID '006E38C43570518C030003010E32CA1F'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress : 
          {
            ip '0E32C911'H
            port 40523
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling TRUE
        nonStandardControl 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data 'E0011200011C351C339E01000367746400000028...'H
          }
        }
        tunnelledSignallingMessage 
        {
          tunnelledProtocolID 
          {
            id tunnelledProtocolAlternateID : 
            {
              protocolType "gtd"
            }
          }
          messageContent 
          {
            '49414D2C0D0A4745412C747273332C30302C312C...'H
          }
        }
      }
    }



009142: *Jul 24 06:49:53.125: H225.0 OUTGOING ENCODE BUFFER::= 20B0060008914A
    00040140050043005500420045002D003128C0B50000120B436973636F4761746577617900324
    0023C0504010020502C050100000105806073B858533300006E38C43570518C030003010E32CA1
    F00CD0D800007000E32C9119E4B1100006E38C43570518C030003010E32CA1F010001000180018
    010A801805C0140B500001255E0011200011C351C339E0100036774640000002849414D2C0D0A4
    745412C747273332C30302C312C792C792C312C393139333932323030300D0A0D0A0A500400010
    3001127F80000000000000000000000000000002F0204677464012849414D2C0D0A4745412C747
    273332C30302C312C792C792C312C393139333932323030300D0A0D0A
009143: *Jul 24 06:49:53.129: 
009144: *Jul 24 06:49:53.129: 
    //2154/006E38C40300/H323/cch323_h225_set_new_state:
    Changing from H225_IDLE state to H225_SETUP state

Step 18

CUBE-2 sends an ARQ request with “answerCall” set to TRUE to Gatekeeper-2.

(GK-CUBE-2.txt)

026357: *Sep 24 12:43:19.442: //23/006E38C40300/H323/cch323_h225_set_new_state:
    Changing from H225_IDLE state to H225_WAIT_FOR_ARQ state
026358: *Sep 24 12:43:19.446: H225 NONSTD OUTGOING PDU ::=

value ARQnonStandardInfo ::= 
    {
      sourceAlias 
      {
      }
      sourceExtAlias 
      {
      }
      callingOctet3a 129
    }



026359: *Sep 24 12:43:19.446: H225 NONSTD OUTGOING ENCODE BUFFER::= 80000010800181
026360: *Sep 24 12:43:19.446: 
026361: *Sep 24 12:43:19.446: RAS OUTGOING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 4351
      callType pointToPoint : NULL
      callModel direct : NULL
      endpointIdentifier {"8591ED9400000001"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000",
        h323-ID : {"CUBE-1"}
      }
      srcCallSignalAddress ipAddress : 
      {
        ip '0E32C911'H
        port 40523
      }
      bandWidth 7680
      callReferenceValue 15
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '80000010800181'H
      }
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall TRUE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      willSupplyUUIEs FALSE
    }

Step 19

Gatekeeper-2 sends an ACF response to CUBE-2.

(GK-CUBE-2.txt)

026383: *Sep 24 12:43:19.494: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 4351
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E017B5F'H
        port 1720
      }
      irrFrequency 240
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
      usageSpec 
      {

        {
          when 
          {
            end NULL
            inIrr NULL
          }
          callStartingPoint 
          {
            connect NULL
          }
          required 
          {
            nonStandardUsageTypes 
            {

              {
                nonStandardIdentifier h221NonStandard : 
                {
                  t35CountryCode 181
                  t35Extension 0
                  manufacturerCode 18
                }
                data '40'H
              }
            }
            startTime NULL
            endTime NULL
            terminationCause NULL
          }
        }
      }
    }

Step 20

CUBE-2 then matches inbound dial-peer 919 and outbound dial-peer 408 and sends an ARQ request for 3#4085252000 to Gatekeeper-2.

(GK-CUBE-2.txt)

026406: *Sep 24 12:43:19.542: //-1/006E38C40300/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x855A8B64, Call Info(
   Calling Number=9193922000,(Calling Name=)(TON=Unknown, NPI=Unknown, 
      Screening=User, Passed, Presentation=Allowed),
   Called Number=3#4085252000(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=Unknown, 
      FinalDestinationFlag=TRUE,
   Incoming Dial-peer=919, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), 
      Call Id=23


026427: *Sep 24 12:43:19.567: //23/006E38C40300/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x855A8B64, Interface Type=1, Destination=, Mode=0x0,
   Call Params(Calling Number=9193922000,(Calling Name=)(TON=Unknown, NPI=Unknown,
      Screening=User, Passed, Presentation=Allowed),
   Called Number=3#4085252000(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=408,
      Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, 
      Application Call Id=)

026451: *Sep 24 12:43:19.583: H225 NONSTD OUTGOING PDU ::=

value ARQnonStandardInfo ::= 
    {
      sourceAlias 
      {
      }
      sourceExtAlias 
      {
      }
      callingOctet3a 129
      gtd '49414D2C0D0A4745412C747273332C30302C312C...'H
      ingressNetwork h323 : NULL
    }



026452: *Sep 24 12:43:19.587: H225 NONSTD OUTGOING ENCODE BUFFER::= 8000001089
   01812A002749414D2C0D0A4745412C747273332C30302C312C792C792C312C3931393339323230
   30300D0A0D0A0120
026453: *Sep 24 12:43:19.587: 
026454: *Sep 24 12:43:19.587: RAS OUTGOING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 4352
      callType pointToPoint : NULL
      callModel direct : NULL
      endpointIdentifier {"8591ED9400000001"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000",
        h323-ID : {"CUBE-2"}
      }
      bandWidth 7680
      callReferenceValue 16
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '800000108901812A002749414D2C0D0A4745412C...'H
      }
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall FALSE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      willSupplyUUIEs FALSE
    }

Step 21

CUBE-2 sends an H225 Call Proceeding message to CUBE-1.

(GK-CUBE-2.txt)

026462: *Sep 24 12:43:19.607: 
    //23/006E38C40300/H323/cch323_h225_set_new_state: 
    Changing from H225_SETUP state to H225_CALLPROC state
026463: *Sep 24 12:43:19.607: //23/006E38C40300/H323/generic_send_callproc: 
    ====== PI = 0
026464: *Sep 24 12:43:19.607: //23/006E38C40300/H323/cch323_build_qosInfo: 
    ccb=0x83D7D3D4. msg_type=0
026465: *Sep 24 12:43:19.607: //23/006E38C40300/H323/cch323_build_qosInfo: 
    media_ip_addr=0x0, remote_qos_video=0, audio_lport=0, audio_rport=0, video=0, 
    video_lport=0, video_rport=0, h245_lport=0, h245_rport=0, remote_qos_audio_bw=0, 
    remote_qos_video_bw=0

026466: *Sep 24 12:43:19.607: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::= 
    {
      rsvpParam rsvpInfo : 
      {
        qosIE 
        {
          audio-rport 0
          video-rport 0
          audio-lport 0
          video-lport 0
          media-ip-addr 0
          remote-qos-video-bw 0
          remote-qos-audio-bw 0
          remote-qos-video 0
        }
      }
    }



026467: *Sep 24 12:43:19.611: H225 NONSTD OUTGOING ENCODE BUFFER::= 
    80A1001127F8000000000000000000000000000000
026468: *Sep 24 12:43:19.611: 
026469: *Sep 24 12:43:19.611: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body callProceeding : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          destinationInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F47617465776179'H
              versionId '32'H
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "3#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling FALSE
        nonStandardControl 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '80A1001127F80000000000000000000000000000...'H
          }
        }
      }
    }

Step 22

Because there are no invia zones configured for CCM-CUBE zone, Gatekeeper-2 performs normal ARQ processing. It finds 3# tech prefix in the destination number.

(GK-CUBE-2.txt)

026487: *Sep 24 12:43:19.667: //006E38C40300/006E38C40300/GK/rassrv_get_addrinfo: 
    (3#4085252000) Matched tech-prefix 3#

Step 23

Gatekeeper-2 uses the remaining digits (4085252000) to find a zone prefix match. It determines that CME zone can handle this 408 prefix and tries to find a gateway that is registered in CME zone with a tech-prefix 3#.

(GK-CUBE-2.txt)

026488: *Sep 24 12:43:19.667: //006E38C40300/006E38C40300/GK/rassrv_get_addrinfo:
    (3#4085252000) Matched zone prefix 4085252 and remainder 000
026489: *Sep 24 12:43:19.667: 
    //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_get_ingress_network: 
    ARQ non-std ingress network = 2
026490: *Sep 24 12:43:19.667: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone: 
    about to check the destination side, dst_zonep=0x86006718
026491: *Sep 24 12:43:19.667: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone: 
    matched zone is CME, and z_outvianamelen=8
026492: *Sep 24 12:43:19.667: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone 
    and z_outvianamep=CME-CUBE
026493: *Sep 24 12:43:19.667: zone_gkid_search_cluster: searching for gkid CME-CUBE
026494: *Sep 24 12:43:19.667: zone_gkid_search_cluster: searching local cluster for
    CME-CUBE, z_gknamep: CME z_flags: 0x3000017
026495: *Sep 24 12:43:19.667: 
    //006E38C40300/006E38C40300/GK/rassrv_arq_select_viazone: 
    Received ARQ for a zone (CME) that has an outviazone (CME-CUBE) specified, 
    but I am that viazone.  Continue normal ARQ processing

Step 24

Gatekeeper-2 selects CME as the destination gateway and sends its IP address (14.1.103.74) in the ACF response.

(GK-CUBE-2.txt)


026502: *Sep 24 12:43:19.671: H225 NONSTD OUTGOING PDU ::=

value ACFnonStandardInfo ::= 
    {
      srcTerminalAlias 
      {
        e164 : "9193922000",
        h323-ID : {"CUBE-2"}
      }
      dstTerminalAlias 
      {
        e164 : "3#4085252000"
      }
    }



026503: *Sep 24 12:43:19.675: H225 NONSTD OUTGOING ENCODE BUFFER::= 
    00020480C4C6C5533340050043005500420045002D00320105806073B8585333
026504: *Sep 24 12:43:19.675: 
026505: *Sep 24 12:43:19.675: H225 NONSTD OUTGOING PDU ::=

value RasnonStdUsageTypes ::= 
    {
      callModes NULL
    }



026506: *Sep 24 12:43:19.675: H225 NONSTD OUTGOING ENCODE BUFFER::= 40
026507: *Sep 24 12:43:19.675: 
026508: *Sep 24 12:43:19.675: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 4352
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E017D7D'H
        port 1720
      }
      irrFrequency 240
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '00020480C4C6C553334005004300550042004500...'H
      }
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
      usageSpec 
      {

        {
          when 
          {
            end NULL
            inIrr NULL
          }
          callStartingPoint 
          {
            connect NULL
          }
          required 
          {
            nonStandardUsageTypes 
            {

              {
                nonStandardIdentifier h221NonStandard : 
                {
                  t35CountryCode 181
                  t35Extension 0
                  manufacturerCode 18
                }
                data '40'H
              }
            }
            startTime NULL
            endTime NULL
            terminationCause NULL
          }
        }
      }
    }

Step 25

CUBE-2 receives the ACF response and sends a H225 setup message to CME.

(GK-CUBE-2.txt)

026549: *Sep 24 12:43:19.747: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body setup : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          sourceAddress 
          {
            h323-ID : {"CUBE-2"}
          }
          sourceInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F47617465776179'H
              versionId '32'H
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "3#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          activeMC FALSE
          conferenceID '006E38C43570518C030003010E32CA1F'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress : 
          {
            ip '0E017B5F'H
            port 11398
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling TRUE
        nonStandardControl 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '80A50004000103001127F8000000000000000000...'H
          }
        }
      }
    }



026550: *Sep 24 12:43:19.775: H225.0 OUTGOING ENCODE BUFFER::= 20A0060008914
    A00040140050043005500420045002D003228C0B50000120B436973636F47617465776179003
    240023C0504010020602C05010000006E38C43570518C030003010E32CA1F00CD0D800007000
    E017B5F2C861100006E38C43570518C030003010E32CA1F010001000180018010A0018021014
    0B50000121A80A50004000103001127F8000000000000000000000000000000
026551: *Sep 24 12:43:19.779: 
026552: *Sep 24 12:43:19.779: //24/006E38C40300/H323/cch323_h225_set_new_state: 
    Changing from H225_IDLE state to H225_SETUP state

Step 26

Gatekeeper receives an ARQ request with “answerCall” set to TRUE from CME and sends an ACF response.

(GK-CUBE-2.txt)

026557: *Sep 24 12:43:19.811: RAS INCOMING PDU ::=

value RasMessage ::= admissionRequest : 
    {
      requestSeqNum 1956
      callType pointToPoint : NULL
      callModel direct : NULL
      endpointIdentifier {"860100E800000002"}
      destinationInfo 
      {
        dialedDigits : "3#4085252000"
      }
      srcInfo 
      {
        dialedDigits : "9193922000",
        h323-ID : {"CUBE-2"}
      }
      srcCallSignalAddress ipAddress : 
      {
        ip '0E017B5F'H
        port 11398
      }
      bandWidth 7680
      callReferenceValue 8
      nonStandardData 
      {
        nonStandardIdentifier h221NonStandard : 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
        data '80000010800181'H
      }
      conferenceID '006E38C43570518C030003010E32CA1F'H
      activeMC FALSE
      answerCall TRUE
      canMapAlias TRUE
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      willSupplyUUIEs FALSE
    }



026558: *Sep 24 12:43:19.823: ARQ (seq# 1956) rcvd
026559: *Sep 24 12:43:19.823: H225 NONSTD INCOMING ENCODE BUFFER::= 80000010800181
026560: *Sep 24 12:43:19.823: 
026561: *Sep 24 12:43:19.823: H225 NONSTD INCOMING PDU ::=

value ARQnonStandardInfo ::= 
    {
      sourceAlias 
      {
      }
      sourceExtAlias 
      {
      }
      callingOctet3a 129
    }


parse_arq_nonstd: ARQ Nonstd decode succeeded, remlen = -2060456504
026562: *Sep 24 12:43:19.827: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_arq: 
    arqp=0x86088C44,crv=0x8, answerCall=1
026563: *Sep 24 12:43:19.827: //006E38C40300/006E38C40300/GK/gk_rassrv_dep_arq: 
    ARQ Didn't use GK_AAA_PROC
026564: *Sep 24 12:43:19.827: H225 NONSTD OUTGOING PDU ::=

value RasnonStdUsageTypes ::= 
    {
      callModes NULL
    }



026565: *Sep 24 12:43:19.827: H225 NONSTD OUTGOING ENCODE BUFFER::= 40
026566: *Sep 24 12:43:19.827: 
026567: *Sep 24 12:43:19.831: RAS OUTGOING PDU ::=

value RasMessage ::= admissionConfirm : 
    {
      requestSeqNum 1956
      bandWidth 7680
      callModel direct : NULL
      destCallSignalAddress ipAddress : 
      {
        ip '0E017D7D'H
        port 1720
      }
      irrFrequency 240
      willRespondToIRR FALSE
      uuiesRequested 
      {
        setup FALSE
        callProceeding FALSE
        connect FALSE
        alerting FALSE
        information FALSE
        releaseComplete FALSE
        facility FALSE
        progress FALSE
        empty FALSE
      }
      usageSpec 
      {

        {
          when 
          {
            end NULL
            inIrr NULL
          }
          callStartingPoint 
          {
            connect NULL
          }
          required 
          {
            nonStandardUsageTypes 
            {

              {
                nonStandardIdentifier h221NonStandard : 
                {
                  t35CountryCode 181
                  t35Extension 0
                  manufacturerCode 18
                }
                data '40'H
              }
            }
            startTime NULL
            endTime NULL
            terminationCause NULL
          }
        }
      }
    }

Step 27

CUBE-2 receives H225 Call proceeding, Alerting and Connect messages from CME, which are then passed all the way back to Cisco Unified Communications Manager.

(GK-CUBE-2.txt)


026577: *Sep 24 12:43:19.895: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body callProceeding : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          destinationInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "3#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling FALSE
        nonStandardControl 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '80A1001127F80000000000000000000000000000...'H
          }
        }
      }
    }



026578: *Sep 24 12:43:19.919: H225 NONSTD INCOMING ENCODE BUFFER::= 
    80A1001127F8000000000000000000000000000000
026579: *Sep 24 12:43:19.919: 
026580: *Sep 24 12:43:19.919: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::= 
    {
      rsvpParam rsvpInfo : 
      {
        qosIE 
        {
          audio-rport 0
          video-rport 0
          audio-lport 0
          video-lport 0
          media-ip-addr 0
          remote-qos-video-bw 0
          remote-qos-audio-bw 0
          remote-qos-video 0
        }
      }
    }



026581: *Sep 24 12:43:19.923: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: 
    Received msg of type CALLPROCIND_CHOSEN
026582: *Sep 24 12:43:19.923: //-1/xxxxxxxxxxxx/H323/cch323_decode_qos_info: 
    media_ip_addr: 0x0, remote_qos_video: 0, audio_lport: 0, audio_rport: 0, 
    video: 0, video_lport: 0, video_rport: 0, remote qos audio bw: 0, remote 
    qos video bw: 0
026583: *Sep 24 12:43:19.923: //24/006E38C40300/H323/callproc_ind: ====== PI = 0
026584: *Sep 24 12:43:19.923: //24/006E38C40300/H323/cch323_h225_receiver: 
    CALLPROCIND_CHOSEN: src address = 14.1.123.95; dest address = 14.1.125.125
026585: *Sep 24 12:43:19.927: //24/006E38C40300/H323/run_h225_sm: Received event 
    H225_EV_CALLPROC_IND while at state H225_SETUP
026586: *Sep 24 12:43:19.927: //24/006E38C40300/H323/callproc_notify: Peer not 
    ready so not starting TCP
026587: *Sep 24 12:43:19.927: //24/006E38C40300/CCAPI/cc_api_call_proceeding:
   Interface=0x855A8B64, Progress Indication=NULL(0)


026596: *Sep 24 12:43:19.935: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body alerting : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          destinationInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "3#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling FALSE
      }
    }



026597: *Sep 24 12:43:19.951: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: 
    Received msg of type ALERTIND_CHOSEN
026598: *Sep 24 12:43:19.951: //24/006E38C40300/H323/alert_ind: ====== PI = 0
026599: *Sep 24 12:43:19.951: //24/006E38C40300/H323/alert_ind: 
    alert ind ie_bit_mask 0x5A60, displayInfo 
026600: *Sep 24 12:43:19.955: //24/006E38C40300/H323/alert_ind: 
    Rcvd ALERT Display Info IE = 
026601: *Sep 24 12:43:19.955: //24/006E38C40300/H323/alert_ind: 
    delay H245 address in alert
026602: *Sep 24 12:43:19.955: //24/006E38C40300/H323/cch323_h225_receiver: 
    ALERTIND_CHOSEN: src address = 14.1.123.95; dest address = 14.1.125.125
026603: *Sep 24 12:43:19.955: //24/006E38C40300/H323/run_h225_sm: 
    Received event H225_EV_ALERT_IND while at state H225_CALLPROC
026604: *Sep 24 12:43:19.955: //24/006E38C40300/H323/generic_alert_notify: 
    aData display_info 
026605: *Sep 24 12:43:19.955: //24/006E38C40300/CCAPI/cc_api_set_delay_xport:
   CallInfo(delay xport=TRUE)
026606: *Sep 24 12:43:19.955: //24/006E38C40300/CCAPI/cc_api_call_alert:
   Interface=0x855A8B64, Progress Indication=NULL(0), Signal Indication=SIGNAL 
    RINGBACK(1)
026607: *Sep 24 12:43:19.955: //24/006E38C40300/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
026608: *Sep 24 12:43:19.959: //24/006E38C40300/H323/cch323_h225_set_new_state: 
   Changing from H225_CALLPROC state to H225_ALERT state
026609: *Sep 24 12:43:19.959: h323chan_chn_process_read_socket
026610: *Sep 24 12:43:19.959: h323chan_chn_process_read_socket: fd=4 of type 
   CONNECTED has data
026611: *Sep 24 12:43:19.959:  h323chan_chn_process_read_socket: h323chan 
   accepted/connected fd=4

026612: *Sep 24 12:43:19.959: H225.0 INCOMING ENCODE BUFFER::= 28501900060008914A
   000400006E38C43570518C030003010E32CA1F10800100
026613: *Sep 24 12:43:19.959: 
026614: *Sep 24 12:43:19.959: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body notify : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
        }
        h245Tunneling FALSE
      }
    }



026615: *Sep 24 12:43:19.967: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: 
   Received msg of type NOTIFYIND_CHOSEN
026616: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_ind: 
   Rcvd NOTIFY Display Info IE = 
026617: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_ind: 
   Rcvd NOTIFY Notification Indicator IE = 113
026618: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_ind: 
   Rcvd NOTIFY Connected Number as IE
026619: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_ind: 
   [cnum]/[oct]/[oct3a]= [4085252000]/[0x00]/[0x00]
026620: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_ind: 
   Notify data embedded, mask=0x00000007
026621: *Sep 24 12:43:19.967: //24/006E38C40300/H323/cch323_h225_receiver: 
   NOTIFYIND_CHOSEN: src address = 14.1.123.95; dest address = 14.1.125.125
026622: *Sep 24 12:43:19.967: //24/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_NOTIFY_IND while at state H225_ALERT
026623: *Sep 24 12:43:19.967: //24/006E38C40300/H323/notify_msg_notify: 
   Notify data found, mask=0x00000007
026624: *Sep 24 12:43:19.967: //24/006E38C40300/CCAPI/cc_api_call_notify:
   Data Bitmask=0x7, Interface=0x855A8B64, Call Id=24
026625: *Sep 24 12:43:19.971: //23/006E38C40300/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
026626: *Sep 24 12:43:19.975: //23/006E38C40300/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, AlertSent=TRUE)



026679: *Sep 24 12:43:25.204: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body connect : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          h245Address ipAddress : 
          {
            ip '0E017D7D'H
            port 11360
          }
          destinationInfo 
          {
            vendor 
            {
              vendor 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
            }
            gateway 
            {
              protocol 
              {
                voice : 
                {
                  supportedPrefixes 
                  {

                    {
                      prefix dialedDigits : "3#"
                    }
                  }
                },                h323 : 
                {
                  supportedPrefixes 
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          conferenceID '006E38C43570518C030003010E32CA1F'H
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling FALSE
      }
    }



026680: *Sep 24 12:43:25.224: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: 
   Received msg of type SETUPCFM_CHOSEN
026681: *Sep 24 12:43:25.224: //24/006E38C40300/H323/setup_cfm_ind: ====== PI = 0
026682: *Sep 24 12:43:25.224: //24/006E38C40300/H323/setup_cfm_ind: 
   Set new event H225_EV_SETUP_CFM_IND
026683: *Sep 24 12:43:25.224: //24/006E38C40300/H323/setup_cfm_ind: 
   Rcvd CONNECT Display Info IE = 
026684: *Sep 24 12:43:25.228: //24/006E38C40300/H323/cch323_h225_receiver: 
   SETUPCFM_CHOSEN: src address = 14.1.123.95; dest address = 14.1.125.125
026685: *Sep 24 12:43:25.228: //24/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_SETUP_CFM_IND while at state H225_ALERT
026686: *Sep 24 12:43:25.228: //24/006E38C40300/H323/setup_cfm_notify: 
   status = 8000009
026687: *Sep 24 12:43:25.228: //24/006E38C40300/H323/generic_setup_cfm_notify: 
   ====== PI = 0; status = 88000009
026688: *Sep 24 12:43:25.228: //24/006E38C40300/CCAPI/cc_api_call_connected:
   Interface=0x855A8B64, Data Bitmask=0x1, Progress Indication=NULL(0),
   Connection Handle=0

Step 28

H.245 negotiation takes place. Audio and video RTP streams are established

(GK-CUBE-2.txt)

026833: *Sep 24 12:43:25.889: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : 
    {
      sequenceNumber 1
      protocolIdentifier { 0 0 8 245 0 10 }
      multiplexCapability h2250Capability : 
      {
        maximumAudioDelayJitter 60
        receiveMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        transmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        receiveAndTransmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        mcCapability 
        {
          centralizedConferenceMC FALSE
          decentralizedConferenceMC FALSE
        }
        rtcpVideoControlCapability FALSE
        mediaPacketizationCapability 
        {
          h261aVideoPacketization FALSE
        }
        logicalChannelSwitchingCapability FALSE
        t120DynamicPortCapability FALSE
      }
      capabilityTable 
      {

        {
          capabilityTableEntryNumber 1
          capability receiveAudioCapability : g722-64k : 60
        },
        {
          capabilityTableEntryNumber 2
          capability receiveAudioCapability : g711Ulaw64k : 40
        },
        {
          capabilityTableEntryNumber 3
          capability receiveAudioCapability : g711Alaw64k : 40
        },
        {
          capabilityTableEntryNumber 4
          capability receiveAudioCapability : g722-56k : 60
        },
        {
          capabilityTableEntryNumber 5
          capability receiveAudioCapability : g722-48k : 60
        },
        {
          capabilityTableEntryNumber 6
          capability receiveAudioCapability : g729wAnnexB : 6
        },
        {
          capabilityTableEntryNumber 7
          capability receiveAudioCapability : g729AnnexAwAnnexB : 6
        },
        {
          capabilityTableEntryNumber 8
          capability receiveAudioCapability : g729 : 6
        },
        {
          capabilityTableEntryNumber 9
          capability receiveAudioCapability : g729AnnexA : 6
        },
        {
          capabilityTableEntryNumber 10
          capability receiveAndTransmitVideoCapability : h263VideoCapability : 
          {
            qcifMPI 1
            cifMPI 1
            maxBitRate 3840
            unrestrictedVector FALSE
            arithmeticCoding FALSE
            advancedPrediction FALSE
            pbFrames FALSE
            temporalSpatialTradeOffCapability FALSE
            errorCompensation FALSE
          }
        },
        {
          capabilityTableEntryNumber 11
          capability receiveAndTransmitVideoCapability : genericVideoCapability : 
          {
            capabilityIdentifier standard : { 0 0 8 241 0 0 1 }
            maxBitRate 3840
            collapsing 
            {

              {
                parameterIdentifier standard : 41
                parameterValue booleanArray : 64
              },
              {
                parameterIdentifier standard : 42
                parameterValue unsignedMin : 78
              }
            }
          }
        },
        {
          capabilityTableEntryNumber 12
          capability receiveAndTransmitUserInputCapability : dtmf : NULL
        },
        {
          capabilityTableEntryNumber 13
          capability receiveAndTransmitUserInputCapability : basicString : NULL
        },
        {
          capabilityTableEntryNumber 44
          capability receiveAndTransmitUserInputCapability : hookflash : NULL
        }
      }
      capabilityDescriptors 
      {

        {
          capabilityDescriptorNumber 0
          simultaneousCapabilities 
          {

            {
              1,
              2,
              3,
              4,
              5,
              6,
              7,
              8,
              9
            },

            {
              10,
              11
            },

            {
              12,
              13
            },

            {
              44
            }
          }
        }
      }
    }



026834: *Sep 24 12:43:25.945: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, 
   bytesLeftToDecode = 0
026835: *Sep 24 12:43:25.949: h245_decode_one_pdu: Read Pkt body: more_pdus:0 
   rc:0 asn_rc:0
026836: *Sep 24 12:43:25.949: //23/006E38C40300/H323/cch323_h245_cap_ind: 
   Masks au=0x7000180F data=0x0 uinp=0x32
026837: *Sep 24 12:43:25.949: //23/006E38C40300/H323/cch323_run_h245_cap_in_sm: 
   Received H245_EVENT_CAP_IND while at state IDLE
026838: *Sep 24 12:43:25.949: //23/006E38C40300/H323/h245_cap_in_set_new_state: 
   changing from IDLE state to AWAITING_RESPONSE state
026839: *Sep 24 12:43:25.949: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_CAP_IND while at state IWF_H245_CONNECTED
026840: *Sep 24 12:43:25.949: //23/006E38C40300/H323/h245_handle_cap_ind: 
   TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
026841: *Sep 24 12:43:25.953: //23/006E38C40300/H323/cch323_iwf_cap_notify: 
   Mask sent to other leg=1
026842: *Sep 24 12:43:25.953: //23/006E38C40300/CCAPI/cc_api_caps_ind:
   Destination Interface=0x855A8B64, Destination Call Id=24, Source Call Id=23,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,
   Modem=0x0, Codec Bytes=20, Signal Type=2)
026843: *Sep 24 12:43:25.953: //23/006E38C40300/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=250(ms), Fax Nom=300(ms))
026844: *Sep 24 12:43:25.953: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_H245_CONNECTED state to IWF_AWAIT_PEER_CAP state
026845: *Sep 24 12:43:25.957: //24/006E38C40300/H323/cch323_peer_caps_ind_common: 
   Update the audio mask: old mask=0x7800EBF7; new mask=0x1
026846: *Sep 24 12:43:25.957: //24/006E38C40300/H323/cch323_peer_caps_ind_common: 
   ExtendedCaps present
026847: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_prepare_preferred_codec_list: Munging caps: 5:1:1
026848: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_prepare_preferred_codec_list: Final mask=1
026849: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_prepare_preferred_codec_list: 
   Copying codec list from extended caps into CCB
026850: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CAPS_IND while at state IWF_IDLE
026851: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_send_event_to_h245_connection_sm: 
   Changing to new event H245_CONNECT_REQ_EVENT
026852: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_h245_connection_sm: 
   state=0, event=1, ccb=8572F6C4, listen state=0
026853: *Sep 24 12:43:25.957: 
   //24/006E38C40300/H323/cch323_h245_connection_sm: H245_CONNECT: 
   Received event H245_CONNECT_REQ_EVENT while at H245_NONE state
026854: *Sep 24 12:43:25.961: 
   //24/006E38C40300/H323/cch323_h245_set_new_state: 
   Changing from H245_NONE state to H245_WAITING state
026855: *Sep 24 12:43:25.961: 
   //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_IDLE state to IWF_AWAIT_H245_CONN state
026856: *Sep 24 12:43:25.965: h323chan_chn_process_read_socket
026857: *Sep 24 12:43:25.965: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECT_PENDING has data
026858: *Sep 24 12:43:25.965: 
   //24/006E38C40300/H323/cch323_h245_channel_established_ind: 
   Using fd=7 to send msgs
026859: *Sep 24 12:43:25.965: 
   //24/006E38C40300/H323/cch323_send_event_to_h245_connection_sm: 
   Changing to new event H245_ESTABLISHED_EVENT
026860: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/cch323_h245_connection_sm: 
   state=1, event=2, ccb=8572F6C4, listen state=0
026861: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/cch323_h245_connection_sm: H245_CONNECT: 
   Received event H245_ESTABLISHED_EVENT while at H245_WAITING state
026862: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/cch323_h245_set_new_state: 
   Changing from H245_WAITING state to H245_CONNECTED state
026863: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_H245_CONNECTED while at state IWF_AWAIT_H245_CONN
026864: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_H245_CONN state to IWF_AWAIT_CAP_MSD_RESP state
026865: *Sep 24 12:43:25.969: 
   //24/006E38C40300/H323/cch323_run_h245_cap_out_sm: 
   Received H245_EVENT_CAP_REQ while at state IDLE
026866: *Sep 24 12:43:25.969: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : 
    {
      sequenceNumber 1
      protocolIdentifier { 0 0 8 245 0 10 }
      multiplexCapability h2250Capability : 
      {
        maximumAudioDelayJitter 60
        receiveMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        transmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        receiveAndTransmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        mcCapability 
        {
          centralizedConferenceMC FALSE
          decentralizedConferenceMC FALSE
        }
        rtcpVideoControlCapability FALSE
        mediaPacketizationCapability 
        {
          h261aVideoPacketization FALSE
        }
        logicalChannelSwitchingCapability FALSE
        t120DynamicPortCapability FALSE
      }
      capabilityTable 
      {

        {
          capabilityTableEntryNumber 1
          capability receiveAudioCapability : g722-64k : 60
        },
        {
          capabilityTableEntryNumber 2
          capability receiveAudioCapability : g711Ulaw64k : 40
        },
        {
          capabilityTableEntryNumber 3
          capability receiveAudioCapability : g711Alaw64k : 40
        },
        {
          capabilityTableEntryNumber 4
          capability receiveAudioCapability : g722-56k : 60
        },
        {
          capabilityTableEntryNumber 5
          capability receiveAudioCapability : g722-48k : 60
        },
        {
          capabilityTableEntryNumber 6
          capability receiveAudioCapability : g729wAnnexB : 6
        },
        {
          capabilityTableEntryNumber 7
          capability receiveAudioCapability : g729AnnexAwAnnexB : 6
        },
        {
          capabilityTableEntryNumber 8
          capability receiveAudioCapability : g729 : 6
        },
        {
          capabilityTableEntryNumber 9
          capability receiveAudioCapability : g729AnnexA : 6
        },
        {
          capabilityTableEntryNumber 10
          capability receiveAndTransmitVideoCapability : h263VideoCapability : 
          {
            qcifMPI 1
            cifMPI 1
            maxBitRate 3840
            unrestrictedVector FALSE
            arithmeticCoding FALSE
            advancedPrediction FALSE
            pbFrames FALSE
            temporalSpatialTradeOffCapability FALSE
            errorCompensation FALSE
          }
        },
        {
          capabilityTableEntryNumber 11
          capability receiveAndTransmitVideoCapability : genericVideoCapability : 
          {
            capabilityIdentifier standard : { 0 0 8 241 0 0 1 }
            maxBitRate 3840
            collapsing 
            {

              {
                parameterIdentifier standard : 41
                parameterValue booleanArray : 64
              },
              {
                parameterIdentifier standard : 42
                parameterValue unsignedMin : 78
              }
            }
          }
        },
        {
          capabilityTableEntryNumber 12
          capability receiveAndTransmitUserInputCapability : dtmf : NULL
        },
        {
          capabilityTableEntryNumber 13
          capability receiveAndTransmitUserInputCapability : basicString : NULL
        },
        {
          capabilityTableEntryNumber 44
          capability receiveAndTransmitUserInputCapability : hookflash : NULL
        }
      }
      capabilityDescriptors 
      {

        {
          capabilityDescriptorNumber 0
          simultaneousCapabilities 
          {

            {
              1,
              2,
              3,
              4,
              5,
              6,
              7,
              8,
              9
            },

            {
              10,
              11
            },

            {
              12,
              13
            },

            {
              44
            }
          }
        }
      }
    }



026867: *Sep 24 12:43:26.029: H245 MSC OUTGOING ENCODE BUFFER::= 02700106000
   88175000A801380003C000100000100000100000CC0010001000D80000021403B80000120C02
   780000220402780000321803B80000421C03B800005240001058000062408010580000722800
   580000822C00580000919D800080EFF007040010080000A1C001560000700088171000001400
   F000202914002A2004E80000B85014080000C85011080002B850150008000030800000001000
   2000300040005000600070008010009000A01000B000C00002B
026868: *Sep 24 12:43:26.029: 
026869: *Sep 24 12:43:26.033: //24/006E38C40300/H323/cch323_send_cap_request: 
   Send caps in passthru mode: retcode 0
026870: *Sep 24 12:43:26.033: //24/006E38C40300/H323/h245_cap_out_set_new_state: 
   changing from IDLE state to AWAITING_RESPONSE state
026871: *Sep 24 12:43:26.033: h323chan_chn_process_read_socket
026872: *Sep 24 12:43:26.033: h323chan_chn_process_read_socket: fd=7 of type 
   CONNECTED has data
026873: *Sep 24 12:43:26.037:  h323chan_chn_process_read_socket: h323chan 
   accepted/connected fd=7

026874: *Sep 24 12:43:26.037: h323chan_chn_process_read_socket
026875: *Sep 24 12:43:26.041: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
026876: *Sep 24 12:43:26.041:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

026877: *Sep 24 12:43:26.041: h245_decode_one_pdu: more_pdus = 0, 
   bytesLeftToDecode = 127
026878: *Sep 24 12:43:26.041: H245 MSC INCOMING ENCODE BUFFER::= 02700106000
   8817500078013800014000100000100000100000CC001000100068000218A061404302D31368
   000184810B50000120C52747044746D6652656C6179000080001E83015080001D83014080001
   A83011080000220C01380002609D800083A97007040010000800103000002000026030021001
   D001A001800001E
026879: *Sep 24 12:43:26.041: 
026880: *Sep 24 12:43:26.045: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : 
    {
      sequenceNumber 1
      protocolIdentifier { 0 0 8 245 0 7 }
      multiplexCapability h2250Capability : 
      {
        maximumAudioDelayJitter 20
        receiveMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        transmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        receiveAndTransmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        mcCapability 
        {
          centralizedConferenceMC FALSE
          decentralizedConferenceMC FALSE
        }
        rtcpVideoControlCapability FALSE
        mediaPacketizationCapability 
        {
          h261aVideoPacketization FALSE
        }
        logicalChannelSwitchingCapability FALSE
        t120DynamicPortCapability FALSE
      }
      capabilityTable 
      {

        {
          capabilityTableEntryNumber 34
          capability receiveRTPAudioTelephonyEventCapability : 
          {
            dynamicRTPPayloadType 101
            audioTelephoneEvent "0-16"
          }
        },
        {
          capabilityTableEntryNumber 25
          capability receiveAndTransmitDataApplicationCapability : 
          {
            application nonStandard : 
            {
              nonStandardIdentifier h221NonStandard : 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              data '52747044746D6652656C6179'H
            }
            maxBitRate 0
          }
        },
        {
          capabilityTableEntryNumber 31
          capability receiveUserInputCapability : hookflash : NULL
        },
        {
          capabilityTableEntryNumber 30
          capability receiveUserInputCapability : dtmf : NULL
        },
        {
          capabilityTableEntryNumber 27
          capability receiveUserInputCapability : basicString : NULL
        },
        {
          capabilityTableEntryNumber 3
          capability receiveAudioCapability : g711Ulaw64k : 20
        },
        {
          capabilityTableEntryNumber 39
          capability receiveVideoCapability : h263VideoCapability : 
          {
            qcifMPI 1
            cifMPI 1
            maxBitRate 15000
            unrestrictedVector FALSE
            arithmeticCoding FALSE
            advancedPrediction FALSE
            pbFrames FALSE
            temporalSpatialTradeOffCapability FALSE
            errorCompensation FALSE
          }
        }
      }
      capabilityDescriptors 
      {

        {
          capabilityDescriptorNumber 1
          simultaneousCapabilities 
          {

            {
              3
            },

            {
              39
            },

            {
              34,
              30,
              27,
              25
            },

            {
              31
            }
          }
        }
      }
    }



026881: *Sep 24 12:43:26.089: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026882: *Sep 24 12:43:26.093: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
026883: *Sep 24 12:43:26.093: //24/006E38C40300/H323/cch323_h245_cap_ind: 
   Masks au=0x1 data=0x2 uinp=0x32
026884: *Sep 24 12:43:26.093: //24/006E38C40300/H323/cch323_run_h245_cap_in_sm: 
   Received H245_EVENT_CAP_IND while at state IDLE
026885: *Sep 24 12:43:26.093: //24/006E38C40300/H323/h245_cap_in_set_new_state: 
   changing from IDLE state to AWAITING_RESPONSE state
026886: *Sep 24 12:43:26.097: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
026887: *Sep 24 12:43:26.097: //24/006E38C40300/H323/h245_handle_cap_ind: 
   TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
026888: *Sep 24 12:43:26.097: //24/006E38C40300/H323/cch323_iwf_cap_notify: 
   Mask sent to other leg=1
026889: *Sep 24 12:43:26.097: //24/006E38C40300/CCAPI/cc_api_caps_ind:
   Destination Interface=0x855A8B64, Destination Call Id=23, Source Call Id=24,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,
   Modem=0x0, Codec Bytes=20, Signal Type=2)
026890: *Sep 24 12:43:26.097: //24/006E38C40300/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=250(ms), Fax Nom=300(ms))
026891: *Sep 24 12:43:26.097: h323chan_chn_process_read_socket
026892: *Sep 24 12:43:26.101: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
026893: *Sep 24 12:43:26.101:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

026894: *Sep 24 12:43:26.101: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 6
026895: *Sep 24 12:43:26.101: H245 MSC INCOMING ENCODE BUFFER::= 01003C40261F
026896: *Sep 24 12:43:26.101: 
026897: *Sep 24 12:43:26.101: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : masterSlaveDetermination : 
    {
      terminalType 60
      statusDeterminationNumber 9759
    }



026898: *Sep 24 12:43:26.101: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026899: *Sep 24 12:43:26.105: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
026900: *Sep 24 12:43:26.105: //24/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MS_IND
026901: *Sep 24 12:43:26.105: //-1/xxxxxxxxxxxx/H323/cch323_do_msd_info: 
   callID=24, sending event CC_EV_H245_MSD_INFO, msd info ptr 0x854C3870
026902: *Sep 24 12:43:26.105: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=146, Call Id=24
026903: *Sep 24 12:43:26.105: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
026904: *Sep 24 12:43:26.105: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
026905: *Sep 24 12:43:26.105: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_MSD_INFO: data ptr=0x854C3870
026906: *Sep 24 12:43:26.109: //23/006E38C40300/H323/cch323_peer_caps_ind_common: 
   Update the audio mask: old mask=0x7800EBF7; new mask=0x1
026907: *Sep 24 12:43:26.109: //23/006E38C40300/H323/cch323_peer_caps_ind_common: 
   ExtendedCaps present
026908: *Sep 24 12:43:26.109: 
   //23/006E38C40300/H323/cch323_prepare_preferred_codec_list: Munging caps: 5:1:1
026909: *Sep 24 12:43:26.113: 
   //23/006E38C40300/H323/cch323_prepare_preferred_codec_list: Final mask=1
026910: *Sep 24 12:43:26.113: 
   //23/006E38C40300/H323/cch323_prepare_preferred_codec_list: 
   Copying codec list from extended caps into CCB
026911: *Sep 24 12:43:26.113: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CAPS_IND while at state IWF_AWAIT_PEER_CAP
026912: *Sep 24 12:43:26.113: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_PEER_CAP state to IWF_AWAIT_CAP_MSD_RESP state
026913: *Sep 24 12:43:26.113: //23/006E38C40300/H323/cch323_run_h245_cap_out_sm: 
   Received H245_EVENT_CAP_REQ while at state IDLE
026914: *Sep 24 12:43:26.113: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : 
    {
      sequenceNumber 1
      protocolIdentifier { 0 0 8 245 0 7 }
      multiplexCapability h2250Capability : 
      {
        maximumAudioDelayJitter 20
        receiveMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        transmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        receiveAndTransmitMultipointCapability 
        {
          multicastCapability FALSE
          multiUniCastConference FALSE
          mediaDistributionCapability 
          {

            {
              centralizedControl FALSE
              distributedControl FALSE
              centralizedAudio FALSE
              distributedAudio FALSE
              centralizedVideo FALSE
              distributedVideo FALSE
            }
          }
        }
        mcCapability 
        {
          centralizedConferenceMC FALSE
          decentralizedConferenceMC FALSE
        }
        rtcpVideoControlCapability FALSE
        mediaPacketizationCapability 
        {
          h261aVideoPacketization FALSE
        }
        logicalChannelSwitchingCapability FALSE
        t120DynamicPortCapability FALSE
      }
      capabilityTable 
      {

        {
          capabilityTableEntryNumber 34
          capability receiveRTPAudioTelephonyEventCapability : 
          {
            dynamicRTPPayloadType 101
            audioTelephoneEvent "0-16"
          }
        },
        {
          capabilityTableEntryNumber 25
          capability receiveAndTransmitDataApplicationCapability : 
          {
            application nonStandard : 
            {
              nonStandardIdentifier h221NonStandard : 
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              data '52747044746D6652656C6179'H
            }
            maxBitRate 0
          }
        },
        {
          capabilityTableEntryNumber 31
          capability receiveUserInputCapability : hookflash : NULL
        },
        {
          capabilityTableEntryNumber 30
          capability receiveUserInputCapability : dtmf : NULL
        },
        {
          capabilityTableEntryNumber 27
          capability receiveUserInputCapability : basicString : NULL
        },
        {
          capabilityTableEntryNumber 3
          capability receiveAudioCapability : g711Ulaw64k : 20
        },
        {
          capabilityTableEntryNumber 39
          capability receiveVideoCapability : h263VideoCapability : 
          {
            qcifMPI 1
            cifMPI 1
            maxBitRate 15000
            unrestrictedVector FALSE
            arithmeticCoding FALSE
            advancedPrediction FALSE
            pbFrames FALSE
            temporalSpatialTradeOffCapability FALSE
            errorCompensation FALSE
          }
        }
      }
      capabilityDescriptors 
      {

        {
          capabilityDescriptorNumber 1
          simultaneousCapabilities 
          {

            {
              3
            },

            {
              39
            },

            {
              34,
              30,
              27,
              25
            },

            {
              31
            }
          }
        }
      }
    }



026915: *Sep 24 12:43:26.161: H245 MSC OUTGOING ENCODE BUFFER::= 02700106000
   8817500078013800014000100000100000100000CC001000100068000218A061404302D31368
   000184810B50000120C52747044746D6652656C6179000080001E83015080001D83014080001
   A83011080000220C01380002609D800083A97007040010000800103000002000026030021001
   D001A001800001E
026916: *Sep 24 12:43:26.161: 
026917: *Sep 24 12:43:26.165: //23/006E38C40300/H323/cch323_send_cap_request: 
   Send caps in passthru mode: retcode 0
026918: *Sep 24 12:43:26.165: //23/006E38C40300/H323/h245_cap_out_set_new_state: 
   changing from IDLE state to AWAITING_RESPONSE state
026919: *Sep 24 12:43:26.165: //23/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MSD
026920: *Sep 24 12:43:26.165: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : masterSlaveDetermination : 
    {
      terminalType 60
      statusDeterminationNumber 9759
    }



026921: *Sep 24 12:43:26.169: H245 MSC OUTGOING ENCODE BUFFER::= 01003C40261F
026922: *Sep 24 12:43:26.169: 
026923: *Sep 24 12:43:26.169: h323chan_chn_process_read_socket
026924: *Sep 24 12:43:26.169: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
026925: *Sep 24 12:43:26.169:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

026926: *Sep 24 12:43:26.169: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 3
026927: *Sep 24 12:43:26.169: H245 MSC INCOMING ENCODE BUFFER::= 218001
026928: *Sep 24 12:43:26.169: 
026929: *Sep 24 12:43:26.169: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : 
    {
      sequenceNumber 1
    }



026930: *Sep 24 12:43:26.173: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026931: *Sep 24 12:43:26.173: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
026932: *Sep 24 12:43:26.173: //24/006E38C40300/H323/cch323_run_h245_cap_out_sm: 
   Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
026933: *Sep 24 12:43:26.173: //24/006E38C40300/H323/h245_cap_out_set_new_state: 
   changing from AWAITING_RESPONSE state to IDLE state
026934: *Sep 24 12:43:26.173: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
026935: *Sep 24 12:43:26.173: 
   //24/006E38C40300/H323/h245_iwf_handle_send_caps_ack_to_peer: 
   Sending caps ack to other leg
026936: *Sep 24 12:43:26.173: 
   //-1/xxxxxxxxxxxx/H323/cch323_do_caps_ack: dstCallID=23, srcCallID=24
026937: *Sep 24 12:43:26.173: //24/006E38C40300/CCAPI/cc_api_caps_ack:
   Destination Interface=0x855A8B64, Destination Call Id=23, Source Call Id=24,
   Caps(Codec=gsmefr(0x0), Fax Rate=Invalid(0x0), Vad=Invalid(0x0),
   Modem=OFF(0x0), Codec Bytes=0, Signal Type=0, Seq Num Start=0)
026938: *Sep 24 12:43:26.177: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
026939: *Sep 24 12:43:26.177: //23/006E38C40300/H323/cch323_peer_caps_ack: 
   Sending caps resp event to CAP sm
026940: *Sep 24 12:43:26.177: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CAPS_ACK while at state IWF_AWAIT_CAP_MSD_RESP
026941: *Sep 24 12:43:26.177: //23/006E38C40300/H323/cch323_run_h245_cap_in_sm: 
   Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
026942: *Sep 24 12:43:26.177: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : 
    {
      sequenceNumber 1
    }



026943: *Sep 24 12:43:26.181: H245 MSC OUTGOING ENCODE BUFFER::= 218001
026944: *Sep 24 12:43:26.181: 
026945: *Sep 24 12:43:26.181: //23/006E38C40300/H323/h245_cap_in_set_new_state: 
   changing from AWAITING_RESPONSE state to IDLE state
026946: *Sep 24 12:43:26.330: h323chan_chn_process_read_socket
026947: *Sep 24 12:43:26.330: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
026948: *Sep 24 12:43:26.330:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

026949: *Sep 24 12:43:26.330: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 3
026950: *Sep 24 12:43:26.334: H245 MSC INCOMING ENCODE BUFFER::= 218001
026951: *Sep 24 12:43:26.334: 
026952: *Sep 24 12:43:26.334: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : 
    {
      sequenceNumber 1
    }



026953: *Sep 24 12:43:26.334: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026954: *Sep 24 12:43:26.334: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
026955: *Sep 24 12:43:26.334: //23/006E38C40300/H323/cch323_run_h245_cap_out_sm: 
   Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
026956: *Sep 24 12:43:26.334: //23/006E38C40300/H323/h245_cap_out_set_new_state: 
   changing from AWAITING_RESPONSE state to IDLE state
026957: *Sep 24 12:43:26.338: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
026958: *Sep 24 12:43:26.338: 
   //23/006E38C40300/H323/h245_iwf_handle_send_caps_ack_to_peer: 
   Sending caps ack to other leg
026959: *Sep 24 12:43:26.338: //-1/xxxxxxxxxxxx/H323/cch323_do_caps_ack: 
   dstCallID=24, srcCallID=23
026960: *Sep 24 12:43:26.338: //23/006E38C40300/CCAPI/cc_api_caps_ack:
   Destination Interface=0x855A8B64, Destination Call Id=24, Source Call Id=23,
   Caps(Codec=gsmefr(0x0), Fax Rate=Invalid(0x0), Vad=Invalid(0x0),
   Modem=OFF(0x0), Codec Bytes=0, Signal Type=0, Seq Num Start=0)
026961: *Sep 24 12:43:26.338: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
026962: *Sep 24 12:43:26.342: //24/006E38C40300/H323/cch323_peer_caps_ack: 
   Sending caps resp event to CAP sm
026963: *Sep 24 12:43:26.342: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CAPS_ACK while at state IWF_AWAIT_MSD_RESP
026964: *Sep 24 12:43:26.342: //24/006E38C40300/H323/cch323_run_h245_cap_in_sm: 
   Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
026965: *Sep 24 12:43:26.342: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : 
    {
      sequenceNumber 1
    }



026966: *Sep 24 12:43:26.342: H245 MSC OUTGOING ENCODE BUFFER::= 218001
026967: *Sep 24 12:43:26.346: 
026968: *Sep 24 12:43:26.346: //24/006E38C40300/H323/h245_cap_in_set_new_state: 
   changing from AWAITING_RESPONSE state to IDLE state
026969: *Sep 24 12:43:26.346: h323chan_chn_process_read_socket
026970: *Sep 24 12:43:26.346: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
026971: *Sep 24 12:43:26.346:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

026972: *Sep 24 12:43:26.350: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 2
026973: *Sep 24 12:43:26.350: H245 MSC INCOMING ENCODE BUFFER::= 2080
026974: *Sep 24 12:43:26.350: 
026975: *Sep 24 12:43:26.350: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : 
    {
      decision master : NULL
    }



026976: *Sep 24 12:43:26.350: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026977: *Sep 24 12:43:26.350: h245_decode_one_pdu: Read Pkt body: 
   more_pdus:0 rc:0 asn_rc:0
026978: *Sep 24 12:43:26.350: //23/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MS_CFM
026979: *Sep 24 12:43:26.350: //-1/xxxxxxxxxxxx/H323/cch323_do_msd_info: 
   callID=23, sending event CC_EV_H245_MSD_INFO, msd info ptr 0x854C36E4
026980: *Sep 24 12:43:26.354: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event=146, Call Id=23
026981: *Sep 24 12:43:26.354: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
026982: *Sep 24 12:43:26.354: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=24
026983: *Sep 24 12:43:26.354: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_MSD_INFO: data ptr=0x854C36E4
026984: *Sep 24 12:43:26.354: //24/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MS_DET_RSP
026985: *Sep 24 12:43:26.354: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : 
    {
      decision master : NULL
    }



026986: *Sep 24 12:43:26.358: H245 MSC OUTGOING ENCODE BUFFER::= 2080
026987: *Sep 24 12:43:26.358: 
026988: *Sep 24 12:43:26.358: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
026989: *Sep 24 12:43:26.358: //24/006E38C40300/H323/h245_iwf_handle_init_olc: 
   No channel info avail
026990: *Sep 24 12:43:26.358: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
026991: *Sep 24 12:43:26.366: h323chan_chn_process_read_socket
026992: *Sep 24 12:43:26.366: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
026993: *Sep 24 12:43:26.366:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

026994: *Sep 24 12:43:26.366: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 2
026995: *Sep 24 12:43:26.366: H245 MSC INCOMING ENCODE BUFFER::= 20A0
026996: *Sep 24 12:43:26.366: 
026997: *Sep 24 12:43:26.366: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : 
    {
      decision slave : NULL
    }



026998: *Sep 24 12:43:26.370: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
026999: *Sep 24 12:43:26.370: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027000: *Sep 24 12:43:26.370: //24/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MS_CFM
027001: *Sep 24 12:43:26.370: //-1/xxxxxxxxxxxx/H323/cch323_do_msd_info: 
   callID=24, sending event CC_EV_H245_MSD_INFO, msd info ptr 0x854C3870
027002: *Sep 24 12:43:26.370: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=146, Call Id=24
027003: *Sep 24 12:43:26.370: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027004: *Sep 24 12:43:26.370: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027005: *Sep 24 12:43:26.370: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_MSD_INFO: data ptr=0x854C3870
027006: *Sep 24 12:43:26.374: //23/006E38C40300/H323/cch323_run_passthru_msd: 
   Received event H245_EVENT_MS_DET_RSP
027007: *Sep 24 12:43:26.374: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : 
    {
      decision slave : NULL
    }



027008: *Sep 24 12:43:26.378: H245 MSC OUTGOING ENCODE BUFFER::= 20A0
027009: *Sep 24 12:43:26.378: 
027010: *Sep 24 12:43:26.378: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
027011: *Sep 24 12:43:26.378: //23/006E38C40300/H323/h245_iwf_handle_init_olc: 
   No channel info avail
027012: *Sep 24 12:43:26.378: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
027013: *Sep 24 12:43:26.378: h323chan_chn_process_read_socket
027014: *Sep 24 12:43:26.378: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
027015: *Sep 24 12:43:26.378:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

027016: *Sep 24 12:43:26.382: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 20
027017: *Sep 24 12:43:26.382: H245 MSC INCOMING ENCODE BUFFER::= 
   030000000C6013800B050001000E017D7D420900
027018: *Sep 24 12:43:26.382: 
027019: *Sep 24 12:43:26.382: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 1
      forwardLogicalChannelParameters 
      {
        dataType audioData : g711Ulaw64k : 20
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 1
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017D7D'H
            tsapIdentifier 16905
          }
          silenceSuppression FALSE
        }
      }
    }



027020: *Sep 24 12:43:26.386: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027021: *Sep 24 12:43:26.390: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027022: *Sep 24 12:43:26.390: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   chan_type 1: chan_num 1
027023: *Sep 24 12:43:26.390: 
   //24/006E38C40300/H323/cch323_h245_find_r_allocate_olc_instance: 
   Using existing OLC instance
027024: *Sep 24 12:43:26.390: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   channel_number: 1
027025: *Sep 24 12:43:26.390: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   h245_rport_tx using RTCP port
027026: *Sep 24 12:43:26.390: //24/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
027027: *Sep 24 12:43:26.390: //24/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_AWAIT_EST state
027028: *Sep 24 12:43:26.390: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_IND while at state IWF_OLC_IDLE
027029: *Sep 24 12:43:26.390: 
   //24/006E38C40300/H323/h245_iwf_build_olc_temp_channel_array: 
   tempChannelArray=0x860420C0
027030: *Sep 24 12:43:26.394: //24/006E38C40300/H323/h245_iwf_validate_olc: 
   ch=1 non-besteffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027031: *Sep 24 12:43:26.394: //24/006E38C40300/H323/h245_iwf_validate_olc: 
   chanNum 1 BW tx:rx(640:640) is not a passthru channel
027032: *Sep 24 12:43:26.394: 
   //24/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=1280
027033: *Sep 24 12:43:26.394: //24/006E38C40300/H323/estIndOlcIdle: 
   Sending olc ind trigger to peer side
027034: *Sep 24 12:43:26.394: //24/006E38C40300/H323/cch323_do_open_channel_ind: 
   Sending event CC_EV_H245_OPEN_CHANNEL_IND, channelInfo pointer 0x860420C0
027035: *Sep 24 12:43:26.394: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=141, Call Id=24
027036: *Sep 24 12:43:26.394: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027037: *Sep 24 12:43:26.394: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027038: *Sep 24 12:43:26.394: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_IND received, channelInfo ptr 0x860420C0
027039: *Sep 24 12:43:26.398: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ind: 
   Entry, callID=23
027040: *Sep 24 12:43:26.398: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_PEER_ACK state
027041: *Sep 24 12:43:26.398: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   chn info coming in chn_ind()
027042: *Sep 24 12:43:26.398: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   Giving event to SLOW start logic: 1
027043: *Sep 24 12:43:26.398: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   chan_type 1, chan_num 1
027044: *Sep 24 12:43:26.398: //23/006E38C40300/H323/cch323_h245_get_olc_instance: 
   Using existing OLC instance
027045: *Sep 24 12:43:26.398: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_IND while at state IWF_OLC_IDLE
027046: *Sep 24 12:43:26.398: 
   //23/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=1280
027047: *Sep 24 12:43:26.402: //23/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
027048: *Sep 24 12:43:26.402: //23/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_AWAIT_EST state
027049: *Sep 24 12:43:26.402: //23/006E38C40300/H323/estReqOutIdle: 
   lcl chn codec = 0x5, codec_bytes = 160
027050: *Sep 24 12:43:26.402: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   ccb channel 1
027051: *Sep 24 12:43:26.402: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   Use the flow thru address
027052: *Sep 24 12:43:26.402: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 1
      forwardLogicalChannelParameters 
      {
        dataType audioData : g711Ulaw64k : 20
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 1
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017B5F'H
            tsapIdentifier 19497
          }
          silenceSuppression FALSE
        }
      }
    }



027053: *Sep 24 12:43:26.410: H245 MSC OUTGOING ENCODE BUFFER::= 
   030000000C6013800B050001000E017B5F4C2900
027054: *Sep 24 12:43:26.410: 
027055: *Sep 24 12:43:26.410: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   Sent OLC in passthru mode: retcode 0
027056: *Sep 24 12:43:26.410: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_EST_CFM state
027057: *Sep 24 12:43:26.450: h323chan_chn_process_read_socket
027058: *Sep 24 12:43:26.450: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
027059: *Sep 24 12:43:26.454:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

027060: *Sep 24 12:43:26.454: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 20
027061: *Sep 24 12:43:26.454: H245 MSC INCOMING ENCODE BUFFER::= 
   030000000C6013800B050001000E32C911402300
027062: *Sep 24 12:43:26.454: 
027063: *Sep 24 12:43:26.454: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 1
      forwardLogicalChannelParameters 
      {
        dataType audioData : g711Ulaw64k : 20
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 1
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E32C911'H
            tsapIdentifier 16419
          }
          silenceSuppression FALSE
        }
      }
    }



027064: *Sep 24 12:43:26.462: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027065: *Sep 24 12:43:26.462: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027066: *Sep 24 12:43:26.462: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   chan_type 1: chan_num 1
027067: *Sep 24 12:43:26.462: 
   //23/006E38C40300/H323/cch323_h245_find_r_allocate_olc_instance: 
   Using existing OLC instance
027068: *Sep 24 12:43:26.462: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   channel_number: 1
027069: *Sep 24 12:43:26.462: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   h245_rport_tx using RTCP port
027070: *Sep 24 12:43:26.462: //23/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
027071: *Sep 24 12:43:26.462: //23/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_AWAIT_EST state
027072: *Sep 24 12:43:26.466: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_IND while at state IWF_OLC_AWAIT_EST_CFM
027073: *Sep 24 12:43:26.466: 
   //23/006E38C40300/H323/h245_iwf_build_olc_temp_channel_array: 
   tempChannelArray=0x86041CD0
027074: *Sep 24 12:43:26.466: //23/006E38C40300/H323/h245_iwf_validate_olc: 
   ch=1 non-besteffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027075: *Sep 24 12:43:26.466: //23/006E38C40300/H323/h245_iwf_validate_olc: 
   chanNum 1 BW tx:rx(640:640) is not a passthru channel
027076: *Sep 24 12:43:26.466: //23/006E38C40300/H323/cch323_do_open_channel_ind: 
   Sending event CC_EV_H245_OPEN_CHANNEL_IND, channelInfo pointer 0x86041CD0
027077: *Sep 24 12:43:26.466: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event=141, Call Id=23
027078: *Sep 24 12:43:26.466: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027079: *Sep 24 12:43:26.466: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=24
027080: *Sep 24 12:43:26.470: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_IND received, channelInfo ptr 0x86041CD0
027081: *Sep 24 12:43:26.470: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ind: 
   Entry, callID=24
027082: *Sep 24 12:43:26.470: 
   //23/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=1280
027083: *Sep 24 12:43:26.470: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_EST_CFM state to IWF_OLC_AWAIT_EST_CFM_PEER_ACK state
027084: *Sep 24 12:43:26.470: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   chn info coming in chn_ind()
027085: *Sep 24 12:43:26.470: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   Giving event to SLOW start logic: 0
027086: *Sep 24 12:43:26.470: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   chan_type 1, chan_num 1
027087: *Sep 24 12:43:26.474: //24/006E38C40300/H323/cch323_h245_get_olc_instance: 
   Using existing OLC instance
027088: *Sep 24 12:43:26.474: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_IND while at state IWF_OLC_AWAIT_PEER_ACK
027089: *Sep 24 12:43:26.474: 
   //24/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=1280
027090: *Sep 24 12:43:26.474: //24/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
027091: *Sep 24 12:43:26.474: //24/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_AWAIT_EST state
027092: *Sep 24 12:43:26.474: //24/006E38C40300/H323/estReqOutIdle: 
   lcl chn codec = 0x5, codec_bytes = 160
027093: *Sep 24 12:43:26.474: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   ccb channel 1
027094: *Sep 24 12:43:26.474: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   Use the flow thru address
027095: *Sep 24 12:43:26.474: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 1
      forwardLogicalChannelParameters 
      {
        dataType audioData : g711Ulaw64k : 20
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 1
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017B5F'H
            tsapIdentifier 16773
          }
          silenceSuppression FALSE
        }
      }
    }



027096: *Sep 24 12:43:26.482: H245 MSC OUTGOING ENCODE BUFFER::= 
   030000000C6013800B050001000E017B5F418500
027097: *Sep 24 12:43:26.482: 
027098: *Sep 24 12:43:26.486: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   Sent OLC in passthru mode: retcode 0
027099: *Sep 24 12:43:26.486: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_PEER_ACK state to IWF_OLC_AWAIT_EST_CFM_PEER_ACK state
027100: *Sep 24 12:43:26.486: h323chan_chn_process_read_socket
027101: *Sep 24 12:43:26.486: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
027102: *Sep 24 12:43:26.486:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

027103: *Sep 24 12:43:26.486: h245_decode_one_pdu: more_pdus = 
   0, bytesLeftToDecode = 28
027104: *Sep 24 12:43:26.486: H245 MSC INCOMING ENCODE BUFFER::= 
   0300000108E400800C7F0070400100800B050002000E32C9114B8500
027105: *Sep 24 12:43:26.486: 
027106: *Sep 24 12:43:26.490: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 2
      forwardLogicalChannelParameters 
      {
        dataType videoData : h263VideoCapability : 
        {
          cifMPI 1
          maxBitRate 3200
          unrestrictedVector FALSE
          arithmeticCoding FALSE
          advancedPrediction FALSE
          pbFrames FALSE
          temporalSpatialTradeOffCapability FALSE
          errorCompensation FALSE
        }
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 2
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E32C911'H
            tsapIdentifier 19333
          }
          silenceSuppression FALSE
        }
      }
    }



027107: *Sep 24 12:43:26.498: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027108: *Sep 24 12:43:26.498: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027109: *Sep 24 12:43:26.498: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   chan_type 2: chan_num 2
027110: *Sep 24 12:43:26.498: 
   //23/006E38C40300/H323/cch323_h245_find_r_allocate_olc_instance: 
   Allocated new OLC instance
027111: *Sep 24 12:43:26.498: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   channel_number: 2
027112: *Sep 24 12:43:26.498: //23/006E38C40300/H323/cch323_h245_uchan_ind: 
   h245_rport_tx using RTCP port
027113: *Sep 24 12:43:26.498: //23/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
027114: *Sep 24 12:43:26.498: //23/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_AWAIT_EST state
027115: *Sep 24 12:43:26.502: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_IND while at state IWF_OLC_IDLE
027116: *Sep 24 12:43:26.502: 
   //23/006E38C40300/H323/h245_iwf_build_olc_temp_channel_array: 
   tempChannelArray=0x86041E80
027117: *Sep 24 12:43:26.502: //23/006E38C40300/H323/h245_iwf_validate_olc: 
   ch=2 non-besteffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027118: *Sep 24 12:43:26.502: //23/006E38C40300/H323/h245_iwf_validate_olc: 
   chanNum 2 BW tx:rx(3200:3200) is a passthru channel; olc=0x8482AA7C, 
   peer_chn_num=0, TchnArray=0x86041E80, PchnArray=0x0
027119: *Sep 24 12:43:26.502: 
   //23/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=7680
027120: *Sep 24 12:43:26.502: //23/006E38C40300/H323/estIndOlcIdle: 
   Sending olc ind trigger to peer side
027121: *Sep 24 12:43:26.502: //23/006E38C40300/H323/cch323_do_open_channel_ind: 
   Sending event CC_EV_H245_OPEN_CHANNEL_IND, channelInfo pointer 0x86041E80
027122: *Sep 24 12:43:26.502: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event=141, Call Id=23
027123: *Sep 24 12:43:26.506: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027124: *Sep 24 12:43:26.506: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=24
027125: *Sep 24 12:43:26.506: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_IND received, channelInfo ptr 0x86041E80
027126: *Sep 24 12:43:26.506: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ind: 
   Entry, callID=24
027127: *Sep 24 12:43:26.506: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_PEER_ACK state
027128: *Sep 24 12:43:26.510: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   chn info coming in chn_ind()
027129: *Sep 24 12:43:26.510: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   Giving event to SLOW start logic: 0
027130: *Sep 24 12:43:26.510: //24/006E38C40300/H323/cch323_peer_channel_ind: 
   chan_type 2, chan_num 2
027131: *Sep 24 12:43:26.510: //24/006E38C40300/H323/cch323_h245_get_olc_instance: 
   Allocated new OLC instance
027132: *Sep 24 12:43:26.510: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_IND while at state IWF_OLC_IDLE
027133: *Sep 24 12:43:26.510: 
   //24/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=7680
027134: *Sep 24 12:43:26.510: //24/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
027135: *Sep 24 12:43:26.514: //24/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_AWAIT_EST state
027136: *Sep 24 12:43:26.514: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   ccb channel 2
027137: *Sep 24 12:43:26.514: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   Use the flow thru address
027138: *Sep 24 12:43:26.514: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 2
      forwardLogicalChannelParameters 
      {
        dataType videoData : h263VideoCapability : 
        {
          cifMPI 1
          maxBitRate 3200
          unrestrictedVector FALSE
          arithmeticCoding FALSE
          advancedPrediction FALSE
          pbFrames FALSE
          temporalSpatialTradeOffCapability FALSE
          errorCompensation FALSE
        }
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 2
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017B5F'H
            tsapIdentifier 17339
          }
          silenceSuppression FALSE
        }
      }
    }



027139: *Sep 24 12:43:26.522: H245 MSC OUTGOING ENCODE BUFFER::= 
   0300000108E400800C7F0070400100800B050002000E017B5F43BB00
027140: *Sep 24 12:43:26.522: 
027141: *Sep 24 12:43:26.526: //24/006E38C40300/H323/cch323_send_olc_passthru: 
   Sent OLC in passthru mode: retcode 0
027142: *Sep 24 12:43:26.526: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_EST_CFM state
027143: *Sep 24 12:43:26.526: h323chan_chn_process_read_socket
027144: *Sep 24 12:43:26.526: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
027145: *Sep 24 12:43:26.526:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

027146: *Sep 24 12:43:26.526: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 26
027147: *Sep 24 12:43:26.526: H245 MSC INCOMING ENCODE BUFFER::= 
   22C000000680134C000E017D7D4208000E017D7D420903000100
027148: *Sep 24 12:43:26.526: 
027149: *Sep 24 12:43:26.530: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 1
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017D7D'H
          tsapIdentifier 16904
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017D7D'H
          tsapIdentifier 16905
        }
        flowControlToZero FALSE
      }
    }



027150: *Sep 24 12:43:26.534: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027151: *Sep 24 12:43:26.534: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027152: *Sep 24 12:43:26.534: //24/006E38C40300/H323/cch323_get_olc_ack_instance: 
   Received OLC_ACK1 msg olc[1] ccb olc[1]
027153: *Sep 24 12:43:26.538: //24/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
027154: *Sep 24 12:43:26.538: //24/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_AWAIT_EST state to H245_OLC_OUT_STATE_ESTABLISHED state
027155: *Sep 24 12:43:26.538: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_CFM while at state IWF_OLC_AWAIT_EST_CFM_PEER_ACK
027156: *Sep 24 12:43:26.538: 
   //24/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   ch=1 non-bestEffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027157: *Sep 24 12:43:26.538: 
   //24/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   qos_mod_used=0, callType=1, do_rsvp=0, flowMode=1
027158: *Sep 24 12:43:26.538: //24/006E38C40300/H323/estCfmAwaitEstCfmPeerAck: 
   Sending olc ack trigger to peer side
027159: *Sep 24 12:43:26.542: //-1/xxxxxxxxxxxx/H323/cch323_do_open_channel_ack: 
   callID=24, sending event CC_EV_H245_OPEN_CHANNEL_ACK, 
   peer channel pointer 0x86041CD0
027160: *Sep 24 12:43:26.542: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=142, Call Id=24
027161: *Sep 24 12:43:26.542: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027162: *Sep 24 12:43:26.542: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027163: *Sep 24 12:43:26.542: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_ACK received, channelInfo ptr 0x86041CD0
027164: *Sep 24 12:43:26.542: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ack: 
   callID=23, Channel info: codec=5 rtp=16904 rtcp=16905 aadr=0xE017D7D
027165: *Sep 24 12:43:26.542: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_EST_CFM_PEER_ACK state to 
   IWF_OLC_OUTDONE_AWAIT_PEER_ACK state
027166: *Sep 24 12:43:26.542: h323chan_chn_process_read_socket
027167: *Sep 24 12:43:26.542: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
027168: *Sep 24 12:43:26.542:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

027169: *Sep 24 12:43:26.546: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 27
027170: *Sep 24 12:43:26.546: H245 MSC INCOMING ENCODE BUFFER::= 
   0300000108E400800EFF0070400100800A040002000E017D7D4349
027171: *Sep 24 12:43:26.546: 
027172: *Sep 24 12:43:26.546: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 2
      forwardLogicalChannelParameters 
      {
        dataType videoData : h263VideoCapability : 
        {
          cifMPI 1
          maxBitRate 3840
          unrestrictedVector FALSE
          arithmeticCoding FALSE
          advancedPrediction FALSE
          pbFrames FALSE
          temporalSpatialTradeOffCapability FALSE
          errorCompensation FALSE
        }
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 2
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017D7D'H
            tsapIdentifier 17225
          }
        }
      }
    }



027173: *Sep 24 12:43:26.554: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027174: *Sep 24 12:43:26.554: h245_decode_one_pdu: Read Pkt body: 
   more_pdus:0 rc:0 asn_rc:0
027175: *Sep 24 12:43:26.554: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   chan_type 2: chan_num 2
027176: *Sep 24 12:43:26.554: 
   //24/006E38C40300/H323/cch323_h245_find_r_allocate_olc_instance: 
   Using existing OLC instance
027177: *Sep 24 12:43:26.554: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   channel_number: 2
027178: *Sep 24 12:43:26.554: //24/006E38C40300/H323/cch323_h245_uchan_ind: 
   h245_rport_tx using RTCP port
027179: *Sep 24 12:43:26.554: //24/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
027180: *Sep 24 12:43:26.554: //24/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_AWAIT_EST state
027181: *Sep 24 12:43:26.558: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_IND while at state IWF_OLC_AWAIT_EST_CFM
027182: *Sep 24 12:43:26.558: 
   //24/006E38C40300/H323/h245_iwf_build_olc_temp_channel_array: 
   tempChannelArray=0x86042150
027183: *Sep 24 12:43:26.558: //24/006E38C40300/H323/h245_iwf_validate_olc: 
   ch=2 non-besteffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027184: *Sep 24 12:43:26.558: //24/006E38C40300/H323/h245_iwf_validate_olc: 
   chanNum 2 BW tx:rx(3200:3840) is a passthru channel; olc=0x852F67E8, 
   peer_chn_num=2, TchnArray=0x86042150, PchnArray=0x86041E80
027185: *Sep 24 12:43:26.558: //24/006E38C40300/H323/cch323_do_open_channel_ind: 
   Sending event CC_EV_H245_OPEN_CHANNEL_IND, channelInfo pointer 0x86042150
027186: *Sep 24 12:43:26.558: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=141, Call Id=24
027187: *Sep 24 12:43:26.558: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027188: *Sep 24 12:43:26.562: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027189: *Sep 24 12:43:26.562: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_IND received, channelInfo ptr 0x86042150
027190: *Sep 24 12:43:26.562: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ind: 
   Entry, callID=23
027191: *Sep 24 12:43:26.562: 
   //24/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=8320
027192: *Sep 24 12:43:26.562: //24/006E38C40300/H323/estIndAwaitEstCfm: 
   Send BRQ for call 8572F6C4
027193: *Sep 24 12:43:26.562: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_EST_CFM state to 
   IWF_OLC_IN_AWAIT_BCF_EST_CFM_PEER_ACK state
027194: *Sep 24 12:43:26.562: RAS OUTGOING PDU ::=

value RasMessage ::= bandwidthRequest : 
    {
      requestSeqNum 4355
      endpointIdentifier {"8591ED9400000001"}
      conferenceID '006E38C43570518C030003010E32CA1F'H
      callReferenceValue 16
      bandWidth 8320
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      answeredCall FALSE
    }



027195: *Sep 24 12:43:26.566: RAS OUTGOING ENCODE BUFFER::= 320011021E003800350039
   0031004500440039003400300030003000300030003000300031006E38C43570518C030003010E32
   CA1F00104020801508001100006E38C43570518C030003010E32CA1F0100
027196: *Sep 24 12:43:26.570: h323chan_dgram_send:Sent UDP msg. Bytes sent: 
   81 to 14.1.123.95:1719 fd=2

027197: *Sep 24 12:43:26.570: RASLib::GW_RASSendBRQ: BRQ (seq# 4355) sent to 
   14.1.123.95
027198: *Sep 24 12:43:26.574: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_process: 
   QUEUE_EVENT (minor 0) wakeup
027199: *Sep 24 12:43:26.574:  RecvUDP_IPSockData  successfully rcvd message of 
   length 81 from 14.1.123.95:64422
027200: *Sep 24 12:43:26.574: RAS INCOMING ENCODE BUFFER::= 320011021E0038003500
   390031004500440039003400300030003000300030003000300031006E38C43570518C030003010E
   32CA1F00104020801508001100006E38C43570518C030003010E32CA1F0100
027201: *Sep 24 12:43:26.574: 
027202: *Sep 24 12:43:26.578: RAS INCOMING PDU ::=

value RasMessage ::= bandwidthRequest : 
    {
      requestSeqNum 4355
      endpointIdentifier {"8591ED9400000001"}
      conferenceID '006E38C43570518C030003010E32CA1F'H
      callReferenceValue 16
      bandWidth 8320
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      answeredCall FALSE
    }



027203: *Sep 24 12:43:26.582: BRQ (seq# 4355) rcvd
027204: *Sep 24 12:43:26.582: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_brq: 
   state = 0xF
027205: *Sep 24 12:43:26.582: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_brq: 
   brqp=0x852FFA80, crv=0x10, bandWidth=8320
027206: *Sep 24 12:43:26.582: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_call_find_crv: 
   endptp=0x8591ED94, crv=16: 
027207: *Sep 24 12:43:26.582: //006E38C40300/006E38C40300/GK/gk_call_find_crv: 
   crv is SEP
027208: *Sep 24 12:43:26.582: RAS OUTGOING PDU ::=

value RasMessage ::= bandwidthConfirm : 
    {
      requestSeqNum 4355
      bandWidth 8320
    }



027209: *Sep 24 12:43:26.586: RAS OUTGOING ENCODE BUFFER::= 341102402080
027210: *Sep 24 12:43:26.586: 
027211: *Sep 24 12:43:26.586:  IPSOCK_RAS_sendto:   msg length 6 from 
   14.1.123.95:1719 to 14.1.123.95: 64422
027212: *Sep 24 12:43:26.586:       RASLib::RASSendBCF: BCF (seq# 4355) sent to 
   14.1.123.95
027213: *Sep 24 12:43:26.586: //23/006E38C40300/H323/cch323_peer_channel_ack: 
   Will send peer chn ack to IWF sm
027214: *Sep 24 12:43:26.586: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_ACK while at state IWF_OLC_AWAIT_EST_CFM_PEER_ACK
027215: *Sep 24 12:43:26.590: //23/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
027216: *Sep 24 12:43:26.590: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 1
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 19496
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 19497
        }
        flowControlToZero FALSE
      }
    }



027217: *Sep 24 12:43:26.594: H245 MSC OUTGOING ENCODE BUFFER::= 22C000000480134
   C000E017B5F4C28000E017B5F4C2903000100
027218: *Sep 24 12:43:26.594: 
027219: *Sep 24 12:43:26.598: //23/006E38C40300/H323/cch323_send_open_channel_ack:
   Send OLC Ack in passthru mode: retcode 0
027220: *Sep 24 12:43:26.598: //23/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_AWAIT_EST state to H245_OLC_IN_STATE_ESTABLISHED state
027221: *Sep 24 12:43:26.598: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_EST_CFM_PEER_ACK state to IWF_OLC_INDONE_AWAIT_EST_CFM state
027222: *Sep 24 12:43:26.598: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   chn info coming in chn_ind()
027223: *Sep 24 12:43:26.598: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   Giving event to SLOW start logic: 1
027224: *Sep 24 12:43:26.598: //23/006E38C40300/H323/cch323_peer_channel_ind: 
   chan_type 2, chan_num 2
027225: *Sep 24 12:43:26.598: //23/006E38C40300/H323/cch323_h245_get_olc_instance: 
   Using existing OLC instance
027226: *Sep 24 12:43:26.598: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_IND while at state IWF_OLC_AWAIT_PEER_ACK
027227: *Sep 24 12:43:26.602: 
   //23/006E38C40300/H323/cch323_selected_codec_req_more_bw: 
   BW approved=7680 inuse=8320
027228: *Sep 24 12:43:26.602: //23/006E38C40300/H323/peerChnIndAwaitPeerAck: 
   Send BRQ for call 83D7D3D4
027229: *Sep 24 12:43:26.602: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_PEER_ACK state 
   to IWF_OLC_OUT_AWAIT_BCF_PEER_ACK state
027230: *Sep 24 12:43:26.602: RAS OUTGOING PDU ::=

value RasMessage ::= bandwidthRequest : 
    {
      requestSeqNum 4356
      endpointIdentifier {"8591ED9400000001"}
      conferenceID '006E38C43570518C030003010E32CA1F'H
      callReferenceValue 15
      bandWidth 8320
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      answeredCall TRUE
    }



027231: *Sep 24 12:43:26.606: RAS OUTGOING ENCODE BUFFER::= 320011031E00380035
   00390031004500440039003400300030003000300030003000300031006E38C43570518C030003
   010E32CA1F000F4020801508001100006E38C43570518C030003010E32CA1F0180
027232: *Sep 24 12:43:26.606: h323chan_dgram_send:Sent UDP msg. Bytes sent: 
   81 to 14.1.123.95:1719 fd=2

027233: *Sep 24 12:43:26.610: RASLib::GW_RASSendBRQ: BRQ (seq# 4356) sent to 
   14.1.123.95
027234: *Sep 24 12:43:26.610: h323chan_chn_process_read_socket
027235: *Sep 24 12:43:26.610: h323chan_chn_process_read_socket: fd=7 of type 
   CONNECTED has data
027236: *Sep 24 12:43:26.610:  h323chan_chn_process_read_socket: h323chan 
   accepted/connected fd=7

027237: *Sep 24 12:43:26.610: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 26
027238: *Sep 24 12:43:26.610: H245 MSC INCOMING ENCODE BUFFER::= 22C000010680134
   C000E017D7D4348000E017D7D434903000100
027239: *Sep 24 12:43:26.610: 
027240: *Sep 24 12:43:26.614: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 2
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017D7D'H
          tsapIdentifier 17224
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017D7D'H
          tsapIdentifier 17225
        }
        flowControlToZero FALSE
      }
    }



027241: *Sep 24 12:43:26.618: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027242: *Sep 24 12:43:26.618: h245_decode_one_pdu: Read Pkt body: 
   more_pdus:0 rc:0 asn_rc:0
027243: *Sep 24 12:43:26.618: //24/006E38C40300/H323/cch323_get_olc_ack_instance: 
   Received OLC_ACK1 msg olc[2] ccb olc[1]
027244: *Sep 24 12:43:26.618: //24/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
027245: *Sep 24 12:43:26.618: //24/006E38C40300/H323/h323_open_add_rtp_stream: 
   ip_video_tos 136
027246: *Sep 24 12:43:26.622: //24/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_AWAIT_EST state to 
   H245_OLC_OUT_STATE_ESTABLISHED state
027247: *Sep 24 12:43:26.622: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_CFM while at state IWF_OLC_IN_AWAIT_BCF_EST_CFM_PEER_ACK
027248: *Sep 24 12:43:26.622: 
   //24/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   ch=2 non-bestEffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027249: *Sep 24 12:43:26.622: 
   //24/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   qos_mod_used=0, callType=1, do_rsvp=0, flowMode=1
027250: *Sep 24 12:43:26.622: //24/006E38C40300/H323/estCfmInAwaitBcfEstCfmPeerAck:
   Sending olc ack trigger to peer side
027251: *Sep 24 12:43:26.622: //-1/xxxxxxxxxxxx/H323/cch323_do_open_channel_ack: 
   callID=24, sending event CC_EV_H245_OPEN_CHANNEL_ACK, peer channel pointer 0x86041E80
027252: *Sep 24 12:43:26.622: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=142, Call Id=24
027253: *Sep 24 12:43:26.626: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027254: *Sep 24 12:43:26.626: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027255: *Sep 24 12:43:26.626: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_ACK received, channelInfo ptr 0x86041E80
027256: *Sep 24 12:43:26.626: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ack: 
   callID=23, Channel info: codec=-1 rtp=17224 rtcp=17225 aadr=0xE017D7D
027257: *Sep 24 12:43:26.626: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_IN_AWAIT_BCF_EST_CFM_PEER_ACK state to IWF_OLC_OUTDONE_AWAIT_BCF_PEER_ACK state
027258: *Sep 24 12:43:26.626: h323chan_chn_process_read_socket
027259: *Sep 24 12:43:26.626: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
027260: *Sep 24 12:43:26.626:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

027261: *Sep 24 12:43:26.626: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 23
027262: *Sep 24 12:43:26.626: H245 MSC INCOMING ENCODE BUFFER::= 
   22C000000480101C00000E32C9114022000E32C9114023
027263: *Sep 24 12:43:26.630: 
027264: *Sep 24 12:43:26.630: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 1
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        sessionID 1
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E32C911'H
          tsapIdentifier 16418
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E32C911'H
          tsapIdentifier 16419
        }
      }
    }



027265: *Sep 24 12:43:26.634: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027266: *Sep 24 12:43:26.634: h245_decode_one_pdu: 
   Read Pkt body: more_pdus:0 rc:0 asn_rc:0
027267: *Sep 24 12:43:26.634: //23/006E38C40300/H323/cch323_get_olc_ack_instance: 
   Received OLC_ACK1 msg olc[1] ccb olc[1]
027268: *Sep 24 12:43:26.638: //23/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
027269: *Sep 24 12:43:26.638: //23/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_AWAIT_EST state to H245_OLC_OUT_STATE_ESTABLISHED state
027270: *Sep 24 12:43:26.638: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_CFM while at state IWF_OLC_INDONE_AWAIT_EST_CFM
027271: *Sep 24 12:43:26.638: 
   //23/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   ch=1 non-bestEffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027272: *Sep 24 12:43:26.638: 
   //23/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   qos_mod_used=0, callType=1, do_rsvp=0, flowMode=1
027273: *Sep 24 12:43:26.638: //23/006E38C40300/H323/estCfmOlcIndoneAwaitEstCfm: 
   Sending olc ack trigger to peer side
027274: *Sep 24 12:43:26.638: //-1/xxxxxxxxxxxx/H323/cch323_do_open_channel_ack: 
   callID=23, sending event CC_EV_H245_OPEN_CHANNEL_ACK, peer channel pointer 0x860420C0
027275: *Sep 24 12:43:26.638: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event=142, Call Id=23
027276: *Sep 24 12:43:26.642: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027277: *Sep 24 12:43:26.642: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=24
027278: *Sep 24 12:43:26.642: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_ACK received, channelInfo ptr 0x860420C0
027279: *Sep 24 12:43:26.642: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ack: 
   callID=24, Channel info: codec=5 rtp=16418 rtcp=16419 aadr=0xE32C911
027280: *Sep 24 12:43:26.642: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_INDONE_AWAIT_EST_CFM state to IWF_OLC_DONE state
027281: *Sep 24 12:43:26.642: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_DONE while at state IWF_ACTIVE
027282: *Sep 24 12:43:26.642: //23/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_H245_SUCCESS while at state H225_WAIT_FOR_H245
027283: *Sep 24 12:43:26.642: //23/006E38C40300/H323/cch323_h225_set_new_state: 
   Changing from H225_WAIT_FOR_H245 state to H225_ACTIVE state
027284: *Sep 24 12:43:26.642: h323chan_chn_process_read_socket
027285: *Sep 24 12:43:26.642: h323chan_chn_process_read_socket: 
   fd=2 of type CONNECTED has data
027286: *Sep 24 12:43:26.646:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=2

027287: *Sep 24 12:43:26.646: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] 
   on fd=2

027288: *Sep 24 12:43:26.646: RAS INCOMING ENCODE BUFFER::= 341102402080
027289: *Sep 24 12:43:26.646: 
027290: *Sep 24 12:43:26.646: RAS INCOMING PDU ::=

value RasMessage ::= bandwidthConfirm : 
    {
      requestSeqNum 4355
      bandWidth 8320
    }



027291: *Sep 24 12:43:26.646: BCF (seq# 4355) rcvd
027292: *Sep 24 12:43:26.646: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_BCF while at state IWF_OLC_OUTDONE_AWAIT_PEER_ACK
027293: *Sep 24 12:43:26.646: //24/006E38C40300/H323/errHdlr: ERROR: 
   Received Unexpected IWF_EV_BCF in state IWF_OLC_OUTDONE_AWAIT_PEER_ACK
027294: *Sep 24 12:43:26.650: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_BCF while at state IWF_OLC_OUTDONE_AWAIT_BCF_PEER_ACK
027295: *Sep 24 12:43:26.650: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_OUTDONE_AWAIT_BCF_PEER_ACK state 
   to IWF_OLC_OUTDONE_AWAIT_PEER_ACK state
027296: *Sep 24 12:43:26.650: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_process: 
   QUEUE_EVENT (minor 0) wakeup
027297: *Sep 24 12:43:26.654:  RecvUDP_IPSockData  successfully rcvd message of 
   length 81 from 14.1.123.95:64422
027298: *Sep 24 12:43:26.654: RAS INCOMING ENCODE BUFFER::= 320011031E00380035
   00390031004500440039003400300030003000300030003000300031006E38C43570518C030003
   010E32CA1F000F4020801508001100006E38C43570518C030003010E32CA1F0180
027299: *Sep 24 12:43:26.654: 
027300: *Sep 24 12:43:26.654: RAS INCOMING PDU ::=

value RasMessage ::= bandwidthRequest : 
    {
      requestSeqNum 4356
      endpointIdentifier {"8591ED9400000001"}
      conferenceID '006E38C43570518C030003010E32CA1F'H
      callReferenceValue 15
      bandWidth 8320
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      answeredCall TRUE
    }



027301: *Sep 24 12:43:26.658: BRQ (seq# 4356) rcvd
027302: *Sep 24 12:43:26.658: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_brq: 
   state = 0xF
027303: *Sep 24 12:43:26.662: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_rassrv_brq: 
   brqp=0x85487690, crv=0xF, bandWidth=8320
027304: *Sep 24 12:43:26.662: //xxxxxxxxxxxx/xxxxxxxxxxxx/GK/gk_call_find_crv: 
   endptp=0x8591ED94, crv=15: 
027305: *Sep 24 12:43:26.662: //006E38C40300/006E38C40300/GK/gk_call_find_crv: 
   crv is DEP
027306: *Sep 24 12:43:26.662: RAS OUTGOING PDU ::=

value RasMessage ::= bandwidthConfirm : 
    {
      requestSeqNum 4356
      bandWidth 8320
    }



027307: *Sep 24 12:43:26.662: RAS OUTGOING ENCODE BUFFER::= 341103402080
027308: *Sep 24 12:43:26.662: 
027309: *Sep 24 12:43:26.666:  IPSOCK_RAS_sendto:   msg length 6 from 
   14.1.123.95:1719 to 14.1.123.95: 64422
027310: *Sep 24 12:43:26.666:       RASLib::RASSendBCF: BCF (seq# 4356) sent to 
   14.1.123.95
027311: *Sep 24 12:43:26.666: //23/006E38C40300/H323/cch323_peer_channel_ack: 
   Will send peer chn ack to IWF sm
027312: *Sep 24 12:43:26.666: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_ACK while at state IWF_OLC_OUT_AWAIT_BCF_PEER_ACK
027313: *Sep 24 12:43:26.670: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_OUT_AWAIT_BCF_PEER_ACK state to IWF_OLC_AWAIT_BCF_ESTIND_RCVD state
027314: *Sep 24 12:43:26.670: //24/006E38C40300/H323/cch323_peer_channel_ack: 
   Will send peer chn ack to IWF sm
027315: *Sep 24 12:43:26.670: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_ACK while at state IWF_OLC_OUTDONE_AWAIT_PEER_ACK
027316: *Sep 24 12:43:26.670: //24/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
027317: *Sep 24 12:43:26.670: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 1
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        sessionID 1
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 16772
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 16773
        }
      }
    }



027318: *Sep 24 12:43:26.678: H245 MSC OUTGOING ENCODE BUFFER::= 
   22C000000480101C00000E017B5F4184000E017B5F4185
027319: *Sep 24 12:43:26.678: 
027320: *Sep 24 12:43:26.678: //24/006E38C40300/H323/cch323_send_open_channel_ack: 
   Send OLC Ack in passthru mode: retcode 0
027321: *Sep 24 12:43:26.678: //24/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_AWAIT_EST state to H245_OLC_IN_STATE_ESTABLISHED state
027322: *Sep 24 12:43:26.678: //24/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_OUTDONE_AWAIT_PEER_ACK state to IWF_OLC_DONE state
027323: *Sep 24 12:43:26.678: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_DONE while at state IWF_ACTIVE
027324: *Sep 24 12:43:26.682: //24/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_H245_SUCCESS while at state H225_WAIT_FOR_H245
027325: *Sep 24 12:43:26.682: //24/006E38C40300/H323/cch323_h225_set_new_state: 
   Changing from H225_WAIT_FOR_H245 state to H225_ACTIVE state
027326: *Sep 24 12:43:26.682: //24/006E38C40300/H323/setup_cfm_notify: 
   status = 8800261B
027327: *Sep 24 12:43:26.682: 
   //24/006E38C40300/H323/cch323_h225_handle_deferred_ind: 
   UnBuffering deferred indications
027328: *Sep 24 12:43:26.682: h323chan_chn_process_read_socket
027329: *Sep 24 12:43:26.682: h323chan_chn_process_read_socket: 
   fd=2 of type CONNECTED has data
027330: *Sep 24 12:43:26.682:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=2

027331: *Sep 24 12:43:26.682: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] 
   on fd=2

027332: *Sep 24 12:43:26.682: RAS INCOMING ENCODE BUFFER::= 341103402080
027333: *Sep 24 12:43:26.682: 
027334: *Sep 24 12:43:26.686: RAS INCOMING PDU ::=

value RasMessage ::= bandwidthConfirm : 
    {
      requestSeqNum 4356
      bandWidth 8320
    }



027335: *Sep 24 12:43:26.686: BCF (seq# 4356) rcvd
027336: *Sep 24 12:43:26.686: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_BCF while at state IWF_OLC_DONE
027337: *Sep 24 12:43:26.686: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_BCF while at state IWF_OLC_AWAIT_BCF_ESTIND_RCVD
027338: *Sep 24 12:43:26.686: //23/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
027339: *Sep 24 12:43:26.686: //23/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_IDLE state 
   to H245_OLC_OUT_STATE_AWAIT_EST state
027340: *Sep 24 12:43:26.686: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   ccb channel 2
027341: *Sep 24 12:43:26.690: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   Use the flow thru address
027342: *Sep 24 12:43:26.690: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= request : openLogicalChannel : 
    {
      forwardLogicalChannelNumber 2
      forwardLogicalChannelParameters 
      {
        dataType videoData : h263VideoCapability : 
        {
          cifMPI 1
          maxBitRate 3840
          unrestrictedVector FALSE
          arithmeticCoding FALSE
          advancedPrediction FALSE
          pbFrames FALSE
          temporalSpatialTradeOffCapability FALSE
          errorCompensation FALSE
        }
        multiplexParameters h2250LogicalChannelParameters : 
        {
          sessionID 2
          mediaControlChannel unicastAddress : iPAddress : 
          {
            network '0E017B5F'H
            tsapIdentifier 17181
          }
        }
      }
    }



027343: *Sep 24 12:43:26.698: H245 MSC OUTGOING ENCODE BUFFER::= 
   0300000108E400800EFF0070400100800A040002000E017B5F431D
027344: *Sep 24 12:43:26.698: 
027345: *Sep 24 12:43:26.698: //23/006E38C40300/H323/cch323_send_olc_passthru: 
   Sent OLC in passthru mode: retcode 0
027346: *Sep 24 12:43:26.698: //23/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
027347: *Sep 24 12:43:26.698: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 2
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 17180
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 17181
        }
        flowControlToZero FALSE
      }
    }



027348: *Sep 24 12:43:26.706: H245 MSC OUTGOING ENCODE BUFFER::= 
   22C000010480134C000E017B5F431C000E017B5F431D03000100
027349: *Sep 24 12:43:26.706: 
027350: *Sep 24 12:43:26.710: //23/006E38C40300/H323/cch323_send_open_channel_ack: 
   Send OLC Ack in passthru mode: retcode 0
027351: *Sep 24 12:43:26.710: //23/006E38C40300/H323/h245_olc_in_set_new_state: 
   Changing from H245_OLC_IN_STATE_AWAIT_EST state to H245_OLC_IN_STATE_ESTABLISHED state
027352: *Sep 24 12:43:26.710: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_AWAIT_BCF_ESTIND_RCVD state 
   to IWF_OLC_INDONE_AWAIT_EST_CFM state
027353: *Sep 24 12:43:26.714: h323chan_chn_process_read_socket
027354: *Sep 24 12:43:26.714: h323chan_chn_process_read_socket: 
   fd=7 of type CONNECTED has data
027355: *Sep 24 12:43:26.714:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=7

027356: *Sep 24 12:43:26.714: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 4
027357: *Sep 24 12:43:26.714: H245 MSC INCOMING ENCODE BUFFER::= 4C000128
027358: *Sep 24 12:43:26.718: 
027359: *Sep 24 12:43:26.718: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= command : miscellaneousCommand : 
    {
      logicalChannelNumber 2
      type videoFastUpdatePicture : NULL
    }



027360: *Sep 24 12:43:26.718: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027361: *Sep 24 12:43:26.718: h245_decode_one_pdu: Read Pkt body: 
   more_pdus:0 rc:0 asn_rc:0
027362: *Sep 24 12:43:26.718: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=145, Call Id=24
027363: *Sep 24 12:43:26.718: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027364: *Sep 24 12:43:26.718: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=23
027365: *Sep 24 12:43:26.718: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_PASSTHRU received
027366: *Sep 24 12:43:26.722: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= command : miscellaneousCommand : 
    {
      logicalChannelNumber 2
      type videoFastUpdatePicture : NULL
    }



027367: *Sep 24 12:43:26.722: H245 MSC OUTGOING ENCODE BUFFER::= 4C000128
027368: *Sep 24 12:43:26.722: 
027369: *Sep 24 12:43:26.726: //23/006E38C40300/H323/cch323_send_passthru_out: 
   Send passthru message retcode 0
027370: *Sep 24 12:43:27.067: h323chan_chn_process_read_socket
027371: *Sep 24 12:43:27.067: h323chan_chn_process_read_socket: 
   fd=6 of type ACCEPTED has data
027372: *Sep 24 12:43:27.067:  h323chan_chn_process_read_socket: 
   h323chan accepted/connected fd=6

027373: *Sep 24 12:43:27.071: h245_decode_one_pdu: 
   more_pdus = 0, bytesLeftToDecode = 23
027374: *Sep 24 12:43:27.071: H245 MSC INCOMING ENCODE BUFFER::= 
   22C000010480101C02000E32C9114B84000E32C9114B85
027375: *Sep 24 12:43:27.071: 
027376: *Sep 24 12:43:27.071: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 2
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        sessionID 2
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E32C911'H
          tsapIdentifier 19332
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E32C911'H
          tsapIdentifier 19333
        }
      }
    }



027377: *Sep 24 12:43:27.079: h245_decode_one_pdu: 
   H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
027378: *Sep 24 12:43:27.079: h245_decode_one_pdu: Read Pkt body: 
   more_pdus:0 rc:0 asn_rc:0
027379: *Sep 24 12:43:27.079: //23/006E38C40300/H323/cch323_get_olc_ack_instance: 
   Received OLC_ACK1 msg olc[2] ccb olc[1]
027380: *Sep 24 12:43:27.079: //23/006E38C40300/H323/h245_olc_out_sm: 
   Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
027381: *Sep 24 12:43:27.079: //23/006E38C40300/H323/h323_open_add_rtp_stream: 
   ip_video_tos 136
027382: *Sep 24 12:43:27.083: //23/006E38C40300/H323/h245_olc_out_set_new_state: 
   Changing from H245_OLC_OUT_STATE_AWAIT_EST state to H245_OLC_OUT_STATE_ESTABLISHED state
027383: *Sep 24 12:43:27.083: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_EST_CFM while at state IWF_OLC_INDONE_AWAIT_EST_CFM
027384: *Sep 24 12:43:27.083: 
   //23/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   ch=2 non-bestEffort=0 sync=0 is_ipip=1, nonsync_rsvp=0
027385: *Sep 24 12:43:27.083: 
   //23/006E38C40300/H323/h245_iwf_request_rsvp_on_olc_ack: 
   qos_mod_used=0, callType=1, do_rsvp=0, flowMode=1
027386: *Sep 24 12:43:27.083: //23/006E38C40300/H323/estCfmOlcIndoneAwaitEstCfm: 
   Sending olc ack trigger to peer side
027387: *Sep 24 12:43:27.083: //-1/xxxxxxxxxxxx/H323/cch323_do_open_channel_ack: 
   callID=23, sending event CC_EV_H245_OPEN_CHANNEL_ACK, peer channel pointer 
   0x86042150
027388: *Sep 24 12:43:27.083: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event=142, Call Id=23
027389: *Sep 24 12:43:27.083: //23/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
027390: *Sep 24 12:43:27.087: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   callID=24
027391: *Sep 24 12:43:27.087: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: 
   Event CC_EV_H245_OPEN_CHANNEL_ACK received, channelInfo ptr 0x86042150
027392: *Sep 24 12:43:27.087: //-1/xxxxxxxxxxxx/H323/cch323_open_channel_ack: 
   callID=24, Channel info: codec=-1 rtp=19332 rtcp=19333 aadr=0xE32C911
027393: *Sep 24 12:43:27.087: //23/006E38C40300/H323/h245_iwf_set_new_state: 
   changing from IWF_OLC_INDONE_AWAIT_EST_CFM state to IWF_OLC_DONE state
027394: *Sep 24 12:43:27.087: //23/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_OLC_DONE while at state IWF_ACTIVE
027395: *Sep 24 12:43:27.087: //23/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_H245_SUCCESS while at state H225_ACTIVE
027396: *Sep 24 12:43:27.091: //24/006E38C40300/H323/cch323_peer_channel_ack: 
   Will send peer chn ack to IWF sm
027397: *Sep 24 12:43:27.091: //24/006E38C40300/H323/run_h245_iwf_sm: 
   received IWF_EV_PEER_CHN_ACK while at state IWF_OLC_OUTDONE_AWAIT_PEER_ACK
027398: *Sep 24 12:43:27.091: //24/006E38C40300/H323/h245_olc_in_sm: 
   Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
027399: *Sep 24 12:43:27.091: H245 MSC OUTGOING PDU ::=

value MultimediaSystemControlMessage ::= response : openLogicalChannelAck : 
    {
      forwardLogicalChannelNumber 2
      forwardMultiplexAckParameters h2250LogicalChannelAckParameters : 
      {
        sessionID 2
        mediaChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 17338
        }
        mediaControlChannel unicastAddress : iPAddress : 
        {
          network '0E017B5F'H
          tsapIdentifier 17339
        }
      }
    }

Step 29

4085252000 hangs up the call. CUBE-2 receives H225 Release-complete from CME.

(GK-CUBE-2.txt)

027697: *Sep 24 12:44:23.720: H225.0 INCOMING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body releaseComplete : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
        }
        h245Tunneling FALSE
      }
    }



027698: *Sep 24 12:44:23.724: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: 
   Received msg of type RELEASEIND_CHOSEN
027699: *Sep 24 12:44:23.724: //24/006E38C40300/H323/release_ind: 
   Disconnect cause 16 location code 0
027700: *Sep 24 12:44:23.724: //24/006E38C40300/H323/cch323_h225_receiver: 
   RELEASEIND_CHOSEN: src address = 14.1.123.95; dest address = 14.1.125.125
027701: *Sep 24 12:44:23.724: //24/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_RELEASE_IND while at state H225_ACTIVE
027702: *Sep 24 12:44:23.728: //24/006E38C40300/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x855A8B64, Call Id=24
027703: *Sep 24 12:44:23.728: //24/006E38C40300/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

Step 30

After receiving / sending Release-complete, CCM, CUBE-1, CUBE-2 and CME send a Disengage Request (DRQ) to their respective Gatekeepers.

(GK-CUBE-2.txt)

027712: *Sep 24 12:44:23.736: RAS INCOMING PDU ::=

value RasMessage ::= disengageRequest : 
    {
      requestSeqNum 1960
      endpointIdentifier {"860100E800000002"}
      conferenceID '006E38C43570518C030003010E32CA1F'H
      callReferenceValue 8
      disengageReason normalDrop : NULL
      callIdentifier 
      {
        guid '006E38C43570518C030003010E32CA1F'H
      }
      answeredCall TRUE
      usageInformation 
      {
        nonStandardUsageFields 
        {

          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '584020020100'H
          }
        }
        connectTime 1220898589
        endTime 1220898647
      }
      terminationCause releaseCompleteCauseIE : '08028090'H
    }

Step 31

CUBE-2 sends Release-complete to CUBE-1, which then sends a corresponding Release-complete message to Cisco Unified Communications Manager and the call disconnects.

(GK-CUBE-2.txt)

027733: *Sep 24 12:44:23.768: //23/006E38C40300/H323/run_h225_sm: 
   Received event H225_EV_RELEASE while at state H225_ACTIVE
027734: *Sep 24 12:44:23.768: //23/006E38C40300/H323/cch323_h225_set_new_state: 
   Changing from H225_ACTIVE state to H225_WAIT_FOR_DRQ state
027735: *Sep 24 12:44:23.768: //23/006E38C40300/H323/cch323_h225_send_release: 
   Cause = 16; Location = 0
027736: *Sep 24 12:44:23.768: //23/006E38C40300/H323/cch323_h225_send_release: 
   h225TerminateRequest: src address = 234978143; dest address = 14.50.201.17
027737: *Sep 24 12:44:23.768: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::= 
    {
      h323-uu-pdu 
      {
        h323-message-body releaseComplete : 
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
        }
        h245Tunneling FALSE
      }
    }

Related Information

Updated: Sep 29, 2008
Document ID: 107976