H.323 calls

hi,

 

When I tried to establish a H.323 trunk from Cisco Call Manager 7.1.3 and UC520 over VPN. I Can able to establish the truk and able to dail from CCM to UC520, But not able to listen the voice in bothsides.. and not able to dail fom UC520 to CCM 7.1.3, getting a busy tone..

 

Here are the logs in UC520... Let me know where actually i went wrong

 


UC520_HW#

UC520_HW#

Nov 25 12:47:10.543:
//-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: callID=26252

Nov 25 12:47:10.543:
//-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0
incoming_media=0

Nov 25 12:47:10.543:
//-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0
incoming_media=0

Nov 25 12:47:10.543:
//-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x849CFE70) is in use

Nov 25 12:47:10.543: //26252/6B9CCD57925D/H323/cch323_call_setup:
gw_id=1

Nov 25 12:47:10.543:
//26252/6B9CCD57925D/H323/cch323_call_setup: Inserted ccb

Nov 25 12:47:10.543:
//-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating
callInfoType container for callID = 26252

Nov 25 12:47:10.543:
//26252/6B9CCD57925D/H323/cch323_get_caps_chn_info: No peer leg setup params

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_get_peer_info: Entry

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_get_peer_info: Have peer

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_get_peer_info: faxrate[45] proto[3]
bitmask[10082] t38_inhibit[0] global_fax[4] t38fax[0]

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_set_pref_codec_list: First preferred codec(bytes)=6(160)

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_get_peer_info: Preferred codec set to
G711_ALAW_64K with Bytes = 160

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_get_peer_info: Flow Mode set to FLOW_THROUGH

Nov 25 12:47:10.547: //26252/6B9CCD57925D/H323/cch323_get_peer_info:
peer: 84980614, peer->voice_peer_tag: C8, ccb: 849CFE70

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_set_h323_parms_from_dp: ccb h245addr bits =
system[0x0007]

Nov 25 12:47:10.547: //26252/6B9CCD57925D/H323/cch323_set_h323_parms_from_dp:
ccb ccm bits = system[0x0028]

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_update_setup_callinfo: callingNumber = 10

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_update_setup_callinfo: called_oct3 = 0x80,
calling_oct3 = 0x0, calling_oct3a = 0x0

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_set_h323_control_options_outgoing: h245 sm
mode = 271

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_set_h323_control_options_outgoing:
h323_ctl=0x20

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_call_setup: No account/pin number available

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_store_call_info: Call_setup Playout Mode:
1,Init 60, Min 40, Max 1000, Fax 300

Nov 25 12:47:10.547: //26252/6B9CCD57925D/H323/cch323_call_setup_normal:
Entry

UC520_HW#

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_call_setup_normal:  SETUP_NTF_TIMER is
not running. Continue processing.

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_h225_set_new_state: Changing from H225_IDLE
state to H225_IDLE state

Nov 25 12:47:10.547:
//26252/6B9CCD57925D/H323/cch323_store_call_info: Call_setup Playout Mode:
1,Init 60, Min 40, Max 1000, Fax 300

UC520_HW#

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/cch323_timer_dispatch:
Timer[CCH323_H225_CONNECTION_TIMER] expired

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 2

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/h323_set_cc_cause_for_spi_err:

Categorized cause:38, category:186

 

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/h323_set_release_source_for_peer: ownCallId[26252],
src[6]

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_traverse_enum_contact_list: State = 0

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/cch323_post_call_statistics: callID=26252

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_do_call_disconnect: gw_id=1, discCause=38

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to
H225 SM

Nov 25 12:47:25.548: //26252/6B9CCD57925D/H323/run_h225_sm:
Received event H225_EV_RELEASE while at state H225_IDLE

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_h225_set_new_state: Changing from H225_IDLE
state to H225_IDLE state

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_call_generic_cleanup: Disconnected flag 1

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_store_call_history_entry: Call Active Playout
Mode: 1,Init 60, Min 40, Max 1000, Fax 300

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/cch323_store_call_history_entry: Call History Playout
Mode: 1,Init 60, Min 40, Max 1000, Fax 300

Nov 25 12:47:25.548: %VOIPAAA-5-VOIP_CALL_HISTORY:
CallLegType 2, ConnectionId 6B9CCD57169A11E1925DE34664C9133, SetupTime 13:47:10.548
CET Fri Nov 25 2011, PeerAddress 69556, PeerSubAddress , DisconnectCause
26  , DisconnectText network out of order (38), ConnectTime 13:47:25.548
CET Fri Nov 25 2011, DisconnectTime 13:47:25.548 CET Fri Nov 25 2011,
CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0,
ReceivePackets 0, ReceiveBytes 0

Nov 25 12:47:25.548: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:TWC,ft:11/25/2011
13:47:10.543,cgn:10,cdn:69556,frs:0,fid:34928,fcid:6B9CCD57169A11E1925DE34664C9133,legID:668C,bguid:6B9CCD57169A11E1925DE346064C9133

UC520_HW#

Nov 25 12:47:25.548:
//26252/6B9CCD57925D/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while
at state IWF_IDLE

Nov 25 12:47:25.548: //26252/6B9CCD57925D/H323/defaultHdlr:
DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: IP_entry is NULL, returning

Nov 25 12:47:25.548:
//-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x849CFE70)

UC520_HW#

Nov 25 12:47:29.704: %VOIPAAA-5-VOIP_CALL_HISTORY:
CallLegType 1, ConnectionId 6B9CCD57169A11E1925DE34664C9133, SetupTime
13:47:08.404 CET Fri Nov 25 2011, PeerAddress 10, PeerSubAddress ,
DisconnectCause 26  , DisconnectText network out of order (38),
ConnectTime 13:47:29.704 CET Fri Nov 25 2011, DisconnectTime 13:47:29.704 CET
Fri Nov 25 2011, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0,
TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

Nov 25 12:47:29.704: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:TWC,ft:11/25/2011
13:47:08.403,cgn:10,cdn:,frs:0,fid:34927,fcid:6B9CCD57169A11E1925DE34664C9133,legID:668B,bguid:6B9CCD57169A11E1925DE346064C9133

 

 

----------------------------------------------------------------------

 


UC520_HW#terminal monitor

UC520_HW#

Nov 25 12:34:28.358: //-1/xxxxxxxxxxxx/H323/cch323_ct_main:
SOCK 0 Event 0x1

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x849CFE70) is in use

Nov 25 12:34:28.358: //-1/xxxxxxxxxxxx/H323/cch323_ct_main:
SOCK 2 Event 0x1

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x86E949F0,
len=230, msgPtr=0x8802FD94

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

Nov 25 12:34:28.358: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver:
Received msg of type SETUPIND_CHOSEN

Nov 25 12:34:28.358: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
callingNumber[69556] calledNumber[10]

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
---- calling IE present

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
====== PI = 0

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
Receive: infoXCap 0

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
Receive: infoXCap ccb 0

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
Receive bearer cap infoXRate 16, rateMult 0

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

Nov 25 12:34:28.358: //26230/003C8A100800/H323/cch323_determine_source_ip_group_info:
No Source IP group match with Src CarrierId/Zone ID

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_determine_source_ip_group_info: No Source IP
group matched with rte id/zone_id/acl

Nov 25 12:34:28.358: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group:
Entry

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_determine_source_ip_group_info: No Source IP
group match with Incoming Called Number

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_determine_source_ip_group_info: No Source IP
group match with Destination pattern

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:

setup_ind: is_overlap = 0, info_complete = 0

 

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_is_preauth_reqd: FALSE

Nov 25 12:34:28.358: //26230/003C8A100800/H323/setup_ind:
Call Manager detected

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address =
217.92.2.139; dest address = 121.243.84.9

Nov 25 12:34:28.358: //26230/003C8A100800/H323/run_h225_sm:
Received event H225_EV_SETUP_IND while at state H225_IDLE

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/common_idle_setupInd_hdlr: no match is found

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/common_idle_setupInd_hdlr: ccb ccm bits =
system[0x0028]

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_h225_set_new_state: Changing from H225_IDLE
state to H225_SETUP state

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_set_h245_state_mc_mode_incoming: h245 state
m/c mode=0x10F, h323_ctl=0x28

Nov 25 12:34:28.358:
//-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_create_incoming_callinfo_block: peer is NULL -
may affect modem pass through! ccb: 849CFE70, ccNewCallInfo 86343360

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/cch323_create_incoming_callinfo_block: Calling Party
is CCM

Nov 25 12:34:28.358:
//26230/003C8A100800/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

Nov 25 12:34:28.362: //26230/003C8A100800/H323/cch323_h225_handle_deferred_ind:
UnBuffering deferred indications

Nov 25 12:34:28.362:
//-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating
callInfoType container for callID = 26230

Nov 25 12:34:28.362:
//26230/003C8A100800/H323/cch323_update_carriers_in_call_info: tg_label_flag
0x0

Nov 25 12:34:28.366:
//-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=26230

Nov 25 12:34:28.366:
//-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data
ptr=0x883ADF90

Nov 25 12:34:28.366:
//26230/003C8A100800/H323/cch323_set_mode: callID=26230, flow Mode=1
spi_mode=0x6

Nov 25 12:34:28.366:
//26230/003C8A100800/H323/cch323_do_set_dialpeer_tag: gw_id=1

Nov 25 12:34:28.370: //26230/003C8A100800/H323/cch323_do_call_proceeding:
gw_id=1

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_do_call_proceeding: set_mode NOT called
yet...saved deferred CALL_PROC

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_send_event_to_h245_connection_sm: Changing to
new event H245_LISTEN_REQ_EVENT

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_h245_connection_sm: state=0, event=0,
ccb=849CFE70, listen state=0

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_h245_connection_sm: H245_LISTEN: Received
event H245_LISTEN_REQ_EVENT while at H245_NONE state

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_h245_set_new_state: Changing from H245_NONE
state to H245_WAITING state

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_process_set_mode: Setting inbound leg mode
flags to 0x10F, flow-mode to FLOW_THROUGH

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_process_set_mode: Sending deferred CALL_PROC

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_do_call_proceeding: gw_id=1

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/cch323_do_call_proceeding: set_mode called so we can
proceed with CALLPROC

Nov 25 12:34:28.370: //26230/003C8A100800/H323/run_h225_sm:
Received event H225_EV_CALLPROC while at state H225_SETUP

Nov 25 12:34:28.370: //26230/003C8A100800/H323/cch323_h225_set_new_state:
Changing from H225_SETUP state to H225_CALLPROC state

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/delay_h245_transport_address: CCM-ITS compatibility
delay transport address for callid[6676]

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/delay_h245_transport_address: Ephone: delay transport
address for callid[6676]

Nov 25 12:34:28.370:
//26230/003C8A100800/H323/generic_send_callproc: ====== PI = 0

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/cch323_call_alert: gw_id=1

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/cch323_put_embedded_obj_to_ccb: ccb=0x849CFE70,
tag=17, size=83

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED,
embedded at 0x88290858

Nov 25 12:34:28.378:
//-1/xxxxxxxxxxxx/H323/cch323_call_alert: stored alert data display_info
Mareike - 10 in ccb

Nov 25 12:34:28.378:
//-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to
H225 SM

Nov 25 12:34:28.378: //26230/003C8A100800/H323/run_h225_sm:
Received event H225_EV_ALERT while at state H225_CALLPROC

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/cch323_h225_set_new_state: Changing from
H225_CALLPROC state to H225_ALERT state

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/delay_h245_transport_address: CCM-ITS compatibility
delay transport address for callid[6676]

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/delay_h245_transport_address: Ephone: delay transport
address for callid[6676]

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/generic_send_alert: ====== PI = 0

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/cch323_get_embedded_obj_from_ccb: ccb=0x849CFE70,
tag=17, size=83

Nov 25 12:34:28.378: //26230/003C8A100800/H323/cch323_get_embedded_obj_from_ccb:
Extraction PASSED from 0x88290858

Nov 25 12:34:28.378:
//26230/003C8A100800/H323/generic_send_alert: get ALERT displayInfo Mareike -
10

UC520_HW#

Nov 25 12:34:43.379: %VOIPAAA-5-VOIP_CALL_HISTORY:
CallLegType 1, ConnectionId 3C8A10C18BF1EC8005101A014628, SetupTime
13:34:28.369 CET Fri Nov 25 2011, PeerAddress 10, PeerSubAddress ,
DisconnectCause 13  , DisconnectText no user answer (19), ConnectTime
13:34:43.379 CET Fri Nov 25 2011, DisconnectTime 13:34:43.379 CET Fri Nov 25
2011, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0,
TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

Nov 25 12:34:43.379: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:CFNA,ft:11/25/2011
13:34:43.375,frs:0,fid:34900,fcid:3C8A10C18BF1EC8005101A014628,legID:6676,frson:3,fdcnt:1,fwder:10,fwdee:69556,fwdto:77,frm:10,bguid:003C8A10C18BF1EC080051010A014628

Nov 25 12:34:43.379: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:TWC,ft:11/25/2011
13:34:28.366,cgn:69556,cdn:10,frs:0,fid:34899,fcid:3C8A10C18BF1EC8005101A014628,legID:6677,bguid:003C8A10C18BF1EC080051010A014628

Nov 25 12:34:43.387:
//-1/xxxxxxxxxxxx/H323/cch323_post_call_statistics: callID=26230

Nov 25 12:34:43.387:
//26230/003C8A100800/H323/cch323_do_call_disconnect: gw_id=1, discCause=3

Nov 25 12:34:43.387:
//-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to
H225 SM

Nov 25 12:34:43.387: //26230/003C8A100800/H323/run_h225_sm:
Received event H225_EV_RELEASE while at state H225_ALERT

Nov 25 12:34:43.387: //26230/003C8A100800/H323/cch323_h225_send_release:
Cause = 3; Location = 0

Nov 25 12:34:43.387:
//26230/003C8A100800/H323/cch323_h225_send_release: h225TerminateRequest: src
address = -648281461; dest address = 121.243.84.9

Nov 25 12:34:43.387: //26230/003C8A100800/H323/cch323_h225_set_new_state:
Changing from H225_ALERT state to H225_IDLE state

Nov 25 12:34:43.387:
//26230/003C8A100800/H323/cch323_call_generic_cleanup: Disconnected flag 1

Nov 25 12:34:43.387:
//26230/003C8A100800/H323/cch323_store_call_history_entry: Call Active Playout
Mode: 0,Init 0, Min 0, Max 0, Fax 0

Nov 25 12:34:43.391:
//26230/003C8A100800/H323/cch323_store_call_history_entry: Call History Playout
Mode: 0,Init 0, Min 0, Max 0, Fax 0

Nov 25 12:34:43.391: %VOIPAAA-5-VOIP_CALL_HISTORY:
CallLegType 2, ConnectionId 3C8A10C18BF1EC8005101A014628, SetupTime
13:34:28.361 CET Fri Nov 25 2011, PeerAddress 69556, PeerSubAddress ,
DisconnectCause 3   , DisconnectText no route to destination (3),
ConnectTime 13:34:43.391 CET Fri Nov 25 2011, DisconnectTime 13:34:43.391 CET
Fri Nov 25 2011, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0,
TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

Nov 25 12:34:43.391: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:CFNA,ft:11/25/2011
13:34:43.383,frs:0,fid:34903,fcid:3C8A10C18BF1EC8005101A014628,legID:0,frson:3,fdcnt:1,fwder:10,fwdee:69556,fwdto:77,frm:10,bguid:003C8A10C18BF1EC080051010A014628

Nov 25 12:34:43.391: %VOIPAAA-5-VOIP_FEAT_HISTORY:
FEAT_VSA=fn:TWC,ft:11/25/2011
13:34:28.358,cgn:69556,cdn:10,frs:1,fid:34898,fcid:3C8A10C18BF1EC8005101A014628,legID:6676,bguid:003C8A10C18BF1EC080051010A014628

UC520_HW#

Nov 25 12:34:43.391: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch:
fd 2

Nov 25 12:34:43.391:
//26230/003C8A100800/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while
at state IWF_IDLE

Nov 25 12:34:43.391: //26230/003C8A100800/H323/defaultHdlr:
DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE

Nov 25 12:34:43.391:
//-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: hashDestroy for TcpFDTbl

Nov 25 12:34:43.391:
//-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 3

Nov 25 12:34:43.391:
//-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x849CFE70)

 

 

 

 

 

 

 

 

 

 

Comments

  • Hi,

    I can see the source and destination are not on the same subnet, may be both of them are on public networks with different network topology.

    Nov 25 12:34:28.358: //26230/003C8A100800/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 217.92.2.139; dest address = 121.243.84.9

    And the disconnect cause is 38, means 'Network is out of order' with zero packet transmission:

    TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

    There might be any of the two reasons which is interrupting you:

    1. The TCP port 1720 is blocked somewhere, may be by firewall in between you don't know or an active ACL, try to telnet with port 1720 to make sure,

    2. The routing issue: check the route of the CUCM and the phones also the UC520 and the phones, traceroute can help you.

    BTW, please post the ip addressing details (IP address, gateway, dns, etc.) for both CUCM, phones at CUCM site, UC520 and phones at UC520 site.

    HTH.

  • CUCM IP: 172.25.158.11

    Phones: 172.25.159.X(Subnet)

    UC520: 192.168.82.1

    UC520 Phones: 192.168.82.X ( Subnet)

     

     

  • Hi,

    Could you able to check if the TCP port 1720 is clear from both sides? Also did you tracerouted the path from both the side if they are following the same route? The IP addresses I can see different from the attached logs, are those behind public network backbone on different locations or they reside on the same network?

  • hi...

    I have done the testing.. I can able to telnet on 1720...

    I have traced done the Extended tracing with source 192.168.82.1 to 172.25.158.11.. and able to reach. I have not tried with normal traceroute..,,

    They resided  behind public network backbone... on different loacations( over internet VPN H.323 calls established.)

    One more to the point... When dialed from CCM to UC520 ringing is happening but RTP not happening.  and not able get any debug messages during that time.

    Above provided logs are from UC520 to CCM.

  • Kaja,

        can you provide a diagram of each network / security endpoint in the communication stream?  something in visio is a bonus, but something alone the line of below gives a decent idea as well...

     

     

    CUCM -- 2811 (IOS Firewall) -- Internet DSL (ethernet of 2811) -- Internet Cloud -- UC520 (outside interface of IOS Firewall)

     

    that tells us that there is a 2811 with an IOS firewall acting as your security entity on the CUCM side and the only other thing is the IOS firewall features of the UC520.

     

     

  • Since you are using a VPN, also give details on what subnets are being encrypted through the VPN.

  • Thanks all for you help... Now i can able to call on both sides...

    these are the actual problem which is not able to establish

    1) In UC520, I have not given the source address... because  of this taking the external interface for placing the call.. because of this, I can't able to dial from UC520 to CCM

    After fixing this, phone user of UC520 is able to hear my voice but CCM end phone user not able to hear after the ring...

    Now i debuged in UC520, using "debug h323 all" and debug voice rtp"

    Then i found that detination(CCM IP) is nated to a static Public IP in CCM End FW. Hence it got NAT and no voice streams happened. Now rectifying all these I can able to establish the call.

    Thanks once again for all your helping and suggestions.

  • It's really great to hear your problem is solved. That was nice troubleshooting game you have enjoyed I'm sure of. Thumbs up!

    The routing issue is much sensitive for voice, specially when it comes with security over different topologies. If you google with 'mute voip calls' you can guess how frequently the engineers are stucking with the same issue.

  • Hi,

    I have sucessfully established another H.323 trunk from UC500 to Avaya. We can sucessfully make the calls from UC to Avaya... but when dialing from Avaya to UC500, Call rings and picks oneway voice is happening( UC500 end not able to hear the voice). Let me know if any changes has to be done..


    In avaya, call is originating with codec wi th G729. I have already provided the codec in codec class and assigned to the dial peer

     

  • Have you verified network reachability/path between the two devices?

    Please also include a copy of your config for reference.

    Matthew

    On Jan 5, 2012, at 5:53 AM, KajaHanumantharao wrote:

    Hi,

    I have sucessfully established another H.323 trunk from UC500 to Avaya. We can sucessfully make the calls from UC to Avaya... but when dialing from Avaya to UC500, Call rings and picks oneway voice is happening( UC500 end not able to hear the voice). Let me know if any changes has to be done..


    In avaya, call is originating with codec wi th G729. I have already provided the codec in codec class and assigned to the dial peer

     



    INE - The Industry Leader in CCIE Preparation
    http://www.INE.com

    Subscription information may be found at:
    http://www.ieoc.com/forums/ForumSubscriptions.aspx

  • In my experience 99.999% of the time one way audio is the result of a routing issue.  From what you have desribed I would venture a guess there is a firewall or ACL that will allow traffic one way but not the other.  Rember that the conversation is made up of 2 udp streams, one in either direction.

    The other .001%?

     bad hand set

     trying to use a phone that has a speaker but no mic as a speaker phone (ie 7912)

     mute button was activated.

Sign In or Register to comment.