one way Voice communication

I have the following setup

CUCM -H323 gateway-h323 gateway-PBX. I can successfully make a call from PBX to CUCM but when I make test calls from CUCM to PBX, although the PBX rings twice, there is no ringback on the IP phone and give busy tone.

on doing debug cch323 all, I get disconnect cause 47. I think all devices have been configured correctly. !'m I missing something?

Comments

  • Try this... make the call from the IP phone and hit the information button twice to get the call stats.  Check the codec.  I've had a couple of weird issues where the phone uses g.722 even if it is shut off at the phone level.

  • Just tried that. codec on the IP phone is g.711u which is what's been configured all the way to the pbx. any other ideas?

  • I don't understand what your problem is!!! If it is not mismatching Codec Issue, and it is also not lack of Bandwidth, it is rather strange if all is configured right though. For "no Ringback" issues this link may be useful http://www.cisco.com/en/US/tech/tk1077/technologies_tech_note09186a0080094c33.shtml

  • Sorry to be stuck on the codec thing, but just to be clear... when you hit the info button and get the stats, do you see G.711u in both the Rx and Tx?  If one of them is blank you have a mismatch.  You may also want to do this on the router to make sure it isn't doing g.729: 

    make a voip dial-peer like this:

    dial-peer voice 10 voip

    destination-pattern .

    codec g711u

     

    Someone correct me if I'm wrong, but I believe if you don't have an inbound matched voip dial-peer it will use the internal null dial-peer with default settings (i think it is g729 by default).

     

  • it says g711u on both Tx and Rx. I am definitely getting dial-peer matches and not the null dial-peer on the routers. My dial-peers have codecs g711 configured. On the gateway attached to the CUCM, debug cch323 all is below. Maybe you can stop the issue. thanks

    Aug 19 15:21:15.629: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 0 Event 0x1
    Aug 19 15:21:15.629: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x63EFBECC) is in use
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 7 Event 0x1
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x65501630, len=225, msgPtr=0x656F4EA4
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: callingNumber[8086385824] calledNumber[1379]
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: ---- calling IE present
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: ====== PI = 0
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: Receive: infoXCap 0
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: Receive: infoXCap ccb 0
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/setup_ind: Receive bearer cap infoXRate 16, rateMult 0
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry
    Aug 19 15:21:15.633: //72907/002D2CEEC422/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number
    Aug 19 15:21:15.633: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/setup_ind:
    setup_ind: is_overlap = 0, info_complete = 0

    Aug 19 15:21:15.637: //-1/xxxxxxxxxxxx/H323/cch323_is_preauth_reqd: FALSE
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/setup_ind: Call Manager detected
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 10.1.100.1; dest address = 10.1.100.10
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE
    Aug 19 15:21:15.637: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/common_idle_setupInd_hdlr: full match is found
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_set_preferred_codec: Using Voice Class Codec, tag = 1
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_set_peer: peer: 654980E4, peer->voice_peer_tag: 16A8, ccb: 63EFBECC
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_set_peer: ccb h245addr bits = system[0x0007]
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_set_peer: ccb ccm bits = system[0x0028]
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_SETUP state
    Aug 19 15:21:15.637: //72907/002D2CEEC422/H323/cch323_set_h245_state_mc_mode_incoming: h245 state m/c mode=0x10F, h323_ctl=0x2F
    Aug 19 15:21:15.637: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_create_incoming_callinfo_block: peer 63B7272C, voice_peer_tag 5800, ccb: 63EFBECC
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_create_incoming_callinfo_block: Calling Party is CCM
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg: (for generic_setup_treatment): raw message is 53 bytes:
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 33 9E 01 00 03 67 74 64 00 00 00 28 49 41 4D
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 0D 0A 47 45 41 2C 74 72 73 33 2C 30 30 2C 31
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 79 2C 79 2C 31 2C 38 30 38 36 33 38 35 38 32
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     34 0D 0A 0D 0A
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_update_call_info_from_dial_peer: Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
    Aug 19 15:21:15.641: //72907/002D2CEEC422/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: callID=72908
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=72907
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x6578E190
    Aug 19 15:21:15.645: //-1/xxxxxxxxxxxx/H323/cch323_set_mode: callID=72907, flow Mode=1 spi_mode=0x1
    Aug 19 15:21:15.645: //72907/002D2CEEC422/H323/cch323_do_set_dialpeer_tag: gw_id=1
    Aug 19 15:21:15.645: //72907/002D2CEEC422/H323/cch323_do_call_proceeding: gw_id=1
    Aug 19 15:21:15.649: //72907/002D2CEEC422/H323/cch323_do_call_proceeding: set_mode NOT called yet...saved deferred CALL_PROC
    Aug 19 15:21:15.649: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x64B56904) is in use
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_call_setup: gw_id=1
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_call_setup: Incoming Call From CCM
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_call_setup: Inserted ccb
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: Entry
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: Have peer
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: faxrate[28] proto[3] bitmask[10082] t38_inhibit[0] global_fax[0] t38fax[0]
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_pref_codec_list: First preferred codec(bytes)=5(160)
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: Preferred codec set to G711_ULAW_64K with Bytes = 160
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: Flow Mode set to FLOW_THROUGH
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_get_peer_info: peer: 656893B0, peer->voice_peer_tag: E1, ccb: 64B56904
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h323_parms_from_dp: ccb h245addr bits = system[0x0007]
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h323_parms_from_dp: ccb ccm bits = system[0x0028]
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h245_state_mc_mode_outgoing: call_spi_mode = 1
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h245_state_mc_mode_outgoing: h245 state m/c mode=0x1AF0, h323_ctl=0x2F
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_update_setup_callinfo: callingNumber = 8086385824
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_update_setup_callinfo: called_oct3 = 0x80, calling_oct3 = 0x1, calling_oct3a = 0x81
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h323_control_options_outgoing: h245 sm mode = 6896
    Aug 19 15:21:15.649: //72908/002D2CEEC422/H323/cch323_set_h323_control_options_outgoing: h323_ctl=0x2F
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/cch323_call_setup: No account/pin number available
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/cch323_call_setup_normal: Entry
    Aug 19 15:21:15.653: //-1/xxxxxxxxxxxx/H323/cch323_h225FD_Print: fd [8] Accepts newcall
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
    Aug 19 15:21:15.653: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xB internal event to H225 SM
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/check_qos_and_send_setup: Setup ccb 0x64B56904
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/check_qos_and_send_setup: Sending setup h323 slow rsvp
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/send_setup: Entry
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/generic_send_setup: Entry
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/generic_send_setup:
    generic_send_setup: is_overlap = 0, info_complete = 0
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/generic_send_setup: sending calling IE
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/generic_send_setup: ====== PI = 0
    Aug 19 15:21:15.653: //72908/002D2CEEC422/H323/cch323_build_qosInfo: ccb=0x64B56904. msg_type=1
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_build_qosInfo: media_ip_addr=0x0, remote_qos_video=0, audio_lport=0, audio_rport=0, video=0, video_lport=0, video_rport=0, h245_lport=0, h245_rport=0, remote_qos_audio_bw=0, remote_qos_video_bw=0
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/generic_send_setup: Send infoXCap=128, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy raw message data to nonStdInfo
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy tunnelled GTD/ISUP content
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for cch323_h225_copy_send_rawMsg): raw message is 53 bytes:
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 33 9E 01 00 03 67 74 64 00 00 00 28 49 41 4D
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 0D 0A 47 45 41 2C 74 72 73 33 2C 30 30 2C 31
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 79 2C 79 2C 31 2C 38 30 38 36 33 38 35 38 32
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     34 0D 0A 0D 0A
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/generic_send_setup: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:15.657: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_SETUP state
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/cch323_process_set_mode: Setting inbound leg mode flags to 0x1AF0, flow-mode to FLOW_THROUGH
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/cch323_process_set_mode: Sending deferred CALL_PROC
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/cch323_do_call_proceeding: gw_id=1
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/cch323_do_call_proceeding: set_mode called so we can proceed with CALLPROC
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state
    Aug 19 15:21:15.661: //72907/002D2CEEC422/H323/generic_send_callproc: ====== PI = 0
    Aug 19 15:21:15.889: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1
    Aug 19 15:21:15.889: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x655015C0, len=94, msgPtr=0x655012B0
    Aug 19 15:21:15.889: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:15.889: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type CALLPROCIND_CHOSEN
    Aug 19 15:21:15.889: //72908/002D2CEEC422/H323/callproc_ind: Entry
    Aug 19 15:21:15.889: //-1/xxxxxxxxxxxx/H323/cch323_decode_qos_info: media_ip_addr: 0x0, remote_qos_video: 0, audio_lport: 0, audio_rport: 0, video: 0, video_lport: 0, video_rport: 0, remote qos audio bw: 0, remote qos video bw: 0
    Aug 19 15:21:15.889: //72908/002D2CEEC422/H323/callproc_ind: ====== PI = 0
    Aug 19 15:21:15.889: //72908/002D2CEEC422/H323/cch323_h225_process_h245_tunneling: Other end of call is dropping tunneling
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/cch323_h225_receiver: CALLPROCIND_CHOSEN: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_CALLPROC_IND while at state H225_SETUP
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/callproc_notify: Peer not ready so not starting TCP
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/generic_callproc_notify: Entry
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/h245_address_ind: ev=0
    Aug 19 15:21:15.893: //-1/xxxxxxxxxxxx/H323/h245_address_ind: Sending event
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/cch323_h245_addr_notify: Sending event CC_EV_H245_ADDR, ev 0
    Aug 19 15:21:15.893: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=72907
    Aug 19 15:21:15.893: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_ADDR received, ADDR event 0
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/callproc_notify: Peer not ready so not starting TCP
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/generic_callproc_notify: Entry
    Aug 19 15:21:15.893: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state
    Aug 19 15:21:15.893: //-1/xxxxxxxxxxxx/H323/cch323_start_h245: entry
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_LISTEN_REQ_EVENT
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: state=0, event=0, ccb=63EFBECC, listen state=0
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: H245_LISTEN: Received event H245_LISTEN_REQ_EVENT while at H245_NONE state
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_WAITING state
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/cch323_start_h245: Drop to Slow w/o sending Facility
    Aug 19 15:21:15.893: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x16 internal event to H225 SM
    Aug 19 15:21:15.893: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_FACIL_SWITCH_H245 while at state H225_CALLPROC
    Aug 19 15:21:15.973: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1
    Aug 19 15:21:15.973: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x655015C0, len=171, msgPtr=0x63E5462C
    Aug 19 15:21:15.973: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:15.973: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type PROGRESSIND_CHOSEN
    Aug 19 15:21:15.973: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for progress_ind): raw message is 49 bytes:
    Aug 19 15:21:15.973: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     18 03 A9 83 81 1E 02 81 81 1C 26 9E 81 00 03 67
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     74 64 00 00 00 1B 43 50 47 2C 0D 0A 50 52 4E 2C
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     69 73 64 6E 2A 2C 2C 51 53 49 47 2A 2C 0D 0A 0D
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     0A
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/progress_ind: ======= PI = 1 cause 0
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_h225_receiver: PROGRESSIND_CHOSEN: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_PROGRESS_IND while at state H225_CALLPROC
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_PROGRESS state
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/h245_address_ind: ev=0
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/h245_address_ind: Sending event
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cch323_h245_addr_notify: Sending event CC_EV_H245_ADDR, ev 0
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=72907
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_ADDR received, ADDR event 0
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/cut_progress_notify: Entry
    Aug 19 15:21:15.977: //72908/002D2CEEC422/H323/generic_cut_progress_notify: ====== PI = 1, cause = 0
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/cch323_start_h245: entry
    Aug 19 15:21:15.977: //72907/002D2CEEC422/H323/cch323_start_h245: Drop to Slow w/o sending Facility
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x16 internal event to H225 SM
    Aug 19 15:21:15.977: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_FACIL_SWITCH_H245 while at state H225_CALLPROC
    Aug 19 15:21:15.977: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x22 internal event to H225 SM
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_PROGRESS while at state H225_CALLPROC
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_PROGRESS state
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/send_generic_progress: ====== PI = 1, cause = 0
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy raw message data to nonStdInfo
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy tunnelled GTD/ISUP content
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg: (for cch323_h225_copy_send_rawMsg): raw message is 49 bytes:
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     18 03 A9 83 81 1E 02 81 81 1C 26 9E 81 00 03 67
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     74 64 00 00 00 1B 43 50 47 2C 0D 0A 50 52 4E 2C
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     69 73 64 6E 2A 2C 2C 51 53 49 47 2A 2C 0D 0A 0D
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     0A
    Aug 19 15:21:15.981: //72907/002D2CEEC422/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb
    Aug 19 15:21:15.981: //72908/002D2CEEC422/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb
    Aug 19 15:21:15.985: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 9 Event 0x1
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/cch323_h245_channel_established_ind: Using fd=10 to send msgs
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_ESTABLISHED_EVENT
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: state=1, event=2, ccb=63EFBECC, listen state=1
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: H245_LISTEN: Received event H245_ESTABLISHED_EVENT while at H245_WAITING state
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/cch323_h245_set_new_state: Changing from H245_WAITING state to H245_CONNECTED state
    Aug 19 15:21:15.989: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2 internal event to H245 IWF SM
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_IDLE
    Aug 19 15:21:15.989: //72907/002D2CEEC422/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_H245_CONNECTED state
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x655015C0, len=216, msgPtr=0x65310640
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type ALERTIND_CHOSEN
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for alert_ind): raw message is 91 bytes:
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 25 91 AA 06 80 01 00 82 01 00 8B 01 00 A1 17
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     02 01 01 06 04 2B 0C 09 01 80 0C 41 64 65 20 45
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     6C 65 67 62 65 64 65 1E 02 81 88 1E 02 81 82 9D
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     32 01 81 1C 26 9E 81 00 03 67 74 64 00 00 00 1B
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     41 43 4D 2C 0D 0A 50 52 4E 2C 69 73 64 6E 2A 2C
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 51 53 49 47 2A 2C 0D 0A 0D 0A
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/alert_ind: ====== PI = 8
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_h225_receiver: ALERTIND_CHOSEN: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_ALERT_IND while at state H225_PROGRESS
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/h245_address_ind: ev=0
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/h245_address_ind: Sending event
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_h245_addr_notify: Sending event CC_EV_H245_ADDR, ev 0
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=72907
    Aug 19 15:21:16.645: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_ADDR received, ADDR event 0
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for generic_alert_notify): raw message is 91 bytes:
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 25 91 AA 06 80 01 00 82 01 00 8B 01 00 A1 17
    Aug 19 15:21:16.645: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     02 01 01 06 04 2B 0C 09 01 80 0C 41 64 65 20 45
    Aug 19 15:21:16.649: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     6C 65 67 62 65 64 65 1E 02 81 88 1E 02 81 82 9D
    Aug 19 15:21:16.649: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     32 01 81 1C 26 9E 81 00 03 67 74 64 00 00 00 1B
    Aug 19 15:21:16.649: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     41 43 4D 2C 0D 0A 50 52 4E 2C 69 73 64 6E 2A 2C
    Aug 19 15:21:16.649: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 51 53 49 47 2A 2C 0D 0A 0D 0A
    Aug 19 15:21:16.649: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_PROGRESS state to H225_ALERT state
    Aug 19 15:21:16.649: //-1/xxxxxxxxxxxx/H323/cch323_start_h245: entry
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/cch323_start_h245: Drop to Slow w/o sending Facility
    Aug 19 15:21:16.649: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x16 internal event to H225 SM
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_FACIL_SWITCH_H245 while at state H225_PROGRESS
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/cch323_call_alert: gw_id=1
    Aug 19 15:21:16.649: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H225 SM
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_PROGRESS
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_PROGRESS state to H225_ALERT state
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/delay_h245_transport_address:
    delay_h245_transport_address: CCM-ITS compatibility delay transport address for callid[11CCB]
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/delay_h245_transport_address:
    delay_h245_transport_address:IP-IP:delay h245 address for callid[11CCB]: by remote
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/generic_send_alert: ====== PI = 8
    Aug 19 15:21:16.649: //72907/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy raw message data to nonStdInfo
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_h225_copy_send_rawMsg: Copy tunnelled GTD/ISUP content
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg: (for cch323_h225_copy_send_rawMsg): raw message is 91 bytes:
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 25 91 AA 06 80 01 00 82 01 00 8B 01 00 A1 17
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     02 01 01 06 04 2B 0C 09 01 80 0C 41 64 65 20 45
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     6C 65 67 62 65 64 65 1E 02 81 88 1E 02 81 82 9D
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     32 01 81 1C 26 9E 81 00 03 67 74 64 00 00 00 1B
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     41 43 4D 2C 0D 0A 50 52 4E 2C 69 73 64 6E 2A 2C
    Aug 19 15:21:16.653: //72907/002D2CEEC422/H323/cch323_prnt_rawmsg:     2C 51 53 49 47 2A 2C 0D 0A 0D 0A
    Aug 19 15:21:25.837: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1
    Aug 19 15:21:25.837: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x655015C0, len=240, msgPtr=0x65310640
    Aug 19 15:21:25.837: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:25.837: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPCFM_CHOSEN
    Aug 19 15:21:25.837: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for setup_cfm_ind): raw message is 87 bytes:
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 25 91 AA 06 80 01 00 82 01 00 8B 01 00 A1 17
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     02 01 02 06 04 2B 0C 09 02 80 0C 41 64 65 20 45
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     6C 65 67 62 65 64 65 1E 02 81 82 9D 32 01 81 1C
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     26 9E 81 00 03 67 74 64 00 00 00 1B 41 4E 4D 2C
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     0D 0A 50 52 4E 2C 69 73 64 6E 2A 2C 2C 51 53 49
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     47 2A 2C 0D 0A 0D 0A
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/setup_cfm_ind: ====== PI = 2
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/setup_cfm_ind: Set new event H225_EV_SETUP_CFM_IND
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/setup_cfm_ind: Rcvd CONNECT Display Info IE =
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: ccb=0x64B56904, tag=17, size=88
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x63E80DA4
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_h225_receiver: SETUPCFM_CHOSEN: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_SETUP_CFM_IND while at state H225_ALERT
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/setup_cfm_notify: status = 8002009
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg: (for setup_cfm_notify): raw message is 87 bytes:
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     1C 25 91 AA 06 80 01 00 82 01 00 8B 01 00 A1 17
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     02 01 02 06 04 2B 0C 09 02 80 0C 41 64 65 20 45
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     6C 65 67 62 65 64 65 1E 02 81 82 9D 32 01 81 1C
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     26 9E 81 00 03 67 74 64 00 00 00 1B 41 4E 4D 2C
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     0D 0A 50 52 4E 2C 69 73 64 6E 2A 2C 2C 51 53 49
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_prnt_rawmsg:     47 2A 2C 0D 0A 0D 0A
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_ALERT state to H225_WAIT_FOR_H245 state
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/h245_address_ind: ev=2
    Aug 19 15:21:25.841: //-1/xxxxxxxxxxxx/H323/h245_address_ind: Sending event
    Aug 19 15:21:25.841: //72908/002D2CEEC422/H323/cch323_h245_addr_notify: Sending event CC_EV_H245_ADDR, ev 2
    Aug 19 15:21:25.841: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=72907
    Aug 19 15:21:25.841: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_ADDR received, ADDR event 2
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_start_h245: entry
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/cch323_start_h245: Sending Progress[pi=8] to CCM
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/cch323_start_h245: Sending Progress[h245Addr, pi=0x00]
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x22 internal event to H225 SM
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_PROGRESS while at state H225_ALERT
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/send_generic_progress: ====== PI = 8, cause = 0
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/cch323_h225_copy_nonStdInfo_prog_ind: Copy Progress Indication
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x22 internal event to H225 SM
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_PROGRESS while at state H225_ALERT
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/send_generic_progress: ====== PI = 3, cause = 0
    Aug 19 15:21:25.845: //72907/002D2CEEC422/H323/cch323_h225_copy_nonStdInfo_prog_ind: Copy Progress Indication
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x655015C0, len=54, msgPtr=0x64B46270
    Aug 19 15:21:25.845: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:25.849: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type NOTIFYIND_CHOSEN
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: Entry
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: Rcvd NOTIFY Display Info IE =
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: Rcvd NOTIFY Notification Indicator IE = 113
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: Rcvd NOTIFY Connected Number as IE
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: [cnum]/[oct]/[oct3a]= [1379]/[0x00]/[0x00]
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: ccb=0x64B56904, tag=15, size=124
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x63E80D14
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_ind: Notify data embedded, mask=0x00000007
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/cch323_h225_receiver: NOTIFYIND_CHOSEN: src address = 10.1.100.1; dest address = 172.31.253.225
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_NOTIFY_IND while at state H225_WAIT_FOR_H245
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_msg_notify: Entry
    Aug 19 15:21:25.849: //72908/002D2CEEC422/H323/notify_msg_notify: Notify data found, mask=0x00000007
    Aug 19 15:21:25.849: //-1/xxxxxxxxxxxx/H323/cch323_post_call_service_msg: callID=72907, notify data mask=0x00000007
    Aug 19 15:21:25.849: //-1/xxxxxxxxxxxx/H323/cch323_do_call_notify: gw_id=1, callID=72907, cs_data present
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: ccb=0x63EFBECC, tag=15, size=124
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x63E80D14
    Aug 19 15:21:25.849: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x20 internal event to H225 SM
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_NOTIFY while at state H225_ALERT
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/send_notify_msg: Notify data found, mask=0x00000007
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/send_notify_msg: Sending NOTIFY Display Info IE =
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/send_notify_msg: Sending NOTIFY Notification Indicator IE = 113
    Aug 19 15:21:25.849: //72907/002D2CEEC422/H323/send_notify_msg: Sending NOTIFY Connected Number as IE
    Aug 19 15:21:25.853: //72907/002D2CEEC422/H323/send_notify_msg: [cnum]/[oct]/[oct3a] = [1379]/[0x00]/[0x00]
    Aug 19 15:21:26.009: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 10 Event 0x1
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: state=0, event=4, ccb=63EFBECC, listen state=2
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_NONE state
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 10
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 9
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 7 Event 0x1
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x65501630, len=46, msgPtr=0x64B46270
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/release_ind: Disconnect cause 47 location code 1
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/h323_set_release_source_for_peer: ownCallId[72907], src[2]
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 10.1.100.1; dest address = 10.1.100.10
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ALERT
    Aug 19 15:21:26.013: //72907/002D2CEEC422/H323/cch323_traverse_enum_contact_list: State = 3
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 7 Event 0x1
    Aug 19 15:21:26.013: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Keepalive ignore timeout = 0
    Aug 19 15:21:26.017: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 7
    Aug 19 15:21:26.017: //-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: hashDestroy for TcpFDTbl
    Aug 19 15:21:26.017: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2D internal event to H225 SM
    Aug 19 15:21:26.017: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_CONN_LOST while at state H225_ALERT
    Aug 19 15:21:26.021: //72908/002D2CEEC422/H323/cch323_do_call_disconnect: gw_id=1, discCause=47
    Aug 19 15:21:26.021: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM
    Aug 19 15:21:26.021: //72908/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_WAIT_FOR_H245
    Aug 19 15:21:26.021: //72908/002D2CEEC422/H323/cch323_h225_send_release: Cause = 47; Location = 1
    Aug 19 15:21:26.021: //72908/002D2CEEC422/H323/cch323_h225_send_release: h225TerminateRequest: src address = 167863297; dest address = 172.31.253.225
    Aug 19 15:21:26.021: //72908/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_H245 state to H225_WAIT_FOR_REL_COMP state
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_do_call_disconnect: gw_id=1, discCause=47
    Aug 19 15:21:26.021: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ALERT
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_h225_set_new_state: Changing from H225_ALERT state to H225_IDLE state
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_call_generic_cleanup: Disconnected flag 1
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_store_call_history_entry: Call Active Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_store_call_history_entry: Call History Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_H245_CONNECTED
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: state=0, event=4, ccb=63EFBECC, listen state=0
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_NONE state
    Aug 19 15:21:26.021: //72907/002D2CEEC422/H323/h245_iwf_set_new_state: changing from IWF_H245_CONNECTED state to IWF_IDLE state
    Aug 19 15:21:26.025: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x63EFBECC)
    Aug 19 15:21:26.233: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 8 Event 0x1

  • If it isn't the codec, I suppose you could look at RTP/Media Termination.  Sorry, my first impulse is to ask the basic things... h323 interface, source-addr and gateway commands, do they match the CUCMs GW device config... etc.  I know in the past these things haven't always mattered that much when you point a CUCM at the gateway, but I'm kind of at a loss for ideas.

  • Hi ,

     

    I can see small problem , but i need more debugs

     

    1 u h323 link is falling to "slow start " ( after normally tring to fast start),

    2. if u have connected ur pbx to GW with isdn , plz provide the " debug isdn q931"

    3 also their could be a problem with auto-cut through

    regards

     

     

     

  • hey,

    see below

     

    Aug 25 16:23:42.585: ISDN Se0/0/0:15 Q931: Applying typeplan for sw-type 0x16 is 0x0 0x1, Calling num 5824
    Aug 25 16:23:42.585: ISDN Se0/0/0:15 Q931: Applying typeplan for sw-type 0x16 is 0x0 0x0, Called num 1379
    Aug 25 16:23:42.585: ISDN Se0/0/0:15 Q931: TX -> SETUP pd = 8  callref = 0x066E
            Sending Complete
            Bearer Capability i = 0x8090A3
                    Standard = CCITT
                    Transfer Capability = Speech 
                    Transfer Mode = Circuit
                    Transfer Rate = 64 kbit/s
            Channel ID i = 0xA98381
                    Exclusive, Channel 1
            Calling Party Number i = 0x0181, '5824'
                    Plan:ISDN, Type:Unknown
            Called Party Number i = 0x80, '1379'
                    Plan:Unknown, Type:Unknown
    Aug 25 16:23:42.665: ISDN Se0/0/0:15 Q931: RX <- CALL_PROC pd = 8  callref = 0x866E
            Channel ID i = 0xA98381
                    Exclusive, Channel 1
            Progress Ind i = 0x8181 - Call not end-to-end ISDN, may have in-band info
    Aug 25 16:23:43.313: ISDN Se0/0/0:15 Q931: RX <- ALERTING pd = 8  callref = 0x866E
            Facility i = 0x91AA068001008201008B0100A11702010106042B0C0901800C41646520456C656762656465
            Progress Ind i = 0x8188 - In-band info or appropriate now available 
            Progress Ind i = 0x8182 - Destination address is non-ISDN 
            Shift to Codeset 5
            Codeset 5 IE 0x32  i = 0x81
    Aug 25 16:23:52.925: ISDN Se0/0/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x066E
            Cause i = 0x82AF - Resource unavailable, unspecified
    Aug 25 16:23:52.981: ISDN Se0/0/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x866E
            Cause i = 0x81AF - Resource unavailable, unspecified
    Aug 25 16:23:52.981: ISDN Se0/0/0:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x066E
    Aug 25 16:24:00.537: ISDN Se0/0/0:15 Q931: RX <- DISCONNECT pd = 8  callref = 0x484E
            Cause i = 0x8190 - Normal call clearing
    Aug 25 16:24:00.537: %ISDN-6-DISCONNECT: Interface Serial0/0/0:10  disconnected from 1683 , call lasted 27 seconds
    Aug 25 16:24:00.541: ISDN Se0/0/0:15 Q931: TX -> RELEASE pd = 8  callref = 0xC84E
    Aug 25 16:24:00.573: ISDN Se0/0/0:15 Q931: RX <- RELEASE_COMP pd = 8  callref = 0x484E

  • Hi,

     

     I could be worng( i accept that [8-|]) but This is not a codec issuse as the call is disconncted before we get the connect message ( ie audio channel was not made till yet)

     

    plz try to stop the facility message b/w the router and pbx  use ( inside interface  s0:23)

     

     

    no isdn outgoing ie 

    and

    no isdn outgoing display-ie

     

     

    and last

    no isdn incoming ie


    test one by one and see the result in isdn debug( i suppose ur problem should be solved by the 1st command)


    regards[:D]

  • HI ,

     

    Is ur problem is solved , if yes plz lets us know how

     

    regards

  • hi,

    Problem is still not resolved.

    tried no isdn outgoing display-ie ,. no joy

    the no isdn outgoing ie has a few option and was not sure which one is appropriate.

    However, I made a change on the CUCM (unticked check far end h245 capability) and now call is getting connected but still no audio.

    the sh isdn q931 is below

    Sep 11 16:32:02.359: ISDN Se0/0/0:15 Q931: Applying typeplan for sw-type 0x16 is 0x0 0x1, Calling num 8086385824
    Sep 11 16:32:02.359: ISDN Se0/0/0:15 Q931: Applying typeplan for sw-type 0x16 is 0x0 0x0, Called num 1379
    Sep 11 16:32:02.359: ISDN Se0/0/0:15 Q931: TX -> SETUP pd = 8  callref = 0x06F8
            Sending Complete
            Bearer Capability i = 0x8090A3
                    Standard = CCITT
                    Transfer Capability = Speech 
                    Transfer Mode = Circuit
                    Transfer Rate = 64 kbit/s
            Channel ID i = 0xA98381
                    Exclusive, Channel 1
            Calling Party Number i = 0x0181, '8086385824'
                    Plan:ISDN, Type:Unknown
            Called Party Number i = 0x80, '1379'
                    Plan:Unknown, Type:Unknown
    Sep 11 16:32:02.439: ISDN Se0/0/0:15 Q931: RX <- CALL_PROC pd = 8  callref = 0x86F8
            Channel ID i = 0xA98381
                    Exclusive, Channel 1
            Progress Ind i = 0x8181 - Call not end-to-end ISDN, may have in-band info
    Sep 11 16:32:03.091: ISDN Se0/0/0:15 Q931: RX <- ALERTING pd = 8  callref = 0x86F8
            Facility i = 0x91AA068001008201008B0100A11702010106042B0C0901800C41646520456C656762656465
            Progress Ind i = 0x8188 - In-band info or appropriate now available 
            Progress Ind i = 0x8182 - Destination address is non-ISDN 
            Shift to Codeset 5
            Codeset 5 IE 0x32  i = 0x81
    Sep 11 16:32:10.587: ISDN Se0/0/0:15 Q931: RX <- CONNECT pd = 8  callref = 0x86F8
            Facility i = 0x91AA068001008201008B0100A11702010206042B0C0902800C41646520456C656762656465
            Progress Ind i = 0x8182 - Destination address is non-ISDN 
            Shift to Codeset 5
            Codeset 5 IE 0x32  i = 0x81
    Sep 11 16:32:10.591: %ISDN-6-CONNECT: Interface Serial0/0/0:0 is now connected to 1379 N/A
    Sep 11 16:32:10.591: ISDN Se0/0/0:15 Q931: TX -> CONNECT_ACK pd = 8  callref = 0x06F8
    Sep 11 16:32:16.591: %ISDN-6-CONNECT: Interface Serial0/0/0:0 is now connected to 1379 N/A
    Sep 11 16:32:20.855: %ISDN-6-DISCONNECT: Interface Serial0/0/0:0  disconnected from 1379 , call lasted 10 secondsd
    Sep 11 16:32:20.859: ISDN Se0/0/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x06F8
            Cause i = 0x82AF - Resource unavailable, unspecified
    Sep 11 16:32:20.915: ISDN Se0/0/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x86F8
            Cause i = 0x81AF - Resource unavailable, unspecified
    Sep 11 16:32:20.919: ISDN Se0/0/0:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x06F8

     

     

  • I guess you should check your Codec Settings, and also make sure you have Network-clock-participate command configured for the T1 Controller Interface!

  • hi,

     

    now that u are geting alerting , now it seems to us as if it is codec issuse .

     

    the h323 Gw which is connected to CUCm is used are ip-to-ip GW ???????? yes or no

     

     

    regards

  • Still could be a multiple issues, but doesn't necessarily sound like codec. Can you paste in your config for the interface that you specified for the h323-gateway voip interface? In addition, when you perform a debug isdn q931, are you getting multiple repeated messages?

     

     

Sign In or Register to comment.