One of the Cisco router with a PRI lost its registration with the Cisco Call Manager – CUCM. None of the phones were working for outbound dialing however local extension dialing was working. When I logged into the Cisco Router and issued the following command: show ccm. I noticed that it is going back and forth trying to register with the Primary and Backup Cisco Call Manager. I looked at the settings on the Cisco Router and all VoIP related config was right. I tried to do no mgcp and then mgcp still no use. Then I started to run a debug to see what is happening and hopefully debug will give me some indication on the issue. Following commands were issued on the router:
- debug mgcp events
- debug mgcp errors
- term mon
Here is what I saw in the log:
Dec 18 13:42:30.080: S0/SU0/DS1-0/* mgcp_endpt_parent_redirected: parent S0/SU0/DS1-0/*, child S0/SU0/DS1-0/23
Dec 18 13:42:30.080: S0/SU0/DS1-0/23 mgcp_endpt_set_notified_entity:
Dec 18 13:42:30.080: S0/SU0/DS1-0/23 mgcp_endpt_set_notified_entity:ne 10.206.110.20:2427, ne addr 10.206.110.20:2427
Dec 18 13:42:30.080: S0/SU0/DS1-0/23 mgcp_endpt_set_call_agent:
Dec 18 13:42:30.080: S0/SU0/DS1-0/23 mgcp_endpt_redirect_children:
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.080: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=4, conn_mode=3
Dec 18 13:42:30.084: mgcp_is_transient: callp->state=0, conn_mode=0
Dec 18 13:42:30.084: MGCP sys msg: (15)
Dec 18 13:42:30.084: xlate sys msg: (15, 4539B688)
Dec 18 13:42:30.084: sys msg values: (INVALID MGCP EVENT, 0, , 1108831796, CALL_IDLE)
Dec 18 13:42:30.084: process mgcp_handle_cmapp_event
Dec 18 13:42:30.084: mgcp_cmapp_send_rsip: Send-RSIP: Sending gw host is XYZ1-RTR-01.mydomain.com, endpt is *
Dec 18 13:42:30.084: mgcp_cmapp_send_rsip_to_callmgr: Send RSIP – Pass-in: ipaddr=10.206.110.20 ca_port=2427
Dec 18 13:42:30.084: * mgcp_root_get_profile:
Dec 18 13:42:30.084: * mgcp_send_rsip_msg:
Dec 18 13:42:30.084: * mgcp_endpt_record_rsip:
Dec 18 13:42:30.084: * mgcp_send_rsip_msg: Manually recroding RSIP method
Dec 18 13:42:30.084: * mgcp_endpt_record_rsip:
Dec 18 13:42:30.084: * mgcp_enq_retx_rsip_msg
Dec 18 13:42:30.084: mgcp_add_trans_id_rec: Add trans id (689668931, 46CBEB70) record
Dec 18 13:42:30.084: mgcp_stw_timer_start timer type 0, duration 500
Dec 18 13:42:30.652: MGC stat – 10.202.110.36, total=340539, succ=340048, failed=146
Dec 18 13:42:30.652: mgcpapp_process_mgcp_msg :
Dec 18 13:42:30.660: * mgcp_msg_ack
Dec 18 13:42:30.660: MGC stat – 10.202.110.36, total=340539, succ=340049, failed=146
Dec 18 13:42:30.660: * mgcp_check_for_redirection: endpt * was not redirected
Dec 18 13:42:30.660: [S] mgcp_msg_ack:6166,Updating (*)=10.202.110.36
Dec 18 13:42:30.660: * mgcp_msg_ack: Removing msg : RSIP 689668929 *@XYZ1-RTR-01.mydomain.com MGCP 0.1
RM: graceful
Dec 18 13:42:30.664: * mgcp_msg_ack: Setting the restart method to NONE
Dec 18 13:42:30.664: mgcpapp_process_socket
Dec 18 13:42:30.664: MGC stat – 10.206.110.20, total=313, succ=169, failed=143
Dec 18 13:42:30.664: mgcpapp_process_mgcp_msg :
Dec 18 13:42:30.664: * mgcp_msg_ack
Dec 18 13:42:30.664: MGC stat – 10.206.110.20, total=313, succ=169, failed=144
Dec 18 13:42:30.664: * mgcp_check_for_redirection: endpt * was not redirected
Dec 18 13:42:30.664: * mgcp_msg_ack: Removing msg : RSIP 689668931 *@XYZ1-RTR-01.mydomain.com MGCP 0.1
RM: restart
Dec 18 13:42:30.664: * mgcp_msg_ack: Setting the restart method to NONE
Dec 18 13:42:30.664: mgcpapp_process_socket
Dec 18 13:42:30.960: unreachable detected
Dec 18 13:42:30.960: mgcp_cr_and_init_evt_node:$$$ the node pointer 46FA4428
After looking at the debug I noticed that the registration was failing for the host “XYZ1-RTR-01.mydomain.com”. When I looked in the Cisco Call Manager I noticed that the registered name was “XYZ-RTR-01.mydomain.com”. So since the host name of the Cisco Voice Gateway hence it was no longer valid in the Cisco Call Manager. I simply updated the name in Cisco Call Manager and the Cisco Voice Gateway successfully re registered with the Cisco Call Manager – CUCM.