Avaya CO trunk+Cisco проблемка!

dron85 · Сообщение **dron85** » Ср 17 фев 2010, 10:58

Доброго времени суток, товарищи форумчане!
Появилась такая непонятка: приехала к нам Cisco 2801 c модулем аналоговых окончаний. Она по оптической трубе соединена с Московской АТС.Была поставлена задача, создать CO trunk и завести эту линию в нашу станцию Avaya S8720+G650, для того чтоб не плодить телефоны на столе директора. все создал сигнал есть, но тут началась такая непонятка. При звонке из москвы на этот номер CO отрабатывает нормально, а если я пробую звонить по этой линии: выбираю TAC, набираю номер...через несколько секунд соединения Avaya дает отбой! В чем может буть дело ума не приложу! Ребята из москвы смотрели логи, говорят все нормально. Что крутить в нашей станции пока не разобрался, прошу помощи!
вот трейс:
10:56:02 Calling party station 1171 cid 0x1267
10:56:02 Calling Number & Name 1171
10:56:02 dial 720
10:56:02 term trunk-group 20 cid 0x1267
10:56:02 dial 720
10:56:02 seize trunk-group 20 member 1 cid 0x1267
10:56:06 dial 7207613
10:56:06 outpulse done 7613
10:56:15 denial event 1712: Ofhk alrt tmo intvl admin D1=0x8c01 D2=0x1267
10:56:15 dial 7207613
10:56:15 denial station 1171 cid 0x1267
10:56:19 idle station 1171 cid 0x1267

P.S. Нашел описание выше полученого denial event, но как с ним бороться не знаю.

Xen · Сообщение **Xen** » Ср 17 фев 2010, 13:12

покажите дебаг с циски, только тег code не забудьте, как то приятнее с ним

debug isdn q931

dron85 · Сообщение **dron85** » Ср 17 фев 2010, 16:49

Из Москвы от хозяев Cisco был получен следующий лог (может поможет):
2801-KMZ<61>#
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: callID=354
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x64CD163C) is in use
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_call_setup: gw_id=1
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_call_setup: Inserted ccb
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:
cch323_create_call_info_type_container: creating callInfoType container for callID = 354
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_caps_chn_info: No peer leg setup params
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: Have peer
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: faxrate[41] proto[3] bitmask[10082] t38_inhibit[0] global_fax[0] t38fax[0]
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_pref_codec_list: Using Voice Class Codec, tag = 3
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_pref_codec_list: First preferred codec(bytes)=16(20)
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: Preferred codec set to G729IETF with Bytes = 20
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: Flow Mode set to FLOW_THROUGH
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_peer_info: peer: 64BEACE8, peer->voice_peer_tag: A, ccb: 64CD163C
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_h323_parms_from_dp: ccb h245addr bits = system[0x0007]
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_h323_parms_from_dp: ccb ccm bits = system[0x0028]
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_update_setup_callinfo: callingNumber = 7612
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_update_setup_callinfo: called_oct3 = 0x80, calling_oct3 = 0x0, calling_oct3a = 0x0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_h323_control_options_outgoing: h245 sm mode = 271
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_h323_control_options_outgoing: h323_ctl=0x20
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_call_setup: No account/pin number available
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_call_setup_normal: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2C internal event to H225 SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_H225_RAS_RESOLVE while at state H225_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_send_event_to_percall_ras: Queued event CCH323_RAS_EVENT_NEWCALL to RAS Percal SM
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_WAIT_FOR_RAS_CONF state
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x3 internal event to RAS Percal SM
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_NEWCALL while at CCH323_RAS_STATE_IDLE state
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_send_arq: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_embedded_obj_from_ccb: ccb=0x64CD163C, tag=0, size=24
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_get_embedded_obj_from_ccb: Extraction FAILED
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_ARQ
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66292B34, len=64, msgPtr=0x655CCEDC
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for RAS
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_ras_handle_recv_msg: received msg of type ACF_CHOSEN
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_rcv_acf: Entry
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_drop_gtd: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_remove_gtd_in_rawmsg: GTD removal from raw message failed
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_remove_gtd_in_rawmsg: Free original raw message
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_rcv_acf: current active call count is 1
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_ACF while at CCH323_RAS_STATE_ARQ state
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_acf_notify: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_ACTIVE
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x17 internal event to H225 SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_RAS_SUCCESS while at state H225_WAIT_FOR_RAS_CONF
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_h225FD_Print: fd [3] Accepts newcall
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_RAS_CONF state to H225_IDLE state
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xB internal event to H225 SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/check_qos_and_send_setup: Setup ccb 0x64CD163C
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_rotary_validate: No peer_ccb available
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xE internal event to H225 SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FS_SETUP while at state H225_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/idle_fsSetup_hdlr: Setup ccb 0x64CD163C
Feb 15 11:15:55: //354/2A6323B98507/H323/idle_fsSetup_hdlr: send_fastStart_setup called
Feb 15 11:15:55: //354/2A6323B98507/H323/send_fastStart_setup: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_local_encoded_fastStartOLCs: state_mc_mode=0x10F on outbound leg
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_local_encoded_fastStartOLCs: srcAddress = 0xA3D7FFE, h245_lport = 0, flow mode = 1, minimum_qos=0
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_set_local_audio_mask: Near-end Pref Codecs = G729IETF G723ar53
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: current codec = 16:20:20
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Codec is G729IETF
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Filling in qosCapability field to 0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: current codec = 15:20:20
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Codec is G723ar53
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Filling in qosCapability field to 0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: current codec = 15:20:20
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Codec is G723ar53
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_generic_open_logical_channel: Filling in qosCapability field to 0
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_send_setup: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_send_setup:
generic_send_setup: is_overlap = 0, info_complete = 0
Feb 15 11:15:55: //354/2A6323B98507/H323/gene
2801-KMZ<61>#ric_send_setup: sending calling IE
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_send_setup: ====== PI = 3
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_send_setup: Send infoXCap=144, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_send_setup: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 IWF SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=141, msgPtr=0x656ECF38
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type CALLPROCIND_CHOSEN
Feb 15 11:15:55: //354/2A6323B98507/H323/callproc_ind: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/callproc_ind: ====== PI = 0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_h225_receiver: CALLPROCIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FS_CALLPROC_IND while at state H225_REQ_FS_SETUP
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_selectFastStart_codecs: Codec: loc(16), rem(16); Bytes: loc(20), Fwd(20), Rev(20)
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_receive_fastStart_cap_response: Send cap ind to peer leg
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_olc_for_ccapi: audioFastStartArray=0x64CF1F6C
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_olc_for_ccapi: channel_info ptr=0x66348814, ccb ptr=0x64CD163C
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_olc_for_ccapi: Channel Information:
Logical Channel Number (fwd): 1
Logical Channel Number (rev): 1
Channel address (fwd/rev): 0xA010007
RTP Channel (fwd/rev): 18582
RTCP Channel (fwd/rev): 18583
QoS Capability (fwd/rev): 0
Symmetric Audio Codec: 16
Symmetric Audio Codec Bytes: 20
Flow Mode: 0
Silence Suppression: 2
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_build_olc_for_ccapi: NumOfElements = 1 idx = 1
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_do_cap_ind: Registry cap_ind is not done
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_receive_fastStart_cap_response: Send BRQ
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_send_event_to_percall_ras: Queued event CCH323_RAS_EVENT_SEND_BRQ to RAS Percal SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_DONE state
Feb 15 11:15:55: //354/2A6323B98507/H323/fastStartIdle: H245_EV_OLC_IN/OUT_DONE sent to olc
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_olc_in_sm: Received H245_EV_OLC_IN_DONE while at state H245_OLC_IN_STATE_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_olc_in_set_new_state: Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_ESTABLISHED state
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_olc_out_sm: Received H245_EV_OLC_OUT_DONE while at state H245_OLC_OUT_STATE_IDLE
Feb 15 11:15:55: //354/2A6323B98507/H323/h245_olc_out_set_new_state: Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_ESTABLISHED state
Feb 15 11:15:55: //354/2A6323B98507/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_rtp_open_notify: Entry
Feb 15 11:15:55: //354/2A6323B98507/H323/generic_callproc_notify: Entry
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_IDLE
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x22 internal event to RAS Percal SM
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_SEND_BRQ while at CCH323_RAS_STATE_ACTIVE state
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_ras_send_brq: Reserved BW 160 same as being used 160
Feb 15 11:15:55: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_ACTIVE
Feb 15 11:15:55: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1D internal event to H245 IWF SM
Feb 15 11:15:55: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_BCF while at state IWF_OLC_DONE
Feb 15 11:15:56: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:56: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=182, msgPtr=0x64BEE738
Feb 15 11:15:56: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:56: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type ALERTIND_CHOSEN
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: (for alert_ind): raw message is 44 bytes:
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1E 02 80 88 1C 26 9E 81 00 03 67 74 64 00 00 00
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1B 41 43 4D 2C 0D 0A 50 52 4E 2C 69 73 64 6E 2A
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 2C 2C 4E 45 54 35 2A 2C 0D 0A 0D 0A
Feb 15 11:15:56: //354/2A6323B98507/H323/alert_ind: ====== PI = 8
Feb 15 11:15:56: //354/2A6323B98507/H323/alert_ind: alert ind ie_bit_mask 0x3A60, displayInfo
Feb 15 11:15:56: //354/2A6323B98507/H323/alert_ind: delay H245 address in alert
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_h225_receiver: ALERTIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:56: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_ALERT_IND while at state H225_ACC_FS_CALLPROC
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_ACC_FS_ALERT state
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: (for generic_alert_notify): raw message is 44 bytes:
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1E 02 80 88 1C 26 9E 81 00 03 67 74 64 00 00 00
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1B 41 43 4D 2C 0D 0A 50 52 4E 2C 69 73 64 6E 2A
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 2C 2C 4E 45 54 35 2A 2C 0D 0A 0D 0A
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_get_embedded_obj_from_ccb: ccb=0x64CD163C, tag=18, size=83
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_get_embedded_obj_from_ccb: Extraction FAILED
Feb 15 11:15:56: //-1/xxxxxxxxxxxx/H323/cch323_post_caps_ind: callID=354, ExtendedCaps not present
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_rtp_open_notify: Entry
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_caps_ind: gw_id=1
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_peer_caps_ind_common: Load DSP with Negotiated (No DP match) codec(16) g729r8, Bytes=20
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_peer_caps_ind_common: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_INBAND_VOICE
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_peer_caps_ind_common: cap_modem_proto: 0, cap_modem_codec: 0, cap_modem_redundancy: 0, payload: 100, modem_relay: 0, gw_xid: 1 gw_controlled=0
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_peer_caps_ack: Sending caps resp event to CAP sm
Feb 15 11:15:56: //354/2A6323B98507/H323/cch323_peer_caps_ack: ccb not interested in caps_ack primitive
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=220, msgPtr=0x64BEE824
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPCFM_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: (for setup_cfm_ind): raw message is 52 bytes:
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1E 02 80 82 4C 06 00 80 31 35 30 34 1C 26 9E 81
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 00 03 67 74 64 00 00 00 1B 41 4E 4D 2C 0D 0A 50
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 52 4E 2C 69 73 64 6E 2A 2C 2C 4E 45 54 35 2A 2C
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 0D 0A 0D 0A
Feb 15 11:15:57: //354/2A6323B98507/H323/setup_cfm_ind: ====== PI = 2
Feb 15 11:15:57: //354/2A6323B98507/H323/setup_cfm_ind: Set new event H225_EV_SETUP_CFM_IND
Feb 15 11:15:57: //354/2A6323B98507/H323/setup_cfm_ind: Rcvd CONNECT Display Info IE =
Feb 15 11:15:57: //354/2A6323B98507/H323/setup_cfm_ind: setup_cfm_ind:- Insertion PASSED
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: SETUPCFM_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_SETUP_CFM_IND while at state H225_ACC_FS_ALERT
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_ALERT state to H225_FS_ACTIVE state
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_rtp_open_notify: Entry
Feb 15 11:15:57: //354/2A6323B98507/H323/generic_setup_cfm_notify: ====== PI = 2; status = 98002309
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: (for generic_setup_cfm_notify): raw message is 52 bytes:
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 1E 02 80 82 4C 06 00 80 31 35 30 34 1C 26 9E 81
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 00 03 67 74 64 00 00 00 1B 41 4E 4D 2C 0D 0A 50
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_r
2801-KMZ<61>#awmsg: 52 4E 2C 69 73 64 6E 2A 2C 2C 4E 45 54 35 2A 2C
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_prnt_rawmsg: 0D 0A 0D 0A
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_event_to_percall_ras: Queued event CCH323_RAS_EVENT_IRR to RAS Percal SM
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x19 internal event to RAS Percal SM
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_IRR while at CCH323_RAS_STATE_ACTIVE state
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=210, msgPtr=0x64BEE824
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_IDLE
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_CONNECT_REQ_EVENT
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h245_connection_sm: state=0, event=1, ccb=64CD163C, listen state=0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_CONNECT_REQ_EVENT while at H245_NONE state
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h245_connection_sm: In connection_sm; doing tunneling
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_CONNECTED state
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_AWAIT_H245_CONN state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_AWAIT_H245_CONN
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_H245_CONN state to IWF_AWAIT_CAP_MSD_RESP state
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h245_cap_ind: Masks au=0xC data=0x2 uinp=0x32
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_IND while at state IDLE
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_cap_in_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP OUT SM
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_REQ while at state IDLE
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: cch323_send_cap_request: ccb->callID: 354, ccb->h245.state_mc_mode: 271
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Second try for videocaps
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: ccb->callID: 354, video_cap_mask: 0x0
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_send_generic_audio_caps: Using DP configured codecs
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_normalize_preferred_codec_list: Near-end Pref Codecs = G729IETF G723ar53
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_send_generic_audio_caps: [reg]audio mask after operation=0x400C
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Included rtp in caps table
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Included signal in caps table
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Included alpha in caps table
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Included hookflash in caps table
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_send_cap_request: Included rtp-nte in caps table
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_cap_out_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 MS SM
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MSD while at state H245_MS_NONE
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: Sent MSD Request
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_ms_set_new_state: Changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x4 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/h323_validate_cap_ind: Remote Pref Codecs UnOrdered (0xC) = [G729IETF] [G729a] []
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_handle_cap_ind: TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP IN SM
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_cap_in_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=98, msgPtr=0x663492C4
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_IND while at state H245_MS_OUTGOING_WAIT
Feb 15 11:15:57: //354/2A6323B98507/H323/MSDetermination: rtt=60 rsdnum=3745, ltt=60 lsdnum=6232
Feb 15 11:15:57: //354/2A6323B98507/H323/MSDetermination: Am SLAVE, ccb->h245.h245_mdStatus = 0x0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: MS_Determine_indication to Appl: Sent MSD ACK!
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_ms_set_new_state: Changing from H245_MS_OUTGOING_WAIT state to H245_MS_INCOMING_WAIT state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x10 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_MSD_ACK_SENT while at state IWF_AWAIT_CAP_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/h323_find_negotiated_codec: Audio Mask 0xC, Negotiated Codec G729IETF(16) Codec Bytes 20
Feb 15 11:15:57: //354/2A6323B98507/H323/h323_find_negotiated_dtmf: peer_data_cap_mask=0x2, peer_user_input_cap_mask=0x32
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
Feb 15 11:15:57: //354/2A6323B98507/H323/h323_find_negotiated_dtmf: Negotiated DTMF relay - 0x0
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_common_msdacksent: Negotiated codecs and dtmf are initialised in ccb
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=67, msgPtr=0x655CCB94
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type NOTIFYIND_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: Entry
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: Rcvd NOTIFY Display Info IE =
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: Rcvd NOTIFY Notification Indicator IE = 113
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: Rcvd NOTIFY Connected Number as ASN
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: [cnum]/[oct]/[oct3a]= [1504]/[0x00]/[0x00]
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_put_embedded_obj_to_ccb: ccb=0x64CD163C, tag=15, size=128
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x64B0EBF8
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_ind: Notify data embedded, mask=0x00000007
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: NOTIFYIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_NOTIFY_IND while at state H225_FS_ACTIVE
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_msg_notify: Entry
Feb 15 11:15:57: //354/2A6323B98507/H323/notify_msg_notify: Notify data found, mask=0x00000007
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_soc
2801-KMZ<61>#ket: owner_data=0x66293EE4, len=95, msgPtr=0x662926D4
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_cap_out_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x5 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=94, msgPtr=0x662926D4
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_FS_ACTIVE
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_CFM while at state H245_MS_INCOMING_WAIT
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: Local Status = 0x2, Remote Status = 0x2
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_run_h245_ms_sm: MSD Determine CFM to APPL, both sides agree!
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_ms_set_new_state: Changing from H245_MS_INCOMING_WAIT state to H245_MS_NONE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x7 internal event to H245 IWF SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_handle_init_olc: Turning off h245 status flag H245_MSD_ACK_SENT_CODEC_INIT_DONE
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Load codec(16) g729r8, codec_bytes = 20
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: cap_modem_proto: 0, cap_modem_codec: 0, cap_modem_redundancy: 0, modem_relay: 0, gw_xid: 0 gw_controlled: 0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Negotiated dtmf relay is inband voice
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Sending caps to the other leg, context = 65F258A0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Sending caps_ack because NTFY DSP set
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Load codec(16) g729r8, codec_bytes = 20
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: cap_modem_proto: 0, cap_modem_codec: 0, cap_modem_redundancy: 0, modem_relay: 0, gw_xid: 0 gw_controlled: 0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Negotiated dtmf relay is inband voice
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Sending caps to the other leg, context = 65F258A0
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_do_cap_ind: Sending caps_ack because NTFY DSP set
Feb 15 11:15:57: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
Feb 15 11:15:57: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x19 internal event to H225 SM
Feb 15 11:15:57: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_FS_ACTIVE
Feb 15 11:15:57: //354/2A6323B98507/H323/default_cleanup_rawbuf_hdlr: Unexpected H225_EV_H245_SUCCESS event in H225_FS_ACTIVE state
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_peer_caps_ack: Sending caps resp event to CAP sm
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_peer_caps_ack: ccb not interested in caps_ack primitive
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_peer_caps_ack: Sending caps resp event to CAP sm
Feb 15 11:15:57: //354/2A6323B98507/H323/cch323_peer_caps_ack: ccb not interested in caps_ack primitive
2801-KMZ<61>#
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_timer_dispatch: Timer[CCH323_RAS_PERIODIC_RRQ_TIMER] expired
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_send_event_to_ras: Queued event CCH323_RAS_EVENT_SEND_LT_RRQ to RAS Global SM
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xA internal event to RAS Global SM
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_run_ras_sm: received event CCH323_RAS_EVENT_SEND_LT_RRQ while at CCH323_RAS_STATE_IDLE state
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_run_ras_sm: changing to CCH323_RAS_STATE_RRQ state
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
2801-KMZ<61>#
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66292B34, len=68, msgPtr=0x655CCB94
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for RAS
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_ras_handle_recv_msg: received msg of type RCF_CHOSEN
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_run_ras_sm: received event CCH323_RAS_EVENT_RCF while at CCH323_RAS_STATE_RRQ state
Feb 15 11:15:59: //-1/xxxxxxxxxxxx/H323/cch323_run_ras_sm: changing to CCH323_RAS_STATE_IDLE state
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_do_call_disconnect: gw_id=1, discCause=16
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM
Feb 15 11:16:05: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_FS_ACTIVE
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_FS_ACTIVE state to H225_WAIT_FOR_DRQ state
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h225_send_release: Cause = 16; Location = 1
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h225_send_release: h225TerminateRequest: src address = 171802622; dest address = 10.1.0.7
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_send_event_to_percall_ras: Queued event CCH323_RAS_EVENT_CALLDISC to RAS Percal SM
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x4 internal event to RAS Percal SM
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_CALLDISC while at CCH323_RAS_STATE_ACTIVE state
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_store_call_history_entry: Call Active Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_store_call_history_entry: Call History Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_DRQ
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 3 Event 0x1
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66293EE4, len=46, msgPtr=0x655CD080
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 10.61.127.254; dest address = 10.1.0.7
Feb 15 11:16:05: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_WAIT_FOR_DRQ
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66292B34, len=3, msgPtr=0x6633B62C
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for RAS
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_ras_handle_recv_msg: received msg of type DCF_CHOSEN
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_ras_rcv_dcf: current active call count is 0
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: received event CCH323_RAS_EVENT_DCF while at CCH323_RAS_STATE_DRQ state
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_percall_ras_sm: ccb 0x64CD163C: changing to new state CCH323_RAS_STATE_IDLE
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x17 internal event to H225 SM
Feb 15 11:16:05: //354/2A6323B98507/H323/run_h225_sm: Received event H225_EV_RAS_SUCCESS while at state H225_WAIT_FOR_DRQ
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_DRQ state to H225_IDLE state
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_call_generic_cleanup: Disconnected flag 1
Feb 15 11:16:05: //354/2A6323B98507/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_ACTIVE
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h245_connection_sm: state=2, event=4, ccb=64CD163C, listen state=0
2801-KMZ<61>#
2801-KMZ<61>#
2801-KMZ<61>#
Feb 15 11:16:05: //354/2A6323B98507/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_CONNECTED state
Feb 15 11:16:05: //354/2A6323B98507/H323/h245_iwf_set_new_state: changing from IWF_ACTIVE state to IWF_IDLE state
Feb 15 11:16:05: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x64CD163C)
2801-KMZ<61>#

P.S. to Xen, Буду благодарен любой полезной информации

Xen · Сообщение **Xen** » Ср 17 фев 2010, 17:13

Я правильно понял что схема такая
ATC -> ip trunk h.323 -> cisco -> CO trunk -> AVAYA ?
жалко что циска не ваша. А владельцы циско не говорят что все должно работать и ищите проблему в своей станции? Тут конечно может дойти дело и до снифера дальней станции, если я правильно понял схему.
покажите конфиг диалпиров с cisco, если это получится.
так же не понятно с какого номера на какой звонили, вижу только что идёт setup по h.323.
Для начала можно поглядеть isdn q931 дебаг, что бы понять какой номер пришел и по каким диалпирам заматчился. И айпи адресацию тоже нужно описать, кто на каких адресах.
Все что вы выше привели, очень сложно читается, а ещё и не понятно что куда и кто куда.

dron85 · Сообщение **dron85** » Чт 18 фев 2010, 08:00

To Xen, Схема такая, ты прав... Прав и в том что владельцы cisco пеняют на нашу станцию)).Попробую вытрясти с них необходимый конфиг.

Непонятно только почему по схеме в одну сторону (слева направо)работает, а наоборот нет?

Xen · Сообщение **Xen** » Чт 18 фев 2010, 12:43

Попробуйте в аналоговый модуль у cisco, вставить аппарат и протестируйте.
Надо было так изначально попробовать

Xen · Сообщение **Xen** » Чт 18 фев 2010, 12:58

А хотя порты наверно FXO...

dron85 · Сообщение **dron85** » Чт 18 фев 2010, 13:51

Попробуйте в аналоговый модуль у cisco, вставить аппарат и протестируйте.
Надо было так изначально попробовать

Так пробовали, все работает... проблема при исходящем соединении с телефона Avaya (выбираю соответствующий busy ind TAC___ набираю внутренний номер московской АТС и через какое то время идет отбой)

А хотя порты наверно FXO...

Незнаю разницы на самом деле, никогда не работал с циской. мне сказали окончание FXS. В чем отличаи от FXO не представляю

dron85 · Сообщение **dron85** » Пн 01 мар 2010, 14:11

Нашел, что в Cisco порт FXS (Station), на плате СО линий тоже порт FXS. Обратился к википедии: такое соединение (FXS-FXS)не должно работать, а у меня звонки из cisco в Avaya проходят нормально, а выбирая BUSY ind_tac - Связь рвется. Не знаю что делать!! HELP

vbr · Сообщение **vbr** » Вт 02 мар 2010, 22:53

FXO (foreign exchange office side) - порт, предназначенный для подключения к нему абонентской линии, полученной от абонентского порта АТС (т.е, он сам эмулирует ТА)

FXS (foreign exchange subscriber side) - порт, предназначенный для подключения к нему телефонного аппарата (или CO-порта АТС, эмулирующего этот ТА)

Разумеется, соединение FXS-FXS работать не должно (также как и соединение FXO-FXO). Причём второе просто не будет работать (так как нет электрической цепи), а первое будет сгорать (так как два источника электрического напряжения соединяются друг с другом).