AOC-Router-2911#debug sccp message Skinny Client Control Protocol messages debugging is on AOC-Router-2911#debug sccp event AOC-Router-2911#debug sccp events Skinny Client Control Protocol events debugging is on AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911#confi t Enter configuration commands, one per line. End with CNTL/Z. AOC-Router-2911(config)#no sccp AOC-Router-2911(config)# Apr 3 01:06:38.659: sccpapp_process_sys_events: sys_eve 0, xlated_eve 15 Apr 3 01:06:38.659: sccpapp_bring_down_appl_services: appl_type 2, state 6, eve_id 15 will be brought down Apr 3 01:06:38.659: sccp_start_tear_down_appl_service: unreg, close soc & act sessions, appl 43682A8C, soc 0, appl_type 2 Apr 3 01:06:38.659: sccp_appl_send_remaining_sccp_q_msgs:Send remaining sccp msgs for profile_id 1 Apr 3 01:06:38.659: sccp_appl_clear_backlog_q: deleting 0 sccp msgs from backlog_q soc(0) appl(43682A8C) prof_id 1 Apr 3 01:06:38.659: sccp_appl_delete_msgs_from_sccpmsg_q: deleting sccp msgs from sccp_msg_q prof_id 1 Apr 3 01:06:38.659: SCCP:send UnregisterMessage Apr 3 01:06:38.659: sccp_appl_send_remaining_sccp_q_msgs:Send remaining sccp msgs for profile_id 1 Apr 3 01:06:38.659: sccp_start_tear_down_appl_service: Going to tear down appl_type 2, state 8 Apr 3 01:06:38.659: sccp_tear_down_appl_service: prof_id 1, appl_type 2 is teared down - total_as_cnt 2, reset(0), last_eve(16), admin_state(0) Apr 3 01:06:38.659: sccp_xapp_disassociate_appl_service: XAPP type 1, prof disassoc result 0, prof_id 1, appl_type 2 Apr 3 01:06:38.659: sccp_tear_down_appl_service: deleting sccp msgs from backlog_q prof_id 1 Apr 3 01:06:38.659: sccp_appl_clear_backlog_q: deleting 0 sccp msgs from backlog_q soc(-1) appl(43682A8C) prof_id 1 Apr 3 01:06:38.659: sccp_tear_down_appl_service: prof_id 1, appl_type 2 - resources are freed and also delinked from appl list Apr 3 01:06:38.659: sccpapp_bring_down_appl_services: appl_type 3, state 6, eve_id 15 will be brought down Apr 3 01:06:38.659: sccp_start_tear_down_appl_service: unreg, close soc & act sessions, appl 436BA77C, soc 1, appl_type 3 Apr 3 01:06:38.659: sccp_appl_send_remaining_sccp_q_msgs:Send remaining sccp msgs for profile_id 2 Apr 3 01:06:38.659: sccp_appl_clear_backlog_q: deleting 0 sccp msgs from backlog_q soc(1) appl(436BA77C) prof_id 2 Apr 3 01:06:38.659: sccp_appl_delete_msgs_from_sccpmsg_q: deleting sccp msgs from sccp_msg_q prof_id 2 Apr 3 01:06:38.659: SCCP:send UnregisterMessage Apr 3 01:06:38.659: sccp_appl_send_remaining_sccp_q_msgs:Send remaining sccp msgs for profile_id 2 Apr 3 01:06:38.659: sccp_start_tear_down_appl_service: Going to tear down appl_type 3, state 8 Apr 3 01:06:38.659: sccp_tear_down_appl_service: prof_id 2, appl_type 3 is teared down - total_as_cnt 1, reset(0), last_eve(16), admin_state(0) Apr 3 01:06:38.659: sccp_xapp_disassociate_appl_service: XAPP type 1, prof disassoc result 0, prof_id 2, appl_type 3 Apr 3 01:06:38.659: sccp_tear_down_appl_service: deleting sccp msgs from backlog_q prof_id 2 Apr 3 01:06:38.659: sccp_appl_clear_backlog_q: deleting 0 sccp msgs from backlog_q soc(-1) appl(436BA77C) prof_id 2 Apr 3 01:06:38.659: sccp_tear_down_appl_service: prof_id 2, appl_type 3 - resources are freed and also delinked from appl list Apr 3 01:06:38.659: sccp_remove_all_services: All the SCCP app services will be removed Apr 3 01:06:38.659: sym_xapp_remove_all_services: delete sym_xapp_eve_q Apr 3 01:06:38.659: sccp_remove_all_services: All SCCP services are removed Apr 3 01:06:38.663: ephone-1[0/-1]:UnregisterAck sent on socket [2] for MTP (1/2/22) Apr 3 09:06:38: %SDSPFARM-6-UNREGISTER_NORMAL: mtp-1:AOPEN_XCD IP:10.192.250.17 Socket:2 DeviceType:MTP has unregistered normally. Apr 3 01:06:38.663: ephone-2[1/-1]:UnregisterAck sent on socket [3] for MTP (0/2/22) Apr 3 09:06:38: %HWCONF-6-UNREGISTER_NORMAL: hwconf-2:AOPEN_CFB IP:10.192.250.17 Socket:3 DeviceType:HW Conference has unregistered normally. Apr 3 01:06:38.663: socket 2 fatal error 254! can't read msg header with size -1, fd 2 Apr 3 01:06:38.663: it's a stale socket! delete it!! Apr 3 01:06:38.663: socket 3 fatal error 254! can't read msg header with size -1, fd 3 Apr 3 01:06:38.663: it's a stale socket! delete it!! AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)#tel AOC-Router-2911(config)#telephony-service AOC-Router-2911(config-telephony)#shutdown AOC-Router-2911(config-telephony)#shutdown AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)#no shut AOC-Router-2911(config-telephony)#no shutdown AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# Apr 3 01:07:04.971: Opening Skinny listening socket on port 2000 Apr 3 01:07:04.971: AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)# AOC-Router-2911(config-telephony)#exit AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)#sccp AOC-Router-2911(config)# Apr 3 01:07:23.719: sym_xapp_init_all_services: create sym_xapp_eve_q Apr 3 01:07:23.723: sccp_setup_appl_service: Appl initialized with appl=436BA77C, type=2, appl_state=0, msg_handler=35F13108, oper_state=0, statis=436D39EC, total_as_cnt=1 Apr 3 01:07:23.723: sym_xapp_transfer_supported_codec: supported codec cnt 9 Apr 3 01:07:23.723: sccp_sym_set_secure_param:: appl profile 1 rpm device_mode 0appl device mode 0trp status for prof_id 1 is 0, sym_xapp_new_rpm_profile_info Apr 3 01:07:23.723: sym_xapp_new_rpm_profile_info: max_streams 8, prof_id 1, codec_cnt 6, iss 8, mspc 0, rsvp un-enabled, tls un-enabled Apr 3 01:07:23.723: sccp_setup_appl_service: Appl initialized with appl=43682A8C, type=3, appl_state=0, msg_handler=35F13108, oper_state=0, statis=3EE83838, total_as_cnt=2 Apr 3 01:07:23.723: sym_xapp_transfer_supported_codec: supported codec cnt 9 Apr 3 01:07:23.723: sccp_sym_set_secure_param:: appl profile 2 rpm device_mode 0appl device mode 0trp status for prof_id 2 is 0, sym_xapp_new_rpm_profile_info Apr 3 01:07:23.723: sym_xapp_new_rpm_profile_info: max_streams 16, prof_id 2, codec_cnt 6, iss 16, mspc 8, rsvp un-enabled, tls enabled Apr 3 01:07:23.723: sccpapp_process_sys_events: sys_eve 3, xlated_eve 0 Apr 3 01:07:23.723: sccp_get_ccm_group_using_assoc_prof: prof_id 1 found in the CCM group 1 Apr 3 01:07:23.723: sccpapp_process_sys_events: prof_id 1, assoc to ccm_group 1,appl_type 2, sys_eve 3 Apr 3 01:07:23.723: sccp_bring_up_appl_service: Trigger FSM for prof_id 1, appl_type 2trp status for prof_id 1, 0 sccp_bring_up_appl_service Apr 3 01:07:23.723: sccp_connect_to_ccm_on_priority_basis: Trying connecting to CCM on priority basis prof_id 1, appl_type 2 Apr 3 01:07:23.723: sccp_connect_to_ccm_on_priority_basis: Trying CCM with ipaddr 10.192.250.17, priority 1, port 2000 Apr 3 01:07:23.723: sccp_tcp_socket_connect: Trying tcp soc connect for appl_type 2, prof_id 1, to ipaddr 10.192.250.17 Apr 3 01:07:23.723: sccp_get_ccm_intf_vrf_id: ccm sccp local interface vrfid=0appl type is 2, appl profile 1 Apr 3 01:07:23.723: sccp_get_local_address_by_idb: get_physical_ip:1, use IP addr 10.192.250.17 Apr 3 01:07:23.723: sccp_tcp_open_and_set_option: Socket 0 opened and binded to addr 10.192.250.17 - for appl_type 2, prof_id 1 w/ local ddr 10.192.250.17 Apr 3 01:07:23.723: sccp_socket_connect_to_ccm :: connecting to port 2000 sccp_socket_connect_to_ccm: soc conn to 10.192.250.17 port 2000 in progressfor appl_type 2, state 1, soc_fd 0 appl 436BA77C Apr 3 01:07:23.723: sccpapp_process_sys_events: sys_eve 3, xlated_eve 0 Apr 3 01:07:23.723: sccp_get_ccm_group_using_assoc_prof: prof_id 2 found in the CCM group 1 Apr 3 01:07:23.723: sccpapp_process_sys_events: prof_id 2, assoc to ccm_group 1,appl_type 3, sys_eve 3 Apr 3 01:07:23.723: sccp_bring_up_appl_service: Trigger FSM for prof_id 2, appl_type 3trp status for prof_id 2, 0 sccp_bring_up_appl_service Apr 3 01:07:23.723: sccp_connect_to_ccm_on_priority_basis: Trying connecting to CCM on priority basis prof_id 2, appl_type 3 Apr 3 01:07:23.723: sccp_connect_to_ccm_on_priority_basis: Trying CCM with ipaddr 10.192.250.17, priority 1, port 2000 Apr 3 01:07:23.723: sccp_tcp_socket_connect: Trying tcp soc connect for appl_type 3, prof_id 2, to ipaddr 10.192.250.17 Apr 3 01:07:23.723: sccp_get_ccm_intf_vrf_id: ccm sccp local interface vrfid=0appl type is 3, appl profile 2 Apr 3 01:07:23.723: sccp_get_local_address_by_idb: get_physical_ip:1, use IP addr 10.192.250.17 Apr 3 01:07:23.723: sccp_tcp_open_and_set_option: Socket 1 opened and binded to addr 10.192.250.17 - for appl_type 3, prof_id 2 w/ local ddr 10.192.250.17 Apr 3 01:07:23.723: sccp_socket_connect_to_ccm :: connecting to port 2000 sccp_socket_connect_to_ccm: soc conn to 10.192.250.17 port 2000 in progressfor appl_type 3, state 1, soc_fd 1 appl 43682A8C Apr 3 01:07:23.727: New Skinny socket accepted [1] from 0, sub 1 (0 active) Apr 3 01:07:23.727: sin_family 2, sin_port 64217, in_addr 10.192.250.17 Apr 3 01:07:23.727: skinny_add_socket 1 10.192.250.17 64217 Apr 3 01:07:23.727: New Skinny socket accepted [1] from 0, sub 1 (1 active) Apr 3 01:07:23.727: sin_family 2, sin_port 30978, in_addr 10.192.250.17 Apr 3 01:07:23.727: skinny_add_socket 1 10.192.250.17 30978 Apr 3 01:07:23.727: sccp_appl_service_stop_timer: Stop 436BA7D4 timer Apr 3 01:07:23.727: sccp_process_socket_connect_result: appl_type 2, eve 3, soc_id 0, state 1 Apr 3 01:07:23.727: sccp_setup_appl_to_tcp_conn_state: soc connected to 10.192.250.17, for prof_id 1, appl_type 2 Apr 3 01:07:23.727: sccp_register_with_connected_ccm: Apr 3 01:07:23.727: sccp_register_with_connected_ccm: Sending regis msg for appl_type 2, state 5 Apr 3 01:07:23.727: sccp_get_appl_serv_by_prof_id: prof_id 1 found in the CCM group 1 Apr 3 01:07:23.727: SCCP:send RegisterMessage, protocol_ver=0x12000100 Apr 3 01:07:23.727: SCCP:send IpPortMessage Apr 3 01:07:23.727: sccp_appl_service_stop_timer: Stop 43682AE4 timer Apr 3 01:07:23.727: sccp_process_socket_connect_result: appl_type 3, eve 3, soc_id 1, state 1 Apr 3 01:07:23.727: sccp_setup_appl_to_tcp_conn_state: soc connected to 10.192.250.17, for prof_id 2, appl_type 3 Apr 3 01:07:23.727: sccp_register_with_connected_ccm: Apr 3 01:07:23.727: sccp_register_with_connected_ccm: Sending regis msg for appl_type 3, state 5 Apr 3 01:07:23.727: sccp_get_appl_serv_by_prof_id: prof_id 2 found in the CCM group 1 Apr 3 01:07:23.727: SCCP:send RegisterMessage, protocol_ver=0x12000100 Apr 3 01:07:23.727: SCCP:send IpPortMessage Apr 3 09:07:23: %SDSPFARM-6-REGISTER: mtp-1:AOPEN_XCD IP:10.192.250.17 Socket:2 DeviceType:MTP has registered. Apr 3 01:07:23.731: ephone-1[0/-1][]:Signal protocol ver 17 to phone with ver 0 Apr 3 09:07:23: %HWCONF-6-REGISTER: hwconf-2:AOPEN_CFB IP:10.192.250.17 Socket:3 DeviceType:HW Conference has registered. Apr 3 01:07:23.731: ephone-2[1/-1][]:Signal protocol ver 17 to phone with ver 0 Apr 3 01:07:23.735: SCCP:rcvd RegisterAckMessage Apr 3 01:07:23.735: sccp_appl_service_stop_timer: Stop 436BA7D4 timer Apr 3 01:07:23.735: SCCP:keepaalive interval=30, agreed_sccp_ver=18 Apr 3 01:07:23.735: sccp_setup_appl_service_to_active: Apr 3 01:07:23.735: SCCP:send MediaResourceNotification Apr 3 01:07:23.735: sccp_setup_appl_service_to_active: appl_type 2 is ACTIVE, conn to pri 1 CCM Apr 3 01:07:23.735: sccp_set_swb_pbm_and_start_timer: prof_id 1, appl_type 2, curr swb_bm 0, ccm_pri 1, temp_swb_bm 0, new swb_bm 0, ccm_priority_to_try 0, need_to_try_swb 0 Apr 3 01:07:23.735: SCCP:send VersionReqMessage Apr 3 01:07:23.735: sccp_xapp_associate_appl_service: XAPP type 1, prof assoc result 0, prof_id 1, appl_type 2 Apr 3 01:07:23.735: SCCP:rcvd RegisterAckMessage Apr 3 01:07:23.735: sccp_appl_service_stop_timer: Stop 43682AE4 timer Apr 3 01:07:23.735: SCCP:keepaalive interval=30, agreed_sccp_ver=18 Apr 3 01:07:23.735: sccp_setup_appl_service_to_active: Apr 3 01:07:23.735: SCCP:send MediaResourceNotification Apr 3 01:07:23.735: sccp_setup_appl_service_to_active: appl_type 3 is ACTIVE, conn to pri 1 CCM Apr 3 01:07:23.735: sccp_set_swb_pbm_and_start_timer: prof_id 2, appl_type 3, curr swb_bm 0, ccm_pri 1, temp_swb_bm 0, new swb_bm 0, ccm_priority_to_try 0, need_to_try_swb 0 Apr 3 01:07:23.735: SCCP:send VersionReqMessage Apr 3 01:07:23.735: sccp_xapp_associate_appl_service: XAPP type 1, prof assoc result 0, prof_id 2, appl_type 3 Apr 3 01:07:23.735: SCCP:rcvd CapabilitiesReqMessage Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 15 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 15 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 11 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 11 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 4 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 4 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 2 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 2 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 12 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 12 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 16 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 16 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 257 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 257 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 259 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 259 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 261 Apr 3 01:07:23.735: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 261 Apr 3 01:07:23.735: SCCP:send CapabilitiesResMessage Apr 3 01:07:23.735: ephone-1[0/-1]:MediaResourceNotification Apr 3 01:07:23.735: ephone-2[1/-1]:MediaResourceNotification Apr 3 01:07:23.735: ephone-1[0/-1]:VersionReq received Apr 3 01:07:23.735: ephone-1[0/-1]:Version Message sent Apr 3 01:07:23.735: ephone-2[1/-1]:VersionReq received Apr 3 01:07:23.735: ephone-2[1/-1]:Version Message sent Apr 3 01:07:23.739: SCCP:rcvd CapabilitiesReqMessage Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 4 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 4 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 2 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 2 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 12 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 12 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 16 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 16 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 11 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 11 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 15 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 15 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 257 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 257 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 259 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 259 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: codec_rec->codec = 261 Apr 3 01:07:23.739: sccp_send_capabilities_rsp_msg_v1: msg_cap->payload_caps = 261 Apr 3 01:07:23.739: SCCP:send CapabilitiesResMessage Apr 3 01:07:23.739: SCCP:rcvd VersionMessage Apr 3 01:07:23.739: VersionMsg Info: version: Apr 3 01:07:23.739: ephone-1[0/-1]:CapabilitiesRes received Apr 3 01:07:23.739: ephone-1[0/-1][]:Caps list 9 G729AnnexB 60 ms, is_mtp 1 G729 60 ms, is_mtp 1 G711Ulaw64k 30 ms, is_mtp 1 G711Alaw64k 30 ms, is_mtp 1 G729AnnexA 60 ms, is_mtp 1 G729AnnexAwAnnexB 60 ms, is_mtp 1 RFC2833 Dynamic Payload 30 ms, is_mtp 1 Dynamic Payloadtype Passthru 30 ms, is_mtp 1 Inband DTMF RFC2833 30 ms, is_mtp 1 Apr 3 01:07:23.739: ephone-2[1/-1]:CapabilitiesRes received Apr 3 01:07:23.739: ephone-2[1/-1][]:Caps list 9 G711Ulaw64k 30 ms, is_mtp 1 G711Alaw64k 30 ms, is_mtp 1 G729AnnexA 60 ms, is_mtp 1 G729AnnexAwAnnexB 60 ms, is_mtp 1 G729 60 ms, is_mtp 1 G729AnnexB 60 ms, is_mtp 1 RFC2833 Dynamic Payload 30 ms, is_mtp 1 Dynamic Payloadtype Passthru 30 ms, is_mtp 1 Inband DTMF RFC2833 30 ms, is_mtp 1 Apr 3 01:07:23.739: SCCP:rcvd VersionMessage Apr 3 01:07:23.739: VersionMsg Info: version: AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)# AOC-Router-2911(config)#end AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# Apr 3 09:07:31: %SYS-5-CONFIG_I: Configured from console by lantel on vty0 (10.192.14.13) AOC-Router-2911# AOC-Router-2911# AOC-Router-2911# AOC-Router-2911#show tel AOC-Router-2911#show telephony-service con AOC-Router-2911#show telephony-service conference hardware Conference Type Active Max Peak Master MasterPhone Last cur(initial) ============================================================================================= AOC-Router-2911# AOC-Router-2911#