********************CALL RINGING****************************

Jun 17 16:40:08.210: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=9-CC_EV_CALL_D
IGIT_END  state=CALL_ACTIVE  major=12-EV_MEDIA_EVT  minor=131-[0-9A-D*#LXT]  pkg=8
Jun 17 16:40:08.210: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MEDIA_EVT
Jun 17 16:40:08.210: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:12.150: MGCP sys msg: (15)
Jun 17 16:40:12.150: xlate sys msg: (15, 883A9058)
Jun 17 16:40:12.150: sys msg values: (INVALID MGCP EVENT, 0,  , 0, CALL_IDLE)
Jun 17 16:40:12.150: process mgcp_handle_cmapp_event
Jun 17 16:40:12.150: mgcp_cmapp_send_keepalive send NTFY KeepAlive
Jun 17 16:40:12.150: send_mgcp_empty_notify_msg: endpt *, complete_endpt *@CH-BG08-2651XM-
02
Jun 17 16:40:12.150: * mgcp_get_ca_addr_port:
Jun 17 16:40:12.150: * mgcp_get_ca_addr_port: Used endpoint NE 10.167.163.12:2427
Jun 17 16:40:12.150: mgcp_get_preffered_ip
Jun 17 16:40:12.150: * mgcp_get_ca_addr_port: NE addr 10.167.163.12:2427
Jun 17 16:40:12.150: * mgcp_enq_retx_msg: trans_id 743379023,ntfy_ent 10.167.163.12:2427
Jun 17 16:40:12.154: * mgcp_root_get_profile:
Jun 17 16:40:12.154: mgcp_add_trans_id_rec: Add trans id (743379023, 887ECBE8) record
Jun 17 16:40:12.154: mgcp_stw_timer_start timer type 0, duration 500
Jun 17 16:40:12.154: send_mgcp_empty_notify_msg NTFY Packet sent successfully - *
Jun 17 16:40:12.158: MGC stat - 10.167.163.12, total=644689, succ=641175, failed=3508
Jun 17 16:40:12.158: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:12.158: * mgcp_msg_ack
Jun 17 16:40:12.158: MGC stat - 10.167.163.12, total=644689, succ=641176, failed=3508
Jun 17 16:40:12.158: * mgcp_msg_ack: Removing msg : NTFY 743379023 *@CH-BG08-2651XM-02 MGC
P 0.1
X: 0
O:

Jun 17 16:40:12.158: * mgcp_msg_ack: Setting the restart method to NONE
Jun 17 16:40:12.158: mgcpapp_process_socket
Jun 17 16:40:13.186: mgcpapp_stw_call_back timer type 1
Jun 17 16:40:13.186: mgcpapp_process_timers: timer of type 1 expired.
Jun 17 16:40:13.186: S0/DS1-0/1 <VOICE>Removing ack mgcp_remove_old_ack: (trans ID 185653)
 : 200 185653 OK



**************************CALL ANSWERED******************************


Jun 17 16:40:18.178: mgcpapp_stw_call_back timer type 1
Jun 17 16:40:18.178: mgcpapp_stw_call_back timer type 1
Jun 17 16:40:18.178: mgcpapp_process_timers: timer of type 1 expired.
Jun 17 16:40:18.178: S0/DS1-0/1 <VOICE>Removing ack mgcp_remove_old_ack: (trans ID 185654)
 : 200 185654 OK

Jun 17 16:40:18.178: S0/DS1-0/1 <VOICE>Removing ack mgcp_remove_old_ack: (trans ID 185655)
 : 250 185655 OK
P: PS=160, OS=2746, PR=229, OR=4580, PL=0, JI=0, LA=0

Jun 17 16:40:18.178: mgcpapp_process_timers: timer of type -1 expired.
Jun 17 16:40:18.762: MGC stat - 10.167.163.12, total=644690, succ=641176, failed=3508
Jun 17 16:40:18.762: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcpapp_setup_per_call_data: callp: 887DAE88, vdbpt
r: 8455A398, state: CALL_ACTIVE
Jun 17 16:40:18.766: S0/DS1-0/1 mgcp_endpt_get_notified_entity:
Jun 17 16:40:18.766: S0/DS1-0/1 mgcp_endpt_get_notified_entity: ne 10.167.163.12:2427, ne
addr 10.167.163.12:2427xlate_mgcp_ev: hdr_type=1
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcpapp_process_mgcp_event:Processing Incoming Mess
age [MDCX 185658]
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm:Msg In-Progress(Active) [INV
VERB 185657], await_ev=0, queued=0x00000000
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcp_process_deferred_queue
Jun 17 16:40:18.766: S0/DS1-0/1 mgcp_store_endpt_and_ntfy_entity_name:
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_CONN
Jun 17 16:40:18.766: S0/DS1-0/1 <VOICE>mgcp_modify_connection: callp 887DAE88
Jun 17 16:40:18.770: S0/DS1-0/1 <VOICE>mgcp_modify_connection: conn_mode=3.
Jun 17 16:40:18.770: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:18.770: S0/DS1-0/1 -- mgcp_is_supported_ev(), mgcp_find_evt_in_mask evtnode->
package_bit_map: 8, evtnode->evt_name: 131
Jun 17 16:40:18.770: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:18.770: mgcp_delete_req_digit_filter_q
Jun 17 16:40:18.770: mgcp_setup_digit_filter:nodep->param=[0-9ABCD*#]
Jun 17 16:40:18.770: mgcp_build_digit_filter:
Jun 17 16:40:18.770:     digit_range = 0x088854A624, digit_filter = 0x08887E9D46, len=33
Jun 17 16:40:18.770:     *digit_range = 025B
Jun 17 16:40:18.770:     endFOR: fptr = 0x08887E9D56
Jun 17 16:40:18.770: mgcp_evt_need_dsp
Jun 17 16:40:18.770: S0/DS1-0/1 <VOICE>mgcp_modify_connection() calls mgcp_call_modify_ton
e_det
Jun 17 16:40:18.774: S0/DS1-0/1 mgcp_call_modify_tone_det, digit mode is 0
Jun 17 16:40:18.774: S0/DS1-0/1 mgcp_call_modify_tone_det() voice_if->oi_detect_state: 0
Jun 17 16:40:18.774:  mgcp_save_event_conn_id(): cur_conn_id: 0x6CA5
Jun 17 16:40:18.774: mgcp_save_event_conn_id: ji found. current conn-id = 0x6CA5
Jun 17 16:40:18.774: S0/DS1-0/1 <VOICE>mgcp_modify_connection: calling mgcp_select_codec
Jun 17 16:40:18.774: mgcp_make_supported_codec_list: begin
Jun 17 16:40:18.774: {0x00000001,1}
Jun 17 16:40:18.774: {0x00000002,2}
Jun 17 16:40:18.774: {0x00000004,6}
Jun 17 16:40:18.774: {0x00000008,7}
Jun 17 16:40:18.774: {0x00000010,5}
Jun 17 16:40:18.774: {0x00000020,4}
Jun 17 16:40:18.774: {0x00000040,3}
Jun 17 16:40:18.774: {0x00000800,8}
Jun 17 16:40:18.774: {0x00020000,128}
Jun 17 16:40:18.774: mgcp_make_supported_codec_list: end
Jun 17 16:40:18.778: mgcp_reserve_dyn_payload_type: could not reserve pt 96
Jun 17 16:40:18.778: S0/DS1-0/1 <VOICE>mgcp_is_t38_supported: t38 is supported
Jun 17 16:40:18.778: S0/DS1-0/1 <VOICE>mgcp_modify_connection: peer_addr=A852834, peer_por
t=0->26230.
Jun 17 16:40:18.778: S0/DS1-0/1 mgcp_modify_modem_relay_params:
Jun 17 16:40:18.778: S0/DS1-0/1 <VOIP>mgcp_modify_modem_relay_params: Modem-relay negotiat
ion not done yet
Jun 17 16:40:18.778: S0/DS1-0/1 <VOICE>mgcp_is_t38_supported: t38 is supported
Jun 17 16:40:18.778: S0/DS1-0/1 <VOIP>mgcp_select_nse_payload:
Jun 17 16:40:18.778: mgcp_reserve_dyn_payload_type: could not reserve pt 96
Jun 17 16:40:18.778: S0/DS1-0/1 mgcp_select_nse_payload_and_modem_relay_params: X-tmr not
present in SDP. Disable modem relay
Jun 17 16:40:18.778: S0/DS1-0/1 mgcp_select_nse_payload_and_modem_relay_params:nse-payload
=100, modem-relay-enabled=0,mr-gw-xid=0
Jun 17 16:40:18.782: mgcp_modify_connection: calling mgcp_select_nte
Jun 17 16:40:18.782: mgcp_nte_select_dtmf_relay - LCO:1, SDP:1, lc_nte_list:0, sdp_nte_lis
t:0
Jun 17 16:40:18.782: mgcp_nte_select_dtmf_relay_using_config_rules -IN: mode/codec = 3/0
Jun 17 16:40:18.782: S0/DS1-0/1 <VOIP>
mgcp_nte_select_dtmf_relay_using_config_rules -OUT: mode/codec = 3/0
Jun 17 16:40:18.782: mgcp_nte_select_lcs_nte_relay: No LCS in SDP
Jun 17 16:40:18.782:     nte_control=00000002,
         nte.max=0,
         nte.negotiated=FALSE,
         nte.last_negotiated_mode=0
Jun 17 16:40:18.782:     nte.list =
Jun 17 16:40:18.782:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:18.782:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:18.782:     nte.payload not set
Jun 17 16:40:18.782:     dtmf relay mode/codec = 3/0
Jun 17 16:40:18.782:     nte_control=00000002,
         nte.max=0,
         nte.negotiated=TRUE,
         nte.last_negotiated_mode=3
Jun 17 16:40:18.782:     nte.list =
Jun 17 16:40:18.786:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:18.786:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:18.786:     nte.payload not set
Jun 17 16:40:18.786:     dtmf relay mode/codec = 3/0
Jun 17 16:40:18.786: S0/DS1-0/1 <VOIP>mgcp_select_nte:
Old NTE:
 New NTE
Jun 17 16:40:18.786: mgcp_modify_connection: fax method selected = 1
Jun 17 16:40:18.786: S0/DS1-0/1 <VOICE>conf_id=28210 callid1=55623 callid2=55624 REMOTE
Jun 17 16:40:18.786: S0/DS1-0/1 <VOICE>mgcp_modify_conference:ccapi conference already exi
sts
Jun 17 16:40:18.790: S0/DS1-0/1 <VOICE>mgcp_modify_connection - rtp/aal2 change, callp 847
18D88, callid 55624, await_ev 2
Jun 17 16:40:18.790: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:18.790: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm: return value = 0
Jun 17 16:40:18.790: mgcpapp_process_socket
Jun 17 16:40:18.798: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_
MODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:18.798: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:18.798: S0/DS1-0/1 <VOICE>mgcp_call_modified:
Jun 17 16:40:18.798: S0/DS1-0/1 <VOICE>mgcp_call_modified - callp 887DAE88, voice_callp 88
7DAE88 voice_if 887E1C20, await_ev 2
Jun 17 16:40:18.798: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:18.798: S0/DS1-0/1 <VOIP>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_M
ODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:18.798: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:calling FSM- callp(84718D88)
, current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:18.798: S0/DS1-0/1 <VOIP>mgcp_call_modified:
Jun 17 16:40:18.802: S0/DS1-0/1 <VOIP>mgcp_call_modified - callp 84718D88, voice_callp 887
DAE88 voice_if 887E1C20, await_ev 1
Jun 17 16:40:18.802: S0/DS1-0/1 <VOIP>mgcp_call_modified - SUCCESS
Jun 17 16:40:18.802: mgcp_error_code_to_ack_code: error_tab_index  = 0, protocol version:
4
Jun 17 16:40:18.802: MGC stat - 10.167.163.12, total=644690, succ=641177, failed=3508
Jun 17 16:40:18.802: S0/DS1-0/1 mgcp_send_and_enqueue_ack:
Jun 17 16:40:18.802: mgcp_stw_timer_start timer type 1, duration 30000
Jun 17 16:40:18.802: S0/DS1-0/1 enqueue_ack: ackqhead=884C1060, ackqtail=884C1110, ackp=88
4C1218, msg=84712510
Jun 17 16:40:18.802: S0/DS1-0/1 mgcp_process_quarantine_after_ack:ack_code=200
Jun 17 16:40:18.802: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:FSM done- callp(84718D88), n
ew state CALL_ACTIVE



********************TRANSFER BUTTON PRESSED******************************



Jun 17 16:40:26.559: MGC stat - 10.167.163.12, total=644691, succ=641177, failed=3508
Jun 17 16:40:26.559: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:26.559: S0/DS1-0/1 <VOICE>mgcpapp_setup_per_call_data: callp: 887DAE88, vdbpt
r: 8455A398, state: CALL_ACTIVE
Jun 17 16:40:26.559: S0/DS1-0/1 mgcp_endpt_get_notified_entity:
Jun 17 16:40:26.559: S0/DS1-0/1 mgcp_endpt_get_notified_entity: ne 10.167.163.12:2427, ne
addr 10.167.163.12:2427xlate_mgcp_ev: hdr_type=1
Jun 17 16:40:26.559: S0/DS1-0/1 <VOICE>mgcpapp_process_mgcp_event:Processing Incoming Mess
age [MDCX 185659]
Jun 17 16:40:26.563: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm:Msg In-Progress(Active) [INV
VERB 185658], await_ev=0, queued=0x00000000
Jun 17 16:40:26.563: S0/DS1-0/1 <VOICE>mgcp_process_deferred_queue
Jun 17 16:40:26.563: S0/DS1-0/1 mgcp_store_endpt_and_ntfy_entity_name:
Jun 17 16:40:26.563: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_CONN
Jun 17 16:40:26.563: S0/DS1-0/1 <VOICE>mgcp_modify_connection: callp 887DAE88
Jun 17 16:40:26.563: S0/DS1-0/1 <VOICE>mgcp_modify_connection: conn_mode=2.
Jun 17 16:40:26.563: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:26.563: S0/DS1-0/1 -- mgcp_is_supported_ev(), mgcp_find_evt_in_mask evtnode->
package_bit_map: 8, evtnode->evt_name: 131
Jun 17 16:40:26.563: mgcp_delete_req_digit_filter_q
Jun 17 16:40:26.563: mgcp_setup_digit_filter:nodep->param=[0-9ABCD*#]
Jun 17 16:40:26.563: mgcp_build_digit_filter:
Jun 17 16:40:26.563:     digit_range = 0x088854A3E4, digit_filter = 0x08887E9D46, len=33
Jun 17 16:40:26.567:     *digit_range = 025B
Jun 17 16:40:26.567:     endFOR: fptr = 0x08887E9D56
Jun 17 16:40:26.567: mgcp_evt_need_dsp
Jun 17 16:40:26.567: S0/DS1-0/1 <VOICE>mgcp_modify_connection() calls mgcp_call_modify_ton
e_det
Jun 17 16:40:26.567: S0/DS1-0/1 mgcp_call_modify_tone_det, digit mode is 0
Jun 17 16:40:26.567: S0/DS1-0/1 mgcp_call_modify_tone_det() voice_if->oi_detect_state: 0
Jun 17 16:40:26.567:  mgcp_save_event_conn_id(): cur_conn_id: 0x6CA5
Jun 17 16:40:26.567: mgcp_save_event_conn_id: ji found. current conn-id = 0x6CA5
Jun 17 16:40:26.567: S0/DS1-0/1 mgcp_modify_modem_relay_params:
Jun 17 16:40:26.567: mgcp_modify_connection: fax method selected = 1
Jun 17 16:40:26.567: S0/DS1-0/1 <VOICE>conf_id=28210 callid1=55623 callid2=55624 REMOTE
Jun 17 16:40:26.571: S0/DS1-0/1 <VOICE>mgcp_modify_conference:ccapi conference already exi
sts
Jun 17 16:40:26.571: S0/DS1-0/1 <VOICE>mgcp_modify_connection - rtp/aal2 change, callp 847
18D88, callid 55624, await_ev 2
Jun 17 16:40:26.571: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:26.571: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm: return value = 0
Jun 17 16:40:26.571: mgcpapp_process_socket
Jun 17 16:40:26.575: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_
MODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:26.575: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:26.575: S0/DS1-0/1 <VOICE>mgcp_call_modified:
Jun 17 16:40:26.575: S0/DS1-0/1 <VOICE>mgcp_call_modified - callp 887DAE88, voice_callp 88
7DAE88 voice_if 887E1C20, await_ev 2
Jun 17 16:40:26.575: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:26.579: S0/DS1-0/1 <VOIP>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_M
ODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:26.579: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:calling FSM- callp(84718D88)
, current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:26.579: S0/DS1-0/1 <VOIP>mgcp_call_modified:
Jun 17 16:40:26.579: S0/DS1-0/1 <VOIP>mgcp_call_modified - callp 84718D88, voice_callp 887
DAE88 voice_if 887E1C20, await_ev 1
Jun 17 16:40:26.579: S0/DS1-0/1 <VOIP>mgcp_call_modified - SUCCESS
Jun 17 16:40:26.579: mgcp_error_code_to_ack_code: error_tab_index  = 0, protocol version:
4
Jun 17 16:40:26.579: MGC stat - 10.167.163.12, total=644691, succ=641178, failed=3508
Jun 17 16:40:26.579: S0/DS1-0/1 mgcp_send_and_enqueue_ack:
Jun 17 16:40:26.579: mgcp_stw_timer_start timer type 1, duration 30000
Jun 17 16:40:26.579: S0/DS1-0/1 enqueue_ack: ackqhead=884C1060, ackqtail=884C1218, ackp=88
4C0FB0, msg=84712D60
Jun 17 16:40:26.583: S0/DS1-0/1 mgcp_process_quarantine_after_ack:ack_code=200
Jun 17 16:40:26.583: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:FSM done- callp(84718D88), n
ew state CALL_ACTIVE
Jun 17 16:40:26.611: MGC stat - 10.167.163.12, total=644692, succ=641178, failed=3508
Jun 17 16:40:26.611: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:26.611: S0/DS1-0/1 <VOICE>mgcpapp_setup_per_call_data: callp: 887DAE88, vdbpt
r: 8455A398, state: CALL_ACTIVE
Jun 17 16:40:26.611: S0/DS1-0/1 mgcp_endpt_get_notified_entity:
Jun 17 16:40:26.611: S0/DS1-0/1 mgcp_endpt_get_notified_entity: ne 10.167.163.12:2427, ne
addr 10.167.163.12:2427xlate_mgcp_ev: hdr_type=1
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcpapp_process_mgcp_event:Processing Incoming Mess
age [MDCX 185660]
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm:Msg In-Progress(Active) [INV
VERB 185659], await_ev=0, queued=0x00000000
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcp_process_deferred_queue
Jun 17 16:40:26.615: S0/DS1-0/1 mgcp_store_endpt_and_ntfy_entity_name:
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_CONN
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcp_modify_connection: callp 887DAE88
Jun 17 16:40:26.615: S0/DS1-0/1 <VOICE>mgcp_modify_connection: conn_mode=2.
Jun 17 16:40:26.615: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:26.615: S0/DS1-0/1 -- mgcp_is_supported_ev(), mgcp_find_evt_in_mask evtnode->
package_bit_map: 8, evtnode->evt_name: 131
Jun 17 16:40:26.615: mgcp_delete_req_digit_filter_q
Jun 17 16:40:26.615: mgcp_setup_digit_filter:nodep->param=[0-9ABCD*#]
Jun 17 16:40:26.615: mgcp_build_digit_filter:
Jun 17 16:40:26.619:     digit_range = 0x088854AE70, digit_filter = 0x08887E9D46, len=33
Jun 17 16:40:26.619:     *digit_range = 025B
Jun 17 16:40:26.619:     endFOR: fptr = 0x08887E9D56
Jun 17 16:40:26.619: mgcp_evt_need_dsp
Jun 17 16:40:26.619: S0/DS1-0/1 <VOICE>mgcp_modify_connection() calls mgcp_call_modify_ton
e_det
Jun 17 16:40:26.619: S0/DS1-0/1 mgcp_call_modify_tone_det, digit mode is 0
Jun 17 16:40:26.619: S0/DS1-0/1 mgcp_call_modify_tone_det() voice_if->oi_detect_state: 0
Jun 17 16:40:26.619:  mgcp_save_event_conn_id(): cur_conn_id: 0x6CA5
Jun 17 16:40:26.619: mgcp_save_event_conn_id: ji found. current conn-id = 0x6CA5
Jun 17 16:40:26.619: S0/DS1-0/1 <VOICE>mgcp_modify_connection: calling mgcp_select_codec
Jun 17 16:40:26.619: mgcp_make_supported_codec_list: begin
Jun 17 16:40:26.619: {0x00000001,1}
Jun 17 16:40:26.619: {0x00000002,2}
Jun 17 16:40:26.619: {0x00000004,6}
Jun 17 16:40:26.619: {0x00000008,7}
Jun 17 16:40:26.623: {0x00000010,5}
Jun 17 16:40:26.623: {0x00000020,4}
Jun 17 16:40:26.623: {0x00000040,3}
Jun 17 16:40:26.623: {0x00000800,8}
Jun 17 16:40:26.623: {0x00020000,128}
Jun 17 16:40:26.623: mgcp_make_supported_codec_list: end
Jun 17 16:40:26.623: S0/DS1-0/1 <VOICE>mgcp_modify_connection: codec changed. mgcp codec n
ew = 6, old = 8
Jun 17 16:40:26.623: S0/DS1-0/1 mgcp_modify_modem_relay_params:
Jun 17 16:40:26.623: mgcp_modify_connection: calling mgcp_select_nte
Jun 17 16:40:26.623: mgcp_nte_select_dtmf_relay - LCO:1, SDP:0, lc_nte_list:0, sdp_nte_lis
t:0
Jun 17 16:40:26.623: mgcp_nte_select_dtmf_relay_using_config_rules -IN: mode/codec = 3/0
Jun 17 16:40:26.623: S0/DS1-0/1 <VOIP>
mgcp_nte_select_dtmf_relay_using_config_rules -OUT: mode/codec = 3/0mgcp_nte_select_lcs_nt
e_relay: No LCS in LCO
Jun 17 16:40:26.623:     nte_control=00000002,
         nte.max=0,
         nte.negotiated=FALSE,
         nte.last_negotiated_mode=0
Jun 17 16:40:26.623:     nte.list =
Jun 17 16:40:26.627:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:26.627:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:26.627:     nte.payload not set
Jun 17 16:40:26.627:     dtmf relay mode/codec = 3/0
Jun 17 16:40:26.627:     nte_control=00000002,
         nte.max=0,
         nte.negotiated=FALSE,
         nte.last_negotiated_mode=0
Jun 17 16:40:26.627:     nte.list =
Jun 17 16:40:26.627:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:26.627:       00000000,   00000000,   00000000,   00000000,
Jun 17 16:40:26.627:     nte.payload not set
Jun 17 16:40:26.627:     dtmf relay mode/codec = 3/0
Jun 17 16:40:26.627: S0/DS1-0/1 <VOIP>mgcp_select_nte:
Old NTE:
 New NTE
Jun 17 16:40:26.627: mgcp_release_cac: VoIP call leg
Jun 17 16:40:26.627: mgcp_revoke_cac: IP
Jun 17 16:40:26.631: mgcp_revoke_cac: ccReleaseCAC() returned SUCCESS
Jun 17 16:40:26.631: mgcp_modify_connection: fax method selected = 1
Jun 17 16:40:26.631: S0/DS1-0/1 <VOICE>conf_id=28210 callid1=55623 callid2=55624 REMOTE
Jun 17 16:40:26.631: S0/DS1-0/1 <VOICE>mgcp_modify_conference:ccapi conference already exi
sts
Jun 17 16:40:26.631: S0/DS1-0/1 <VOICE>mgcp_modify_connection - rtp/aal2 change, callp 847
18D88, callid 55624, await_ev 2
Jun 17 16:40:26.631: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:26.631: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm: return value = 0
Jun 17 16:40:26.631: mgcpapp_process_socket
Jun 17 16:40:26.639: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_
MODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:26.639: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:26.639: S0/DS1-0/1 <VOICE>mgcp_call_modified:
Jun 17 16:40:26.639: S0/DS1-0/1 <VOICE>mgcp_call_modified - callp 887DAE88, voice_callp 88
7DAE88 voice_if 887E1C20, await_ev 2
Jun 17 16:40:26.639: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:26.639: S0/DS1-0/1 <VOIP>mgcpapp_process_ccapi_ev CCAPI event=32-CC_EV_CALL_M
ODIFY_DONE  state=CALL_ACTIVE  major=16-EV_MODIFY_DONE  minor=135-   pkg=8
Jun 17 16:40:26.639: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:calling FSM- callp(84718D88)
, current state CALL_ACTIVE, event EV_MODIFY_DONE
Jun 17 16:40:26.639: S0/DS1-0/1 <VOIP>mgcp_call_modified:
Jun 17 16:40:26.639: S0/DS1-0/1 <VOIP>mgcp_call_modified - callp 84718D88, voice_callp 887
DAE88 voice_if 887E1C20, await_ev 1
Jun 17 16:40:26.643: S0/DS1-0/1 <VOIP>mgcp_call_modified - SUCCESS
Jun 17 16:40:26.643: mgcp_error_code_to_ack_code: error_tab_index  = 0, protocol version:
4
Jun 17 16:40:26.643: MGC stat - 10.167.163.12, total=644692, succ=641179, failed=3508
Jun 17 16:40:26.643: generate_local_voip_sdp: entered
Jun 17 16:40:26.643: S0/DS1-0/1 <VOICE>First Audio codec, 6, local encoding, 18
Jun 17 16:40:26.643: S0/DS1-0/1 <VOICE>mgcp_is_t38_supported: t38 is supported
Jun 17 16:40:26.647: S0/DS1-0/1 mgcp_send_and_enqueue_ack:
Jun 17 16:40:26.647: mgcp_stw_timer_start timer type 1, duration 30000
Jun 17 16:40:26.647: S0/DS1-0/1 enqueue_ack: ackqhead=884C1060, ackqtail=884C0FB0, ackp=88
4C0EA8, msg=83BBEDA8
Jun 17 16:40:26.647: S0/DS1-0/1 mgcp_process_quarantine_after_ack:ack_code=200
Jun 17 16:40:26.647: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:FSM done- callp(84718D88), n
ew state CALL_ACTIVE
Jun 17 16:40:26.715: MGC stat - 10.167.163.12, total=644693, succ=641179, failed=3508
Jun 17 16:40:26.715: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:26.719: S0/DS1-0/1 <VOICE>mgcpapp_setup_per_call_data: callp: 887DAE88, vdbpt
r: 8455A398, state: CALL_ACTIVE
Jun 17 16:40:26.719: S0/DS1-0/1 mgcp_endpt_get_notified_entity:
Jun 17 16:40:26.719: S0/DS1-0/1 mgcp_endpt_get_notified_entity: ne 10.167.163.12:2427, ne
addr 10.167.163.12:2427xlate_mgcp_ev: hdr_type=1
Jun 17 16:40:26.719: S0/DS1-0/1 <VOICE>mgcpapp_process_mgcp_event:Processing Incoming Mess
age [MDCX 185661]
Jun 17 16:40:26.719: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm:Msg In-Progress(Active) [INV
VERB 185660], await_ev=0, queued=0x00000000
Jun 17 16:40:26.719: S0/DS1-0/1 <VOICE>mgcp_process_deferred_queue
Jun 17 16:40:26.723: S0/DS1-0/1 mgcp_store_endpt_and_ntfy_entity_name:
Jun 17 16:40:26.723: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_MODIFY_CONN
Jun 17 16:40:26.723: S0/DS1-0/1 <VOICE>mgcp_modify_connection: callp 887DAE88
Jun 17 16:40:26.723: S0/DS1-0/1 <VOICE>mgcp_modify_connection: conn_mode=2.
Jun 17 16:40:26.723: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:26.723: S0/DS1-0/1 -- mgcp_is_supported_ev(), mgcp_find_evt_in_mask evtnode->
package_bit_map: 8, evtnode->evt_name: 131
Jun 17 16:40:26.723: mgcp_delete_req_digit_filter_q
Jun 17 16:40:26.723: mgcp_setup_digit_filter:nodep->param=[0-9ABCD*#]
Jun 17 16:40:26.723: mgcp_build_digit_filter:
Jun 17 16:40:26.723:     digit_range = 0x088854A624, digit_filter = 0x08887E9D46, len=33
Jun 17 16:40:26.723:     *digit_range = 025B
Jun 17 16:40:26.723:     endFOR: fptr = 0x08887E9D56
Jun 17 16:40:26.727: mgcp_evt_need_dsp
Jun 17 16:40:26.727: S0/DS1-0/1 <VOICE>mgcp_modify_connection() calls mgcp_call_modify_ton
e_det
Jun 17 16:40:26.727: S0/DS1-0/1 mgcp_call_modify_tone_det, digit mode is 0
Jun 17 16:40:26.727: S0/DS1-0/1 mgcp_call_modify_tone_det() voice_if->oi_detect_state: 0
Jun 17 16:40:26.727:  mgcp_save_event_conn_id(): cur_conn_id: 0x6CA5
Jun 17 16:40:26.727: mgcp_save_event_conn_id: ji found. current conn-id = 0x6CA5
Jun 17 16:40:26.727: S0/DS1-0/1 <VOICE>mgcp_modify_connection: calling mgcp_select_codec
Jun 17 16:40:26.727: mgcp_make_supported_codec_list: begin
Jun 17 16:40:26.727: {0x00000001,1}
Jun 17 16:40:26.727: {0x00000002,2}
Jun 17 16:40:26.727: {0x00000004,6}
Jun 17 16:40:26.727: {0x00000008,7}
Jun 17 16:40:26.727: {0x00000010,5}
Jun 17 16:40:26.727: {0x00000020,4}
Jun 17 16:40:26.731: {0x00000040,3}
Jun 17 16:40:26.731: {0x00000800,8}
Jun 17 16:40:26.731: {0x00020000,128}
Jun 17 16:40:26.731: mgcp_make_supported_codec_list: end
Jun 17 16:40:26.731: mgcp_error_code_to_ack_code: error_tab_index  = 19, protocol version:
 4
Jun 17 16:40:26.731: MGC stat - 10.167.163.12, total=644693, succ=641179, failed=3509
Jun 17 16:40:26.731: S0/DS1-0/1 mgcp_send_and_enqueue_ack:
Jun 17 16:40:26.731: mgcp_stw_timer_start timer type 1, duration 30000
Jun 17 16:40:26.731: S0/DS1-0/1 enqueue_ack: ackqhead=884C1060, ackqtail=884C0EA8, ackp=88
4C0E50, msg=84712BA0
Jun 17 16:40:26.735: S0/DS1-0/1 mgcp_process_quarantine_after_ack:ack_code=502
Jun 17 16:40:26.735: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_ACTIVE
Jun 17 16:40:26.735: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm: return value = 76
Jun 17 16:40:26.735: mgcpapp_process_socket
Jun 17 16:40:26.887: MGC stat - 10.167.163.12, total=644694, succ=641179, failed=3509
Jun 17 16:40:26.887: mgcpapp_process_mgcp_msg : <NEW MGCP MSG From CA>
Jun 17 16:40:26.887: S0/DS1-0/1 <VOICE>mgcpapp_setup_per_call_data: callp: 887DAE88, vdbpt
r: 8455A398, state: CALL_ACTIVE
Jun 17 16:40:26.891: S0/DS1-0/1 mgcp_endpt_get_notified_entity:
Jun 17 16:40:26.891: S0/DS1-0/1 mgcp_endpt_get_notified_entity: ne 10.167.163.12:2427, ne
addr 10.167.163.12:2427xlate_mgcp_ev: hdr_type=1
Jun 17 16:40:26.891: S0/DS1-0/1 <VOICE>mgcpapp_process_mgcp_event:Processing Incoming Mess
age [DLCX 185662]
Jun 17 16:40:26.891: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm:Msg In-Progress(Active) [INV
VERB 185661], await_ev=0, queued=0x00000000
Jun 17 16:40:26.891: S0/DS1-0/1 <VOICE>mgcp_process_deferred_queue
Jun 17 16:40:26.891: S0/DS1-0/1 mgcp_store_endpt_and_ntfy_entity_name:
Jun 17 16:40:26.891: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_ACTIVE, event EV_DELETE_CONN
Jun 17 16:40:26.891: S0/DS1-0/1 <VOICE>mgcp_dlcx_msg:
Jun 17 16:40:26.891: S0/DS1-0/1 -- mgcp_is_supported_ev(begin)
Jun 17 16:40:26.895: S0/DS1-0/1 <VOICE>mgcp_process_dlcx_msg() calls mgcp_call_modify_tone
_det
Jun 17 16:40:26.895: S0/DS1-0/1 mgcp_call_modify_tone_det, digit mode is -1
Jun 17 16:40:26.895: S0/DS1-0/1 mgcp_call_modify_tone_det() voice_if->oi_detect_state: 0
Jun 17 16:40:26.895: S0/DS1-0/1 <VOICE>mgcp_delete_single_conn: conn id = 27813
Jun 17 16:40:26.899: S0/DS1-0/1 <VOICE>start_active_conn_delete (conn_recp=0x887DD510)
Jun 17 16:40:26.899: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_CONF_DESTROYING
Jun 17 16:40:26.899: S0/DS1-0/1 <VOICE>mgcpapp_invoke_mgcp_sm: return value = 0
Jun 17 16:40:26.899: mgcpapp_process_socket
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=56-CC_EV_CALL_
REPORT_DIGITS_DONE  state=CALL_CONF_DESTROYING  major=21-INVALID MGCP EVENT  minor=135-
pkg=8
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>MGCP ccapi invalid ev=56-CC_EV_CALL_REPORT_DIGITS_D
ONE  major=21-INVALID MGCP EVENT  minor=135-   pkg=8
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=30-CC_EV_CONF_
DESTROY_DONE  state=CALL_CONF_DESTROYING  major=7-EV_CONF_DESTROY  minor=135-   pkg=8
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_CONF_DESTROYING, event EV_CONF_DESTROY
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>mgcp_conf_destroyed: callp(0x887DAE88)
Jun 17 16:40:26.903: S0/DS1-0/1 <VOICE>start_inactive_conn_delete (conn_recp=0x887DD510)
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>start_inactive_conn_delete: last connection, termin
ate call legs.
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>mgcp_terminate_call_legs
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>mgcp_terminate_call_legs:check to see if any signal
 needs dsp.
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>mgcp_terminate_call_legs:any signal needs dsp=0.
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>is_mgcp_endpt_offhook: hook_state is 5
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>is_mgcp_waiting_for_shu: callp->waiting_for_shu is
0
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>mgcp_disconnect_call_leg: Releasing CAC
Jun 17 16:40:26.907: mgcp_release_cac: Voice call leg
Jun 17 16:40:26.907: mgcp_release_cac: peer type is VoIP
Jun 17 16:40:26.907: mgcp_revoke_cac: IP
Jun 17 16:40:26.907: mgcp_revoke_cac: ccReleaseCAC() returned SUCCESS
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>is_mgcp_endpt_offhook: hook_state is 5
Jun 17 16:40:26.907: S0/DS1-0/1 <VOICE>is_mgcp_waiting_for_shu: callp->waiting_for_shu is
0
Jun 17 16:40:26.911: S0/DS1-0/1 <VOICE>mgcp_disconnect_call_leg O.K. call_id=55623
Jun 17 16:40:26.911: S0/DS1-0/1 <VOICE>mgcp_disconnect_call_leg:resetting the waiting_for_
shu to FALSE
Jun 17 16:40:26.911: S0/DS1-0/1 <VOIP>mgcp_disconnect_call_leg: Releasing CAC
Jun 17 16:40:26.911: mgcp_release_cac: VoIP call leg
Jun 17 16:40:26.911: S0/DS1-0/1 <VOIP>mgcp_disconnect_call_leg O.K. call_id=55624
Jun 17 16:40:26.911: S0/DS1-0/1 <VOIP>mgcp_free_local_dyn_nse_payload: local NSE payload 1
00, configured NSE payload = 100
Jun 17 16:40:26.911: S0/DS1-0/1 <VOIP>mgcp_free_local_dyn_nse_payload: Payload bitmask aft
er free = 0x2F000013
Jun 17 16:40:26.911: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_DISCONNECTING
Jun 17 16:40:26.999: S0/DS1-0/1 <VOIP>mgcpapp_process_ccapi_ev CCAPI event=12-CC_EV_CALL_D
ISCONNECT_DONE  state=CALL_DISCONNECTING  major=8-EV_CALL_DISCONNECT  minor=135-   pkg=8
Jun 17 16:40:26.999: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:calling FSM- callp(84718D88)
, current state CALL_DISCONNECTING, event EV_CALL_DISCONNECT
Jun 17 16:40:26.999: S0/DS1-0/1 <VOIP>mgcp_call_disconnected: callp=0x84718D88 call_id=556
24
Jun 17 16:40:26.999: -- mgcp_stop_ld_timer()
Jun 17 16:40:27.003: -- mgcp_stop_ld_timer() - stop timer
Jun 17 16:40:27.003: S0/DS1-0/1 <VOIP>mgcp_invoke_app_sm:MGCP:FSM done- callp(84718D88), n
ew state CALL_IDLE
Jun 17 16:40:27.007: S0/DS1-0/1 <VOICE>mgcpapp_process_ccapi_ev CCAPI event=12-CC_EV_CALL_
DISCONNECT_DONE  state=CALL_DISCONNECTING  major=8-EV_CALL_DISCONNECT  minor=135-   pkg=8
Jun 17 16:40:27.007: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:calling FSM- callp(887DAE88
), current state CALL_DISCONNECTING, event EV_CALL_DISCONNECT
Jun 17 16:40:27.007: S0/DS1-0/1 <VOICE>mgcp_call_disconnected: callp=0x887DAE88 call_id=55
623
Jun 17 16:40:27.007: mgcp_error_code_to_ack_code: error_tab_index  = 6, protocol version:
4
Jun 17 16:40:27.007: MGC stat - 10.167.163.12, total=644694, succ=641180, failed=3509
Jun 17 16:40:27.007: S0/DS1-0/1 mgcp_send_and_enqueue_ack:
Jun 17 16:40:27.007: mgcp_stw_timer_start timer type 1, duration 30000
Jun 17 16:40:27.011: S0/DS1-0/1 enqueue_ack: ackqhead=884C1060, ackqtail=884C0E50, ackp=88
4C1378, msg=8885669C
Jun 17 16:40:27.011: S0/DS1-0/1 mgcp_process_quarantine_after_ack:ack_code=250
Jun 17 16:40:27.011: mgcp_delete_qb_evt_q:cleanup QB evt q
Jun 17 16:40:27.011: S0/DS1-0/1 <VOICE>mgcp_call_disconnected: voice_if->ntfy_queue=887E1C
38, ntfy_qhead=0, ntfy_qtail=0
Jun 17 16:40:27.011: -- mgcp_stop_ld_timer()
Jun 17 16:40:27.011: S0/DS1-0/1 <VOICE>mgcp_invoke_app_sm:MGCP:FSM done- callp(887DAE88),
new state CALL_IDLE




***************************CALL DISCONNECTED********************************