Syslog logging: enabled (0 messages dropped, 82 messages rate-limited, 2542 flushes, 0 overruns) Console logging: disabled Monitor logging: disabled Buffer logging: level debugging, 13558 messages logged Logging Exception size (8192 bytes) Trap logging: level informational, 963639 message lines logged Logging to 194.4.106.12, 963639 message lines logged Logging to 194.4.106.11, 963639 message lines logged Log Buffer (5000000 bytes): 1528769: Jun 5 11:18:27.810: cc_api_call_setup_ind (vdbPtr=0x6364BA90, callInfo={called=22222,called_oct3=0x81,calling=11111,calling_oct3=0x80,calling_oct3a=0x0,calling_xlated=false,subscriber_type_str=RegularLine,fdest=1,peer_tag=28048, prog_ind=0,callingIE_present 1},callID=0x63A9EAE8) 1528770: Jun 5 11:18:27.810: cc_api_call_setup_ind type 13 , prot 0 1528771: Jun 5 11:18:27.810: cc_process_call_setup_ind (event=0x631C43E8) 1528772: Jun 5 11:18:27.810: >>>>CCAPI handed cid 467129 with tag 28048 to app "DEFAULT" 1528773: Jun 5 11:18:27.814: sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(467129), disp(0) 1528774: Jun 5 11:18:27.814: sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(467129), disp(0) 1528775: Jun 5 11:18:27.814: ssaCallSetupInd 1528776: Jun 5 11:18:27.814: ccCallSetContext (callID=0x720B9, context=0x63995394) 1528777: Jun 5 11:18:27.814: ssaCallSetupInd cid(467129), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1 1528778: Jun 5 11:18:27.814: ssaCallSetupInd finalDest cllng(11111), clled(22222) 1528779: Jun 5 11:18:27.814: ssaCallSetupInd cid(467129), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0 1528780: Jun 5 11:18:27.814: ssaSetupPeer cid(467129) peer list: tag(99999) called number (22222) 1528781: Jun 5 11:18:27.814: ssaSetupPeer cid(467129), destPat(22222), matched(0), prefix(), peer(6383DA58), peer->encapType (2) 1528782: Jun 5 11:18:27.814: ccCallProceeding (callID=0x720B9, prog_ind=0x0) 1528783: Jun 5 11:18:27.814: ccCallSetupRequest (Inbound call = 0x720B9, outbound peer =99999, dest=, params=0x6345EC10 mode=0, *callID=0x6345EF80, prog_ind = 0) callingIE_present 1 1528784: Jun 5 11:18:27.814: ccCallSetupRequest numbering_type 0x81 1528785: Jun 5 11:18:27.814: ccCallSetupRequest encapType 2 clid_restrict_disable 1 null_orig_clg 0 clid_transparent 0 callingNumber 11111 1528786: Jun 5 11:18:27.814: dest pattern ....., called 22222, digit_strip 0 1528787: Jun 5 11:18:27.814: callingNumber=11111, calledNumber=22222, redirectNumber= display_info=Main-Circ calling_oct3a=0 1528788: Jun 5 11:18:27.814: accountNumber=, finalDestFlag=1, guid=6c80.510a.ebb8.11e3.9ce0.837e.4c69.79e6 1528789: Jun 5 11:18:27.814: peer_tag=99999 1528790: Jun 5 11:18:27.814: ccIFCallSetupRequestPrivate: (vdbPtr=0x62F22A24, dest=, callParams={called=22222,called_oct3=0x81, calling=11111,calling_oct3=0x80, calling_xlated=false, subscriber_type_str=RegularLine, fdest=1, voice_peer_tag=99999},mode=0x0) vdbPtr type = 1 1528791: Jun 5 11:18:27.814: ccIFCallSetupRequestPrivate: (vdbPtr=0x62F22A24, dest=, callParams={called=22222, called_oct3 0x81, calling=11111,calling_oct3 0x80, calling_xlated=false, fdest=1, voice_peer_tag=99999}, mode=0x0, xltrc=-5)cch323_post_call_setup_request: for callID 720BA 1528792: Jun 5 11:18:27.814: ccSaveDialpeerTag (callID=0x720B9, dialpeer_tag=0x1869F) 1528793: Jun 5 11:18:27.814: ccCallSetContext (callID=0x720BA, context=0x63D0FF68) 1528794: Jun 5 11:18:27.814: ccCallReportDigits (callID=0x720B9, enable=0x0) 1528795: Jun 5 11:18:27.814: cc_api_call_report_digits_done (vdbPtr=0x6364BA90, callID=0x720B9, disp=0) 1528796: Jun 5 11:18:27.814: sess_appl: ev(53=CC_EV_CALL_REPORT_DIGITS_DONE), cid(467129), disp(0) 1528797: Jun 5 11:18:27.814: cid(467129)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE) oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1) 1528798: Jun 5 11:18:27.814: -cid2(467130)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_MAPPING) 1528799: Jun 5 11:18:27.814: ssaReportDigitsDone cid(467129) peer list: (empty) 1528800: Jun 5 11:18:27.814: ssaReportDigitsDone callid=467129 Reporting disabled. 1528801: Jun 5 11:18:27.814: cch323_call_setup: gw_id=1, callID=467130 1528802: Jun 5 11:18:27.814: cch323_get_new_ccb: ccb (0x63A88038) is in use 1528803: Jun 5 11:18:27.814: cch323_call_setup: inserted ccb cch323_get_peer_info:faxrate[21]proto[2]bitmask[10002]t38_inhibit[0]global_fax[0] 1528804: Jun 5 11:18:27.814: Using Voice Class Codec, tag = 100 1528805: Jun 5 11:18:27.814: cch323_get_peer_info: preffered_codec set to G711_ALAW_64K with Bytes = 160 1528806: Jun 5 11:18:27.814: cch323_get_peer_info: peer: 6383DA58, peer->voice_peer_tag: 1869F, ccb: 63A88038 1528807: Jun 5 11:18:27.814: Call_setup Playout Mode: 1,Init 60, Min 40, Max 200 1528808: Jun 5 11:18:27.814: No account/pin number available 1528809: Jun 5 11:18:27.814: cch323_call_setup_normal: for callID 720BA 1528810: Jun 5 11:18:27.814: H.225 SM: process event H225_EVENT_RAS_RESOLVE, for callID 720BA 1528811: Jun 5 11:18:27.814: cch323_run_h225_sm: received event H225_EVENT_RAS_RESOLVE while at state H225_IDLE 1528812: Jun 5 11:18:27.814: timer(0x63A88528) stops 1528813: Jun 5 11:18:27.814: cch323_percall_ras_sm(0x63A88038): received event CCH323_RAS_EVENT_NEWCALL while at CCH323_RAS_STATE_IDLE state cch323_ras_send_arq: called 1528814: Jun 5 11:18:27.814: H225 NONSTD OUTGOING PDU ::= value ARQnonStandardInfo ::= { sourceAlias { } sourceExtAlias { } displayInformationElement "Main-Circ" interfaceSpecificBillingId "ISDN-VOICE" } 1528815: Jun 5 11:18:27.814: H225 NONSTD OUTGOING ENCODE BUFFER::= 80000008600A104D61696E2D436972630B124953444E2D564F494345 1528816: Jun 5 11:18:27.814: 1528817: Jun 5 11:18:27.814: RAS OUTGOING PDU ::= value RasMessage ::= admissionRequest : { requestSeqNum 60331 callType pointToPoint : NULL callModel direct : NULL endpointIdentifier {"644FD85C000000DE"} destinationInfo { e164 : "22222" } srcInfo { e164 : "11111", h323-ID : {"c7206"} } bandWidth 640 callReferenceValue 6325 nonStandardData { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '80000008600A104D61696E2D436972630B124953...'H } conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H activeMC FALSE answerCall FALSE canMapAlias TRUE callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } willSupplyUUIEs FALSE } 1528818: Jun 5 11:18:27.818: RAS OUTGOING ENCODE BUFFER::= 2788EBAA00F000360034003400460044003800350043003000300030003000300030004400450102009B6430020200565CC40700470049002D005500560044002D005240028018B540B50000121C80000008600A104D61696E2D436972630B124953444E2D564F4943456C80510AEBB811E39CE0837E4C6979E604E020018011006C80510AEBB811E39CE1837E4C6979E60100 1528819: Jun 5 11:18:27.818: 1528820: Jun 5 11:18:27.818: timer (0x63A88528)starts - delay (3000) 1528821: Jun 5 11:18:27.818: cch323_percall_ras_sm(0x63A88038): changing to new state CCH323_RAS_STATE_ARQ 1528822: Jun 5 11:18:27.818: H.225 SM: changing from H225_IDLE state to H225_WAIT_FOR_RAS_CONF state for callID 720BA 1528823: Jun 5 11:18:27.818: cch323_ct_main: SOCK 1 Event 0x1 1528824: Jun 5 11:18:27.818: [1]towner_data=0x6394A84C, len=61, msgPtr=0x63C0E548 1528825: Jun 5 11:18:27.818: cch323_gw_process_read_socket: received msg for RAS 1528826: Jun 5 11:18:27.818: RAS INCOMING ENCODE BUFFER::= 2B80EBAA400280000A0A090C06B800EF40B50000121E00020200565CC40700470049002D005500560044002D00520102009B64302800C0000100020000 1528827: Jun 5 11:18:27.822: 1528828: Jun 5 11:18:27.822: RAS INCOMING PDU ::= value RasMessage ::= admissionConfirm : { requestSeqNum 60331 bandWidth 640 callModel direct : NULL destCallSignalAddress ipAddress : { ip '0A0A090C'H port 1720 } irrFrequency 240 nonStandardData { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '00020200565CC40700470049002D005500560044...'H } willRespondToIRR FALSE uuiesRequested { setup FALSE callProceeding FALSE connect FALSE alerting FALSE information FALSE releaseComplete FALSE facility FALSE progress FALSE empty FALSE } } 1528829: Jun 5 11:18:27.822: H225 NONSTD INCOMING ENCODE BUFFER::= 00020200565CC40700470049002D005500560044002D00520102009B6430 1528830: Jun 5 11:18:27.822: 1528831: Jun 5 11:18:27.822: H225 NONSTD INCOMING PDU ::= value ACFnonStandardInfo ::= { srcTerminalAlias { e164 : "11111", h323-ID : {"c7206"} } dstTerminalAlias { e164 : "22222" } } 1528832: Jun 5 11:18:27.822: cch323_ras_handle_recv_msg: received msg of type ACF_CHOSEN cch323_ras_rcv_acf:called 1528833: Jun 5 11:18:27.822: current active call count is 2 1528834: Jun 5 11:18:27.822: timer(0x63A88528) stops 1528835: Jun 5 11:18:27.822: cch323_percall_ras_sm(0x63A88038): received event CCH323_RAS_EVENT_ACF while at CCH323_RAS_STATE_ARQ state 1528836: Jun 5 11:18:27.822: timer (0x63A88548)starts - delay (240000)cch323_ras_acf_notify:called 1528837: Jun 5 11:18:27.822: H.225 SM: process event H225_EVENT_RAS_SUCCESS, for callID 720BA 1528838: Jun 5 11:18:27.822: cch323_run_h225_sm: received event H225_EVENT_RAS_SUCCESS while at state H225_WAIT_FOR_RAS_CONF 1528839: Jun 5 11:18:27.822: timer (0x63A883E0)starts - delay (15000) 1528840: Jun 5 11:18:27.822: H.225 SM: changing from H225_WAIT_FOR_RAS_CONF state to H225_IDLE state for callID 720BA 1528841: Jun 5 11:18:27.822: cch323_percall_ras_sm(0x63A88038): changing to new state CCH323_RAS_STATE_ACTIVE X 1528842: Jun 5 11:18:27.826: cch323_ct_main: SOCK 2 Event 0x1 1528843: Jun 5 11:18:27.826: timer(0x63A883E0) stops 1528844: Jun 5 11:18:27.826: H.225 SM: process event H225_EVENT_SETUP, for callID 720BA 1528845: Jun 5 11:18:27.826: cch323_run_h225_sm: received event H225_EVENT_SETUP while at state H225_IDLE 1528846: Jun 5 11:18:27.826: cch323_h225_check_qos_and_snd_setup: Setup ccb 0x63A88038 callID 0x720BA 1528847: Jun 5 11:18:27.826: H.225 SM: process event H225_EVENT_FAST_SETUP, for callID 720BA 1528848: Jun 5 11:18:27.826: cch323_run_h225_sm: received event H225_EVENT_FAST_SETUP while at state H225_IDLE 1528849: Jun 5 11:18:27.826: cch323_run_h225_sm: Setup ccb 0x63A88038 callID 0x720BA 1528850: Jun 5 11:18:27.826: cch323_h225_send_fastStart_setup: for callID 720BA 1528851: Jun 5 11:18:27.826: Near-end Pref Codecs = G.711 A G.726r32 G.728 G.729 IETFG.723r63 1528852: Jun 5 11:18:27.826: generic_open_logical_channel:codec is g711_alaw_64k 1528853: Jun 5 11:18:27.826: cch323_generic_open_logical_channel: Filling in qosCapability field to 0 1528854: Jun 5 11:18:27.826: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 1 forwardLogicalChannelParameters { dataType audioData : g711Alaw64k : 20 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528855: Jun 5 11:18:27.830: H245 FS OLC OUTGOING ENCODE BUFFER::= 0000000C2013800A040001000A0A0FD1468B 1528856: Jun 5 11:18:27.830: 1528857: Jun 5 11:18:27.830: H245 FS OLC OUTGOING PDU ::= R value OpenLogicalChannel ::= { forwardLogicalChannelNumber 1 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : g711Alaw64k : 20 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528858: Jun 5 11:18:27.830: H245 FS OLC OUTGOING ENCODE BUFFER::= 400000060401004C20138011140001000A0A0FD1468A000A0A0FD1468B 1528859: Jun 5 11:18:27.830: 1528860: Jun 5 11:18:27.830: generic_open_logical_channel:codec is g726r32 1528861: Jun 5 11:18:27.830: cch323_generic_open_logical_channel: Filling in qosCapability field to 0 1528862: Jun 5 11:18:27.830: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 2 forwardLogicalChannelParameters { dataType audioData : nonStandard : { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '47373236723332'H } multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528863: Jun 5 11:18:27.830: H245 FS OLC OUTGOING ENCODE BUFFER::= 0000010C10B50000120747373236723332800A040001000A0A0FD1468B 1528864: Jun 5 11:18:27.830: 1528865: Jun 5 11:18:27.830: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 2 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : nonStandard : { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '47373236723332'H } multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528866: Jun 5 11:18:27.834: H245 FS OLC OUTGOING ENCODE BUFFER::= 400001060401004C10B500001207473732367233328011140001000A0A0FD1468A000A0A0FD1468B 1528867: Jun 5 11:18:27.834: 1528868: Jun 5 11:18:27.834: generic_open_logical_channel:codec is g728 1528869: Jun 5 11:18:27.834: cch323_generic_open_logical_channel: Filling in qosCapability field to 0 1528870: Jun 5 11:18:27.834: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 3 forwardLogicalChannelParameters { dataType audioData : g728 : 16 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528871: Jun 5 11:18:27.834: H245 FS OLC OUTGOING ENCODE BUFFER::= 0000020D200F800A040001000A0A0FD1468B 1528872: Jun 5 11:18:27.834: 1528873: Jun 5 11:18:27.834: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 3 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : g728 : 16 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528874: Jun 5 11:18:27.838: H245 FS OLC OUTGOING ENCODE BUFFER::= 400002060401004D200F8011140001000A0A0FD1468A000A0A0FD1468B 1528875: Jun 5 11:18:27.838: 1528876: Jun 5 11:18:27.838: generic_open_logical_channel:codec is g729 1528877: Jun 5 11:18:27.838: cch323_generic_open_logical_channel: Filling in qosCapability field to 0 1528878: Jun 5 11:18:27.838: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 4 forwardLogicalChannelParameters { dataType audioData : g729 : 8 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528879: Jun 5 11:18:27.838: H245 FS OLC OUTGOING ENCODE BUFFER::= 0000030D4007800A040001000A0A0FD1468B 1528880: Jun 5 11:18:27.838: 1528881: Jun 5 11:18:27.838: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 4 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : g729 : 8 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528882: Jun 5 11:18:27.838: H245 FS OLC OUTGOING ENCODE BUFFER::= 400003060401004D40078011140001000A0A0FD1468A000A0A0FD1468B 1528883: Jun 5 11:18:27.838: 1528884: Jun 5 11:18:27.838: generic_open_logical_channel:codec is g723r63 1528885: Jun 5 11:18:27.838: cch323_generic_open_logical_channel: Filling in qosCapability field to 0 1528886: Jun 5 11:18:27.838: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 5 forwardLogicalChannelParameters { dataType audioData : g7231 : { maxAl-sduAudioFrames 3 silenceSuppression FALSE } multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528887: Jun 5 11:18:27.842: H245 FS OLC OUTGOING ENCODE BUFFER::= 0000040D000240000A040001000A0A0FD1468B 1528888: Jun 5 11:18:27.842: 1528889: Jun 5 11:18:27.842: H245 FS OLC OUTGOING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 5 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : g7231 : { maxAl-sduAudioFrames 3 silenceSuppression FALSE } multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18059 } } } } 1528890: Jun 5 11:18:27.842: H245 FS OLC OUTGOING ENCODE BUFFER::= 400004060401004D0002400011140001000A0A0FD1468A000A0A0FD1468B 1528891: Jun 5 11:18:27.842: 1528892: Jun 5 11:18:27.842: cch323_h225_generic_send_setup: for callID 720BA 1528893: Jun 5 11:18:27.842: cch323_h225_generic_send_setup-- sending calling IE 1528894: Jun 5 11:18:27.842: ======= PI in cch323_h225_generic_send_setup = 0 1528895: Jun 5 11:18:27.842: Send infoXCap 128 1528896: Jun 5 11:18:27.842: cch323_h225_generic_send_setup:raw message is 38 bytes:A1 04 03 80 90 A3 18 03 A1 83 9B 28 09 4D 61 69 6E 2D 43 69 72 63 6C 06 80 32 33 32 39 39 70 06 81 36 38 33 31 30 1528897: Jun 5 11:18:27.842: src address = 10.10.10.10 of h225SetupRequest 1528898: Jun 5 11:18:27.842: dest address = 20.20.20.20 of h225SetupRequest 1528899: Jun 5 11:18:27.842: H225 NONSTD OUTGOING PDU ::= value H323_UU_NonStdInfo ::= { version 1 protoParam qsigNonStdInfo : { iei -95 rawMesg 'A104038090A31803A1839B28094D61696E2D4369...'H } } 1528900: Jun 5 11:18:27.842: H225 NONSTD OUTGOING ENCODE BUFFER::= 6001010001A126A104038090A31803A1839B28094D61696E2D436972636C068032333239397006813638333130 1528901: Jun 5 11:18:27.842: 1528902: Jun 5 11:18:27.846: H225.0 OUTGOING PDU ::= XR value H323_UserInformation ::= { h323-uu-pdu { h323-message-body setup : { protocolIdentifier { 0 0 8 2250 0 2 } sourceAddress { h323-ID : {"c7206"} } sourceInfo { vendor { vendor { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } } gateway { protocol { voice : { supportedPrefixes { { prefix e164 : "1#" } } } } } mc FALSE undefinedNode FALSE } activeMC FALSE conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H conferenceGoal create : NULL callType pointToPoint : NULL sourceCallSignalAddress ipAddress : { ip '0A0A0FD1'H port 61669 } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } fastStart { '0000000C2013800A040001000A0A0FD1468B'H, '400000060401004C20138011140001000A0A0FD1...'H, '0000010C10B50000120747373236723332800A04...'H, '400001060401004C10B500001207473732367233...'H, '0000020D200F800A040001000A0A0FD1468B'H, '400002060401004D200F8011140001000A0A0FD1...'H, '0000030D4007800A040001000A0A0FD1468B'H, '400003060401004D40078011140001000A0A0FD1...'H, '0000040D000240000A040001000A0A0FD1468B'H, '400004060401004D0002400011140001000A0A0F...'H } mediaWaitForConnect FALSE canOverlapSend FALSE } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '6001010001A126A104038090A31803A1839B2809...'H } } } } 1528903: Jun 5 11:18:27.850: H225.0 OUTGOING ENCODE BUFFER::= 20A0060008914A000201400700470049002D005500560044002D00522800B500001240013C050401002040006C80510AEBB811E39CE0837E4C6979E600451C07000A0A0FD1F0E511006C80510AEBB811E39CE1837E4C6979E6810E0A120000000C2013800A040001000A0A0FD1468B1D400000060401004C20138011140001000A0A0FD1468A000A0A0FD1468B1D0000010C10B50000120747373236723332800A040001000A0A0FD1468B28400001060401004C10B500001207473732367233328011140001000A0A0FD1468A000A0A0FD1468B120000020D200F800A040001000A0A0FD1468B1D400002060401004D200F8011140001000A0A0FD1468A000A0A0FD1468B120000030D4007800A040001000A0A0FD1468B1D400003060401004D40078011140001000A0A0FD1468A000A0A0FD1468B130000040D000240000A040001000A0A0FD1468B1E400004060401004D0002400011140001000A0A0FD1468A000A0A0FD1468B0100010006A00100340140B50000122D6001010001A126A104038090A31803A1839B28094D61696E2D436972636C068032333239397006813638333130 1528904: Jun 5 11:18:27.854: Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x18B5 Message Type : 0x05: SETUP Bearer Capability: Length Of IE=3 Data 8090A3 Display: Length Of IE=9 Data 4D61696E2D43697263 Calling Party Number: Length Of IE=6 Data 803233323939 Called Party Number: Length Of IE=6 Data 813638333130 User-User: Length Of IE=423 Data 0520A0060008914A000201400700470049002D005500560044002D00522800B500001240013C050401002040006C80510AEBB811E39CE0837E4C6979E600451C07000A0A0FD1F0E511006C80510AEBB811E39CE1837E4C6979E6810E0A120000000C2013800A040001000A0A0FD1468B1D400000060401004C20138011140001000A0A0FD1468A000A0A0FD1468B1D0000010C10B50000120747373236723332800A040001000A0A0FD1468B28400001060401004C10B500001207473732367233328011140001000A0A0FD1468A000A0A0FD1468B120000020D200F800A040001000A0A0FD1468B1D400002060401004D200F8011140001000A0A0FD1468A000A0A0FD1468B120000030D4007800A040001000A0A0FD1468B1D400003060401004D40078011140001000A0A0FD1468A000A0A0FD1468B130000040D000240000A040001000A0A0FD1468B1E400004060401004D0002400011140001000A0A0FD1468A000A0A0FD1468B0100010006A00100340140B50000122D6001010001A126A104038090A31803A1839B28094D61696E2D436972636C068032333239397006813638333130 1528905: Jun 5 11:18:27.854: timer (0x63A883E0)starts - delay (15000) 1528906: Jun 5 11:18:27.854: H.225 SM: changing from H225_IDLE state to H225_REQ_SETUP state for callID 720BA 1528907: Jun 5 11:18:27.854: cch323_ct_main: SOCK 2 Event 0x1XRXR 1528908: Jun 5 11:18:27.898: cch323_ct_main: SOCK 2 Event 0x1 1528909: Jun 5 11:18:27.898: [1]towner_data=0x63CDCCA4, len=126, msgPtr=0x63C8DA30 1528910: Jun 5 11:18:27.898: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x02: CALL_PROC User-User: Length Of IE=114 Data 052180060008914A00042800B500001240023C0501002C05010004470011006C80510AEBB811E39CE1837E4C6979E63A02190000000C20138011140001000A0A090C405C000A0A090C405D1E400000060401004C20138012150001000A0A0FD1468A000A0A090C405D000100010010800100 1528911: Jun 5 11:18:27.898: H225.0 INCOMING ENCODE BUFFER::= 2180060008914A00042800B500001240023C0501002C05010004470011006C80510AEBB811E39CE1837E4C6979E63A02190000000C20138011140001000A0A090C405C000A0A090C405D1E400000060401004C20138012150001000A0A0FD1468A000A0A090C405D000100010010800100 1528912: Jun 5 11:18:27.898: 1528913: Jun 5 11:18:27.902: 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 { } }, h323 : { supportedPrefixes { } } } } mc FALSE undefinedNode FALSE } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } fastStart { '0000000C20138011140001000A0A090C405C000A...'H, '400000060401004C20138012150001000A0A0FD1...'H } } h245Tunneling FALSE } } 1528914: Jun 5 11:18:27.902: cch323_h225_receiver: received msg of type CALLPROCIND_CHOSEN 1528915: Jun 5 11:18:27.902: timer(0x63A883E0) stops 1528916: Jun 5 11:18:27.902: timer (0x63A883E0)starts - delay (180000) 1528917: Jun 5 11:18:27.902: cch323_h225_callproc_ind: 1528918: Jun 5 11:18:27.902: ====== PI in cch323_h225_callproc_ind = 0 1528919: Jun 5 11:18:27.902: src address = 10.10.10.10 of CALLPROCIND_CHOSEN 1528920: Jun 5 11:18:27.902: dest address = 20.20.20.20 of CALLPROCIND_CHOSEN 1528921: Jun 5 11:18:27.902: cch323_run_h225_sm: received event H225_EVENT_FAST_CALLPROC_IND while at state H225_REQ_SETUP 1528922: Jun 5 11:18:27.902: H.225 SM: changing from H225_REQ_SETUP state to H225_ACC_CALLPROC state for callID 720BA 1528923: Jun 5 11:18:27.902: cch323_h225_generic_callproc_notify: 1528924: Jun 5 11:18:27.902: cc_api_call_proceeding(vdbPtr=0x62F22A24, callID=0x720BA, prog_ind=0x0) 1528925: Jun 5 11:18:27.902: H245 FS OLC INCOMING ENCODE BUFFER::= 0000000C20138011140001000A0A090C405C000A0A090C405D 1528926: Jun 5 11:18:27.906: 1528927: Jun 5 11:18:27.906: H245 FS OLC INCOMING PDU ::= X value OpenLogicalChannel ::= { forwardLogicalChannelNumber 1 forwardLogicalChannelParameters { dataType audioData : g711Alaw64k : 20 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A090C'H tsapIdentifier 16476 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A090C'H tsapIdentifier 16477 } } } } 1528928: Jun 5 11:18:27.906: H245 FS OLC INCOMING ENCODE BUFFER::= 400000060401004C20138012150001000A0A0FD1468A000A0A090C405D00 1528929: Jun 5 11:18:27.906: 1528930: Jun 5 11:18:27.906: H245 FS OLC INCOMING PDU ::= value OpenLogicalChannel ::= { forwardLogicalChannelNumber 1 forwardLogicalChannelParameters { dataType nullData : NULL multiplexParameters none : NULL } reverseLogicalChannelParameters { dataType audioData : g711Alaw64k : 20 multiplexParameters h2250LogicalChannelParameters : { sessionID 1 mediaChannel unicastAddress : iPAddress : { network '0A0A0FD1'H tsapIdentifier 18058 } mediaControlChannel unicastAddress : iPAddress : { network '0A0A090C'H tsapIdentifier 16477 } silenceSuppression FALSE } } } 1528931: Jun 5 11:18:27.906: Codec: loc(6), rem(6), Bytes: loc(160), Fwd(160), Rev(160) 1528932: Jun 5 11:18:27.906: cch323_rtp_set_non_rtp_call: Non-RTP call end 1528933: Jun 5 11:18:27.906: cch323_rtp_open_notify: 1528934: Jun 5 11:18:27.906: ****** cut through in BACKWARD direction ***** 1528935: Jun 5 11:18:27.906: sess_appl: ev(21=CC_EV_CALL_PROCEEDING), cid(467130), disp(0) 1528936: Jun 5 11:18:27.910: cid(467130)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_PROCEEDING) oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(0)fDest(0) 1528937: Jun 5 11:18:27.910: -cid2(467129)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING) 1528938: Jun 5 11:18:27.910: ssaCallProc 1528939: Jun 5 11:18:27.910: ccGetDialpeerTag (callID=0x720B9) 1528940: Jun 5 11:18:27.910: ssaIgnore cid(467130), st(SSA_CS_CALL_SETTING),oldst(1), ev(21) 1528941: Jun 5 11:18:27.910: cch323_ct_main: SOCK 2 Event 0x1 1528942: Jun 5 11:18:27.910: [1]towner_data=0x63CDCCA4, len=92, msgPtr=0x63C0E548 1528943: Jun 5 11:18:27.910: cch323_gw_process_read_socket: received msg for H.225 R Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x03: PROGRESS Facility: Length Of IE=0 Data Progress Ind: Length Of IE=2 Data 8088 User-User: Length Of IE=74 Data 0528002F80060008914A00042800B500001240023C0501002C050100006C80510AEBB811E39CE1837E4C6979E605800100010010A00100120140B50000120B60011000011E041E028188 1528944: Jun 5 11:18:27.910: H225.0 INCOMING ENCODE BUFFER::= 28002F80060008914A00042800B500001240023C0501002C050100006C80510AEBB811E39CE1837E4C6979E605800100010010A00100120140B50000120B60011000011E041E028188 1528945: Jun 5 11:18:27.910: 1528946: Jun 5 11:18:27.910: H225.0 INCOMING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body progress : { protocolIdentifier { 0 0 8 2250 0 4 } destinationInfo { vendor { vendor { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } } gateway { protocol { voice : { supportedPrefixes { } }, h323 : { supportedPrefixes { } } } } mc FALSE undefinedNode FALSE } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '60011000011E041E028188'H } } } } 1528947: Jun 5 11:18:27.914: H225 NONSTD INCOMING ENCODE BUFFER::= 60011000011E041E028188 1528948: Jun 5 11:18:27.914: 1528949: Jun 5 11:18:27.914: H225 NONSTD INCOMING PDU ::= value H323_UU_NonStdInfo ::= { version 16 protoParam qsigNonStdInfo : { iei 30 rawMesg '1E028188'H } } 1528950: Jun 5 11:18:27.914: cch323_h225_receiver: received msg of type PROGRESSIND_CHOSEN 1528951: Jun 5 11:18:27.914: cch323_h225_progress_ind:raw message is 4 bytes:1E 02 81 88 1528952: Jun 5 11:18:27.914: ======= PI in cch323_h225_progress_ind = 8 1528953: Jun 5 11:18:27.914: src address = 10.10.10.10 of PROGRESSIND_CHOSEN 1528954: Jun 5 11:18:27.914: dest address = 20.20.20.20 of PROGRESSIND_CHOSEN 1528955: Jun 5 11:18:27.914: cch323_run_h225_sm: received event H225_EVENT_PROGRESS_IND while at state H225_ACC_CALLPROC 1528956: Jun 5 11:18:27.914: H.225 SM: changing from H225_ACC_CALLPROC state to H225_ACC_PROGRESS state for callID 720BA 1528957: Jun 5 11:18:27.914: cch323_h225_cut_progress_notify: 1528958: Jun 5 11:18:27.914: ======= PI in cch323_h225_generic_cut_progress_notify = 8 1528959: Jun 5 11:18:27.914: cc_api_call_cut_progress(vdbPtr=0x62F22A24, callID=0x720BA, prog_ind=0x8, sig_ind=0x1) 1528960: Jun 5 11:18:27.914: cch323_ct_main: SOCK 2 Event 0x1 1528961: Jun 5 11:18:27.914: [1]towner_data=0x63CDCCA4, len=73, msgPtr=0x63C0E548 1528962: Jun 5 11:18:27.914: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x6E: NOTIFY Notification Ind: Length Of IE=1 Data F1 Display: Length Of IE=0 Data User-User: Length Of IE=56 Data 0528501900060008914A0004006C80510AEBB811E39CE1837E4C6979E610A00100160140B50000120F8182000B44004C0700003638333130 1528963: Jun 5 11:18:27.914: H225.0 INCOMING ENCODE BUFFER::= 28501900060008914A0004006C80510AEBB811E39CE1837E4C6979E610A00100160140B50000120F8182000B44004C0700003638333130 1528964: Jun 5 11:18:27.914: 1528965: Jun 5 11:18:27.914: H225.0 INCOMING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body Unrecognized Field : { } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '8182000B44004C0700003638333130'H } } } } 1528966: Jun 5 11:18:27.918: H225 NONSTD INCOMING ENCODE BUFFER::= 8182000B44004C0700003638333130 1528967: Jun 5 11:18:27.918: 1528968: Jun 5 11:18:27.918: H225 NONSTD INCOMING PDU ::= value H323_UU_NonStdInfo ::= { } 1528969: Jun 5 11:18:27.918: cch323_h225_receiver: received msg of type NOTIFYIND_CHOSEN 1528970: Jun 5 11:18:27.918: cch323_h225_notify_ind: 1528971: Jun 5 11:18:27.918: src address = 10.10.10.10 of NOTIFYIND_CHOSEN 1528972: Jun 5 11:18:27.918: dest address = 20.20.20.20 of NOTIFYIND_CHOSEN 1528973: Jun 5 11:18:27.918: cch323_run_h225_sm: received event H225_EVENT_NOTIFY_IND while at state H225_ACC_PROGRESS cch323_h225_notifymsg_notify: 1528974: Jun 5 11:18:27.918: cc_api_call_notify(vdbPtr=0x62F22A24, callID=0x720BA 1528975: Jun 5 11:18:27.918: sess_appl: ev(22=CC_EV_CALL_PROGRESS), cid(467130), disp(0) 1528976: Jun 5 11:18:27.918: cid(467130)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_PROGRESS) oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0) 1528977: Jun 5 11:18:27.918: -cid2(467129)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING) 1528978: Jun 5 11:18:27.918: ssaCutProgress 1528979: Jun 5 11:18:27.918: ccGetDialpeerTag (callID=0x720B9) 1528980: Jun 5 11:18:27.918: ccCallCutProgress (callID=0x720B9, prog_ind=0x8, sig_ind=0x1) 1528981: Jun 5 11:18:27.918: ccCallAlert (callID=0x720B9, prog_ind=0x8, sig_ind=0x1) 1528982: Jun 5 11:18:27.918: ccConferenceCreate (confID=0x6345F00C, callID1=0x720B9, callID2=0x720BA, tag=0x0) 1528983: Jun 5 11:18:27.918: cc_api_bridge_done (confID=0x2FB7E, srcIF=0x62F22A24, srcCallID=0x720BA, dstCallID=0x720B9, disposition=0, tag=0x0) 1528984: Jun 5 11:18:27.918: cch323_rtp_open_notify: 1528985: Jun 5 11:18:27.918: ****** cut through in BACKWARD direction ***** 1528986: Jun 5 11:18:27.918: sess_appl: ev(20=CC_EV_CALL_NOTIFY), cid(467130), disp(0) 1528987: Jun 5 11:18:27.918: cid(467130)st(SSA_CS_CONFERENCING_PROGRESS)ev(SSA_EV_CALL_NOTIFY) oldst(SSA_CS_CALL_SETTING)cfid(195454)csize(0)in(0)fDest(0) 1528988: Jun 5 11:18:27.918: -cid2(467129)st2(SSA_CS_CONFERENCING_PROGRESS)oldst2(SSA_CS_CALL_SETTING) 1528989: Jun 5 11:18:27.918: ssaNotify 1528990: Jun 5 11:18:27.918: ccCallNotify (callID=0x720B9) 1528991: Jun 5 11:18:27.918: cc_api_bridge_done (confID=0x2FB7E, srcIF=0x6364BA90, srcCallID=0x720B9, dstCallID=0x720BA, disposition=0, tag=0x0) 1528992: Jun 5 11:18:27.918: cc_api_caps_ind (dstVdbPtr=0x62F22A24, dstCallId=0x720BA, srcCallId=0x720B9, caps={codec=0x2887F, fax_rate=0x7F, vad=0x3, modem=0x2 codec_bytes=0, signal_type=3}) 1528993: Jun 5 11:18:27.918: cc_api_caps_ind (Playout: mode 1, initial 60,min 40, max 200) 1528994: Jun 5 11:18:27.918: cch323_caps_ind: cap_modem_proto: 0, cap_modem_codec: 0, cap_modem_redundancy: 0 payload 100 1528995: Jun 5 11:18:27.918: cch323_caps_ind: Load DSP with Negotiated codec(6) g711alaw, Bytes=160 1528996: Jun 5 11:18:27.918: cch323_caps_ind: set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_INBAND_VOICE 1528997: Jun 5 11:18:27.918: cc_api_caps_ind (dstVdbPtr=0x6364BA90, dstCallId=0x720B9, srcCallId=0x720BA, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2}) 1528998: Jun 5 11:18:27.918: cc_api_caps_ind (Playout: mode 1, initial 60,min 40, max 200) 1528999: Jun 5 11:18:27.918: cc_api_caps_ack (dstVdbPtr=0x6364BA90, dstCallId=0x720B9, srcCallId=0x720BA, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2, seq_num_start=2935}) 1529000: Jun 5 11:18:27.918: cc_api_caps_ack (dstVdbPtr=0x62F22A24, dstCallId=0x720BA, srcCallId=0x720B9, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2, seq_num_start=2935}) 1529001: Jun 5 11:18:27.918: cc_api_voice_mode_event , callID=0x720B9 1529002: Jun 5 11:18:27.918: Call Pointer =63995394 1529003: Jun 5 11:18:27.918: sess_appl: ev(29=CC_EV_CONF_CREATE_DONE), cid(467129), disp(0) 1529004: Jun 5 11:18:27.918: cid(467129)st(SSA_CS_CONFERENCING_PROGRESS)ev(SSA_EV_CONF_CREATE_DONE) oldst(SSA_CS_CALL_SETTING)cfid(195454)csize(0)in(1)fDest(1) 1529005: Jun 5 11:18:27.918: -cid2(467130)st2(SSA_CS_CONFERENCING_PROGRESS)oldst2(SSA_CS_CONFERENCING_PROGRESS) 1529006: Jun 5 11:18:27.918: ssaConfCreateDoneAlert 1529007: Jun 5 11:18:27.918: sess_appl: ev(51=CC_EV_VOICE_MODE_DONE), cid(467129), disp(0) 1529008: Jun 5 11:18:27.918: cid(467129)st(SSA_CS_CONFERENCED_ALERT)ev(SSA_EV_VOICE_MODE_DONE) oldst(SSA_CS_CONFERENCING_PROGRESS)cfid(195454)csize(0)in(1)fDest(1) 1529009: Jun 5 11:18:27.918: -cid2(467130)st2(SSA_CS_CONFERENCED_ALERT)oldst2(SSA_CS_CONFERENCING_PROGRESS) 1529010: Jun 5 11:18:27.918: ssaIgnore cid(467129), st(SSA_CS_CONFERENCED_ALERT),oldst(4), ev(51) 1529011: Jun 5 11:18:27.918: cc_process_notify_bridge_done (event=0x631C2630)XRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXR 1529012: Jun 5 11:18:28.474: Timer[CCH323_RAS_PERIODIC_RRQ_TIMER] expired 1529013: Jun 5 11:18:28.474: Changing to new event CCH323_RAS_EVENT_SEND_LT_RRQ 1529014: Jun 5 11:18:28.474: cch323_run_ras_sm: received event CCH323_RAS_EVENT_SEND_LT_RRQ while at CCH323_RAS_STATE_IDLE state 1529015: Jun 5 11:18:28.474: timer(0x62F22908) stops 1529016: Jun 5 11:18:28.474: timer(0x62F22948) stops 1529017: Jun 5 11:18:28.474: RAS OUTGOING PDU ::= value RasMessage ::= registrationRequest : { requestSeqNum 60332 protocolIdentifier { 0 0 8 2250 0 2 } discoveryComplete FALSE callSignalAddress { } rasAddress { ipAddress : { ip '0A0A0901'H port 56042 } } terminalType { mc FALSE undefinedNode FALSE } gatekeeperIdentifier {"GK"} endpointVendor { vendor { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } } timeToLive 60 keepAlive TRUE endpointIdentifier {"644FD85C000000DE"} willSupplyUUIEs FALSE } 1529018: Jun 5 11:18:28.474: RAS OUTGOING ENCODE BUFFER::= 0E40EBAB060008914A0002000001000A0A0901DAEA000800470055002D005000330038002D0047004B00B50000120E8E02003B0180211E00360034003400460044003800350043003000300030003000300030004400450100 1529019: Jun 5 11:18:28.474: 1529020: Jun 5 11:18:28.474: timer (0x62F22908)starts - delay (5000) 1529021: Jun 5 11:18:28.474: cch323_run_ras_sm: changing to CCH323_RAS_STATE_RRQ state 1529022: Jun 5 11:18:28.474: cch323_ct_main: SOCK 1 Event 0x1 1529023: Jun 5 11:18:28.474: [1]towner_data=0x6394A84C, len=105, msgPtr=0x63C45F90 1529024: Jun 5 11:18:28.474: cch323_gw_process_read_socket: received msg for RAS 1529025: Jun 5 11:18:28.478: RAS INCOMING ENCODE BUFFER::= 1240EBAB060008914A0004001000470055002D005000330038002D0047004B1E0036003400340046004400380035004300300030003000300030003000440045218A001E0140C2046A1506B71200470055002D005000330038002D0047004B00328002003B01000100 1529026: Jun 5 11:18:28.478: 1529027: Jun 5 11:18:28.478: RAS INCOMING PDU ::= R value RasMessage ::= registrationConfirm : { requestSeqNum 60332 protocolIdentifier { 0 0 8 2250 0 4 } callSignalAddress { } gatekeeperIdentifier {"GK"} endpointIdentifier {"644FD85C000000DE"} alternateGatekeeper { { rasAddress ipAddress : { ip 'C2046A15'H port 1719 } gatekeeperIdentifier {"GK2"} needToRegister TRUE priority 0 } } timeToLive 60 willRespondToIRR FALSE } 1529028: Jun 5 11:18:28.478: cch323_ras_handle_recv_msg: received msg of type RCF_CHOSEN 1529029: Jun 5 11:18:28.478: cch323_run_ras_sm: received event CCH323_RAS_EVENT_RCF while at CCH323_RAS_STATE_RRQ state 1529030: Jun 5 11:18:28.478: timer(0x62F22908) stops 1529031: Jun 5 11:18:28.478: timer (0x62F22948)starts - delay (45000) 1529032: Jun 5 11:18:28.478: cch323_run_ras_sm: changing to CCH323_RAS_STATE_IDLE state xXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRx 1529033: Jun 5 11:18:30.502: RECEIVE: 1529034: Jun 5 11:18:30.502: SDES: ssrc=0x1CA2090C name=1 len=16 data=0.0.0@20.20.20.20 1529035: Jun 5 11:18:30.502: RTT: udp=0xF39B46C rtcp=0xF39B474 ssrc=0x10890FD1XRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRx 1529036: Jun 5 11:18:32.002: RECEIVE: 1529037: Jun 5 11:18:32.002: SDES: ssrc=0x1CA2090C name=1 len=16 data=0.0.0@20.20.20.20 1529038: Jun 5 11:18:32.002: RTT: udp=0xF3988AC rtcp=0xF3988B4 ssrc=0x10890FD1XRRxXRRxXRRxXRRx 1529039: Jun 5 11:18:32.082: RECEIVE: 1529040: Jun 5 11:18:32.082: SR: ssrc=0x1C740FEA sr_ntp_h=0xD73A9848 sr_ntp_l=0x14DD0B60 sr_timestamp=0x6A88BFEB sr_npackets=1061 sr_nbytes=169760 1529041: Jun 5 11:18:32.082: RR: ssrc=0x23550FE9 rr_loss=0x0 rr_ehsr=9118 rr_jitter=432 rr_lsr=0x984651A7 rr_dlsr=0x1C28F 1529042: Jun 5 11:18:32.082: SDES: ssrc=0x1C740FEA name=1 len=18 data=0.0.0@10.10.15.234 1529043: Jun 5 11:18:32.082: RTT: udp=0xF396C8C rtcp=0xF396C94 ssrc=0x23550FE9 1529044: Jun 5 11:18:32.082: RTT: now_mid_32=0x984815F9 rr->rr_dlsr=0x1C28F rr->rr_lsr=0x984651A7 rtt=6 (ms)XRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXR 1529045: Jun 5 11:18:35.370: RECEIVE: 1529046: Jun 5 11:18:35.370: SR: ssrc=0x1C740FEA sr_ntp_h=0xD73A984B sr_ntp_l=0x5E9907F2 sr_timestamp=0x6A8926BB sr_npackets=1226 sr_nbytes=196160 1529047: Jun 5 11:18:35.370: RR: ssrc=0x23550FE9 rr_loss=0x0 rr_ehsr=9282 rr_jitter=432 rr_lsr=0x984B28B1 rr_dlsr=0x353F 1529048: Jun 5 11:18:35.370: SDES: ssrc=0x1C740FEA name=1 len=18 data=0.0.0@10.10.15.234 1529049: Jun 5 11:18:35.370: RTT: udp=0xF39AB0C rtcp=0xF39AB14 ssrc=0x23550FE9 1529050: Jun 5 11:18:35.370: RTT: now_mid_32=0x984B5FBB rr->rr_dlsr=0x353F rr->rr_lsr=0x984B28B1 rtt=7 (ms)RxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXR 1529051: Jun 5 11:18:37.298: RECEIVE: 1529052: Jun 5 11:18:37.298: SDES: ssrc=0x1CA2090C name=1 len=16 data=0.0.0@20.20.20.20 1529053: Jun 5 11:18:37.298: RTT: udp=0xF3972CC rtcp=0xF3972D4 ssrc=0x10890FD1RxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRx 1529054: Jun 5 11:18:40.718: RECEIVE: 1529055: Jun 5 11:18:40.718: SR: ssrc=0x1C740FEA sr_ntp_h=0xD73A9850 sr_ntp_l=0xB7DBE5AE sr_timestamp=0x6A89CDD7 sr_npackets=1493 sr_nbytes=238880 1529056: Jun 5 11:18:40.718: RR: ssrc=0x23550FE9 rr_loss=0x0 rr_ehsr=9550 rr_jitter=432 rr_lsr=0x984B28B1 rr_dlsr=0x58E56 1529057: Jun 5 11:18:40.718: SDES: ssrc=0x1C740FEA name=1 len=18 data=0.0.0@10.10.15.234 1529058: Jun 5 11:18:40.718: RTT: udp=0xF39790C rtcp=0xF397914 ssrc=0x23550FE9 1529059: Jun 5 11:18:40.718: RTT: now_mid_32=0x9850B8FA rr->rr_dlsr=0x58E56 rr->rr_lsr=0x984B28B1 rtt=7 (ms)XRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRxRXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxXRRxX 1529060: Jun 5 11:18:41.126: cch323_ct_main: SOCK 2 Event 0x1 1529061: Jun 5 11:18:41.126: [1]towner_data=0x63CDCCA4, len=116, msgPtr=0x62E671DC 1529062: Jun 5 11:18:41.126: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x07: CONNECT Bearer Capability: Length Of IE=3 Data 8090A3 Progress Ind: Length Of IE=2 Data 8082 Display: Length Of IE=0 Data User-User: Length Of IE=93 Data 052280060008914A00042800B500001240023C0501002C050100006C80510AEBB811E39CE0837E4C6979E61D0C0011006C80510AEBB811E39CE1837E4C6979E60100010010A00100140140B50000120DC0010018800006000400000002 1529063: Jun 5 11:18:41.126: H225.0 INCOMING ENCODE BUFFER::= 2280060008914A00042800B500001240023C0501002C050100006C80510AEBB811E39CE0837E4C6979E61D0C0011006C80510AEBB811E39CE1837E4C6979E60100010010A00100140140B50000120DC0010018800006000400000002 1529064: Jun 5 11:18:41.126: 1529065: Jun 5 11:18:41.126: H225.0 INCOMING PDU ::= R value H323_UserInformation ::= { h323-uu-pdu { h323-message-body connect : { protocolIdentifier { 0 0 8 2250 0 4 } destinationInfo { vendor { vendor { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } } gateway { protocol { voice : { supportedPrefixes { } }, h323 : { supportedPrefixes { } } } } mc FALSE undefinedNode FALSE } conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data 'C0010018800006000400000002'H } } } } 1529066: Jun 5 11:18:41.130: H225 NONSTD INCOMING ENCODE BUFFER::= C0010018800006000400000002 1529067: Jun 5 11:18:41.130: 1529068: Jun 5 11:18:41.130: H225 NONSTD INCOMING PDU ::= value H323_UU_NonStdInfo ::= { version 0 progIndParam progIndIEinfo : { progIndIE '00000002'H } } 1529069: Jun 5 11:18:41.130: cch323_h225_receiver: received msg of type SETUPCFM_CHOSEN 1529070: Jun 5 11:18:41.130: timer(0x63A883E0) stops 1529071: Jun 5 11:18:41.134: ======= PI in cch323_h225_setup_cfm_ind = 2 1529072: Jun 5 11:18:41.134: Set new event H225_EVENT_SETUP_CFM_IND 1529073: Jun 5 11:18:41.134: src address = 10.10.10.10 of SETUPCFM_CHOSEN 1529074: Jun 5 11:18:41.134: dest address = 20.20.20.20 of SETUPCFM_CHOSEN 1529075: Jun 5 11:18:41.134: cch323_run_h225_sm: received event H225_EVENT_SETUP_CFM_IND while at state H225_ACC_PROGRESS 1529076: Jun 5 11:18:41.134: H.225 SM: changing from H225_ACC_PROGRESS state to H225_ACTIVE_FASTSTART state for callID 720BA 1529077: Jun 5 11:18:41.134: cch323_rtp_open_notify: 1529078: Jun 5 11:18:41.134: ****** cut through in BOTH direction ***** 1529079: Jun 5 11:18:41.134: cch323_h225_generic_setup_cfm_notify: status 2309 1529080: Jun 5 11:18:41.134: cc_api_call_connected(vdbPtr=0x62F22A24, callID=0x720BA), prog_ind = 2cc_api_call_connected: setting callEntry->connected to TRUE 1529081: Jun 5 11:18:41.134: ======= PI in cch323_h225_generic_setup_cfm_notify = 2 1529082: Jun 5 11:18:41.134: cch323_rtp_open_notify: 1529083: Jun 5 11:18:41.134: ****** cut through in BOTH direction ***** 1529084: Jun 5 11:18:41.134: timer(0x63A88528) stops 1529085: Jun 5 11:18:41.134: cch323_percall_ras_sm(0x63A88038): received event CCH323_RAS_EVENT_IRR while at CCH323_RAS_STATE_ACTIVE state 1529086: Jun 5 11:18:41.134: timer(0x63A88548) stops 1529087: Jun 5 11:18:41.134: H225 NONSTD OUTGOING PDU ::= value IRRperCallnonStandardInfo ::= { startTime 1401952721 } 1529088: Jun 5 11:18:41.134: H225 NONSTD OUTGOING ENCODE BUFFER::= 70539019D1 1529089: Jun 5 11:18:41.134: 1529090: Jun 5 11:18:41.134: RAS OUTGOING PDU ::= R value RasMessage ::= infoRequestResponse : { requestSeqNum 60333 endpointType { vendor { vendor { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } } gateway { protocol { voice : { supportedPrefixes { { prefix e164 : "1#" } } } } } mc FALSE undefinedNode FALSE } endpointIdentifier {"644FD85C000000DE"} rasAddress ipAddress : { ip '0A0A0901'H port 56042 } callSignalAddress { ipAddress : { ip '0A0A0901'H port 1720 } } endpointAlias { h323-ID : {"c7206"} } perCallInfo { { nonStandardData { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '70539019D1'H } callReferenceValue 6325 conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H h245 { } callSignaling { } callType pointToPoint : NULL bandWidth 640 callModel direct : NULL callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } } needResponse FALSE } X 1529091: Jun 5 11:18:41.138: RAS OUTGOING ENCODE BUFFER::= 5AC0EBAC2800B500001240013C05040100204007800036003400340046004400380035004300300030003000300030003000440045000A0A0901DAEA01000A0A090106B801400700470049002D005500560044002D005201C100B50000120570539019D118B56C80510AEBB811E39CE0837E4C6979E600200280024011006C80510AEBB811E39CE1837E4C6979E606200100 1529092: Jun 5 11:18:41.138: 1529093: Jun 5 11:18:41.138: timer (0x63A88548)starts - delay (240000) 1529094: Jun 5 11:18:41.138: cch323_percall_ras_sm(0x63A88038): changing to new state CCH323_RAS_STATE_ACTIVE 1529095: Jun 5 11:18:41.138: sess_appl: ev(8=CC_EV_CALL_CONNECTED), cid(467130), disp(0) 1529096: Jun 5 11:18:41.138: cid(467130)st(SSA_CS_CONFERENCED_ALERT)ev(SSA_EV_CALL_CONNECTED) oldst(SSA_CS_CONFERENCING_PROGRESS)cfid(195454)csize(0)in(0)fDest(0) 1529097: Jun 5 11:18:41.138: -cid2(467129)st2(SSA_CS_CONFERENCED_ALERT)oldst2(SSA_CS_CONFERENCED_ALERT) 1529098: Jun 5 11:18:41.138: ssaConnectAlert 1529099: Jun 5 11:18:41.138: ccGetDialpeerTag (callID=0x720B9) 1529100: Jun 5 11:18:41.138: ccCallConnect (callID=0x720B9), prog_ind = 2ccCallConnect: setting callEntry->connected to TRUE 1529101: Jun 5 11:18:41.138: ssaFlushPeerTagQueue cid(467129) peer list: (empty) 1529102: Jun 5 11:18:41.138: cch323_ct_main: SOCK 2 Event 0x1 1529103: Jun 5 11:18:41.138: [1]towner_data=0x63CDCCA4, len=101, msgPtr=0x62E671DC 1529104: Jun 5 11:18:41.138: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x62: FACILITY Facility: Length Of IE=0 Data Display: Length Of IE=19 Data 636973636F2053797374656D732C20496E632E User-User: Length Of IE=66 Data 052690060008914A00046C80510AEBB811E39CE0837E4C6979E68101001F058011006C80510AEBB811E39CE1837E4C6979E607000A0A090CAA030100010010800100 1529105: Jun 5 11:18:41.142: H225.0 INCOMING ENCODE BUFFER::= 2690060008914A00046C80510AEBB811E39CE0837E4C6979E68101001F058011006C80510AEBB811E39CE1837E4C6979E607000A0A090CAA030100010010800100 1529106: Jun 5 11:18:41.142: 1529107: Jun 5 11:18:41.142: H225.0 INCOMING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body facility : { protocolIdentifier { 0 0 8 2250 0 4 } conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H reason startH245 : NULL callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } h245Address ipAddress : { ip '0A0A090C'H port 43523 } } h245Tunneling FALSE } } 1529108: Jun 5 11:18:41.142: cch323_h225_receiver: received msg of type FACILIND_CHOSEN 1529109: Jun 5 11:18:41.142: src address = 10.10.10.10 of FACILIND_CHOSEN 1529110: Jun 5 11:18:41.142: dest address = 20.20.20.20 of FACILIND_CHOSEN 1529111: Jun 5 11:18:41.142: cch323_run_h225_sm: received event H225_EVENT_FACIL_SWITCH_245_IND while at state H225_ACTIVE_FASTSTART 1529112: Jun 5 11:18:41.142: Changing to new event H245_CONNECT_REQ_EVENT 1529113: Jun 5 11:18:41.142: cch323_h245_connection_sm:H245_CONNECT: received event H245_CONNECT_REQ_EVENT while at H245_NONE state 1529114: Jun 5 11:18:41.142: timer (0x63A885B8)starts - delay (5000)changing from H245_NONE state to H245_WAITING state 1529115: Jun 5 11:18:41.142: H.225 SM: changing from H225_ACTIVE_FASTSTART state to H225_WAIT_FOR_H245 state for callID 720BA 1529116: Jun 5 11:18:41.142: cch323_ct_main: SOCK 2 Event 0x1 1529117: Jun 5 11:18:41.142: [1]towner_data=0x63CDCCA4, len=73, msgPtr=0x62E671DC 1529118: Jun 5 11:18:41.142: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x6E: NOTIFY Notification Ind: Length Of IE=1 Data F1 Display: Length Of IE=0 Data User-User: Length Of IE=56 Data 0528501900060008914A0004006C80510AEBB811E39CE1837E4C6979E610A00100160140B50000120F8182000B44004C0700003638333130 1529119: Jun 5 11:18:41.146: H225.0 INCOMING ENCODE BUFFER::= 28501900060008914A0004006C80510AEBB811E39CE1837E4C6979E610A00100160140B50000120F8182000B44004C0700003638333130 1529120: Jun 5 11:18:41.146: 1529121: Jun 5 11:18:41.146: H225.0 INCOMING PDU ::= X value H323_UserInformation ::= { h323-uu-pdu { h323-message-body Unrecognized Field : { } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '8182000B44004C0700003638333130'H } } } } 1529122: Jun 5 11:18:41.146: H225 NONSTD INCOMING ENCODE BUFFER::= 8182000B44004C0700003638333130 1529123: Jun 5 11:18:41.146: 1529124: Jun 5 11:18:41.146: H225 NONSTD INCOMING PDU ::= value H323_UU_NonStdInfo ::= { } 1529125: Jun 5 11:18:41.146: cch323_h225_receiver: received msg of type NOTIFYIND_CHOSEN 1529126: Jun 5 11:18:41.146: cch323_h225_notify_ind: 1529127: Jun 5 11:18:41.146: src address = 10.10.10.10 of NOTIFYIND_CHOSEN 1529128: Jun 5 11:18:41.146: dest address = 20.20.20.20 of NOTIFYIND_CHOSEN 1529129: Jun 5 11:18:41.146: cch323_run_h225_sm: received event H225_EVENT_NOTIFY_IND while at state H225_WAIT_FOR_H245 cch323_h225_notifymsg_notify: 1529130: Jun 5 11:18:41.146: cc_api_call_notify(vdbPtr=0x62F22A24, callID=0x720BA 1529131: Jun 5 11:18:41.146: sess_appl: ev(20=CC_EV_CALL_NOTIFY), cid(467130), disp(0) 1529132: Jun 5 11:18:41.146: cid(467130)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_NOTIFY) oldst(SSA_CS_CONFERENCED_ALERT)cfid(195454)csize(0)in(0)fDest(0) 1529133: Jun 5 11:18:41.146: -cid2(467129)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_CONFERENCED_ALERT) 1529134: Jun 5 11:18:41.146: ssaNotify 1529135: Jun 5 11:18:41.146: ccCallNotify (callID=0x720B9) 1529136: Jun 5 11:18:41.146: cch323_ct_main: SOCK 3 Event 0x1 cch323_h245_channel_established_ind: using fd=3 to send msgs 1529137: Jun 5 11:18:41.146: Changing to new event H245_ESTABLISHED_EVENT 1529138: Jun 5 11:18:41.146: cch323_h245_connection_sm:H245_CONNECT: received event H245_ESTABLISHED_EVENT while at H245_WAITING state 1529139: Jun 5 11:18:41.146: timer(0x63A885B8) stopsSet new event H245_EVENT_CAP_REQ, for callID 720BA 1529140: Jun 5 11:18:41.146: cch323_run_h245_cap_out_sm: received event H245_EVENT_CAP_REQ while at state H245_CAP_NONE 1529141: Jun 5 11:18:41.146: Using DP configured Codecs 1529142: Jun 5 11:18:41.146: Near-end Pref Codecs = G.711 A G.726r32 G.728 G.729 IETFG.723r63 1529143: Jun 5 11:18:41.150: send_caps_request: included rtp in caps table 1529144: Jun 5 11:18:41.150: send_caps_request: included signal in caps table 1529145: Jun 5 11:18:41.150: send_caps_request: included alpha in caps table 1529146: Jun 5 11:18:41.150: send_caps_request: included hookflash in caps table 1529147: Jun 5 11:18:41.150: H245 MSC OUTGOING PDU ::= RR value MultimediaSystemControlMessage ::= request : terminalCapabilitySet : { sequenceNumber 1 protocolIdentifier { 0 0 8 245 0 3 } 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 17 capability receiveAndTransmitDataApplicationCapability : { application nonStandard : { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '52747044746D6652656C6179'H } maxBitRate 0 } }, { capabilityTableEntryNumber 23 capability receiveUserInputCapability : hookflash : NULL }, { capabilityTableEntryNumber 22 capability receiveUserInputCapability : dtmf : NULL }, { capabilityTableEntryNumber 19 capability receiveUserInputCapability : basicString : NULL }, { capabilityTableEntryNumber 4 capability receiveAudioCapability : g729AnnexA : 8 }, { capabilityTableEntryNumber 3 capability receiveAudioCapability : g729 : 8 }, { capabilityTableEntryNumber 9 capability receiveAudioCapability : g7231 : { maxAl-sduAudioFrames 3 silenceSuppression FALSE } }, { capabilityTableEntryNumber 2 capability receiveAudioCapability : g711Alaw64k : 20 }, { capabilityTableEntryNumber 8 capability receiveAudioCapability : g728 : 16 }, { capabilityTableEntryNumber 7 capability receiveAudioCapability : nonStandard : { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '47373236723332'H } } } capabilityDescriptors { { capabilityDescriptorNumber 1 simultaneousCapabilities { { 23 }, { 22, 19, 17 }, { 4, 3, 9, 2, 8, 7 } } } } } X 1529148: Jun 5 11:18:41.158: H245 MSC OUTGOING ENCODE BUFFER::= 027001060008817500038013800014000100000100000100000CC001000100098000104810B50000120C52747044746D6652656C6179000080001683015080001583014080001283011080000322C00780000222800780000822000240000120401380000722400F8000062020B50000120747373236723332008001020000160200150012001005000300020008000100070006 1529149: Jun 5 11:18:41.158: 1529150: Jun 5 11:18:41.158: timer (0x63A885F8)starts - delay (15000) 1529151: Jun 5 11:18:41.158: changing from H245_CAP_NONE state to H245_CAP_WAIT state 1529152: Jun 5 11:18:41.158: Changing to new event H245_EVENT_MSD, for callID 720BA 1529153: Jun 5 11:18:41.158: cch323_run_h245_ms_sm: received event H245_EVENT_MSD while at state H245_MS_NONE 1529154: Jun 5 11:18:41.158: H245 MSC OUTGOING PDU ::= value MultimediaSystemControlMessage ::= request : masterSlaveDetermination : { terminalType 60 statusDeterminationNumber 236 } 1529155: Jun 5 11:18:41.158: H245 MSC OUTGOING ENCODE BUFFER::= 01003C00EC 1529156: Jun 5 11:18:41.158: 1529157: Jun 5 11:18:41.158: changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state 1529158: Jun 5 11:18:41.158: timer (0x63A885D8)starts - delay (15000)cch323_run_h245_ms_sm: Sent MSD Request changing from H245_WAITING state to H245_CONNECTED state 1529159: Jun 5 11:18:41.158: cch323_ct_main: SOCK 3 Event 0x1 1529160: Jun 5 11:18:41.162: cch323_ct_main: SOCK 3 Event 0x1 1529161: Jun 5 11:18:41.162: [1]towner_data=0x63A88038, len=128, msgPtr=0x63C8B3D8 1529162: Jun 5 11:18:41.162: cch323_gw_process_read_socket: received msg for H.245 1529163: Jun 5 11:18:41.162: H245 MSC INCOMING ENCODE BUFFER::= 027001060008817500078013800014000100000100000100000CC0010001000680001F4A0812860100807C0100014008C00200C802014040009080000522C00780000422800780000A22000240000320401380000922400F8000082020B500001207473732367233320080010006001F00030008000900040005000A 1529164: Jun 5 11:18:41.162: 1529165: Jun 5 11:18:41.162: H245 MSC INCOMING PDU ::= XR 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 32 capability receiveAndTransmitDataApplicationCapability : { application t38fax : { t38FaxProtocol udp : NULL t38FaxProfile { fillBitRemoval FALSE transcodingJBIG FALSE transcodingMMR FALSE version 0 t38FaxRateManagement transferredTCF : NULL t38FaxUdpOptions { t38FaxMaxBuffer 200 t38FaxMaxDatagram 320 t38FaxUdpEC t38UDPRedundancy : NULL } } } maxBitRate 144 } }, { capabilityTableEntryNumber 6 capability receiveAudioCapability : g729AnnexA : 8 }, { capabilityTableEntryNumber 5 capability receiveAudioCapability : g729 : 8 }, { capabilityTableEntryNumber 11 capability receiveAudioCapability : g7231 : { maxAl-sduAudioFrames 3 silenceSuppression FALSE } }, { capabilityTableEntryNumber 4 capability receiveAudioCapability : g711Alaw64k : 20 }, { capabilityTableEntryNumber 10 capability receiveAudioCapability : g728 : 16 }, { capabilityTableEntryNumber 9 capability receiveAudioCapability : nonStandard : { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '47373236723332'H } } } capabilityDescriptors { { capabilityDescriptorNumber 1 simultaneousCapabilities { { 32, 4, 9, 10, 5, 6, 11 } } } } } Set new event H245_EVENT_CAP_IND, for callID 720BA 1529166: Jun 5 11:18:41.170: cch323_run_h245_cap_in_sm: received event H245_EVENT_CAP_IND while at state H245_CAP_IDLE Set new event H245_EVENT_CAP_REQ, for callID 720BA 1529167: Jun 5 11:18:41.170: cch323_run_h245_cap_out_sm: received event H245_EVENT_CAP_REQ while at state H245_CAP_WAIT 1529168: Jun 5 11:18:41.170: cch323_run_h245_cap_out_sm: Invalid event H245_EVENT_CAP_REQ for state H245_CAP_WAIT 1529169: Jun 5 11:18:41.170: changing from H245_CAP_IDLE state to H245_CAP_AWAITING_RESPONSE state 1529170: Jun 5 11:18:41.170: cch323_check_cap: Remote audio mask is 3CE 1529171: Jun 5 11:18:41.170: Remote Pref Codecs UnOrdered = [G729IETF], [G729a], [G711_ALAW_64K], [G726r32], [G728], [G723r63], [G723r53], 1529172: Jun 5 11:18:41.170: cch323_check_cap: Audio Mask 0x3CE, Negotiated Codec(6) G711_ALAW_64K Codec Bytes 160 1529173: Jun 5 11:18:41.170: cch323_check_data_and_usr_inp_cap: peer_data_cap_mask=0x4,peer_user_input_cap_mask=0x0 1529174: Jun 5 11:18:41.170: Sending digits as inband voice. No configured peer 1529175: Jun 5 11:18:41.170: cch323_check_data_and_usr_inp_cap: Negotiated DTMF relay - 0x0 1529176: Jun 5 11:18:41.170: cch323_do_cap_ind: Load codec(6) g711alaw, codec_bytes = 160 1529177: Jun 5 11:18:41.170: cch323_do_cap_ind: cap_modem_proto: 0, cap_modem_codec: 0, cap_modem_redundancy: 0 1529178: Jun 5 11:18:41.170: cch323_do_cap_ind: negotiated dtmf relay is inband voice 1529179: Jun 5 11:18:41.170: cc_api_caps_ind (dstVdbPtr=0x6364BA90, dstCallId=0x720B9, srcCallId=0x720BA, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2}) 1529180: Jun 5 11:18:41.170: cc_api_caps_ind (Playout: mode 1, initial 60,min 40, max 200) 1529181: Jun 5 11:18:41.170: cc_api_caps_ack (dstVdbPtr=0x6364BA90, dstCallId=0x720B9, srcCallId=0x720BA, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2, seq_num_start=3597})Set new event H245_EVENT_CAP_RESP, for callID 720BA 1529182: Jun 5 11:18:41.170: cch323_run_h245_cap_in_sm: received event H245_EVENT_CAP_RESP while at state H245_CAP_AWAITING_RESPONSE 1529183: Jun 5 11:18:41.170: H245 MSC OUTGOING PDU ::= value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck : { sequenceNumber 1 } 1529184: Jun 5 11:18:41.174: H245 MSC OUTGOING ENCODE BUFFER::= 218001 1529185: Jun 5 11:18:41.174: 1529186: Jun 5 11:18:41.174: changing from H245_CAP_AWAITING_RESPONSE state to H245_CAP_IDLE state 1529187: Jun 5 11:18:41.174: cch323_check_data_and_usr_inp_cap: peer_data_cap_mask=0x4,peer_user_input_cap_mask=0x0 1529188: Jun 5 11:18:41.174: Sending digits as inband voice. No configured peer 1529189: Jun 5 11:18:41.174: cch323_check_data_and_usr_inp_cap: Negotiated DTMF relay - 0x0cap_notify: check_data_and_usr_inp returned 1529190: Jun 5 11:18:41.174: cch323_ct_main: SOCK 3 Event 0x1 1529191: Jun 5 11:18:41.174: [1]towner_data=0x63A88038, len=10, msgPtr=0x63A8A5A8 1529192: Jun 5 11:18:41.174: cch323_gw_process_read_socket: received msg for H.245 1529193: Jun 5 11:18:41.174: H245 MSC INCOMING ENCODE BUFFER::= 01003C402043 1529194: Jun 5 11:18:41.174: 1529195: Jun 5 11:18:41.174: H245 MSC INCOMING PDU ::= value MultimediaSystemControlMessage ::= request : masterSlaveDetermination : { terminalType 60 statusDeterminationNumber 8259 } 1529196: Jun 5 11:18:41.174: Changing to new event H245_EVENT_MS_IND, for callID 720BA 1529197: Jun 5 11:18:41.174: cch323_run_h245_ms_sm: received event H245_EVENT_MS_IND while at state H245_MS_OUTGOING_WAIT 1529198: Jun 5 11:18:41.174: timer(0x63A885D8) stopsMSDetermination: rtt=60 rsdnum=8259, ltt=60 lsdnum=236 MSDetermination: Am MASTER, ccb->h245.h245_mdStatus = 0x1 1529199: Jun 5 11:18:41.174: H245 MSC OUTGOING PDU ::= value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck : { decision slave : NULL } 1529200: Jun 5 11:18:41.174: H245 MSC OUTGOING ENCODE BUFFER::= 20A0 1529201: Jun 5 11:18:41.174: MS_Determine_indication to Appl! 1529202: Jun 5 11:18:41.174: timer (0x63A885D8)starts - delay (15000) 1529203: Jun 5 11:18:41.174: changing from H245_MS_OUTGOING_WAIT state to H245_MS_INCOMING_WAIT state cch323_run_h245_ms_sm: Sent MSD ACK! 1529204: Jun 5 11:18:41.174: cc_api_caps_ack (dstVdbPtr=0x62F22A24, dstCallId=0x720BA, srcCallId=0x720B9, caps={codec=0x2, fax_rate=0x2, vad=0x1, modem=0x0 codec_bytes=160, signal_type=2, seq_num_start=3597}) 1529205: Jun 5 11:18:41.174: cc_api_voice_mode_event , callID=0x720B9 1529206: Jun 5 11:18:41.174: Call Pointer =63995394 1529207: Jun 5 11:18:41.174: sess_appl: ev(51=CC_EV_VOICE_MODE_DONE), cid(467129), disp(0) 1529208: Jun 5 11:18:41.174: cid(467129)st(SSA_CS_ACTIVE)ev(SSA_EV_VOICE_MODE_DONE) oldst(SSA_CS_CONFERENCED_ALERT)cfid(195454)csize(0)in(1)fDest(1) 1529209: Jun 5 11:18:41.174: -cid2(467130)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_ACTIVE) 1529210: Jun 5 11:18:41.174: ssaIgnore cid(467129), st(SSA_CS_ACTIVE),oldst(5), ev(51)RXXRRXXRRXXRRXXRXRXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRXRXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRXRXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRRXXRXR 1529211: Jun 5 11:18:42.146: cc_api_call_disconnected(vdbPtr=0x6364BA90, callID=0x720B7, cause=0x10)X 1529212: Jun 5 11:18:42.146: sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(467127), disp(0) 1529213: Jun 5 11:18:42.146: cid(467127)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED) oldst(SSA_CS_CONFERENCED_ALERT)cfid(195453)csize(0)in(1)fDest(1) 1529214: Jun 5 11:18:42.146: -cid2(467128)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_CONFERENCED_ALERT) 1529215: Jun 5 11:18:42.146: ssa: Disconnected cid(467127) state(5) cause(0x10) 1529216: Jun 5 11:18:42.146: ccConferenceDestroy (confID=0x2FB7D, tag=0x0) 1529217: Jun 5 11:18:42.146: cc_api_bridge_drop_done (confID=0x2FB7D, srcIF=0x62F22A24, srcCallID=0x720B8, dstCallID=0x720B7, disposition=0 tag=0x0) 1529218: Jun 5 11:18:42.146: cc_api_bridge_drop_done (confID=0x2FB7D, srcIF=0x6364BA90, srcCallID=0x720B7, dstCallID=0x720B8, disposition=0 tag=0x0) 1529219: Jun 5 11:18:42.146: sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(467127), disp(0) 1529220: Jun 5 11:18:42.146: cid(467127)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE) oldst(SSA_CS_ACTIVE)cfid(-1)csize(0)in(1)fDest(1) 1529221: Jun 5 11:18:42.146: -cid2(467128)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_CONFERENCED_ALERT) 1529222: Jun 5 11:18:42.146: ssaConfDestroyDone 1529223: Jun 5 11:18:42.146: ccCallDisconnect (callID=0x720B7, cause=0x10 tag=0x0) 1529224: Jun 5 11:18:42.146: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10 1529225: Jun 5 11:18:42.146: ccCallDisconnect (callID=0x720B8, cause=0x10 tag=0x0) 1529226: Jun 5 11:18:42.146: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10cch323_do_call_disconnect: gw_id=1, callID=467128 1529227: Jun 5 11:18:42.146: H.225 SM: process event H225_EVENT_RELEASE, for callID 720B8 1529228: Jun 5 11:18:42.146: cch323_run_h225_sm: received event H225_EVENT_RELEASE while at state H225_ACTIVE_FASTSTART 1529229: Jun 5 11:18:42.146: H.225 SM: changing from H225_ACTIVE_FASTSTART state to H225_WAIT_FOR_DRQ state for callID 720B8 1529230: Jun 5 11:18:42.146: cch323_h225_send_release:raw message is 4 bytes:08 02 81 90 1529231: Jun 5 11:18:42.146: src address = 10.10.15.233 of h225TerminateRequest 1529232: Jun 5 11:18:42.146: dest address = 10.10.15.234 of h225TerminateRequest 1529233: Jun 5 11:18:42.146: H225 NONSTD OUTGOING PDU ::= value H323_UU_NonStdInfo ::= { version 1 protoParam qsigNonStdInfo : { iei 8 rawMesg '08028190'H } } 1529234: Jun 5 11:18:42.146: H225 NONSTD OUTGOING ENCODE BUFFER::= 6001010001080408028190 1529235: Jun 5 11:18:42.146: 1529236: Jun 5 11:18:42.146: H225.0 OUTGOING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body releaseComplete : { protocolIdentifier { 0 0 8 2250 0 2 } callIdentifier { guid '62566188EBB811E39CDD837E4C6979E6'H } } h245Tunneling FALSE nonStandardControl { { nonStandardIdentifier h221NonStandard : { t35CountryCode 181 t35Extension 0 manufacturerCode 18 } data '6001010001080408028190'H } } } } 1529237: Jun 5 11:18:42.150: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A000201110062566188EBB811E39CDD837E4C6979E606A00100120140B50000120B6001010001080408028190 1529238: Jun 5 11:18:42.150: R Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x18B4 Message Type : 0x5A: RELEASE_COMP Cause: Length Of IE=2 Data 8190 User-User: Length Of IE=52 Data 052580060008914A000201110062566188EBB811E39CDD837E4C6979E606A00100120140B50000120B6001010001080408028190 1529239: Jun 5 11:18:42.150: timer (0x63AC4968)starts - delay (2000) 1529240: Jun 5 11:18:42.150: timer(0x63AC4A90) stops 1529241: Jun 5 11:18:42.150: cch323_percall_ras_sm(0x63AC45A0): received event CCH323_RAS_EVENT_CALLDISC while at CCH323_RAS_STATE_ACTIVE state 1529242: Jun 5 11:18:42.150: timer(0x63AC4AB0) stops 1529243: Jun 5 11:18:42.150: RAS OUTGOING PDU ::= value RasMessage ::= disengageRequest : { requestSeqNum 60334 endpointIdentifier {"644FD85C000000DE"} conferenceID '62566188EBB811E39CDC837E4C6979E6'H callReferenceValue 6324 disengageReason normalDrop : NULL callIdentifier { guid '62566188EBB811E39CDD837E4C6979E6'H } answeredCall FALSE } 1529244: Jun 5 11:18:42.150: RAS OUTGOING ENCODE BUFFER::= 3EEBAD1E003600340034004600440038003500430030003000300030003000300044004562566188EBB811E39CDC837E4C6979E618B42161110062566188EBB811E39CDD837E4C6979E60100 1529245: Jun 5 11:18:42.150: 1529246: Jun 5 11:18:42.150: timer (0x63AC4A90)starts - delay (3000) 1529247: Jun 5 11:18:42.150: cch323_percall_ras_sm(0x63AC45A0): changing to new state CCH323_RAS_STATE_DRQ 1529248: Jun 5 11:18:42.150: Call Active Playout Mode: 1,Init 60, Min 40, Max 200 1529249: Jun 5 11:18:42.150: Call History Playout Mode: 1,Init 60, Min 40, Max 200 1529250: Jun 5 11:18:42.150: cc_api_icpif: expect factor = 0 1529251: Jun 5 11:18:42.150: g113_calculate_impairment (delay=87, loss=0), Io=0 Iq=0 Idte=0 Idd=0 Ie=0 Itot=0 1529252: Jun 5 11:18:42.154: cc_api_call_disconnect_done(vdbPtr=0x6364BA90, callID=0x720B7, disp=0, tag=0x0) 1529253: Jun 5 11:18:42.154: cch323_ct_main: SOCK 1 Event 0x1 1529254: Jun 5 11:18:42.154: [1]towner_data=0x6394A84C, len=3, msgPtr=0x639A4CE0 1529255: Jun 5 11:18:42.154: cch323_gw_process_read_socket: received msg for RAS 1529256: Jun 5 11:18:42.154: RAS INCOMING ENCODE BUFFER::= 40EBAD 1529257: Jun 5 11:18:42.154: 1529258: Jun 5 11:18:42.154: RAS INCOMING PDU ::= value RasMessage ::= disengageConfirm : { requestSeqNum 60334 } 1529259: Jun 5 11:18:42.154: cch323_ras_handle_recv_msg: received msg of type DCF_CHOSEN 1529260: Jun 5 11:18:42.154: cch323_ras_rcv_dcf: current active call count is 1 1529261: Jun 5 11:18:42.154: timer(0x63AC4A90) stops 1529262: Jun 5 11:18:42.154: cch323_percall_ras_sm(0x63AC45A0): received event CCH323_RAS_EVENT_DCF while at CCH323_RAS_STATE_DRQ state 1529263: Jun 5 11:18:42.154: H.225 SM: process event H225_EVENT_RAS_SUCCESS, for callID 720B8 1529264: Jun 5 11:18:42.154: cch323_run_h225_sm: received event H225_EVENT_RAS_SUCCESS while at state H225_WAIT_FOR_DRQ 1529265: Jun 5 11:18:42.154: H.225 SM: changing from H225_WAIT_FOR_DRQ state to H225_WAIT_REL_COMP state for callID 720B8 1529266: Jun 5 11:18:42.154: cch323_percall_ras_sm(0x63AC45A0): changing to new state CCH323_RAS_STATE_IDLE 1529267: Jun 5 11:18:42.154: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(467127), disp(0) 1529268: Jun 5 11:18:42.154: cid(467127)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(0)in(1)fDest(1) 1529269: Jun 5 11:18:42.154: -cid2(467128)st2(SSA_CS_DISCONNECTING)oldst2(SSA_CS_CONFERENCED_ALERT) 1529270: Jun 5 11:18:42.154: ssaDisconnectDone RX 1529271: Jun 5 11:18:42.162: cch323_ct_main: SOCK 4 Event 0x1 1529272: Jun 5 11:18:42.162: [1]towner_data=0x629E9684, len=49, msgPtr=0x63A87ED0 1529273: Jun 5 11:18:42.162: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B4 Message Type : 0x5A: RELEASE_COMP Cause: Length Of IE=2 Data 8090 User-User: Length Of IE=33 Data 052580060008914A000201110062566188EBB811E39CDD837E4C6979E606800100 1529274: Jun 5 11:18:42.162: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A000201110062566188EBB811E39CDD837E4C6979E606800100 1529275: Jun 5 11:18:42.162: 1529276: Jun 5 11:18:42.162: H225.0 INCOMING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body releaseComplete : { protocolIdentifier { 0 0 8 2250 0 2 } callIdentifier { guid '62566188EBB811E39CDD837E4C6979E6'H } } h245Tunneling FALSE } } 1529277: Jun 5 11:18:42.166: cch323_h225_receiver: received msg of type RELEASEIND_CHOSEN 1529278: Jun 5 11:18:42.166: timer(0x63AC4948) stops 1529279: Jun 5 11:18:42.166: src address = 10.10.15.233 of RELEASEIND_CHOSEN 1529280: Jun 5 11:18:42.166: dest address = 10.10.15.234 of RELEASEIND_CHOSEN 1529281: Jun 5 11:18:42.166: cch323_run_h225_sm: received event H225_EVENT_RELEASE_IND while at state H225_WAIT_REL_COMP 1529282: Jun 5 11:18:42.166: timer(0x63AC4968) stops 1529283: Jun 5 11:18:42.166: H.225 SM: changing from H225_WAIT_REL_COMP state to H225_IDLE state for callID 720B8 cch323_call_generic_cleanup: for callID 720B8 disconnected flag 1 1529284: Jun 5 11:18:42.166: timer(0x63AC4B20) stops 1529285: Jun 5 11:18:42.166: timer(0x63AC4B40) stops 1529286: Jun 5 11:18:42.166: timer(0x63AC4BA0) stops 1529287: Jun 5 11:18:42.166: timer(0x63AC4B60) stops 1529288: Jun 5 11:18:42.166: timer(0x63AC4B80) stops 1529289: Jun 5 11:18:42.166: timer(0x63AC4BC0) stops 1529290: Jun 5 11:18:42.166: timer(0x63AC4D70) stops 1529291: Jun 5 11:18:42.166: timer(0x63AC4A90) stops 1529292: Jun 5 11:18:42.166: timer(0x63AC4AB0) stops 1529293: Jun 5 11:18:42.166: timer(0x63AC4DF8) stops 1529294: Jun 5 11:18:42.166: timer(0x63AC4E38) stops 1529295: Jun 5 11:18:42.166: timer(0x63AC4948) stops 1529296: Jun 5 11:18:42.166: timer(0x63AC4B20) stops 1529297: Jun 5 11:18:42.166: timer(0x63AC4B40) stops 1529298: Jun 5 11:18:42.166: timer(0x63AC4B60) stops 1529299: Jun 5 11:18:42.166: timer(0x63AC4B80) stops 1529300: Jun 5 11:18:42.166: timer(0x63AC4D70) stops 1529301: Jun 5 11:18:42.166: timer(0x63AC4A90) stops 1529302: Jun 5 11:18:42.166: timer(0x63AC4AB0) stops 1529303: Jun 5 11:18:42.166: timer(0x63AC4DF8) stops 1529304: Jun 5 11:18:42.166: timer(0x63AC4E38) stops 1529305: Jun 5 11:18:42.166: cc_api_call_disconnect_done(vdbPtr=0x62F22A24, callID=0x720B8, disp=0, tag=0x0) 1529306: Jun 5 11:18:42.166: cch323_call_generic_cleanup: freeing ccb (0x63AC45A0) 1529307: Jun 5 11:18:42.166: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(467128), disp(0) 1529308: Jun 5 11:18:42.166: cid(467128)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONFERENCED_ALERT)cfid(-1)csize(1)in(0)fDest(0) 1529309: Jun 5 11:18:42.166: ssaDisconnectDone RXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRX 1529310: Jun 5 11:18:47.138: RECEIVE: 1529311: Jun 5 11:18:47.138: SR: ssrc=0x1CA2090C sr_ntp_h=0xD73A9857 sr_ntp_l=0x2357A535 sr_timestamp=0x0 sr_npackets=300 sr_nbytes=50400 1529312: Jun 5 11:18:47.138: RR: ssrc=0x10890FD1 rr_loss=0x0 rr_ehsr=3894 rr_jitter=0 rr_lsr=0x9853EB44 rr_dlsr=0x33851 1529313: Jun 5 11:18:47.138: SDES: ssrc=0x1CA2090C name=1 len=16 data=0.0.0@20.20.20.20 1529314: Jun 5 11:18:47.138: RTT: udp=0xF39A7EC rtcp=0xF39A7F4 ssrc=0x10890FD1 1529315: Jun 5 11:18:47.138: RTT: now_mid_32=0x9857243D rr->rr_dlsr=0x33851 rr->rr_lsr=0x9853EB44 rtt=2 (ms)RXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRX 1529316: Jun 5 11:18:53.386: RECEIVE: 1529317: Jun 5 11:18:53.386: SR: ssrc=0x1CA2090C sr_ntp_h=0xD73A985D sr_ntp_l=0x62ADB6AF sr_timestamp=0x0 sr_npackets=613 sr_nbytes=102984 1529318: Jun 5 11:18:53.386: RR: ssrc=0x10890FD1 rr_loss=0x0 rr_ehsr=4207 rr_jitter=0 rr_lsr=0x985BDEFA rr_dlsr=0x18418 1529319: Jun 5 11:18:53.386: SDES: ssrc=0x1CA2090C name=1 len=16 data=0.0.0@20.20.20.20 1529320: Jun 5 11:18:53.386: RTT: udp=0xF398EEC rtcp=0xF398EF4 ssrc=0x10890FD1 1529321: Jun 5 11:18:53.386: RTT: now_mid_32=0x985D6369 rr->rr_dlsr=0x18418 rr->rr_lsr=0x985BDEFA rtt=1 (ms)RXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXRXXRRXRXRXRXRXRXRXRXRX 1529322: Jun 5 11:18:56.158: cch323_ct_main: SOCK 3 Event 0x1 1529323: Jun 5 11:18:56.158: [1]towner_data=0x63A88038, len=6, msgPtr=0x63A84D6C 1529324: Jun 5 11:18:56.158: cch323_gw_process_read_socket: received msg for H.245 1529325: Jun 5 11:18:56.158: H245 MSC INCOMING ENCODE BUFFER::= 6300 1529326: Jun 5 11:18:56.158: 1529327: Jun 5 11:18:56.158: H245 MSC INCOMING PDU ::= value MultimediaSystemControlMessage ::= indication : terminalCapabilitySetRelease : { placeholder } Set new event H245_EVENT_CAP_REL, for callID 720BA 1529328: Jun 5 11:18:56.158: cch323_run_h245_cap_in_sm: received event H245_EVENT_CAP_REL while at state H245_CAP_IDLE 1529329: Jun 5 11:18:56.158: Timer[CCH323_H245_CAP_TIMER] expired Set new event H245_EVENT_CAP_TIMER_EXPIRY, for callID 720BA 1529330: Jun 5 11:18:56.158: cch323_run_h245_cap_out_sm: received event H245_EVENT_CAP_TIMER_EXPIRY while at state H245_CAP_WAIT 1529331: Jun 5 11:18:56.158: H245 MSC OUTGOING PDU ::= value MultimediaSystemControlMessage ::= indication : terminalCapabilitySetRelease : { placeholder } 1529332: Jun 5 11:18:56.158: H245 MSC OUTGOING ENCODE BUFFER::= 6300 1529333: Jun 5 11:18:56.158: 1529334: Jun 5 11:18:56.158: CAP_TIMER_EXPIRY 1529335: Jun 5 11:18:56.158: timer(0x63A885F8) stops 1529336: Jun 5 11:18:56.158: H.225 SM: process event H225_EVENT_H245_FAILED, for callID 720BA 1529337: Jun 5 11:18:56.158: cch323_run_h225_sm: received event H225_EVENT_H245_FAILED while at state H225_WAIT_FOR_H245 1529338: Jun 5 11:18:56.158: src address = 10.10.10.10 of h225TerminateRequest 1529339: Jun 5 11:18:56.158: dest address = 20.20.20.20 of h225TerminateRequest 1529340: Jun 5 11:18:56.158: H225.0 OUTGOING PDU ::= XR value H323_UserInformation ::= { h323-uu-pdu { h323-message-body releaseComplete : { protocolIdentifier { 0 0 8 2250 0 2 } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } h245Tunneling FALSE } } 1529341: Jun 5 11:18:56.158: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00020111006C80510AEBB811E39CE1837E4C6979E606800100 1529342: Jun 5 11:18:56.158: Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x18B5 Message Type : 0x5A: RELEASE_COMP Cause: Length Of IE=2 Data 80BF User-User: Length Of IE=33 Data 052580060008914A00020111006C80510AEBB811E39CE1837E4C6979E606800100 1529343: Jun 5 11:18:56.158: cc_api_call_disconnected(vdbPtr=0x62F22A24, callID=0x720BA, cause=0x3F) 1529344: Jun 5 11:18:56.158: changing from H245_CAP_WAIT state to H245_CAP_NONE state 1529345: Jun 5 11:18:56.158: sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(467130), disp(0) 1529346: Jun 5 11:18:56.158: cid(467130)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED) oldst(SSA_CS_ACTIVE)cfid(195454)csize(0)in(0)fDest(0) 1529347: Jun 5 11:18:56.158: -cid2(467129)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_ACTIVE) 1529348: Jun 5 11:18:56.158: ssa: Disconnected cid(467130) state(5) cause(0x3F) 1529349: Jun 5 11:18:56.158: ccConferenceDestroy (confID=0x2FB7E, tag=0x0) 1529350: Jun 5 11:18:56.162: cc_api_bridge_drop_done (confID=0x2FB7E, srcIF=0x62F22A24, srcCallID=0x720BA, dstCallID=0x720B9, disposition=0 tag=0x0) 1529351: Jun 5 11:18:56.162: cc_api_bridge_drop_done (confID=0x2FB7E, srcIF=0x6364BA90, srcCallID=0x720B9, dstCallID=0x720BA, disposition=0 tag=0x0) 1529352: Jun 5 11:18:56.162: cch323_ct_main: SOCK 2 Event 0x1 1529353: Jun 5 11:18:56.162: [1]towner_data=0x63CDCCA4, len=50, msgPtr=0x639FE0D4 1529354: Jun 5 11:18:56.162: cch323_gw_process_read_socket: received msg for H.225 Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x98B5 Message Type : 0x5A: RELEASE_COMP Cause: Length Of IE=2 Data 80A9 User-User: Length Of IE=34 Data 052580060008914A0004110011006C80510AEBB811E39CE1837E4C6979E610800100 1529355: Jun 5 11:18:56.162: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A0004110011006C80510AEBB811E39CE1837E4C6979E610800100 1529356: Jun 5 11:18:56.162: 1529357: Jun 5 11:18:56.162: H225.0 INCOMING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body releaseComplete : { protocolIdentifier { 0 0 8 2250 0 4 } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } h245Tunneling FALSE } } 1529358: Jun 5 11:18:56.162: cch323_h225_receiver: received msg of type RELEASEIND_CHOSEN 1529359: Jun 5 11:18:56.162: timer(0x63A883E0) stops 1529360: Jun 5 11:18:56.162: cch323_h225_release_ind: disconnect cause 41 1529361: Jun 5 11:18:56.162: src address = 10.10.10.10 of RELEASEIND_CHOSEN 1529362: Jun 5 11:18:56.162: dest address = 20.20.20.20 of RELEASEIND_CHOSEN 1529363: Jun 5 11:18:56.162: cch323_run_h225_sm: received event H225_EVENT_RELEASE_IND while at state H225_WAIT_FOR_H245 1529364: Jun 5 11:18:56.162: cch323_ct_main: SOCK 3 Event 0x1 1529365: Jun 5 11:18:56.162: [1]towner_data=0x63A88038, len=6, msgPtr=0x63C43780 1529366: Jun 5 11:18:56.162: cch323_gw_process_read_socket: received msg for H.245 1529367: Jun 5 11:18:56.162: H245 MSC INCOMING ENCODE BUFFER::= 6200 1529368: Jun 5 11:18:56.162: 1529369: Jun 5 11:18:56.162: H245 MSC INCOMING PDU ::= value MultimediaSystemControlMessage ::= indication : masterSlaveDeterminationRelease : { placeholder } 1529370: Jun 5 11:18:56.162: Changing to new event H245_EVENT_MS_RELEASE_IND, for callID 720BA 1529371: Jun 5 11:18:56.162: cch323_run_h245_ms_sm: received event H245_EVENT_MS_RELEASE_IND while at state H245_MS_INCOMING_WAIT 1529372: Jun 5 11:18:56.162: timer(0x63A885D8) stopsError B, Recvd MSD Release ! Reject_ind to APPL! 1529373: Jun 5 11:18:56.162: changing from H245_MS_INCOMING_WAIT state to H245_MS_NONE state 1529374: Jun 5 11:18:56.162: sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(467129), disp(0) 1529375: Jun 5 11:18:56.162: cid(467129)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE) oldst(SSA_CS_ACTIVE)cfid(195454)csize(0)in(1)fDest(1) 1529376: Jun 5 11:18:56.162: -cid2(467130)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_ACTIVE) 1529377: Jun 5 11:18:56.162: ssaConfDestroyDone 1529378: Jun 5 11:18:56.162: ccCallDisconnect (callID=0x720B9, cause=0x3F tag=0x0) 1529379: Jun 5 11:18:56.162: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3F 1529380: Jun 5 11:18:56.162: ccCallDisconnect (callID=0x720BA, cause=0x3F tag=0x0) 1529381: Jun 5 11:18:56.162: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x3Fcch323_do_call_disconnect: gw_id=1, callID=467130 1529382: Jun 5 11:18:56.162: H.225 SM: process event H225_EVENT_RELEASE, for callID 720BA 1529383: Jun 5 11:18:56.162: cch323_run_h225_sm: received event H225_EVENT_RELEASE while at state H225_WAIT_FOR_H245 1529384: Jun 5 11:18:56.162: H.225 SM: changing from H225_WAIT_FOR_H245 state to H225_WAIT_FOR_DRQ state for callID 720BA 1529385: Jun 5 11:18:56.162: src address = 10.10.10.10 of h225TerminateRequest 1529386: Jun 5 11:18:56.162: dest address = 20.20.20.20 of h225TerminateRequest 1529387: Jun 5 11:18:56.162: H225.0 OUTGOING PDU ::= value H323_UserInformation ::= { h323-uu-pdu { h323-message-body releaseComplete : { protocolIdentifier { 0 0 8 2250 0 2 } callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } } h245Tunneling FALSE } } 1529388: Jun 5 11:18:56.166: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00020111006C80510AEBB811E39CE1837E4C6979E606800100 1529389: Jun 5 11:18:56.166: Q931 Message IE Decodes Protocol Discriminator : 0x08 CRV Length : 2 CRV Value : 0x18B5 Message Type : 0x5A: RELEASE_COMP Cause: Length Of IE=2 Data 80BF User-User: Length Of IE=33 Data 052580060008914A00020111006C80510AEBB811E39CE1837E4C6979E606800100 1529390: Jun 5 11:18:56.166: timer(0x63A88528) stops 1529391: Jun 5 11:18:56.166: cch323_percall_ras_sm(0x63A88038): received event CCH323_RAS_EVENT_CALLDISC while at CCH323_RAS_STATE_ACTIVE state 1529392: Jun 5 11:18:56.166: timer(0x63A88548) stops 1529393: Jun 5 11:18:56.166: RAS OUTGOING PDU ::= value RasMessage ::= disengageRequest : { requestSeqNum 60335 endpointIdentifier {"644FD85C000000DE"} conferenceID '6C80510AEBB811E39CE0837E4C6979E6'H callReferenceValue 6325 disengageReason normalDrop : NULL callIdentifier { guid '6C80510AEBB811E39CE1837E4C6979E6'H } answeredCall FALSE } 1529394: Jun 5 11:18:56.166: RAS OUTGOING ENCODE BUFFER::= 3EEBAE1E00360034003400460044003800350043003000300030003000300030004400456C80510AEBB811E39CE0837E4C6979E618B5216111006C80510AEBB811E39CE1837E4C6979E60100 1529395: Jun 5 11:18:56.166: 1529396: Jun 5 11:18:56.166: timer (0x63A88528)starts - delay (3000) 1529397: Jun 5 11:18:56.166: cch323_percall_ras_sm(0x63A88038): changing to new state CCH323_RAS_STATE_DRQ 1529398: Jun 5 11:18:56.166: Call Active Playout Mode: 1,Init 60, Min 40, Max 200 1529399: Jun 5 11:18:56.166: Call History Playout Mode: 1,Init 60, Min 40, Max 200 1529400: Jun 5 11:18:56.166: cc_api_icpif: expect factor = 0 1529401: Jun 5 11:18:56.166: g113_calculate_impairment (delay=84, loss=0), Io=0 Iq=0 Idte=0 Idd=0 Ie=0 Itot=0 1529402: Jun 5 11:18:56.166: cch323_ct_main: SOCK 1 Event 0x1 1529403: Jun 5 11:18:56.166: [1]towner_data=0x6394A84C, len=3, msgPtr=0x63B56184 1529404: Jun 5 11:18:56.166: cch323_gw_process_read_socket: received msg for RAS 1529405: Jun 5 11:18:56.166: RAS INCOMING ENCODE BUFFER::= 40EBAE 1529406: Jun 5 11:18:56.166: 1529407: Jun 5 11:18:56.166: RAS INCOMING PDU ::= value RasMessage ::= disengageConfirm : { requestSeqNum 60335 } 1529408: Jun 5 11:18:56.170: cch323_ras_handle_recv_msg: received msg of type DCF_CHOSEN 1529409: Jun 5 11:18:56.170: cch323_ras_rcv_dcf: current active call count is 0 1529410: Jun 5 11:18:56.170: timer(0x63A88528) stops 1529411: Jun 5 11:18:56.170: cch323_percall_ras_sm(0x63A88038): received event CCH323_RAS_EVENT_DCF while at CCH323_RAS_STATE_DRQ state 1529412: Jun 5 11:18:56.170: H.225 SM: process event H225_EVENT_RAS_SUCCESS, for callID 720BA 1529413: Jun 5 11:18:56.170: cch323_run_h225_sm: received event H225_EVENT_RAS_SUCCESS while at state H225_WAIT_FOR_DRQ 1529414: Jun 5 11:18:56.170: H.225 SM: changing from H225_WAIT_FOR_DRQ state to H225_IDLE state for callID 720BA cch323_call_generic_cleanup: for callID 720BA disconnected flag 1 1529415: Jun 5 11:18:56.170: timer(0x63A885B8) stops 1529416: Jun 5 11:18:56.170: timer(0x63A885D8) stops 1529417: Jun 5 11:18:56.170: timer(0x63A88638) stops 1529418: Jun 5 11:18:56.170: timer(0x63A885F8) stops 1529419: Jun 5 11:18:56.170: timer(0x63A88618) stops 1529420: Jun 5 11:18:56.170: timer(0x63A88658) stops 1529421: Jun 5 11:18:56.170: timer(0x63A88808) stops 1529422: Jun 5 11:18:56.170: timer(0x63A88528) stops 1529423: Jun 5 11:18:56.170: timer(0x63A88548) stops 1529424: Jun 5 11:18:56.170: timer(0x63A88890) stops 1529425: Jun 5 11:18:56.170: timer(0x63A888D0) stops 1529426: Jun 5 11:18:56.170: timer(0x63A883E0) stops 1529427: Jun 5 11:18:56.170: timer(0x63A885B8) stops 1529428: Jun 5 11:18:56.170: timer(0x63A885D8) stops 1529429: Jun 5 11:18:56.170: timer(0x63A885F8) stops 1529430: Jun 5 11:18:56.170: timer(0x63A88618) stops 1529431: Jun 5 11:18:56.170: timer(0x63A88808) stops 1529432: Jun 5 11:18:56.170: timer(0x63A88528) stops 1529433: Jun 5 11:18:56.170: timer(0x63A88548) stops 1529434: Jun 5 11:18:56.170: timer(0x63A88890) stops 1529435: Jun 5 11:18:56.170: timer(0x63A888D0) stops 1529436: Jun 5 11:18:56.170: cc_api_call_disconnect_done(vdbPtr=0x62F22A24, callID=0x720BA, disp=0, tag=0x0) 1529437: Jun 5 11:18:56.170: cch323_call_generic_cleanup: freeing ccb (0x63A88038) 1529438: Jun 5 11:18:56.170: cch323_percall_ras_sm(0x63A88038): changing to new state CCH323_RAS_STATE_IDLE 1529439: Jun 5 11:18:56.170: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(467130), disp(0) 1529440: Jun 5 11:18:56.170: cid(467130)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_ACTIVE)cfid(-1)csize(0)in(0)fDest(0) 1529441: Jun 5 11:18:56.170: -cid2(467129)st2(SSA_CS_DISCONNECTING)oldst2(SSA_CS_CONF_DESTROYING) 1529442: Jun 5 11:18:56.170: ssaDisconnectDone 1529443: Jun 5 11:18:56.170: cc_api_call_disconnect_done(vdbPtr=0x6364BA90, callID=0x720B9, disp=0, tag=0x0) 1529444: Jun 5 11:18:56.170: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(467129), disp(0) 1529445: Jun 5 11:18:56.170: cid(467129)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(1)in(1)fDest(1) 1529446: Jun 5 11:18:56.170: ssaDisconnectDone