CME-1#show gateway
H.323 ITU-T Version: 4.0   H323 Stack Version: 0.1 

 H.323 service is up
 Gateway  CME-1  is registered to Gatekeeper CME

Alias list (CLI configured) 
 H323-ID CME-1
Alias list (last RCF) 
 H323-ID CME-1

 H323 resource thresholding is Disabled
CME-1#show call active video brief
<ID>: <CallID> <start>ms.<index> +<connect> pid:<peer_id> <dir> <addr> <state> 
  dur hh:mm:ss tx:<packets>/<bytes> rx:<packets>/<bytes>
 IP <ip>:<udp> rtt:<time>ms pl:<play>/<gap>ms lost:<lost>/<early>/<late>
  delay:<last>/<min>/<max>ms <codec>
  
 media inactive detected:<y/n> media cntrl rcvd:<y/n> timestamp:<time>
  
 long duration call detected:<y/n> long duration call duration :<sec> timestamp:<time>
  MODEMPASS <method> buf:<fills>/<drains> loss <overall%> <multipkt>/<corrected>
   last <buf event time>s dur:<Min>/<Max>s
 FR <protocol> [int dlci cid] vad:<y/n> dtmf:<y/n> seq:<y/n>
  <codec> (payload size)
 ATM <protocol> [int vpi/vci cid] vad:<y/n> dtmf:<y/n> seq:<y/n>
  <codec> (payload size)
 Tele <int> (callID) [channel_id] tx:<tot>/<v>/<fax>ms <codec> noise:<l> acom:<l> i/o:<l>/<l> dBm
   video: h320:<type> tx:<video codec> <video pkts>/<video bytes> rx:<video codec> <video pkts>/<video bytes>
  MODEMRELAY info:<rcvd>/<sent>/<resent> xid:<rcvd>/<sent> total:<rcvd>/<sent>/<drops>
         speeds(bps): local <rx>/<tx> remote <rx>/<tx>
 Proxy <ip>:<audio udp>,<video udp>,<tcp0>,<tcp1>,<tcp2>,<tcp3> endpt: <type>/<manf>
 bw: <req>/<act> codec: <audio>/<video>
  tx: <audio pkts>/<audio bytes>,<video pkts>/<video bytes>,<t120 pkts>/<t120 bytes>
 rx: <audio pkts>/<audio bytes>,<video pkts>/<video bytes>,<t120 pkts>/<t120 bytes>
 

Telephony call-legs: 1
SIP call-legs: 0
H323 call-legs: 1
Call agent controlled call-legs: 0
SCCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 2
148C : 24 86715490ms.1 +5330 pid:2 Answer 9193922000 active
 dur 00:00:48 tx:3522/1142976 rx:3496/704520
 IP 14.1.123.95:17338 SRTP: off rtt:0ms pl:44240/1ms 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 : 25 86715510ms.1 +5290 pid:20001 Originate 4085252000 active
 dur 00:00:48 tx:2217/381324 rx:2212/380464
 Tele 50/0/30 (25) [50/0/30.0] tx:44340/44340/0ms g711ulaw noise:0 acom:0  i/0:0/0 dBm

Telephony call-legs: 1
SIP call-legs: 0
H323 call-legs: 1
Call agent controlled call-legs: 0
SCCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 2

CME-1#show voip rtp connection
VoIP RTP active connections :
No. CallId     dstCallId  LocalRTP RmtRTP LocalIP                                RemoteIP                               
1   24         25         16904    16772  14.1.125.125                           14.1.123.95                            
2   27         26         17224    17338  14.1.125.125                           14.1.123.95                            
Found 2 active RTP connections

CME-1#
CME-1#
CME-1#
CME-1#sh log
Syslog logging: enabled (0 messages dropped, 1 messages rate-limited,
                0 flushes, 0 overruns, xml disabled, filtering disabled)

No Active Message Discriminator.



No Inactive Message Discriminator.


    Console logging: disabled
    Monitor logging: level debugging, 0 messages logged, xml disabled,
                     filtering disabled
    Buffer logging:  level debugging, 4498 messages logged, xml disabled,
                     filtering disabled
    Logging Exception size (4096 bytes)
    Count and timestamp logging messages: disabled
    Persistent logging: disabled
    Trap logging: level informational, 58 message lines logged

Log Buffer (5000000 bytes):

002256: *Sep  8 18:29:43.710: h323chan_chn_process_read_socket
002257: *Sep  8 18:29:43.710: h323chan_chn_process_read_socket: fd=3 of type ACCEPTED has data
002258: *Sep  8 18:29:43.714:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=3

002259: *Sep  8 18:29:43.714: H225.0 INCOMING ENCODE BUFFER::= 20A0060008914A00040140050043005500420045002D003228C0B50000120B436973636F47617465776179003240023C0504010020602C05010000006E38C43570518C030003010E32CA1F00CD0D800007000E017B5F2C861100006E38C43570518C030003010E32CA1F010001000180018010A00180210140B50000121A80A50004000103001127F8000000000000000000000000000000
002260: *Sep  8 18:29:43.714: 
002261: *Sep  8 18:29:43.714: H225.0 INCOMING 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
          }
        }
      }
    }



002262: *Sep  8 18:29:43.722: H225 NONSTD INCOMING ENCODE BUFFER::= 80A50004000103001127F8000000000000000000000000000000
002263: *Sep  8 18:29:43.722: 
002264: *Sep  8 18:29:43.722: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::= 
    {
      callMgrParam 
      {
        interclusterVersion 3
        enterpriseID {}
      }
      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
        }
      }
    }



002265: *Sep  8 18:29:43.726: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
002266: *Sep  8 18:29:43.726: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry
002267: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: callingNumber[9193922000] calledNumber[3#4085252000]
002268: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: ---- calling IE present
002269: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: ====== PI = 0
002270: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: Receive: infoXCap 8
002271: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: Receive: infoXCap ccb 8
002272: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: 
setup_ind: is_overlap = 0, info_complete = 0

002273: *Sep  8 18:29:43.726: //24/006E38C40300/H323/setup_ind: Call Manager detected
002274: *Sep  8 18:29:43.726: //24/006E38C40300/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 14.1.125.125; dest address = 14.1.123.95
002275: *Sep  8 18:29:43.726: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE
002276: *Sep  8 18:29:43.726: //24/006E38C40300/H323/common_idle_setupInd_hdlr: full match is found
002277: *Sep  8 18:29:43.726: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_WAIT_FOR_ARQ state
002278: *Sep  8 18:29:43.726: H225 NONSTD OUTGOING PDU ::=

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



002279: *Sep  8 18:29:43.730: H225 NONSTD OUTGOING ENCODE BUFFER::= 80000010800181
002280: *Sep  8 18:29:43.730: 
002281: *Sep  8 18:29:43.730: RAS OUTGOING 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
    }



002282: *Sep  8 18:29:43.734: RAS OUTGOING ENCODE BUFFER::= 279807A300F000380036003000310030003000450038003000300030003000300030003000320105806073B8585333020480C4C6C5533340050043005500420045002D0032000E017B5F2C86401E00000840B50000120780000010800181006E38C43570518C030003010E32CA1F48E0200001801100006E38C43570518C030003010E32CA1F0100
002283: *Sep  8 18:29:43.734: h323chan_dgram_send:Sent UDP msg. Bytes sent: 136 to 14.1.123.95:1719 fd=2

002284: *Sep  8 18:29:43.734: RASLib::GW_RASSendARQ: ARQ (seq# 1956) sent to 14.1.123.95
002285: *Sep  8 18:29:43.778: h323chan_chn_process_read_socket
002286: *Sep  8 18:29:43.778: h323chan_chn_process_read_socket: fd=2 of type CONNECTED has data
002287: *Sep  8 18:29:43.778:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=2

002288: *Sep  8 18:29:43.778: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] on fd=2

002289: *Sep  8 18:29:43.782: RAS INCOMING ENCODE BUFFER::= 2B0007A3401E00000E017D7D06B800EF2800C20001000200000B014CB80140B50000120140
002290: *Sep  8 18:29:43.782: 
002291: *Sep  8 18:29:43.782: RAS INCOMING 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
          }
        }
      }
    }



002292: *Sep  8 18:29:43.786: ACF (seq# 1956) rcvd
002293: *Sep  8 18:29:43.786: H225 NONSTD INCOMING ENCODE BUFFER::= 40
002294: *Sep  8 18:29:43.786: 
002295: *Sep  8 18:29:43.786: H225 NONSTD INCOMING PDU ::=

value RasnonStdUsageTypes ::= 
    {
      callModes NULL
    }


parse_ras_usage_info_types: Ras Usage Info Nonstd decode succeeded, remlen = 125
002296: *Sep  8 18:29:43.786: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_RAS_SUCCESS while at state H225_WAIT_FOR_ARQ
002297: *Sep  8 18:29:43.786: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_ARQ state to H225_SETUP state
002298: *Sep  8 18:29:43.786: //24/006E38C40300/H323/cch323_create_incoming_callinfo_block: peer 4570C0B4, voice_peer_tag 2, ccb: 4727F964
002299: *Sep  8 18:29:43.786: //24/006E38C40300/H323/cch323_create_incoming_callinfo_block: Calling Party is CCM
002300: *Sep  8 18:29:43.786: //-1/006E38C40300/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=CUBE-2
   ----- ccCallInfo IE subfields -----
   cisco-ani=9193922000
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=3#4085252000
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=-1
   cisco-rdnplan=-1
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

002301: *Sep  8 18:29:43.790: //-1/006E38C40300/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x472E59CC, 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=2, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=24
002302: *Sep  8 18:29:43.790: //-1/006E38C40300/CCAPI/ccCheckClipClir:
   In: Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
002303: *Sep  8 18:29:43.790: //-1/006E38C40300/CCAPI/ccCheckClipClir:
   Out: Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
002304: *Sep  8 18:29:43.790: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002305: *Sep  8 18:29:43.790: :cc_get_feature_vsa malloc success
002306: *Sep  8 18:29:43.790: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002307: *Sep  8 18:29:43.790:  cc_get_feature_vsa count is 1
002308: *Sep  8 18:29:43.790: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002309: *Sep  8 18:29:43.790: :FEATURE_VSA attributes are: feature_name:0,feature_time:1218345144,feature_id:16
002310: *Sep  8 18:29:43.790: //24/006E38C40300/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=3#4085252000(TON=Unknown, NPI=Unknown))
002311: *Sep  8 18:29:43.790: //24/006E38C40300/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications
002312: *Sep  8 18:29:43.794: //24/006E38C40300/CCAPI/cc_process_call_setup_ind:
   Event=0x474C80B8
002313: *Sep  8 18:29:43.794: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 3#4085252000
002314: *Sep  8 18:29:43.794: //24/006E38C40300/CCAPI/ccCallSetContext:
   Context=0x4895D144
002315: *Sep  8 18:29:43.794: //24/006E38C40300/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 24 with tag 2 to app "_ManagedAppProcess_Default"
002316: *Sep  8 18:29:43.794: //24/006E38C40300/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
002317: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=20001, Params=0x4895E084, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
002318: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccCheckClipClir:
   In: Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
002319: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccCheckClipClir:
   Out: Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
002320: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccCallSetupRequest:
   Destination Pattern=4085252000$, Called Number=4085252000, Digit Strip=TRUE
002321: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccCallSetupRequest:
   Calling Number=9193922000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=4085252000(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=
   Account Number=CUBE-2, Final Destination Flag=TRUE,
   Guid=006E38C4-3570-518C-0300-03010E32CA1F, Outgoing Dial-peer=20001
002322: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=CUBE-2
   ----- ccCallInfo IE subfields -----
   cisco-ani=9193922000
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=1
   dest=4085252000
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=-1
   cisco-rdnplan=-1
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

002323: *Sep  8 18:29:43.798: //24/006E38C40300/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x4792F4A0, Interface Type=6, Destination=, Mode=0x0,
   Call Params(Calling Number=9193922000,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
   Called Number=4085252000(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20001, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
002324: *Sep  8 18:29:43.798: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002325: *Sep  8 18:29:43.802: :cc_get_feature_vsa malloc success
002326: *Sep  8 18:29:43.802: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002327: *Sep  8 18:29:43.802:  cc_get_feature_vsa count is 2
002328: *Sep  8 18:29:43.802: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
   
002329: *Sep  8 18:29:43.802: :FEATURE_VSA attributes are: feature_name:0,feature_time:1218344920,feature_id:17
002330: *Sep  8 18:29:43.802: //25/006E38C40300/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
002331: *Sep  8 18:29:43.802: //25/006E38C40300/CCAPI/ccCallSetContext:
   Context=0x4895E034
002332: *Sep  8 18:29:43.802: //24/006E38C40300/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=20001
002333: *Sep  8 18:29:43.806: //25/006E38C40300/CCAPI/cc_api_update_call_info:
   Interface=0x4792F4A0, Call Id=0x19
002334: *Sep  8 18:29:43.810: //25/006E38C40300/CCAPI/cc_api_call_proceeding:
   Interface=0x4792F4A0, Progress Indication=NULL(0)
002335: *Sep  8 18:29:43.810: //25/006E38C40300/CCAPI/cc_api_call_alert:
   Interface=0x4792F4A0, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
002336: *Sep  8 18:29:43.810: //25/006E38C40300/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
002337: *Sep  8 18:29:43.810: //24/006E38C40300/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_LISTEN_REQ_EVENT
002338: *Sep  8 18:29:43.810: //24/006E38C40300/H323/cch323_h245_connection_sm: H245_LISTEN: Received event H245_LISTEN_REQ_EVENT while at H245_NONE state
002339: *Sep  8 18:29:43.810: //24/006E38C40300/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_WAITING state
002340: *Sep  8 18:29:43.814: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP
002341: *Sep  8 18:29:43.814: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state
002342: *Sep  8 18:29:43.814: //24/006E38C40300/H323/delay_h245_transport_address: CCM-ITS compatibility delay transport address for callid[18]
002343: *Sep  8 18:29:43.814: //24/006E38C40300/H323/delay_h245_transport_address: Ephone: delay transport address for callid[18]
002344: *Sep  8 18:29:43.814: //24/006E38C40300/H323/generic_send_callproc: ====== PI = 0
002345: *Sep  8 18:29:43.814: 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
        }
      }
    }



002346: *Sep  8 18:29:43.814: H225 NONSTD OUTGOING ENCODE BUFFER::= 80A1001127F8000000000000000000000000000000
002347: *Sep  8 18:29:43.814: 
002348: *Sep  8 18:29:43.814: 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
              }
            }
            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
          }
        }
      }
    }



002349: *Sep  8 18:29:43.822: H225.0 OUTGOING ENCODE BUFFER::= 2180060008914A00042800B500001240023C0504010020602C0501000443001100006E38C43570518C030003010E32CA1F0180018010A001001C0140B50000121580A1001127F8000000000000000000000000000000
002350: *Sep  8 18:29:43.822: 
002351: *Sep  8 18:29:43.830: //24/006E38C40300/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
002352: *Sep  8 18:29:43.830: //24/006E38C40300/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
002353: *Sep  8 18:29:43.830: //25/006E38C40300/CCAPI/cc_api_get_called_ccm_detected:
   CallInfo(ccm detected=0)
002354: *Sep  8 18:29:43.834: //25/006E38C40300/CCAPI/ccCallFeature:
   Feature Type=25, Call Id=25
002355: *Sep  8 18:29:43.834: //24/006E38C40300/CCAPI/ccCallNotify:
   Data Bitmask=0x7, Call Id=24
002356: *Sep  8 18:29:43.834: //25/006E38C40300/CCAPI/cc_api_get_called_ccm_detected:
   CallInfo(ccm detected=0)
002357: *Sep  8 18:29:43.834: //-1/xxxxxxxxxxxx/H323/cch323_post_call_service_msg: callID=24, notify data mask=0x00000007
002358: *Sep  8 18:29:43.834: //24/006E38C40300/CCAPI/cc_api_get_delay_xport:
   CallInfo(delay xport=FALSE)
002359: *Sep  8 18:29:43.834: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_CALLPROC
002360: *Sep  8 18:29:43.834: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_ALERT state
002361: *Sep  8 18:29:43.834: //24/006E38C40300/H323/delay_h245_transport_address: CCM-ITS compatibility delay transport address for callid[18]
002362: *Sep  8 18:29:43.834: //24/006E38C40300/H323/delay_h245_transport_address: Ephone: delay transport address for callid[18]
002363: *Sep  8 18:29:43.834: //24/006E38C40300/H323/generic_send_alert: ====== PI = 0
002364: *Sep  8 18:29:43.834: //24/006E38C40300/H323/generic_send_alert: get ALERT displayInfo 
002365: *Sep  8 18:29:43.834: H225.0 OUTGOING 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
      }
    }



002366: *Sep  8 18:29:43.842: H225.0 OUTGOING ENCODE BUFFER::= 2380060008914A00042800B500001240023C0504010020602C05010006C3001100006E38C43570518C030003010E32CA1F0180018010800100
002367: *Sep  8 18:29:43.842: 
002368: *Sep  8 18:29:43.842: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_NOTIFY while at state H225_ALERT
002369: *Sep  8 18:29:43.842: //24/006E38C40300/H323/send_notify_msg: Notify data found, mask=0x00000007
002370: *Sep  8 18:29:43.842: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Display Info IE = 
002371: *Sep  8 18:29:43.842: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Notification Indicator IE = 113
002372: *Sep  8 18:29:43.842: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Connected Number as IE
002373: *Sep  8 18:29:43.842: //24/006E38C40300/H323/send_notify_msg: [cnum]/[oct]/[oct3a] = [4085252000]/[0x00]/[0x00]
002374: *Sep  8 18:29:43.842: H225.0 OUTGOING PDU ::=

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



002375: *Sep  8 18:29:43.842: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A000400006E38C43570518C030003010E32CA1F10800100
002376: *Sep  8 18:29:43.842: 
002377: *Sep  8 18:29:49.106: //25/006E38C40300/CCAPI/cc_api_call_connected:
   Interface=0x4792F4A0, Data Bitmask=0x1, Progress Indication=NULL(0),
   Connection Handle=0
002378: *Sep  8 18:29:49.106: //25/006E38C40300/CCAPI/cc_api_call_connected:
   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
002379: *Sep  8 18:29:49.110: //24/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
   (confID=0x45DDEDF4, callID1=0x18, callID2=0x19, tag=0x0)
002380: *Sep  8 18:29:49.110: //24/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
   (confID=0x45DDEDF4, callID1=0x18, gcid=0-0-0-0, tag=0x0)
002381: *Sep  8 18:29:49.110: //25/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
   (confID=0x45DDEDF4, callID2=0x19, gcid=0-0-0-0, tag=0x0)
002382: *Sep  8 18:29:49.110: //24/006E38C40300/CCAPI/ccConferenceCreate:
   Conference Id=0x45DDEDF4, Call Id1=24, Call Id2=25, Tag=0x0
002383: *Sep  8 18:29:49.110: //24/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
   
002384: *Sep  8 18:29:49.110: cc_api_get_xcode_stream : 4510
002385: *Sep  8 18:29:49.110: //24/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
   Conference Id=0x7, Source Interface=0x472E59CC, Source Call Id=24,
   Destination Call Id=25, Disposition=0x0, Tag=0x0
002386: *Sep  8 18:29:49.110: //25/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
   Conference Id=0x7, Source Interface=0x4792F4A0, Source Call Id=25,
   Destination Call Id=24, Disposition=0x0, Tag=0xFFFFFFFF
002387: *Sep  8 18:29:49.110: //24/006E38C40300/CCAPI/cc_generic_bridge_done:
   Conference Id=0x7, Source Interface=0x4792F4A0, Source Call Id=25,
   Destination Call Id=24, Disposition=0x0, Tag=0xFFFFFFFF
002388: *Sep  8 18:29:49.110: //24/006E38C40300/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x7, Destination Call Id=25)
002389: *Sep  8 18:29:49.110: //25/006E38C40300/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x7, Destination Call Id=24)
002390: *Sep  8 18:29:49.114: //25/006E38C40300/CCAPI/cc_api_caps_ind:
   Destination Interface=0x472E59CC, Destination Call Id=24, Source Call Id=25,
   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,
   Modem=0x0, Codec Bytes=20, Signal Type=3)
002391: *Sep  8 18:29:49.114: //25/006E38C40300/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=1000(ms), Fax Nom=300(ms))
002392: *Sep  8 18:29:49.114: //24/006E38C40300/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x7, Call Id1=24, Call Id2=25
002393: *Sep  8 18:29:49.118: //24/006E38C40300/CCAPI/ccCallConnect:
   Progress Indication=NULL(0), Data Bitmask=0x1
002394: *Sep  8 18:29:49.118: //25/006E38C40300/CCAPI/cc_api_get_called_ccm_detected:
   CallInfo(ccm detected=0)
002395: *Sep  8 18:29:49.118: //24/006E38C40300/CCAPI/ccCallConnect:
   Call Entry(Connected=TRUE, Responsed=TRUE)
002396: *Sep  8 18:29:49.118: //25/006E38C40300/CCAPI/ccCallFeature:
   Feature Type=25, Call Id=25
002397: *Sep  8 18:29:49.122: //24/006E38C40300/CCAPI/ccCallNotify:
   Data Bitmask=0x7, Call Id=24
002398: *Sep  8 18:29:49.122: //25/006E38C40300/CCAPI/cc_api_get_called_ccm_detected:
   CallInfo(ccm detected=0)
002399: *Sep  8 18:29:49.122: //-1/xxxxxxxxxxxx/H323/cch323_post_call_service_msg: callID=24, notify data mask=0x00000007
002400: *Sep  8 18:29:49.122: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_SETUP_CFM while at state H225_ALERT
002401: *Sep  8 18:29:49.122: //24/006E38C40300/H323/generic_send_setup_cfm: ====== PI = 0
002402: *Sep  8 18:29:49.122: //24/006E38C40300/H323/generic_send_setup_cfm: Sending CONNECT Display Info IE = 
002403: *Sep  8 18:29:49.122: //24/006E38C40300/H323/copy_token_from_ccb: copy_token_from_ccb: Entered
002404: *Sep  8 18:29:49.122: H225.0 OUTGOING 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
      }
    }



002405: *Sep  8 18:29:49.130: H225.0 OUTGOING ENCODE BUFFER::= 22C0060008914A0004000E017D7D2C602800B500001240023C0504010020602C05010000006E38C43570518C030003010E32CA1F1D0C001100006E38C43570518C030003010E32CA1F0180018010800100
002406: *Sep  8 18:29:49.130: 
002407: *Sep  8 18:29:49.130: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_ALERT state to H225_WAIT_FOR_H245 state
002408: *Sep  8 18:29:49.130: H225 NONSTD OUTGOING PDU ::=

value IRRperCallnonStandardInfo ::= 
    {
      startTime 1220898589
    }



002409: *Sep  8 18:29:49.130: H225 NONSTD OUTGOING ENCODE BUFFER::= 7048C56F1D
002410: *Sep  8 18:29:49.130: 
002411: *Sep  8 18:29:49.130: RAS OUTGOING PDU ::=

value RasMessage ::= infoRequestResponse : 
    {
      requestSeqNum 1957
      endpointType 
      {
        vendor 
        {
          vendor 
          {
            t35CountryCode 181
            t35Extension 0
            manufacturerCode 18
          }
        }
        gateway 
        {
          protocol 
          {
            voice : 
            {
              supportedPrefixes 
              {

                {
                  prefix dialedDigits : "3#"
                }
              }
            },            h323 : 
            {
              supportedPrefixes 
              {
              }
            }
          }
        }
        mc FALSE
        undefinedNode FALSE
      }
      endpointIdentifier {"860100E800000002"}
      rasAddress ipAddress : 
      {
        ip '0E017D7D'H
        port 56689
      }
      callSignalAddress 
      {
        ipAddress : 
        {
          ip '0E017D7D'H
          port 1720
        }
      }
      endpointAlias 
      {
        h323-ID : {"CME-1"}
      }
      perCallInfo 
      {

        {
          nonStandardData 
          {
            nonStandardIdentifier h221NonStandard : 
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '7048C56F1D'H
          }
          callReferenceValue 8
          conferenceID '006E38C43570518C030003010E32CA1F'H
          originator FALSE
          h245 
          {
          }
          callSignaling 
          {
          }
          callType pointToPoint : NULL
          bandWidth 7680
          callModel direct : NULL
          callIdentifier 
          {
            guid '006E38C43570518C030003010E32CA1F'H
          }
          substituteConfIDs 
          {
          }
          usageInformation 
          {
            nonStandardUsageFields 
            {
            }
            connectTime 1220898589
          }
        }
      }
      needResponse FALSE
      unsolicited TRUE
    }



002412: *Sep  8 18:29:49.142: RAS OUTGOING ENCODE BUFFER::= 5AC007A42800B500001240023C0504010020602C05010007800038003600300031003000300045003800300030003000300030003000300032000E017D7DDD7101000E017D7D06B80140040043004D0045002D003101E100B5000012057048C56F1D0008006E38C43570518C030003010E32CA1F00101E0003C9001100006E38C43570518C030003010E32CA1F0100072000C048C56F1C0E2401000180
002413: *Sep  8 18:29:49.142: h323chan_dgram_send:Sent UDP msg. Bytes sent: 157 to 14.1.123.95:1719 fd=2

002414: *Sep  8 18:29:49.142: RASLib::GW_RASSendIRR: IRR (seq# 1957) sent to 14.1.123.95
002415: *Sep  8 18:29:49.142: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_NOTIFY while at state H225_WAIT_FOR_H245
002416: *Sep  8 18:29:49.142: //24/006E38C40300/H323/send_notify_msg: Notify data found, mask=0x00000007
002417: *Sep  8 18:29:49.142: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Display Info IE = 
002418: *Sep  8 18:29:49.142: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Notification Indicator IE = 113
002419: *Sep  8 18:29:49.142: //24/006E38C40300/H323/send_notify_msg: Sending NOTIFY Connected Number as IE
002420: *Sep  8 18:29:49.142: //24/006E38C40300/H323/send_notify_msg: [cnum]/[oct]/[oct3a] = [4085252000]/[0x00]/[0x00]
002421: *Sep  8 18:29:49.142: H225.0 OUTGOING PDU ::=

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



002422: *Sep  8 18:29:49.146: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A000400006E38C43570518C030003010E32CA1F10800100
002423: *Sep  8 18:29:49.146: 
002424: *Sep  8 18:29:49.898: h323chan_chn_process_read_socket
002425: *Sep  8 18:29:49.898: h323chan_chn_process_read_socket: fd=4 of type LISTENING has data
002426: *Sep  8 18:29:49.898: //24/006E38C40300/H323/cch323_h245_channel_established_ind: Using fd=5 to send msgs
002427: *Sep  8 18:29:49.898: //24/006E38C40300/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_ESTABLISHED_EVENT
002428: *Sep  8 18:29:49.898: //24/006E38C40300/H323/cch323_h245_connection_sm: H245_LISTEN: Received event H245_ESTABLISHED_EVENT while at H245_WAITING state
002429: *Sep  8 18:29:49.898: //24/006E38C40300/H323/cch323_h245_set_new_state: Changing from H245_WAITING state to H245_CONNECTED state
002430: *Sep  8 18:29:49.898: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_IDLE
002431: *Sep  8 18:29:49.898: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_AWAIT_CAP_MSD_RESP state
002432: *Sep  8 18:29:49.898: //24/006E38C40300/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_REQ while at state IDLE
002433: *Sep  8 18:29:49.902: 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
            }
          }
        }
      }
    }



002434: *Sep  8 18:29:49.914: H245 MSC OUTGOING ENCODE BUFFER::= 027001060008817500078013800014000100000100000100000CC001000100068000218A061404302D31368000184810B50000120C52747044746D6652656C6179000080001E83015080001D83014080001A83011080000220C01380002609D800083A97007040010000800103000002000026030021001D001A001800001E
002435: *Sep  8 18:29:49.918: 
002436: *Sep  8 18:29:49.918: //24/006E38C40300/H323/h245_cap_out_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
002437: *Sep  8 18:29:49.918: //24/006E38C40300/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MSD while at state H245_MS_NONE
002438: *Sep  8 18:29:49.918: H245 MSC OUTGOING PDU ::=

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



002439: *Sep  8 18:29:49.918: H245 MSC OUTGOING ENCODE BUFFER::= 01003C40261F
002440: *Sep  8 18:29:49.918: 
002441: *Sep  8 18:29:49.918: //24/006E38C40300/H323/cch323_run_h245_ms_sm: Sent MSD Request
002442: *Sep  8 18:29:49.918: //24/006E38C40300/H323/h245_ms_set_new_state: Changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state
002443: *Sep  8 18:29:49.966: h323chan_chn_process_read_socket
002444: *Sep  8 18:29:49.966: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002445: *Sep  8 18:29:49.966:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002446: *Sep  8 18:29:49.966: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 183
002447: *Sep  8 18:29:49.966: H245 MSC INCOMING ENCODE BUFFER::= 0270010600088175000A801380003C000100000100000100000CC0010001000D80000021403B80000120C02780000220402780000321803B80000421C03B800005240001058000062408010580000722800580000822C00580000919D800080EFF007040010080000A1C001560000700088171000001400F000202914002A2004E80000B85014080000C85011080002B8501500080000308000000010002000300040005000600070008010009000A01000B000C00002B
002448: *Sep  8 18:29:49.966: 
002449: *Sep  8 18:29:49.970: 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
            }
          }
        }
      }
    }



002450: *Sep  8 18:29:49.986: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002451: *Sep  8 18:29:49.986: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002452: *Sep  8 18:29:49.986: //24/006E38C40300/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_IND while at state IDLE
002453: *Sep  8 18:29:49.986: //24/006E38C40300/H323/h245_cap_in_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
002454: *Sep  8 18:29:49.990: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
002455: *Sep  8 18:29:49.990: //24/006E38C40300/H323/h245_handle_cap_ind: TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
002456: *Sep  8 18:29:49.990: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=163, Call Id=24
002457: *Sep  8 18:29:49.990: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002458: *Sep  8 18:29:49.990: //24/006E38C40300/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
002459: *Sep  8 18:29:49.990: H245 MSC OUTGOING PDU ::=

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



002460: *Sep  8 18:29:49.990: H245 MSC OUTGOING ENCODE BUFFER::= 218001
002461: *Sep  8 18:29:49.994: 
002462: *Sep  8 18:29:49.994: //24/006E38C40300/H323/h245_cap_in_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
002463: *Sep  8 18:29:50.278: h323chan_chn_process_read_socket
002464: *Sep  8 18:29:50.278: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002465: *Sep  8 18:29:50.278:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002466: *Sep  8 18:29:50.278: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 3
002467: *Sep  8 18:29:50.278: H245 MSC INCOMING ENCODE BUFFER::= 218001
002468: *Sep  8 18:29:50.278: 
002469: *Sep  8 18:29:50.278: H245 MSC INCOMING PDU ::=

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



002470: *Sep  8 18:29:50.278: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002471: *Sep  8 18:29:50.278: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002472: *Sep  8 18:29:50.278: //24/006E38C40300/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
002473: *Sep  8 18:29:50.278: //24/006E38C40300/H323/h245_cap_out_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
002474: *Sep  8 18:29:50.278: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
002475: *Sep  8 18:29:50.278: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
002476: *Sep  8 18:29:50.290: h323chan_chn_process_read_socket
002477: *Sep  8 18:29:50.290: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002478: *Sep  8 18:29:50.290:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002479: *Sep  8 18:29:50.290: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 2
002480: *Sep  8 18:29:50.290: H245 MSC INCOMING ENCODE BUFFER::= 2080
002481: *Sep  8 18:29:50.290: 
002482: *Sep  8 18:29:50.294: H245 MSC INCOMING PDU ::=

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



002483: *Sep  8 18:29:50.294: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002484: *Sep  8 18:29:50.294: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002485: *Sep  8 18:29:50.294: //24/006E38C40300/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_CFM while at state H245_MS_OUTGOING_WAIT
002486: *Sep  8 18:29:50.294: H245 MSC OUTGOING PDU ::=

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



002487: *Sep  8 18:29:50.294: H245 MSC OUTGOING ENCODE BUFFER::= 20A0
002488: *Sep  8 18:29:50.294: 
002489: *Sep  8 18:29:50.294: //24/006E38C40300/H323/cch323_run_h245_ms_sm: MS_Determine_indication to Appl: Sent MSD ACK! MS determine status=master
002490: *Sep  8 18:29:50.294: //24/006E38C40300/H323/h245_ms_set_new_state: Changing from H245_MS_OUTGOING_WAIT state to H245_MS_NONE state
002491: *Sep  8 18:29:50.294: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
002492: *Sep  8 18:29:50.294: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
002493: *Sep  8 18:29:50.294: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_START_OLC while at state IWF_OLC_IDLE
002494: *Sep  8 18:29:50.294: //24/006E38C40300/H323/h245_olc_out_sm: Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
002495: *Sep  8 18:29:50.294: //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
002496: *Sep  8 18:29:50.294: H245 MSC OUTGOING 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
        }
      }
    }



002497: *Sep  8 18:29:50.298: H245 MSC OUTGOING ENCODE BUFFER::= 030000000C6013800B050001000E017D7D420900
002498: *Sep  8 18:29:50.298: 
002499: *Sep  8 18:29:50.298: //24/006E38C40300/H323/cch323_open_logical_channel: OLC using g711ulaw
002500: *Sep  8 18:29:50.298: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_EST_CFM state
002501: *Sep  8 18:29:50.418: h323chan_chn_process_read_socket
002502: *Sep  8 18:29:50.418: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002503: *Sep  8 18:29:50.418:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002504: *Sep  8 18:29:50.418: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 20
002505: *Sep  8 18:29:50.418: H245 MSC INCOMING ENCODE BUFFER::= 030000000C6013800B050001000E017B5F418500
002506: *Sep  8 18:29:50.418: 
002507: *Sep  8 18:29:50.418: H245 MSC INCOMING 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
        }
      }
    }



002508: *Sep  8 18:29:50.422: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002509: *Sep  8 18:29:50.422: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002510: *Sep  8 18:29:50.422: //24/006E38C40300/H323/h245_olc_in_sm: Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
002511: *Sep  8 18:29:50.422: //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
002512: *Sep  8 18:29:50.422: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_EST_IND while at state IWF_OLC_AWAIT_EST_CFM
002513: *Sep  8 18:29:50.422: //24/006E38C40300/CCAPI/cc_api_caps_ind:
   Destination Interface=0x4792F4A0, Destination Call Id=25, Source Call Id=24,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,
   Modem=0x0, Codec Bytes=160, Signal Type=2)
002514: *Sep  8 18:29:50.422: //24/006E38C40300/CCAPI/cc_api_caps_ind:
   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
   Playout Max=1000(ms), Fax Nom=300(ms))
002515: *Sep  8 18:29:50.422: //24/006E38C40300/CCAPI/cc_api_caps_ack:
   Destination Interface=0x4792F4A0, Destination Call Id=25, Source Call Id=24,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=2224)
002516: *Sep  8 18:29:50.422: //24/006E38C40300/H323/h245_olc_in_sm: Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
002517: *Sep  8 18:29:50.422: H245 MSC OUTGOING 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
      }
    }



002518: *Sep  8 18:29:50.426: H245 MSC OUTGOING ENCODE BUFFER::= 22C000000680134C000E017D7D4208000E017D7D420903000100
002519: *Sep  8 18:29:50.426: 
002520: *Sep  8 18:29:50.426: //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
002521: *Sep  8 18:29:50.426: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_AWAIT_EST_CFM state to IWF_OLC_INDONE_AWAIT_EST_CFM state
002522: *Sep  8 18:29:50.426: //25/006E38C40300/CCAPI/cc_api_caps_ack:
   Destination Interface=0x472E59CC, Destination Call Id=24, Source Call Id=25,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=2224)
002523: *Sep  8 18:29:50.430: //25/006E38C40300/CCAPI/cc_api_voice_mode_event:
   Call Id=25
002524: *Sep  8 18:29:50.430: //25/006E38C40300/CCAPI/cc_api_voice_mode_event:
   Call Entry(Context=0x4895E034)
002525: *Sep  8 18:29:50.430: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event=153, Call Id=25
002526: *Sep  8 18:29:50.430: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002527: *Sep  8 18:29:50.434: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_START_OLC while at state IWF_OLC_IDLE
002528: *Sep  8 18:29:50.434: //24/006E38C40300/H323/h245_olc_out_sm: Received H245_EV_OLC_EST_REQ while at state H245_OLC_OUT_STATE_IDLE
002529: *Sep  8 18:29:50.434: //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
002530: *Sep  8 18:29:50.434: 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 '0E017D7D'H
            tsapIdentifier 17225
          }
        }
      }
    }



002531: *Sep  8 18:29:50.438: H245 MSC OUTGOING ENCODE BUFFER::= 0300000108E400800EFF0070400100800A040002000E017D7D4349
002532: *Sep  8 18:29:50.438: 
002533: *Sep  8 18:29:50.438: //24/006E38C40300/H323/cch323_open_logical_channel: OLC using g711ulaw
002534: *Sep  8 18:29:50.438: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_AWAIT_EST_CFM state
002535: *Sep  8 18:29:50.458: h323chan_chn_process_read_socket
002536: *Sep  8 18:29:50.458: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002537: *Sep  8 18:29:50.458:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002538: *Sep  8 18:29:50.458: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 28
002539: *Sep  8 18:29:50.458: H245 MSC INCOMING ENCODE BUFFER::= 0300000108E400800C7F0070400100800B050002000E017B5F43BB00
002540: *Sep  8 18:29:50.458: 
002541: *Sep  8 18:29:50.458: 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 '0E017B5F'H
            tsapIdentifier 17339
          }
          silenceSuppression FALSE
        }
      }
    }



002542: *Sep  8 18:29:50.462: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002543: *Sep  8 18:29:50.462: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002544: *Sep  8 18:29:50.462: //24/006E38C40300/H323/h245_olc_in_sm: Received H245_EV_OLC_IND while at state H245_OLC_IN_STATE_IDLE
002545: *Sep  8 18:29:50.462: //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
002546: *Sep  8 18:29:50.462: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_EST_IND while at state IWF_OLC_AWAIT_EST_CFM
002547: *Sep  8 18:29:50.462: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=153, Call Id=24
002548: *Sep  8 18:29:50.462: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002549: *Sep  8 18:29:50.462: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_AWAIT_EST_CFM state to IWF_OLC_AWAIT_EST_CFM_PEER_ACK state
002550: *Sep  8 18:29:50.462: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event=154, Call Id=25
002551: *Sep  8 18:29:50.466: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002552: *Sep  8 18:29:50.466: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_PEER_CHN_ACK while at state IWF_OLC_AWAIT_EST_CFM_PEER_ACK
002553: *Sep  8 18:29:50.466: //24/006E38C40300/H323/h245_olc_in_sm: Received H245_EV_OLC_EST_RESP while at state H245_OLC_IN_STATE_AWAIT_EST
002554: *Sep  8 18:29:50.466: H245 MSC OUTGOING 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
      }
    }



002555: *Sep  8 18:29:50.470: H245 MSC OUTGOING ENCODE BUFFER::= 22C000010680134C000E017D7D4348000E017D7D434903000100
002556: *Sep  8 18:29:50.470: 
002557: *Sep  8 18:29:50.470: //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
002558: *Sep  8 18:29:50.470: //24/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
002559: *Sep  8 18:29:50.606: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event=164, Call Id=25
002560: *Sep  8 18:29:50.610: //25/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002561: *Sep  8 18:29:50.610: H245 MSC OUTGOING PDU ::=

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



002562: *Sep  8 18:29:50.610: H245 MSC OUTGOING ENCODE BUFFER::= 4C000128
002563: *Sep  8 18:29:50.610: 
002564: *Sep  8 18:29:50.610: h323chan_chn_process_read_socket
002565: *Sep  8 18:29:50.610: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002566: *Sep  8 18:29:50.610:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002567: *Sep  8 18:29:50.614: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 23
002568: *Sep  8 18:29:50.614: H245 MSC INCOMING ENCODE BUFFER::= 22C000000480101C00000E017B5F4184000E017B5F4185
002569: *Sep  8 18:29:50.614: 
002570: *Sep  8 18:29:50.614: H245 MSC INCOMING 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
        }
      }
    }



002571: *Sep  8 18:29:50.614: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002572: *Sep  8 18:29:50.614: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002573: *Sep  8 18:29:50.614: //24/006E38C40300/H323/h245_olc_out_sm: Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
002574: *Sep  8 18:29:50.618: //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
002575: *Sep  8 18:29:50.618: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_EST_CFM while at state IWF_OLC_INDONE_AWAIT_EST_CFM
002576: *Sep  8 18:29:50.618: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_INDONE_AWAIT_EST_CFM state to IWF_OLC_DONE state
002577: *Sep  8 18:29:50.618: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_DONE while at state IWF_ACTIVE
002578: *Sep  8 18:29:50.618: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_WAIT_FOR_H245
002579: *Sep  8 18:29:50.618: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_H245 state to H225_ACTIVE state
002580: *Sep  8 18:29:51.034: h323chan_chn_process_read_socket
002581: *Sep  8 18:29:51.034: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002582: *Sep  8 18:29:51.034:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002583: *Sep  8 18:29:51.034: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 23
002584: *Sep  8 18:29:51.034: H245 MSC INCOMING ENCODE BUFFER::= 22C000010480101C02000E017B5F43BA000E017B5F43BB
002585: *Sep  8 18:29:51.034: 
002586: *Sep  8 18:29:51.034: H245 MSC INCOMING 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
        }
      }
    }



002587: *Sep  8 18:29:51.038: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002588: *Sep  8 18:29:51.038: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002589: *Sep  8 18:29:51.038: //24/006E38C40300/H323/h245_olc_out_sm: Received H245_EV_OLC_CFM while at state H245_OLC_OUT_STATE_AWAIT_EST
002590: *Sep  8 18:29:51.038: //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
002591: *Sep  8 18:29:51.038: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_EST_CFM while at state IWF_OLC_INDONE_AWAIT_EST_CFM
002592: *Sep  8 18:29:51.038: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=154, Call Id=24
002593: *Sep  8 18:29:51.038: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002594: *Sep  8 18:29:51.038: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_OLC_INDONE_AWAIT_EST_CFM state to IWF_OLC_DONE state
002595: *Sep  8 18:29:51.038: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_OLC_DONE while at state IWF_ACTIVE
002596: *Sep  8 18:29:51.038: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_ACTIVE
002597: *Sep  8 18:29:51.046: h323chan_chn_process_read_socket
002598: *Sep  8 18:29:51.046: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002599: *Sep  8 18:29:51.046:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002600: *Sep  8 18:29:51.046: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 7
002601: *Sep  8 18:29:51.050: H245 MSC INCOMING ENCODE BUFFER::= 48000001200C80
002602: *Sep  8 18:29:51.050: 
002603: *Sep  8 18:29:51.050: H245 MSC INCOMING PDU ::=

value MultimediaSystemControlMessage ::= command : flowControlCommand : 
    {
      scope logicalChannelNumber : 2
      restriction maximumBitRate : 3200
    }



002604: *Sep  8 18:29:51.050: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002605: *Sep  8 18:29:51.050: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002606: *Sep  8 18:29:51.050: //24/006E38C40300/H323/cch323_h245_flow_control_cmd_ind: current state: UNRESTRICTED
002607: *Sep  8 18:29:51.050: //24/006E38C40300/H323/cch323_h245_flow_control_cmd_ind: MaxBitRate = 320.0 KHz, Codec Rate = 64.0 KHz No State change
002608: *Sep  8 18:29:51.050: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=164, Call Id=24
002609: *Sep  8 18:29:51.050: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002610: *Sep  8 18:29:51.186: h323chan_chn_process_read_socket
002611: *Sep  8 18:29:51.186: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data
002612: *Sep  8 18:29:51.186:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=5

002613: *Sep  8 18:29:51.186: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 4
002614: *Sep  8 18:29:51.186: H245 MSC INCOMING ENCODE BUFFER::= 4C000128
002615: *Sep  8 18:29:51.186: 
002616: *Sep  8 18:29:51.190: H245 MSC INCOMING PDU ::=

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



002617: *Sep  8 18:29:51.190: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
002618: *Sep  8 18:29:51.190: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
002619: *Sep  8 18:29:51.190: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event=164, Call Id=24
002620: *Sep  8 18:29:51.190: //24/006E38C40300/CCAPI/cc_api_event_indication:
   Event Is Sent To Conferenced SPI(s) Directly
002621: *Sep  8 18:30:01.678: RAS OUTGOING PDU ::=

value RasMessage ::= registrationRequest : 
    {
      requestSeqNum 1958
      protocolIdentifier { 0 0 8 2250 0 4 }
      discoveryComplete TRUE
      callSignalAddress 
      {
      }
      rasAddress 
      {
        ipAddress : 
        {
          ip '0E017D7D'H
          port 56689
        }
      }
      terminalType 
      {
        mc FALSE
        undefinedNode FALSE
      }
      gatekeeperIdentifier {"CME"}
      endpointVendor 
      {
        vendor 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
      }
      timeToLive 60
      keepAlive TRUE
      endpointIdentifier {"860100E800000002"}
      willSupplyUUIEs FALSE
      maintainConnection TRUE
    }



002622: *Sep  8 18:30:01.678: RAS OUTGOING ENCODE BUFFER::= 0E4007A5060008914A0004800001000E017D7DDD7100020043004D004500B5000012288F000002003B0180211E003800360030003100300030004500380030003000300030003000300030003201000180
002623: *Sep  8 18:30:01.682: h323chan_dgram_send:Sent UDP msg. Bytes sent: 81 to 14.1.123.95:1719 fd=2

002624: *Sep  8 18:30:01.682: RASLib::GW_RASSendRRQ: RRQ (seq# 1958) sent to 14.1.123.95
002625: *Sep  8 18:30:01.702: h323chan_chn_process_read_socket
002626: *Sep  8 18:30:01.702: h323chan_chn_process_read_socket: fd=2 of type CONNECTED has data
002627: *Sep  8 18:30:01.702:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=2

002628: *Sep  8 18:30:01.706: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] on fd=2

002629: *Sep  8 18:30:01.706: RAS INCOMING ENCODE BUFFER::= 124007A5060008914A000400040043004D00451E0038003600300031003000300045003800300030003000300030003000300032208A0002003B01000180
002630: *Sep  8 18:30:01.706: 
002631: *Sep  8 18:30:01.706: RAS INCOMING PDU ::=

value RasMessage ::= registrationConfirm : 
    {
      requestSeqNum 1958
      protocolIdentifier { 0 0 8 2250 0 4 }
      callSignalAddress 
      {
      }
      gatekeeperIdentifier {"CME"}
      endpointIdentifier {"860100E800000002"}
      timeToLive 60
      willRespondToIRR FALSE
      maintainConnection TRUE
    }



002632: *Sep  8 18:30:01.706: RCF (seq# 1958) rcvd
002633: *Sep  8 18:30:46.706: RAS OUTGOING PDU ::=

value RasMessage ::= registrationRequest : 
    {
      requestSeqNum 1959
      protocolIdentifier { 0 0 8 2250 0 4 }
      discoveryComplete TRUE
      callSignalAddress 
      {
      }
      rasAddress 
      {
        ipAddress : 
        {
          ip '0E017D7D'H
          port 56689
        }
      }
      terminalType 
      {
        mc FALSE
        undefinedNode FALSE
      }
      gatekeeperIdentifier {"CME"}
      endpointVendor 
      {
        vendor 
        {
          t35CountryCode 181
          t35Extension 0
          manufacturerCode 18
        }
      }
      timeToLive 60
      keepAlive TRUE
      endpointIdentifier {"860100E800000002"}
      willSupplyUUIEs FALSE
      maintainConnection TRUE
    }



002634: *Sep  8 18:30:46.706: RAS OUTGOING ENCODE BUFFER::= 0E4007A6060008914A0004800001000E017D7DDD7100020043004D004500B5000012288F000002003B0180211E003800360030003100300030004500380030003000300030003000300030003201000180
002635: *Sep  8 18:30:46.706: h323chan_dgram_send:Sent UDP msg. Bytes sent: 81 to 14.1.123.95:1719 fd=2

002636: *Sep  8 18:30:46.710: RASLib::GW_RASSendRRQ: RRQ (seq# 1959) sent to 14.1.123.95
002637: *Sep  8 18:30:46.730: h323chan_chn_process_read_socket
002638: *Sep  8 18:30:46.730: h323chan_chn_process_read_socket: fd=2 of type CONNECTED has data
002639: *Sep  8 18:30:46.730:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=2

002640: *Sep  8 18:30:46.730: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] on fd=2

002641: *Sep  8 18:30:46.730: RAS INCOMING ENCODE BUFFER::= 124007A6060008914A000400040043004D00451E0038003600300031003000300045003800300030003000300030003000300032208A0002003B01000180
002642: *Sep  8 18:30:46.730: 
002643: *Sep  8 18:30:46.734: RAS INCOMING PDU ::=

value RasMessage ::= registrationConfirm : 
    {
      requestSeqNum 1959
      protocolIdentifier { 0 0 8 2250 0 4 }
      callSignalAddress 
      {
      }
      gatekeeperIdentifier {"CME"}
      endpointIdentifier {"860100E800000002"}
      timeToLive 60
      willRespondToIRR FALSE
      maintainConnection TRUE
    }



002644: *Sep  8 18:30:46.734: RCF (seq# 1959) rcvd
002645: *Sep  8 18:30:47.630: //25/006E38C40300/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x4792F4A0, Call Id=25
002646: *Sep  8 18:30:47.630: //25/006E38C40300/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
002647: *Sep  8 18:30:47.634: //24/006E38C40300/CCAPI/ccConferenceDestroy:
   Conference Id=0x7, Tag=0x0
002648: *Sep  8 18:30:47.634: //24/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x7, Source Interface=0x472E59CC, Source Call Id=24,
   Destination Call Id=25, Disposition=0x0, Tag=0x0
002649: *Sep  8 18:30:47.634: //25/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x7, Source Interface=0x4792F4A0, Source Call Id=25,
   Destination Call Id=24, Disposition=0x0, Tag=0x0
002650: *Sep  8 18:30:47.634: //24/006E38C40300/CCAPI/cc_generic_bridge_done:
   Conference Id=0x7, Source Interface=0x4792F4A0, Source Call Id=25,
   Destination Call Id=24, Disposition=0x0, Tag=0x0
002651: *Sep  8 18:30:47.638: //24/006E38C40300/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
002652: *Sep  8 18:30:47.638: //24/006E38C40300/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
002653: *Sep  8 18:30:47.638: //24/006E38C40300/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
002654: *Sep  8 18:30:47.638: //25/006E38C40300/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
002655: *Sep  8 18:30:47.638: //25/006E38C40300/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
002656: *Sep  8 18:30:47.638: //25/006E38C40300/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
002657: *Sep  8 18:30:47.642: //25/006E38C40300/CCAPI/cc_api_call_feature:
   Feature Type=6, Interface=0x4792F4A0, Call Id=25
002658: *Sep  8 18:30:47.646: //24/006E38C40300/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
002659: *Sep  8 18:30:47.646: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACTIVE
002660: *Sep  8 18:30:47.646: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_ACTIVE state to H225_WAIT_FOR_DRQ state
002661: *Sep  8 18:30:47.646: //24/006E38C40300/H323/cch323_h225_send_release: Cause = 16; Location = 0
002662: *Sep  8 18:30:47.646: //24/006E38C40300/H323/cch323_h225_send_release: h225TerminateRequest: src address = 234978685; dest address = 14.1.123.95
002663: *Sep  8 18:30:47.646: 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
      }
    }



002664: *Sep  8 18:30:47.646: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A000411001100006E38C43570518C030003010E32CA1F10800100
002665: *Sep  8 18:30:47.646: 
002666: *Sep  8 18:30:47.650: H225 NONSTD OUTGOING PDU ::=

value RasnonStdUsageInformation ::= 
    {
      rasMessageSpecificData drqRasnonStdUsageData : 
      {
        callReleaseSource calledPartyInPstn : NULL
        callModes 
        {

          {
            infoType voice : NULL
          }
        }
      }
    }



002667: *Sep  8 18:30:47.650: H225 NONSTD OUTGOING ENCODE BUFFER::= 584020020100
002668: *Sep  8 18:30:47.650: 
002669: *Sep  8 18:30:47.650: RAS OUTGOING 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
    }



002670: *Sep  8 18:30:47.654: RAS OUTGOING ENCODE BUFFER::= 3E07A71E0038003600300031003000300045003800300030003000300030003000300032006E38C43570518C030003010E32CA1F00082321181100006E38C43570518C030003010E32CA1F018018300140B500001206584020020100C048C56F1CC048C56F56054408028090
002671: *Sep  8 18:30:47.654: h323chan_dgram_send:Sent UDP msg. Bytes sent: 108 to 14.1.123.95:1719 fd=2

002672: *Sep  8 18:30:47.654: RASLib::GW_RASSendDRQ: DRQ (seq# 1960) sent to 14.1.123.95
002673: *Sep  8 18:30:47.658: //25/006E38C40300/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x4792F4A0, Tag=0x0, Call Id=25,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
002674: *Sep  8 18:30:47.658: //25/006E38C40300/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
002675: *Sep  8 18:30:47.658: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
002676: *Sep  8 18:30:47.662: :cc_free_feature_vsa freeing 489E77D0
002677: *Sep  8 18:30:47.662: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
002678: *Sep  8 18:30:47.662:  vsacount in free is 1
002679: *Sep  8 18:30:47.690: h323chan_chn_process_read_socket
002680: *Sep  8 18:30:47.690: h323chan_chn_process_read_socket: fd=2 of type CONNECTED has data
002681: *Sep  8 18:30:47.694:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=2

002682: *Sep  8 18:30:47.694: h323chan_dgram_recvdata:rcvd from [14.1.123.95:1719] on fd=2

002683: *Sep  8 18:30:47.694: RAS INCOMING ENCODE BUFFER::= 4007A7
002684: *Sep  8 18:30:47.694: 
002685: *Sep  8 18:30:47.694: RAS INCOMING PDU ::=

value RasMessage ::= disengageConfirm : 
    {
      requestSeqNum 1960
    }



002686: *Sep  8 18:30:47.694: DCF (seq# 1960) rcvd
002687: *Sep  8 18:30:47.694: //24/006E38C40300/H323/run_h225_sm: Received event H225_EV_RAS_SUCCESS while at state H225_WAIT_FOR_DRQ
002688: *Sep  8 18:30:47.694: //24/006E38C40300/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_DRQ state to H225_IDLE state
002689: *Sep  8 18:30:47.694: //24/006E38C40300/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_ACTIVE
002690: *Sep  8 18:30:47.694: //24/006E38C40300/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
002691: *Sep  8 18:30:47.694: //24/006E38C40300/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_NONE state
002692: *Sep  8 18:30:47.694: //24/006E38C40300/H323/h245_iwf_set_new_state: changing from IWF_ACTIVE state to IWF_IDLE state
002693: *Sep  8 18:30:47.694: //24/006E38C40300/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x472E59CC, Tag=0x0, Call Id=24,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
002694: *Sep  8 18:30:47.694: //24/006E38C40300/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
002695: *Sep  8 18:30:47.698: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
002696: *Sep  8 18:30:47.698: :cc_free_feature_vsa freeing 489E78B0
002697: *Sep  8 18:30:47.698: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
   
002698: *Sep  8 18:30:47.698:  vsacount in free is 0
002699: *Sep  8 18:30:47.738: h323chan_chn_process_read_socket
002700: *Sep  8 18:30:47.738: h323chan_chn_process_read_socket: fd=3 of type ACCEPTED has data
002701: *Sep  8 18:30:47.738:  h323chan_chn_process_read_socket: h323chan accepted/connected fd=3

002702: *Sep  8 18:30:47.742: //-1/xxxxxxxxxxxx/H323/validate_crv: No CCB for crv: 0x8010