=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2019.03.21 21:17:21 =~=~=~=~=~=~=~=~=~=~=~= VG_SAN_FRAN_01#debug mgcp all This may severely impact network performance. Continue[confirm] Media Gateway Control Protocol all debugging is on, trace-level Verbose VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01#ter mon VG_SAN_FRAN_01# VG_SAN_FRAN_01#no ccm-manager config ^ % Invalid input detected at '^' marker. VG_SAN_FRAN_01# Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC47C0) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC47E0) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xg VG_SAN_FRAN_01#cp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:17:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130118 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130118, 7F2F98CE0340) record Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4800) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:17:5 VG_SAN_FRAN_01#9: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4820) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:17:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130120 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130120, 7F2F98CE05A0) record Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6776, succ=3387, failed=3388 Mar 21 21:17:59: MGCP Packet received from 192.168.80.9:2427---> 200 30130118 <--- Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6776, succ=3388, failed=3388 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130118 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6779, succ=3388, failed=3390 Mar 21 21:17:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130120 <--- Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP VG_SAN_FRAN_01#/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6779, succ=3388, failed=3391 Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130120 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:17:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01#conf t Enter configuration commands, one per line. End with CNTL/Z. VG_SAN_FRAN_01(config)#no ccm-manager config VG_SAN_FRAN_01(config)#ccm-manager config VG_SAN_FRAN_01(config)# Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_xmlcfg_reg_with_CM(721):[lvl=0]MGCP_XMLCFG Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_name(1110):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_name_parse_root_asterisk(913):[lvl=0]* Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_cfg_send_rsip(536):[lvl=1]MGCP_XMLCFG: endpt *, restart_method=3, rsip_shutdown=1 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_cfg_send_rsip(539):[lvl=1]MGCP_XMLCFG: Sending gw host is VG_SAN_FRAN_01.elvalle.com Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_get_active_ccm_ip_addr(2616):[lvl=2]Using actv CCM IP addr 192.168.80.8, port 2427 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_cfg_send_rsip(555):[lvl=1]MGCP_XMLCFG: Sending RSIP to Notify entity (192.168.80.8) Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(379):[lvl=2]socket_set_option returned -1. Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(389):[lvl=2]ERROR: MGCPAPP send_mgcp_msg failure, status -1, errno 9 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(868):[lvl=0]Unable to send rsip Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method VG_SAN_FRAN_01(config)# Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130122, 7F2F98CE05A0) record Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_stw_call_back(316):[lvl=0]timer type 0 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_timers(1465):[lvl=0]timer of type 0 expired. Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1933):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1951):[lvl=0]retx_recp->,*next:0x0,trans_id:30130122,*msgp:0x7F2F917A43D0,laddr:0.0.0.0,raddr_info:192.168.80.8,lport:2427,rport:2427,retx_cnt:0 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1951):[lvl=0]retx_recp->,retx_timer:0x7F2F98CE05D8,tsmax_timer:0x7F2F98CE0628,firstsent_ts:515,is_restart_rsip:0,prot_ver:4,*profile:0x561A41B4A080,wait_def_mgc_ack:1,rsip_shutdown:1,rqnt_ack:0 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1951):[lvl=0]retx_recp->,msg_type:8,last_ntfy_trans_id:0,max1_retry:5,max2_retry:7,timeout_thist:30000,timeout_tsmax:20000,timeout_tdinit:15 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1951):[lvl=0]retx_recp->,endpt_name:*,*endpt:0x7F2F9845F8C0,notify entity:192.168.80.8 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2005):[lvl=1]max2 no of retries kicks in Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1481):[lvl=1]to raddr=192.168.80.8,rport=2427 fromladdr=0.0.0.0,lport = 2427 Mar 21 21:18:20: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130122 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1623):[lvl=1]SUCCESSFUL retransmission Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2052):[lvl=1]retx msg again, retx_cnt=1 timeout 1000 ms Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 1000 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6780, succ=3388, failed=3391 Mar 21 21:18:20: MGCP Packet received from 192.168.80.8:2427---> 500 30130122 <--- Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6780, succ=3388, failed=3392 Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130122 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE VG_SAN_FRAN_01(config)# Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:18:20: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)# VG_SAN_FRAN_01(config)#end VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01#sh Mar 21 21:18:28.205: %SYS-5-CONFIG_I: Configured from console by soporte on vty0 (192.168.80.51) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4840) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:18:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130124 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130124, 7F2F98CE05A0) record Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4860) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4880) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:18:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130126 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130126, 7F2F98CE0340) record Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6781, succ=3388, failed=3392 Mar 21 21:18:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130124 <--- Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6781, succ=3389, failed=3392 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 301 VG_SAN_FRAN_01#show30124 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6777, succ=3388, failed=3388 Mar 21 21:18:29: MGCP Packet received from 192.168.80.9:2427---> 500 30130126 <--- Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6777, succ=3388, failed=3389 Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130126 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:18:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01#show cc VG_SAN_FRAN_01#show ccm-m VG_SAN_FRAN_01#show ccm-manager MGCP Domain Name: VG_SAN_FRAN_01.elvalle.com Priority Status Host ============================================================ Primary Backup Ready 192.168.80.8 First Backup Registering with CM 192.168.80.9 Second Backup None Current active Call Manager: None Backhaul/Redundant link port: 2428 Failover Interval: 30 seconds Keepalive Interval: 15 seconds Last keepalive sent: 20:24:40 GMT Mar 18 2019 (elapsed time: 3d00h) Last MGCP traffic time: 21:18:29 GMT Mar 21 2019 (elapsed time: 00:00:07) Last failover time: 21:18:29 GMT Mar 21 2019 from (192.168.80.8) Last switchback time: 21:17:59 GMT Mar 21 2019 from (192.168.80.9) Switchback mode: Graceful MGCP Fallback mode: Not Selected Last MGCP Fallback start time: None Last MGCP Fallback end time: None MGCP Download Tones: Disabled TFTP retry count to shut Ports: 2 Configuration Auto-Download Information ======================================= No configurations downloaded Current state: Waiting for commands Configuration Download statistics: Download Attempted : 1 Download Successful : 0 Download Failed : 1 TFTP Download Failed : 1 Configuration Attempted : 0 Configuration Successful : 0 Configuration Failed(Parsing): 0 Configuration Failed(config) : 0 Last config download command: FAX mode: disable Configuration Error History: TFTP File download failed VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01#un   VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# VG_SAN_FRAN_01# Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC48A0) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC48C0) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:18:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130128 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130128, 7F2F98CE0340) record Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC48E0) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4900) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:18:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130130 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130130, 7F2F98CE05A0) record Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6778, succ=3388, failed=3389 Mar 21 21:18:59: MGCP Packet received from 192.168.80.9:24 VG_SAN_FRAN_01#27---> 200 30130128 <--- Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6778, succ=3389, failed=3389 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130128 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6782, succ=3389, failed=3392 Mar 21 21:18:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130130 <--- Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6782, succ=3389, failed=3393 Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130130 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:18:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4920) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:19:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130132 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130132, 7F2F98CE05A0) record Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4940) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4960) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:19:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130134 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130134, 7F2F98CE0340) record Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6783, succ=3389, failed=3393 Mar 21 21:19:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130132 <--- Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6783, succ=3390, failed=3393 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130132 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6779, succ=3389, failed=3389 Mar 21 21:19:29: MGCP Packet received from 1 VG_SAN_FRAN_01#92.168.80.9:2427---> 500 30130134 <--- Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6779, succ=3389, failed=3390 Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130134 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:19:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4980) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC49A0) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:19:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130136 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130136, 7F2F98CE0340) record Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC49C0) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC49E0) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:19:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130138 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130138, 7F2F98CE05A0) record Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6780, succ=3389, failed=3390 Mar 21 21:19:59: MGCP Packet received from 192.168.80.9:24 VG_SAN_FRAN_01#27---> 200 30130136 <--- Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6780, succ=3390, failed=3390 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130136 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6784, succ=3390, failed=3393 Mar 21 21:19:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130138 <--- Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6784, succ=3390, failed=3394 Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130138 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:19:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4A00) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:20:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130140 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130140, 7F2F98CE05A0) record Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4A20) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4A40) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:20:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130142 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130142, 7F2F98CE0340) record Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6785, succ=3390, failed=3394 Mar 21 21:20:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130140 <--- Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6785, succ=3391, failed=3394 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130140 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6781, succ=3390, failed=3390 Mar 21 21:20:29: MGCP Packet received from 1 VG_SAN_FRAN_01#92.168.80.9:2427---> 500 30130142 <--- Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6781, succ=3390, failed=3391 Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130142 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:20:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4A60) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4A80) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:20:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130144 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130144, 7F2F98CE0340) record Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4AA0) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4AC0) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:20:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130146 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130146, 7F2F98CE05A0) record Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6782, succ=3390, failed=3391 Mar 21 21:20:59: MGCP Packet received from 192.168.80.9:24 VG_SAN_FRAN_01#27---> 200 30130144 <--- Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6782, succ=3391, failed=3391 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130144 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6786, succ=3391, failed=3394 Mar 21 21:20:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130146 <--- Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6786, succ=3391, failed=3395 Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130146 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:20:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4AE0) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:21:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130148 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130148, 7F2F98CE05A0) record Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4B00) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4B20) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:21:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130150 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130150, 7F2F98CE0340) record Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6787, succ=3391, failed=3395 Mar 21 21:21:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130148 <--- Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6787, succ=3392, failed=3395 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130148 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6783, succ=3391, failed=3391 Mar 21 21:21:29: MGCP Packet received from 1 VG_SAN_FRAN_01#92.168.80.9:2427---> 500 30130150 <--- Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6783, succ=3391, failed=3392 Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130150 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:21:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4B40) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4B60) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:21:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130152 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130152, 7F2F98CE0340) record Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4B80) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4BA0) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:21:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130154 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130154, 7F2F98CE05A0) record Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6784, succ=3391, failed=3392 Mar 21 21:21:59: MGCP Packet received from 192.168.80.9:24 VG_SAN_FRAN_01#27---> 200 30130152 <--- Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6784, succ=3392, failed=3392 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130152 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6788, succ=3392, failed=3395 Mar 21 21:21:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130154 <--- Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6788, succ=3392, failed=3396 Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130154 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:21:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4BC0) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:22:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130156 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130156, 7F2F98CE05A0) record Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4BE0) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4C00) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:22:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130158 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130158, 7F2F98CE0340) record Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6789, succ=3392, failed=3396 Mar 21 21:22:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130156 <--- Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6789, succ=3393, failed=3396 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130156 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6785, succ=3392, failed=3392 Mar 21 21:22:29: MGCP Packet received from 1 VG_SAN_FRAN_01#92.168.80.9:2427---> 500 30130158 <--- Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6785, succ=3392, failed=3393 Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130158 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:22:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4C20) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=0.0.0.0 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=0.0.0.0 cm_port=2427 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4C40) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:22:59: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130160 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced <--- Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130160, 7F2F98CE0340) record Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4C60) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.8 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.8 cm_port=2427 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.8:2427 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.8:2427 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4C80) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:22:59: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130162 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130162, 7F2F98CE05A0) record Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6786, succ=3392, failed=3393 Mar 21 21:22:59: MGCP Packet received from 192.168.80.9:24 VG_SAN_FRAN_01#27---> 200 30130160 <--- Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6786, succ=3393, failed=3393 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130160 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: forced Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6790, succ=3393, failed=3396 Mar 21 21:22:59: MGCP Packet received from 192.168.80.8:2427---> 500 30130162 <--- Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6790, succ=3393, failed=3397 Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130162 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:22:59: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4CA0) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.8 next->192.168.80.8 Mar 21 21:23:29: MGCP Packet sent to 192.168.80.8:2427---> RSIP 30130164 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful <--- Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130164, 7F2F98CE05A0) record Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4CC0) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(456):[lvl=1]new cmapp_req->new_ca_ip_addr=192.168.80.9 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_redirect_gateway(231):[lvl=0]cm_ipaddr=192.168.80.9 cm_port=2427 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(684):[lvl=0]ne 192.168.80.9:2427 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(525):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(549):[lvl=1]ne 192.168.80.9:2427 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_call_agent(499):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(711):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 00D0 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first(3265):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_first_child(3160):[lvl=0]endpt * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_default_get_first_child(3203):[lvl=0]endpt * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(208):[lvl=0]MGCP sys msg: (EXTERNAL_EVENT) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xlate_mgcp_sys_ev(69):[lvl=0]xlate sys msg: (EXTERNAL_EVENT, 7F2F90CC4CE0) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_sys_msg(217):[lvl=0]sys msg values: (INVALID MGCP EVENT, 0, , 0, CALL_IDLE) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_handle_cmapp_event(441):[lvl=0]process Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_by_ifn(1330):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1149):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_compute_key(198):[lvl=0]type -1 slot FFFFFFFFFFFFFFFF subunit 0000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/xgcp_endpt_get_tree_link_by_ifn(1161):[lvl=0]computed key 0xF0000000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_cmapp_send_rsip(406):[lvl=1]Send-RSIP: Sending gw host is VG_SAN_FRAN_01.elvalle.com, endpt is * Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_root_get_profile(1906):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(737):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(864):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(496):[lvl=1]328 bytes needed for header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(785):[lvl=1]344 bytes needed after checking parameter lines Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_estimate_msg_buf_length(808):[lvl=1]344 bytes needed after checking SDP lines Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9543):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_val_mandatory_parms(13063):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12117):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_restart_method(12139):[lvl=0]SUCCESS: Building Restart Method (RM:) OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_parameter_lines(9846):[lvl=0]SUCCESS: Building MGCP Parameter lines is OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_build_packet(1018):[lvl=1]SUCCESS: MGCP message building OK - END of building Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/MGCP_NEDB_get_next_addr(1211):[lvl=1] NOTIFY ENTITY = 192.168.80.9 next->192.168.80.9 Mar 21 21:23:29: MGCP Packet sent to 192.168.80.9:2427---> RSIP 30130166 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart <--- Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(882):[lvl=1]Manually recording RSIP method Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(615):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(396):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_add_trans_id_rec(1163):[lvl=1]Add trans id (30130166, 7F2F98CE0340) record Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 0, duration 500 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_stw_timer_start(393):[lvl=0]timer type 15, duration 20000 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6791, succ=3393, failed=3397 Mar 21 21:23:29: MGCP Packet received from 192.168.80.8:2427---> 200 30130164 <--- Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.8, total=6791, succ=3394, failed=3397 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130164 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: graceful Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6787, succ=3393, failed=3393 Mar 21 21:23:29: MGCP Packet received from 1 VG_SAN_FRAN_01#92.168.80.9:2427---> 500 30130166 <--- Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_msg(3534):[lvl=0] : Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(312):[lvl=0]call mgcp_parse_header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(316):[lvl=0]out mgcp_parse_header Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(326):[lvl=1]SUCCESS: - MGCP Header parsing was OK Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_parse_packet(344):[lvl=2]SUCCESS: - END of Parsing Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6873):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_count_active_mgc_msg_stat(239):[lvl=1]MGC stat - 192.168.80.9, total=6787, succ=3393, failed=3394 Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(830):[lvl=2]Invalid parameter (pkt 0x7F2F993E3850 pkt->mgcp_parm_lines 0x0) Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6734):[lvl=1]endpt * was not redirected Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6945):[lvl=1]Removing msg : RSIP 30130166 *@VG_SAN_FRAN_01.elvalle.com MGCP 0.1 RM: restart Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(7017):[lvl=0] Received ACK for RSIP, setting the restart method to NONE Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(2116):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_info(3329):[lvl=0]Entered Mar 21 21:23:29: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_socket(3645):[lvl=0]Entered VG_SAN_FRAN_01# VG_SAN_FRAN_01#un all All possible debugging has been turned off VG_SAN_FRAN_01#ter no mon VG_SAN_FRAN_01#clear logg Clear logging buffer [confirm] VG_SAN_FRAN_01#exit