Created by: Konstantin Chrispens on 14-11-2011 03:41:51 AM Hi, I noticed that there are missing call notifications in the CMECSTAListener for incoming PSTN calls. I get the CMECallConnectionRingingEvent but when I answer the call there is no CMECallAnsweredEvent. The CMECallConnectionDisconnectedEvent is also missing when I dropp the call. I see in the wireshark capture that there is incoming csta data reporting these events so I do not think this is a CUCME configuration problem. This behaviour occurs only for the direction PSTN-CUCME. If I place a call to the PSTN all Events are delivered. Internal calls are also reported properly. I'm using the version 1.0.2. Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 14-11-2011 04:48:57 AM Hi Raghavendra,
thanks for the quick reply!
I placed a call from 2242939783 (PSTN) to 4922429236541060 (internal) and here is the debug output:
Nov 14 09:45:04.767: //CTI/SM:sm_process_sys_event event 8 Nov 14 09:45:04.767: //CTI/SM:cti_teardown_sessions_by_tag mode 0 tag 1 Nov 14 09:45:04.767: //CTI/SM:sm_cleanup_one_context context_id 145278 Nov 14 09:45:04.767: //CTI/SM:sm_find_scb_node_by_context context_id 145278 Nov 14 09:45:04.767: //CTI/SM: to return 2C3405BC Nov 14 09:45:04.767: //CTI/PI:pi_send_sys_event event 4 context_id 145278 Nov 14 09:45:04.767: //CTI/SM:sm_cleanup_one_context context_id 145283 Nov 14 09:45:04.767: //CTI/SM:sm_find_scb_node_by_context context_id 145283 Nov 14 09:45:04.767: //CTI/SM: to return 31FD0440 Nov 14 09:45:04.767: //CTI/PI:pi_send_sys_event event 4 context_id 145283 Nov 14 09:45:04.767: //CTI/SM:sm_cleanup_one_context context_id 145285 Nov 14 09:45:04.767: //CTI/SM:sm_find_scb_node_by_context context_id 145285 Nov 14 09:45:04.767: //CTI/SM: to return 2C33DF14 Nov 14 09:45:04.767: //CTI/PI:pi_send_sys_event event 4 context_id 145285 Nov 14 09:45:04.767: //CTI/SM:sm_cleanup_one_context context_id 145286 Nov 14 09:45:04.767: //CTI/SM:sm_find_scb_node_by_context context_id 145286 Nov 14 09:45:04.767: //CTI/SM: to return 2A720AF0 Nov 14 09:45:04.767: //CTI/PI:pi_send_sys_event event 4 context_id 145286 Nov 14 09:45:04.767: //CTI/CC:ccm_process_sm_event event 34 Nov 14 09:45:04.767: //CTI/CM: Nov 14 09:45:04.767: //CTI/CM:cmm_unsubscribe_handler for 4922429236541060 sub_id 145278 Nov 14 09:45:04.767: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:04.771: //CTI/CM: target_node 323CED54 Nov 14 09:45:04.771: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:04.771: //CTI/CM:delete_subinfo_from_lineinfo sub_id 145278 Nov 14 09:45:04.771: //CTI/CM:find_subinfo_from_lineinfo sub_id 145278 Nov 14 09:45:04.771: //CTI/CM: sub_info 300AA980 00AA980> Nov 14 09:45:04.771: //CTI/CM: Found rp sub_info 300AA980 145278 Nov 14 09:45:04.771: //CTI/CM: found sub_info_tar Nov 14 09:45:04.771: //CTI/CC:ccm_process_sm_event event 34 Nov 14 09:45:04.771: //CTI/CM: Nov 14 09:45:04.771: //CTI/CM:cmm_unsubscribe_handler for 4922429236541061 sub_id 145283 Nov 14 09:45:04.771: //CTI/CM:find_lineinfo_node finding number 4922429236541061 Nov 14 09:45:04.771: //CTI/CM: target_node 3239396C Nov 14 09:45:04.771: //CTI/CM: - dn 4922429236541061
Nov 14 09:45:04.771: //CTI/CM:delete_subinfo_from_lineinfo sub_id 145283 Nov 14 09:45:04.771: //CTI/CM:find_subinfo_from_lineinfo sub_id 145283 Nov 14 09:45:04.771: //CTI/CM: sub_info 300AA9FC 00AA9FC> Nov 14 09:45:04.771: //CTI/CM: Found rp sub_info 300AA9FC 145283 Nov 14 09:45:04.771: //CTI/CM: found sub_info_tar Nov 14 09:45:04.771: //CTI/CC:ccm_process_sm_event event 34 Nov 14 09:45:04.771: //CTI/CM: Nov 14 09:45:04.771: //CTI/CM:cmm_unsubscribe_handler for 4922429236541062 sub_id 145285 Nov 14 09:45:04.771: //CTI/CM:find_lineinfo_node finding number 4922429236541062 Nov 14 09:45:04.771: //CTI/CM: target_node 2C556610 Nov 14 09:45:04.771: //CTI/CM: - dn 4922429236541062
Nov 14 09:45:04.771: //CTI/CM:delete_subinfo_from_lineinfo sub_id 145285 Nov 14 09:45:04.771: //CTI/CM:find_subinfo_from_lineinfo sub_id 145285 Nov 14 09:45:04.771: //CTI/CM: sub_info 300AAA78 00AAA78> Nov 14 09:45:04.771: //CTI/CM: Found rp sub_info 300AAA78 145285 Nov 14 09:45:04.771: //CTI/CM: found sub_info_tar Nov 14 09:45:04.771: //CTI/CC:ccm_process_sm_event event 34 Nov 14 09:45:04.771: //CTI/CM: Nov 14 09:45:04.771: //CTI/CM:cmm_unsubscribe_handler for 4922429236541063 sub_id 145286 Nov 14 09:45:04.771: //CTI/CM:find_lineinfo_node finding number 4922429236541063 Nov 14 09:45:04.771: //CTI/CM: target_node 3009A884 Nov 14 09:45:04.771: //CTI/CM: - dn 4922429236541063
Nov 14 09:45:04.771: //CTI/CM:delete_subinfo_from_lineinfo sub_id 145286 Nov 14 09:45:04.771: //CTI/CM:find_subinfo_from_lineinfo sub_id 145286 Nov 14 09:45:04.771: //CTI/CM: sub_info 300AA904 00AA904> Nov 14 09:45:04.771: //CTI/CM: Found rp sub_info 300AA904 145286 Nov 14 09:45:04.771: //CTI/CM: found sub_info_tar Nov 14 09:45:04.771: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~1 From: <sip:kc_test@10.40.0.13>;tag=dsedc44 To: <sip:kc_test@10.40.0.13> Date: Mon, 14 Nov 2011 09:45:04 GMT Call-ID: 13212639143271@10.40.0.13 Server: Cisco-SIPGateway/IOS-12.x CSeq: 2 REGISTER Content-Length: 0
Nov 14 09:45:04.771: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~1 From: <sip:kc_test@10.40.0.13>;tag=dsedc44 To: <sip:kc_test@10.40.0.13>;tag=6184E188-BD4 Date: Mon, 14 Nov 2011 09:45:04 GMT Call-ID: 13212639143271@10.40.0.13 Server: Cisco-SIPGateway/IOS-12.x CSeq: 2 REGISTER X-cisco-referenceID: 2306ED Contact: <sip:kc_test@10.10.2.2:5070;transport=tcp>;expires=60 Expires: 60 Content-Length: 0
Nov 14 09:45:05.775: //CTI/PI:cti_frontend_proc [2379C]: received CC Event [26]: CC_EV_CALL_SETUP_IND Nov 14 09:45:05.775: //CTI/PI:pi_process_service_event event 26 Nov 14 09:45:05.775: //CTI/PI: got CC_EV_CALL_SETUP_IND callID 145308 Nov 14 09:45:05.775: //CTI/PI:pi_parse_service event 0 Nov 14 09:45:05.775: CTI_PARSERrocessing XML message <?xml version="1.0"?> <RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <extensions> <privateData> <private> <lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension"> tel:4922429236541058;device=08173514D84D </lcs:line> </private> </privateData> </extensions> </RequestSystemStatus>
Nov 14 09:45:05.775: //CTI/XI:csta_RequestSystemStatus_cb: Function Called Nov 14 09:45:05.775: //CTI/XI:csta_extensions_cb: Function Called Nov 14 09:45:05.775: //CTI/XI:csta_privateData_cb: Function Called Nov 14 09:45:05.775: //CTI/XI:csta_private_cb: Function Called Nov 14 09:45:05.775: //CTI/XI:lcs_line_cb: Function Called Nov 14 09:45:05.775: //CTI/XI:csta2cti:789 csta_service_type_t(19) Nov 14 09:45:05.775: //CTI/XML:ERROR: csta2cti_DeviceID: device size(13) is bigger than buffer size(13) Nov 14 09:45:05.775: //CTI/XI:csta2cti_DeviceID: csta( tel:4922429236541058;device=08173514D84D ) attr(2, tel number(4922429236541058) phone-context=(0,) device=(08173514D84D) Nov 14 09:45:05.775: //CTI/XI:csta2cti:1034 cti_ev_type_t(1) Nov 14 09:45:05.775: //CTI/SM:sm_handle_sys_service event 3 Nov 14 09:45:05.775: //CTI/SM:sm_find_scb_node_by_context context_id 145308 Nov 14 09:45:05.775: //CTI/SM: to return 0 Nov 14 09:45:05.775: //CTI/SM:sm_handle_sys_open_session Nov 14 09:45:05.775: //CTI/SM:created scb node 32434E40 context_id 145308 Nov 14 09:45:05.775: //CTI/SM:sm_grant_session: num=4922429236541058 Nov 14 09:45:05.775: //CTI/SM: input mac present, value=08173514D84D Nov 14 09:45:05.775: //CTI/SM: Target mac address resolved: 08173514D84D, num_expanded 0 Nov 14 09:45:05.775: //CTI/PI:pi_send_sys_event event 18 context_id 145308 Nov 14 09:45:05.775: //CTI/PI:pi_build_event event 17 Nov 14 09:45:05.775: //CTI/XI:cti2csta:1048 cti_ev_type_t(17) Nov 14 09:45:05.775: //CTI/XI:cti2csta_error: cti_error = 22 Nov 14 09:45:05.775: //CTI/XML:ERROR: cti2csta_error: Undefined enum(22) Nov 14 09:45:05.775: //CTI/XI:cti2csta:2075 csta_EventType_t(1) Nov 14 09:45:05.775: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(171), message(171) <?xml version="1.0" encoding="UTF-8"?> <CSTAErrorCode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <operation>generic</operation> </CSTAErrorCode> Nov 14 09:45:05.779: //145308/2AAB85109597/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~2 From: CME_SDK <sip:4922429236541058_08173514D84D@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541058@10.40.0.13> Date: Mon, 14 Nov 2011 09:45:05 GMT Call-ID: 13212639154202@10.10.2.2 CSeq: 1 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Content-Length: 0
Nov 14 09:45:05.779: //145308/2AAB85109597/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 404 Not Found Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~2 From: CME_SDK <sip:4922429236541058_08173514D84D@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541058@10.40.0.13>;tag=6184E580-F20 Date: Mon, 14 Nov 2011 09:45:05 GMT Call-ID: 13212639154202@10.10.2.2 CSeq: 1 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Reason: Q.850;cause=1 Content-Type: application/csta+xml Content-Disposition: signal;handling=optional Content-Length: 171
Nov 14 09:45:06.619: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [19]: CC_EV_CALL_INFO Nov 14 09:45:06.619: //CTI/PI:pi_process_service_event event 19 Nov 14 09:45:06.619: //CTI/PI: got CC_EV_CALL_INFO callID 145309 Nov 14 09:45:06.619: //CTI/PI:pi_parse_service event 0 Nov 14 09:45:06.619: CTI_PARSERrocessing XML message <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <MonitorStart xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorObject> <deviceObject>tel:4922429236541060</deviceObject> </monitorObject> </MonitorStart>
Nov 14 09:45:06.619: //CTI/XI:csta_MonitorStart_cb: Function Called Nov 14 09:45:06.619: //CTI/XI:csta_monitorObject_cb: Function Called Nov 14 09:45:06.619: //CTI/XI:csta_deviceObject_cb: Function Called Nov 14 09:45:06.619: //CTI/XI:csta2cti:789 csta_service_type_t(21) Nov 14 09:45:06.619: //CTI/XI:csta2cti_DeviceID: csta(tel:4922429236541060) attr(1,tel number(4922429236541060) phone-context=(0,) device=() Nov 14 09:45:06.619: //CTI/XI:csta2cti:1034 cti_ev_type_t(6) Nov 14 09:45:06.619: //CTI/SM:sm_handle_sys_service event 6 Nov 14 09:45:06.619: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:06.619: //CTI/SM: to return 32434E40 Nov 14 09:45:06.619: //CTI/SM:sm_handle_sys_monitor_start_stop event 6 Nov 14 09:45:06.619: //CTI/PI:pi_send_sys_event event 21 context_id 145309 Nov 14 09:45:06.619: //CTI/PI:pi_build_event event 21 Nov 14 09:45:06.619: //CTI/XI:cti2csta:1048 cti_ev_type_t(21) Nov 14 09:45:06.623: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:06.623: //CTI/XI:cti2csta:2075 csta_EventType_t(22) Nov 14 09:45:06.623: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(202), message(202) <?xml version="1.0" encoding="UTF-8"?> <MonitorStartResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> </MonitorStartResponse> Nov 14 09:45:06.623: //CTI/CC:ccm_process_sm_event event 33 Nov 14 09:45:06.623: //CTI/CM: Nov 14 09:45:06.623: //CTI/CM:cmm_subscribe_handler for 4922429236541060 sub_id 145309, strip_mac 0 cm_feature 3 Nov 14 09:45:06.623: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:06.623: //CTI/CM: target_node 323CED54 Nov 14 09:45:06.623: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:06.623: //CTI/CM:find_subinfo_from_lineinfo sub_id 145309 Nov 14 09:45:06.623: //CTI/CM: sub_info 0 Nov 14 09:45:06.623: //CTI/CM: Nov 14 09:45:06.623: No sub_info found Nov 14 09:45:06.623: device 4922429236541060 Nov 14 09:45:06.623: --attribute 1 Nov 14 09:45:06.623: --tag 0 Nov 14 09:45:06.623: --phone_ctx 0 Nov 14 09:45:06.623: --mac 0014F29CD919 Nov 14 09:45:06.623: //CTI/CM:phone idx 5 Nov 14 09:45:06.623: //CTI/CM:insert_subinfo_to_lineinfo sub_id 145309 to line 4922429236541060 Nov 14 09:45:06.623: //CTI/CM: sub_info_tar 0 Nov 14 09:45:06.623: _subscribed - 300AA904 145309, Nov 14 09:45:07.291: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~6 From: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Content-Length: 0
Nov 14 09:45:07.291: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~6 From: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 202
Nov 14 09:45:08.455: //CTI/PI:cti_frontend_proc [2379E]: received CC Event [19]: CC_EV_CALL_INFO Nov 14 09:45:08.455: //CTI/PI:pi_process_service_event event 19 Nov 14 09:45:08.455: //CTI/PI: got CC_EV_CALL_INFO callID 145310 Nov 14 09:45:08.455: //CTI/PI:pi_parse_service event 0 Nov 14 09:45:08.455: CTI_PARSERrocessing XML message <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <MonitorStart xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorObject> <deviceObject>tel:4922429236541061</deviceObject> </monitorObject> </MonitorStart>
Nov 14 09:45:08.455: //CTI/XI:csta_MonitorStart_cb: Function Called Nov 14 09:45:08.455: //CTI/XI:csta_monitorObject_cb: Function Called Nov 14 09:45:08.455: //CTI/XI:csta_deviceObject_cb: Function Called Nov 14 09:45:08.455: //CTI/XI:csta2cti:789 csta_service_type_t(21) Nov 14 09:45:08.455: //CTI/XI:csta2cti_DeviceID: csta(tel:4922429236541061) attr(1,tel number(4922429236541061) phone-context=(0,) device=() Nov 14 09:45:08.455: //CTI/XI:csta2cti:1034 cti_ev_type_t(6) Nov 14 09:45:08.455: //CTI/SM:sm_handle_sys_service event 6 Nov 14 09:45:08.455: //CTI/SM:sm_find_scb_node_by_context context_id 145310 Nov 14 09:45:08.455: //CTI/SM: to return 2C3405BC Nov 14 09:45:08.455: //CTI/SM:sm_handle_sys_monitor_start_stop event 6 Nov 14 09:45:08.455: //CTI/PI:pi_send_sys_event event 21 context_id 145310 Nov 14 09:45:08.455: //CTI/PI:pi_build_event event 21 Nov 14 09:45:08.455: //CTI/XI:cti2csta:1048 cti_ev_type_t(21) Nov 14 09:45:08.455: //CTI/XI:cti2csta_xrefID: xrefID (54E) Nov 14 09:45:08.455: //CTI/XI:cti2csta:2075 csta_EventType_t(22) Nov 14 09:45:08.455: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(202), message(202) <?xml version="1.0" encoding="UTF-8"?> <MonitorStartResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054E</monitorCrossRefID> </MonitorStartResponse> Nov 14 09:45:08.459: //CTI/CC:ccm_process_sm_event event 33 Nov 14 09:45:08.459: //CTI/CM: Nov 14 09:45:08.459: //CTI/CM:cmm_subscribe_handler for 4922429236541061 sub_id 145310, strip_mac 0 cm_feature 3 Nov 14 09:45:08.459: //CTI/CM:find_lineinfo_node finding number 4922429236541061 Nov 14 09:45:08.459: //CTI/CM: target_node 3239396C Nov 14 09:45:08.459: //CTI/CM: - dn 4922429236541061
Nov 14 09:45:08.459: //CTI/CM:find_subinfo_from_lineinfo sub_id 145310 Nov 14 09:45:08.459: //CTI/CM: sub_info 0 Nov 14 09:45:08.459: //CTI/CM: Nov 14 09:45:08.459: No sub_info found Nov 14 09:45:08.459: device 4922429236541061 Nov 14 09:45:08.459: --attribute 1 Nov 14 09:45:08.459: --tag 0 Nov 14 09:45:08.459: --phone_ctx 0 Nov 14 09:45:08.459: --mac 0012D91F9795 Nov 14 09:45:08.459: //CTI/CM:phone idx 6 Nov 14 09:45:08.459: //CTI/CM:insert_subinfo_to_lineinfo sub_id 145310 to line 4922429236541061 Nov 14 09:45:08.459: //CTI/CM: sub_info_tar 0 Nov 14 09:45:08.459: _subscribed - qs-cucme01# 300AAA78 145310, Nov 14 09:45:09.239: //145310/2C0BB2A995A1/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~10 From: CME_SDK <sip:4922429236541061_0012D91F9795@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541061@10.40.0.13>;tag=6184EE90-1AF0 Date: Mon, 14 Nov 2011 09:45:08 GMT Call-ID: 13212639169614@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Content-Length: 0
Nov 14 09:45:09.239: //145310/2C0BB2A995A1/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~10 From: CME_SDK <sip:4922429236541061_0012D91F9795@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541061@10.40.0.13>;tag=6184EE90-1AF0 Date: Mon, 14 Nov 2011 09:45:08 GMT Call-ID: 13212639169614@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Contact: <sip:4922429236541061@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 202
Nov 14 09:45:09.767: //CTI/PI:cti_frontend_proc [2379F]: received CC Event [19]: CC_EV_CALL_INFO Nov 14 09:45:09.767: //CTI/PI:pi_process_service_event event 19 Nov 14 09:45:09.767: //CTI/PI: got CC_EV_CALL_INFO callID 145311 Nov 14 09:45:09.767: //CTI/PI:pi_parse_service event 0 Nov 14 09:45:09.767: CTI_PARSERrocessing XML message <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <MonitorStart xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorObject> <deviceObject>tel:4922429236541062</deviceObject> </monitorObject> </MonitorStart>
Nov 14 09:45:09.767: //CTI/XI:csta_MonitorStart_cb: Function Called Nov 14 09:45:09.767: //CTI/XI:csta_monitorObject_cb: Function Called Nov 14 09:45:09.767: //CTI/XI:csta_deviceObject_cb: Function Called Nov 14 09:45:09.767: //CTI/XI:csta2cti:789 csta_service_type_t(21) Nov 14 09:45:09.767: //CTI/XI:csta2cti_DeviceID: csta(tel:4922429236541062) attr(1,tel number(4922429236541062) phone-context=(0,) device=() Nov 14 09:45:09.767: //CTI/XI:csta2cti:1034 cti_ev_type_t(6) Nov 14 09:45:09.767: //CTI/SM:sm_handle_sys_service event 6 Nov 14 09:45:09.767: //CTI/SM:sm_find_scb_node_by_context context_id 145311 Nov 14 09:45:09.767: //CTI/SM: to return 2C33DF14 Nov 14 09:45:09.767: //CTI/SM:sm_handle_sys_monitor_start_stop event 6 Nov 14 09:45:09.767: //CTI/PI:pi_send_sys_event event 21 context_id 145311 Nov 14 09:45:09.767: //CTI/PI:pi_build_event event 21 Nov 14 09:45:09.767: //CTI/XI:cti2csta:1048 cti_ev_type_t(21) Nov 14 09:45:09.767: //CTI/XI:cti2csta_xrefID: xrefID (54F) Nov 14 09:45:09.767: //CTI/XI:cti2csta:2075 csta_EventType_t(22) Nov 14 09:45:09.767: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(202), message(202) <?xml version="1.0" encoding="UTF-8"?> <MonitorStartResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054F</monitorCrossRefID> </MonitorStartResponse> Nov 14 09:45:09.771: //CTI/CC:ccm_process_sm_event event 33 Nov 14 09:45:09.771: //CTI/CM: Nov 14 09:45:09.771: //CTI/CM:cmm_subscribe_handler for 4922429236541062 sub_id 145311, strip_mac 0 cm_feature 3 Nov 14 09:45:09.771: //CTI/CM:find_lineinfo_node finding number 4922429236541062 Nov 14 09:45:09.771: //CTI/CM: target_node 2C556610 Nov 14 09:45:09.771: //CTI/CM: - dn 4922429236541062
Nov 14 09:45:09.771: //CTI/CM:find_subinfo_from_lineinfo sub_id 145311 Nov 14 09:45:09.771: //CTI/CM: sub_info 0 Nov 14 09:45:09.771: //CTI/CM: Nov 14 09:45:09.771: No sub_info found Nov 14 09:45:09.771: device 4922429236541062 Nov 14 09:45:09.771: --attribute 1 Nov 14 09:45:09.771: --tag 0 Nov 14 09:45:09.771: --phone_ctx 0 Nov 14 09:45:09.771: --mac 001DA2906088 Nov 14 09:45:09.771: //CTI/CM:phone idx 7 Nov 14 09:45:09.771: //CTI/CM:insert_subinfo_to_lineinfo sub_id 145311 to line 4922429236541062 Nov 14 09:45:09.771: //CTI/CM: sub_info_tar 0 Nov 14 09:45:09.771: _subscribed - qs-cucme01# 300AA9FC 145311, Nov 14 09:45:10.551: //145311/2CBFC05B95A6/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~14 From: CME_SDK <sip:4922429236541062_001DA2906088@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541062@10.40.0.13>;tag=6184F324-17B3 Date: Mon, 14 Nov 2011 09:45:09 GMT Call-ID: 13212639189125@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Content-Length: 0
Nov 14 09:45:10.551: //145311/2CBFC05B95A6/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~14 From: CME_SDK <sip:4922429236541062_001DA2906088@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541062@10.40.0.13>;tag=6184F324-17B3 Date: Mon, 14 Nov 2011 09:45:09 GMT Call-ID: 13212639189125@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Contact: <sip:4922429236541062@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 202
Nov 14 09:45:11.019: //CTI/PI:cti_frontend_proc [237A0]: received CC Event [19]: CC_EV_CALL_INFO Nov 14 09:45:11.019: //CTI/PI:pi_process_service_event event 19 Nov 14 09:45:11.019: //CTI/PI: got CC_EV_CALL_INFO callID 145312 Nov 14 09:45:11.019: //CTI/PI:pi_parse_service event 0 Nov 14 09:45:11.019: CTI_PARSERrocessing XML message <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <MonitorStart xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorObject> <deviceObject>tel:4922429236541063</deviceObject> </monitorObject> </MonitorStart>
Nov 14 09:45:11.019: //CTI/XI:csta_MonitorStart_cb: Function Called Nov 14 09:45:11.019: //CTI/XI:csta_monitorObject_cb: Function Called Nov 14 09:45:11.019: //CTI/XI:csta_deviceObject_cb: Function Called Nov 14 09:45:11.019: //CTI/XI:csta2cti:789 csta_service_type_t(21) Nov 14 09:45:11.023: //CTI/XI:csta2cti_DeviceID: csta(tel:4922429236541063) attr(1,tel number(4922429236541063) phone-context=(0,) device=() Nov 14 09:45:11.023: //CTI/XI:csta2cti:1034 cti_ev_type_t(6) Nov 14 09:45:11.023: //CTI/SM:sm_handle_sys_service event 6 Nov 14 09:45:11.023: //CTI/SM:sm_find_scb_node_by_context context_id 145312 Nov 14 09:45:11.023: //CTI/SM: to return 3217D86C Nov 14 09:45:11.023: //CTI/SM:sm_handle_sys_monitor_start_stop event 6 Nov 14 09:45:11.023: //CTI/PI:pi_send_sys_event event 21 context_id 145312 Nov 14 09:45:11.023: //CTI/PI:pi_build_event event 21 Nov 14 09:45:11.023: //CTI/XI:cti2csta:1048 cti_ev_type_t(21) Nov 14 09:45:11.023: //CTI/XI:cti2csta_xrefID: xrefID (550) Nov 14 09:45:11.023: //CTI/XI:cti2csta:2075 csta_EventType_t(22) Nov 14 09:45:11.023: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(202), message(202) <?xml version="1.0" encoding="UTF-8"?> <MonitorStartResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000550</monitorCrossRefID> </MonitorStartResponse> Nov 14 09:45:11.023: //CTI/CC:ccm_process_sm_event event 33 Nov 14 09:45:11.023: //CTI/CM: Nov 14 09:45:11.023: //CTI/CM:cmm_subscribe_handler for 4922429236541063 sub_id 145312, strip_mac 0 cm_feature 3 Nov 14 09:45:11.023: //CTI/CM:find_lineinfo_node finding number 4922429236541063 Nov 14 09:45:11.023: //CTI/CM: target_node 3009A884 Nov 14 09:45:11.023: //CTI/CM: - dn 4922429236541063
Nov 14 09:45:11.023: //CTI/CM:find_subinfo_from_lineinfo sub_id 145312 Nov 14 09:45:11.023: //CTI/CM: sub_info 0 Nov 14 09:45:11.023: //CTI/CM: Nov 14 09:45:11.023: No sub_info found Nov 14 09:45:11.023: device 4922429236541063 Nov 14 09:45:11.023: --attribute 1 Nov 14 09:45:11.023: --tag 0 Nov 14 09:45:11.023: --phone_ctx 0 Nov 14 09:45:11.023: --mac 002584A1F7AB Nov 14 09:45:11.023: //CTI/CM:phone idx 8 Nov 14 09:45:11.023: //CTI/CM:insert_subinfo_to_lineinfo sub_id 145312 to line 4922429236541063 Nov 14 09:45:11.023: //CTI/CM: sub_info_tar 0 Nov 14 09:45:11.023: _subscribed - 300AA980 145312, Nov 14 09:45:11.027: //145312/2D888DBE95AB/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~18 From: CME_SDK <sip:4922429236541063_002584A1F7AB@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541063@10.40.0.13>;tag=6184F844-76 Date: Mon, 14 Nov 2011 09:45:11 GMT Call-ID: 13212639202246@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Content-Length: 0
Nov 14 09:45:11.027: //145312/2D888DBE95AB/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKomMB6qSa0xK2pXHyaQHsCQ~~18 From: CME_SDK <sip:4922429236541063_002584A1F7AB@csta-sdk>;tag=ds51e5444 To: <sip:4922429236541063@10.40.0.13>;tag=6184F844-76 Date: Mon, 14 Nov 2011 09:45:11 GMT Call-ID: 13212639202246@10.10.2.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 5 INFO Contact: <sip:4922429236541063@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 202
<?x qs-cucme01#ml version="1.0" encoding="UTF-8"?> <MonitorStartResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000550</monitorCrossRefID></MonitorStartResponse> qs-cucme01# Nov 14 09:45:17.447: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:17.447: //CTI/CM: Nov 14 09:45:17.447: //CTI/CM: Nov 14 09:45:17.447: //CTI/CM:-- trigger 1, callID 145313, dn 2242939783, reason 3291442, result 859190580 Nov 14 09:45:17.447: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.447: //CTI/CM: target_node 0 Nov 14 09:45:17.447: //CTI/CM:Lineinfo node Search 2242939783 FAILED Nov 14 09:45:17.447: //CTI/CM:create_lineinfo_node Nov 14 09:45:17.447: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.447: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.447: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.447: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.447: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.447: //CTI/CM: CallEntry 3001C078 Nov 14 09:45:17.447: //CTI/CM: dstCallID -1 Nov 14 09:45:17.447: //CTI/CM: line_info 2AB98020, dn 2242939783 Nov 14 09:45:17.447: //CTI/CM: * cmm_crs_proc_tr_call_orig Nov 14 09:45:17.447: //CTI/CM: callID = 145313, CG 2242939783, GCID = Nov 14 09:45:17.447: //CTI/CM:cmm_is_hairpin_leg number 2242939783 callID 145313 Nov 14 09:45:17.447: //CTI/CM:cti_cmm_is_ephone_leg cid 145313 interface type 0 return FALSE Nov 14 09:45:17.447: //CTI/CM:cti_cmm_has_2nd_ephone_leg_in_gcid compared number 2242939783 cid 145313 Nov 14 09:45:17.447: //CTI/CM:find_gcidinfo_node Nov 14 09:45:17.447: //CTI/CM: target_node 0 Nov 14 09:45:17.447: //CTI/CM: Gcidinfo node Search FAILED Nov 14 09:45:17.447: //CTI/CM: cti_cmm_has_2nd_ephone_leg_in_gcid callID -1 leg NOT FOUND00000000-00000000-00000000-00000000 Nov 14 09:45:17.447: //CTI/CM:increase_gcid_ref_count 145313 0 Nov 14 09:45:17.447: //CTI/CM:find_gcidinfo_node Nov 14 09:45:17.447: //CTI/CM: target_node 0 Nov 14 09:45:17.447: //CTI/CM: Gcidinfo node Search FAILED Nov 14 09:45:17.447: //CTI/CM:create_gcidinfo_node 145313 Nov 14 09:45:17.447: //CTI/CM: target_node 2A687540 Nov 14 09:45:17.447: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.447: //CTI/CM: count = 1 Nov 14 09:45:17.447: //CTI/CM:cti_cmm_call_inst_set_DN_phone call_inst 2C58EF6C callID 145313 dn_tag -1 dn_chan -1 Nov 14 09:45:17.447: //CTI/CM: orig --> callID 145313, line_info 2AB98020, call_inst 2C58EF6C, gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.447: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.447: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.447: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.447: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 2242939783 callID 145313 Nov 14 09:45:17.447: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.447: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.447: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.447: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag -1 mac no mac Nov 14 09:45:17.447: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_ORIGINATED Nov 14 09:45:17.455: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:17.455: //CTI/CM: Nov 14 09:45:17.455: //CTI/CM: Nov 14 09:45:17.455: //CTI/CM:-- trigger 14, callID 145314, dn 4922429236541060, reason 0, result 0 Nov 14 09:45:17.455: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:17.455: //CTI/CM: target_node 323CED54 Nov 14 09:45:17.455: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:17.455: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:17.455: //CTI/CM: dstCallID 145313 Nov 14 09:45:17.455: //CTI/CM: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:17.455: //CTI/CM: * cti_cmm_crs_proc_tr_call_active Nov 14 09:45:17.455: //CTI/CM: callID = 145314,src_callid = 145313, CG 2242939783, CD = 4922429236541060, GCID =00000000-00000000-00000000-00000000 Nov 14 09:45:17.455: //CTI/CM:increase_gcid_ref_count 145314 0 Nov 14 09:45:17.455: //CTI/CM:find_gcidinfo_node Nov 14 09:45:17.455: //CTI/CM: target_node 2A687540 Nov 14 09:45:17.455: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.455: //CTI/CM: count = 2 Nov 14 09:45:17.455: //CTI/CM: set originalCalled = 4922429236541060 Nov 14 09:45:17.459: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:17.459: //CTI/CM: Nov 14 09:45:17.459: //CTI/CM: Nov 14 09:45:17.459: //CTI/CM:-- trigger 2, callID 145314, dn 4922429236541060, reason 825243184, result 0 Nov 14 09:45:17.459: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:17.459: //CTI/CM: target_node 323CED54 Nov 14 09:45:17.459: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:17.459: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:17.459: //CTI/CM: dstCallID 145313 Nov 14 09:45:17.459: //CTI/CM: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:17.459: //CTI/CM: * cmm_crs_proc_tr_call_offr Nov 14 09:45:17.459: //CTI/CM: offr --> line_info 323CED60 Nov 14 09:45:17.459: //CTI/CM: CallEntry_src 3001C078, id = 145313 Nov 14 09:45:17.459: //CTI/CM: call_inst_src 2C58EF6C gcid_parent 00000000-00000000-00000000-00000000 Nov 14 09:45:17.459: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.459: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.459: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.459: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 2242939783 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.459: //CTI/CM:cmm_find_callid_from_gcid dn 2242939783 Nov 14 09:45:17.459: //CTI/CM:find_gcidinfo_node Nov 14 09:45:17.459: //CTI/CM: target_node 2A687540 Nov 14 09:45:17.459: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.459: //CTI/CM: to return callID 145313 Nov 14 09:45:17.459: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 2242939783 callID 145313 Nov 14 09:45:17.459: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.459: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.459: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.459: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag -1 mac no mac Nov 14 09:45:17.459: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_OFFERED Nov 14 09:45:17.459: <== CMM show CTI event ==> Nov 14 09:45:17.459: - Calling 2242939783 Nov 14 09:45:17.459: - Calling attr 1 Nov 14 09:45:17.459: - Calling tag -1 Nov 14 09:45:17.459: - Calling MAC Nov 14 09:45:17.459: - Called 4922429236541060 Nov 14 09:45:17.459: - Called attr 1 Nov 14 09:45:17.459: - Called tag 0 Nov 14 09:45:17.459: - Called MAC Nov 14 09:45:17.459: - ConnAddr 4922429236541060 Nov 14 09:45:17.459: - ConnAddr attr 1 Nov 14 09:45:17.459: - ConnAddr tag -1 Nov 14 09:45:17.459: - ConnAddr MAC Nov 14 09:45:17.459: - type 66 Nov 14 09:45:17.459: - Cause 1 normal Nov 14 09:45:17.459: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.459: - EV_OFFERED Nov 14 09:45:17.459: - LastRedirectAddr Nov 14 09:45:17.459: - LastRedirectAddr attr 0 Nov 14 09:45:17.459: - type 0 Nov 14 09:45:17.459: - direction 0 Nov 14 09:45:17.459: - originalCalled Nov 14 09:45:17.459: - originalCalled attr 0 Nov 14 09:45:17.459: - parentGcid00000000-00000000-00000000-00000000 Nov 14 09:45:17.459: - localConnectionState Alerting Nov 14 09:45:17.459: Nov 14 09:45:17.459: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:17.459: send EV_OFFERED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:17.459: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_OFFERED Nov 14 09:45:17.459: //CTI/CM: CID = 145314, CallEntry_dst = 30022F90, call_inst_dst = 2C590384 Nov 14 09:45:17.459: //CTI/CM:cti_cmm_call_inst_set_DN_phone call_inst 2C590384 callID 145314 dn_tag -1 dn_chan -1 Nov 14 09:45:17.459: //CTI/CC:ccm_handle_call_event type 66 ConnAddr 4922429236541060 Nov 14 09:45:17.459: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:17.459: //CTI/CM: target_node 323CED54 Nov 14 09:45:17.459: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:17.459: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:17.459: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:17.459: //CTI/CC: final ccb count 1 Nov 14 09:45:17.463: //CTI/CC:ccm_drive_fsm state_curr 1 event 66 Nov 14 09:45:17.463: //CTI/CC: Eventual selection 12 Nov 14 09:45:17.463: //CTI/CC:Fsm_AnyState_AnyEvent state_curr 1 event 66 Nov 14 09:45:17.463: //CTI/CC:ccm_delete_ccb Nov 14 09:45:17.463: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:17.463: //CTI/CC: final ccb count 0 Nov 14 09:45:17.471: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:17.471: //CTI/CM: Nov 14 09:45:17.471: //CTI/CM: Nov 14 09:45:17.471: //CTI/CM:-- trigger 3, callID 145314, dn 4922429236541060, reason 693099180, result 58 Nov 14 09:45:17.471: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:17.471: //CTI/CM: target_node 323CED54 Nov 14 09:45:17.471: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:17.471: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:17.471: //CTI/CM: dstCallID 145313 Nov 14 09:45:17.471: //CTI/CM: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:17.471: //CTI/CM: * cmm_crs_proc_tr_call_ring Nov 14 09:45:17.471: //CTI/CM: call_inst_src 2C58EF6C gcid_parent 00000000-00000000-00000000-00000000 Nov 14 09:45:17.471: //CTI/CM:cti_cmm_call_inst_set_DN_tag call_inst 2C590384 dn_tag 11 dn_chan 1 Nov 14 09:45:17.471: //CTI/CM: ring --> callID 145314, line_info 323CED60, call_inst 2C590384, gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.471: //CTI/CM:cti_cmm_ringing_notify_ccm trigger EV_DELIVERED Nov 14 09:45:17.471: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_DELIVERED Nov 14 09:45:17.471: <== CMM show CTI event ==> Nov 14 09:45:17.471: - Calling 2242939783 Nov 14 09:45:17.471: - Calling attr 1 Nov 14 09:45:17.471: - Calling tag -1 Nov 14 09:45:17.471: - Calling MAC Nov 14 09:45:17.471: - Called 4922429236541060 Nov 14 09:45:17.471: - Called attr 1 Nov 14 09:45:17.471: - Called tag 11 Nov 14 09:45:17.471: - Called MAC 0014F29CD919 Nov 14 09:45:17.471: - ConnAddr 4922429236541060 Nov 14 09:45:17.471: - ConnAddr attr 1 Nov 14 09:45:17.471: - ConnAddr tag 11 Nov 14 09:45:17.471: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:17.471: - type 67 Nov 14 09:45:17.471: - Cause 1 normal Nov 14 09:45:17.471: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:17.471: - EV_DELIVERED Nov 14 09:45:17.471: - type 0 Nov 14 09:45:17.471: - LastRedirectAddr Nov 14 09:45:17.471: - LastRedirectAddr attr 0 Nov 14 09:45:17.471: - direction 0 Nov 14 09:45:17.471: - originalCalled Nov 14 09:45:17.471: - originalCalled attr 0 Nov 14 09:45:17.471: - parentGcid00000000-00000000-00000000-00000000 Nov 14 09:45:17.471: - localConnectionState Alerting Nov 14 09:45:17.471: Nov 14 09:45:17.471: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:17.471: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:17.471: send EV_DELIVERED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:17.471: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:17.471: //CTI/CM: target_node 2AB98014 Nov 14 09:45:17.471: //CTI/CM: - dn 2242939783
Nov 14 09:45:17.471: //CTI/CM:cti_cmm_ringing_notify_ccm trigger EV_DELIVERED Nov 14 09:45:17.471: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_DELIVERED Nov 14 09:45:17.471: //CTI/CC:ccm_handle_call_event type 67 ConnAddr 4922429236541060 Nov 14 09:45:17.471: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:17.471: //CTI/CM: target_node 323CED54 Nov 14 09:45:17.471: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:17.471: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:17.471: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:17.471: //CTI/CC: final ccb count 1 Nov 14 09:45:17.475: //CTI/CC:ccm_drive_fsm state_curr 1 event 67 Nov 14 09:45:17.475: //CTI/CC: Eventual selection 1 Nov 14 09:45:17.475: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 67 Nov 14 09:45:17.475: //CTI/CC:ccm_delete_ccb Nov 14 09:45:17.475: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:17.475: //CTI/CC: final ccb count 0 Nov 14 09:45:17.475: //CTI/SM:sm_process_ccm_event event 67 Nov 14 09:45:17.475: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:17.475: //CTI/SM: to return 32434E40 Nov 14 09:45:17.475: //CTI/PI:pi_send_cc_event event 67 context_id 145309 Nov 14 09:45:17.475: //CTI/PI:pi_build_event event 67 Nov 14 09:45:17.475: //CTI/XI:cti2csta:1048 cti_ev_type_t(67) Nov 14 09:45:17.475: //CTI/XI:cti2csta_cause: cti_cause = 1 Nov 14 09:45:17.475: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:17.475: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:17.475: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:17.475: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(2242939783) phone-context=(1,dialstring) device=() Nov 14 09:45:17.475: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:17.475: //CTI/XI:cti2csta:2075 csta_EventType_t(29) Nov 14 09:45:17.475: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(744), message(744) <?xml version="1.0" encoding="UTF-8"?> <DeliveredEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <connection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </connection> <alertingDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </alertingDevice> <callingDevice> <deviceIdentifier>tel:2242939783;phone-context=dialstring</deviceIdentifier> </callingDevice> <calledDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </calledDevice> <lastRedirectionDevice> <notRequired/> </lastRedirectionDevice> <localConnectionInfo>alerting</localConnectionInfo> <cause>normal</cause> </DeliveredEvent> Nov 14 09:45:17.475: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DB81D45 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263917 CSeq: 101 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 744
Nov 14 09:45:17.907: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:17.907: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:17.907: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:17.907: //CTI/PI: got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:17.907: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:17.907: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:17.907: //CTI/SM: to return 32434E40 Nov 14 09:45:17.907: //CTI/SM: got CTI_EV_ACK, callID 145309 qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# Nov 14 09:45:28.955: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:28.955: //CTI/CM: Nov 14 09:45:28.955: //CTI/CM: Nov 14 09:45:28.955: //CTI/CM:-- trigger 5, callID 145314, dn 4922429236541060, reason 745979368, result 0 Nov 14 09:45:28.955: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:28.955: //CTI/CM: target_node 323CED54 Nov 14 09:45:28.955: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:28.955: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:28.955: //CTI/CM: dstCallID 145313 Nov 14 09:45:28.955: //CTI/CM: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:28.955: //CTI/CM: * cmm_crs_proc_tr_call_ans Nov 14 09:45:28.955: //CTI/CM: ans --> callID 145314, line_info 323CED60, call_inst 2C590384 Nov 14 09:45:28.955: //CTI/CM:cti_cmm_call_inst_set_DN_phone call_inst 2C590384 callID 145314 dn_tag 11 dn_chan 1 Nov 14 09:45:28.955: //CTI/CM:cti_cmm_call_inst_set_DN_phone ccGetCallInfo ret succ. phone tag 5 Nov 14 09:45:28.955: //CTI/CM:cti_cmm_call_inst_set_DN_phone call_inst 2C58EF6C callID 145313 dn_tag -1 dn_chan -1 Nov 14 09:45:28.955: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:28.955: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:28.955: //CTI/CM:find_gcidinfo_node Nov 14 09:45:28.955: //CTI/CM: target_node 2A687540 Nov 14 09:45:28.955: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:28.955: //CTI/CM: to return callID 145314 Nov 14 09:45:28.959: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:28.959: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:28.959: //CTI/CM: target_node 323CED54 Nov 14 09:45:28.959: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:28.959: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:28.959: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:28.959: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:28.959: //CTI/CM:find_gcidinfo_node Nov 14 09:45:28.959: //CTI/CM: target_node 2A687540 Nov 14 09:45:28.959: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:28.959: //CTI/CM: to return callID 145314 Nov 14 09:45:28.959: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:28.959: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:28.959: //CTI/CM: target_node 323CED54 Nov 14 09:45:28.959: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:28.959: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:28.959: //CTI/CM:cti_is_sccp_endpoint DN 4922429236541060 Nov 14 09:45:28.959: //CTI/CM: Nov 14 09:45:28.959: sccp endpoint TRUE Nov 14 09:45:28.959: //CTI/CM: Nov 14 09:45:28.959: cti_cmm_IsParkDn phone number 4922429236541060 dn 11 Nov 14 09:45:28.959: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_ESTABLISHED Nov 14 09:45:28.959: <== CMM show CTI event ==> Nov 14 09:45:28.959: - Calling 2242939783 Nov 14 09:45:28.959: - Calling attr 1 Nov 14 09:45:28.959: - Calling tag -1 Nov 14 09:45:28.959: - Calling MAC Nov 14 09:45:28.959: - Called 4922429236541060 Nov 14 09:45:28.959: - Called attr 1 Nov 14 09:45:28.959: - Called tag 11 Nov 14 09:45:28.959: - Called MAC 0014F29CD919 Nov 14 09:45:28.959: - ConnAddr 4922429236541060 Nov 14 09:45:28.959: - ConnAddr attr 1 Nov 14 09:45:28.959: - ConnAddr tag 11 Nov 14 09:45:28.959: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:28.959: - type 68 Nov 14 09:45:28.959: - Cause 1 normal Nov 14 09:45:28.959: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:28.959: - EV_ESTABLISHED Nov 14 09:45:28.959: - LastRedirectAddr Nov 14 09:45:28.959: - LastRedirectAddr attr 0 Nov 14 09:45:28.959: - type 0 Nov 14 09:45:28.959: - direction 0 Nov 14 09:45:28.959: - originalCalled Nov 14 09:45:28.959: - originalCalled attr 0 Nov 14 09:45:28.959: - callID 0 Nov 14 09:45:28.959: - parentGcid00000000-00000000-00000000-00000000 Nov 14 09:45:28.959: - localConnectionState Connected Nov 14 09:45:28.959: Nov 14 09:45:28.959: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:28.959: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:28.959: send EV_ESTABLISHED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:28.959: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:28.959: //CTI/CM: target_node 2AB98014 Nov 14 09:45:28.959: //CTI/CM: - dn 2242939783
Nov 14 09:45:28.959: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_ESTABLISHED Nov 14 09:45:28.963: //CTI/CC:ccm_handle_call_event type 68 ConnAddr 4922429236541060 Nov 14 09:45:28.963: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:28.963: //CTI/CM: target_node 323CED54 Nov 14 09:45:28.963: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:28.963: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:28.963: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:28.963: //CTI/CC: final ccb count 1 Nov 14 09:45:28.963: //CTI/CC:ccm_drive_fsm state_curr 1 event 68 Nov 14 09:45:28.963: //CTI/CC: Eventual selection 2 Nov 14 09:45:28.963: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 68 Nov 14 09:45:28.963: //CTI/CC:ccm_delete_ccb Nov 14 09:45:28.963: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:28.963: //CTI/CC: final ccb count 0 Nov 14 09:45:28.963: //CTI/SM:sm_process_ccm_event event 68 Nov 14 09:45:28.963: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:28.963: //CTI/SM: to return 32434E40 Nov 14 09:45:28.963: //CTI/PI:pi_send_cc_event event 68 context_id 145309 Nov 14 09:45:28.963: //CTI/PI:pi_build_event event 68 Nov 14 09:45:28.963: //CTI/XI:cti2csta:1048 cti_ev_type_t(68) Nov 14 09:45:28.963: //CTI/XI:cti2csta_cause: cti_cause = 1 Nov 14 09:45:28.963: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:28.963: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(1,dialstring) device=(0014F29CD919) Nov 14 09:45:28.963: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(1,dialstring) device=(0014F29CD919) Nov 14 09:45:28.963: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(2242939783) phone-context=(0,) device=() Nov 14 09:45:28.963: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:28.963: //CTI/XI:cti2csta:2075 csta_EventType_t(31) Nov 14 09:45:28.967: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(798), message(798) <?xml version="1.0" encoding="UTF-8"?> <EstablishedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <establishedConnection> <deviceID>tel:4922429236541060;phone-context=dialstring;device=0014F29CD919</deviceID> </establishedConnection> <answeringDevice> <deviceIdentifier>tel:4922429236541060;phone-context=dialstring;device=0014F29CD919</deviceIdentifier> </answeringDevice> <callingDevice> <deviceIdentifier>tel:2242939783</deviceIdentifier> </callingDevice> <calledDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </calledDevice> <lastRedirectionDevice> <notRequired/> </lastRedirectionDevice> <localConnectionInfo>connected</localConnectionInfo> <cause>normal</cause> </EstablishedEvent> Nov 14 09:45:28.967: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DB9214D From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263928 CSeq: 102 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 798
Nov 14 09:45:29.175: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:29.179: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:29.179: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:29.179: //CTI/PI: got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:29.179: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:29.179: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:29.179: //CTI/SM: to return 32434E40 Nov 14 09:45:29.179: //CTI/SM: got CTI_EV_ACK, callID 145309 Nov 14 09:45:29.179: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:29.179: //CTI/CM: Nov 14 09:45:29.179: //CTI/CM: Nov 14 09:45:29.179: //CTI/MD:-- trigger 22, callID 145314, dn 4922429236541060, reason 0, result 0 Nov 14 09:45:29.179: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.179: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.179: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.179: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:29.179: //CTI/CM: dstCallID 145313 Nov 14 09:45:29.179: //CTI/MD: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:29.179: //CTI/MD: * cti_cmm_crs_proc_tr_call_mediaattached Nov 14 09:45:29.179: //CTI/MD: parm --> callID 145314, line_info 323CED60, call_inst 2C590384 Nov 14 09:45:29.179: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.179: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:29.179: //CTI/CM:find_gcidinfo_node Nov 14 09:45:29.179: //CTI/CM: target_node 2A687540 Nov 14 09:45:29.179: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.183: //CTI/CM: to return callID 145314 Nov 14 09:45:29.183: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:29.183: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.183: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.183: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.183: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:29.183: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.183: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:29.183: //CTI/CM:find_gcidinfo_node Nov 14 09:45:29.183: //CTI/CM: target_node 2A687540 Nov 14 09:45:29.183: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.183: //CTI/CM: to return callID 145314 Nov 14 09:45:29.183: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:29.183: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.183: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.183: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.183: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:29.183: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_MEDIAATTACHED Nov 14 09:45:29.183: <== CMM show CTI event ==> Nov 14 09:45:29.183: - Calling Nov 14 09:45:29.183: - Calling attr 0 Nov 14 09:45:29.183: - Calling tag 0 Nov 14 09:45:29.183: - Calling MAC Nov 14 09:45:29.183: - Called 4922429236541060 Nov 14 09:45:29.183: - Called attr 1 Nov 14 09:45:29.183: - Called tag 11 Nov 14 09:45:29.183: - Called MAC 0014F29CD919 Nov 14 09:45:29.183: - ConnAddr 4922429236541060 Nov 14 09:45:29.183: - ConnAddr attr 1 Nov 14 09:45:29.183: - ConnAddr tag 11 Nov 14 09:45:29.183: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:29.183: - type 82 Nov 14 09:45:29.183: - Cause 1 normal Nov 14 09:45:29.183: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.183: - EV_MEDIAATTACHED Nov 14 09:45:29.183: - mediaServiceType 26 Nov 14 09:45:29.183: - flowDirection 1 Nov 14 09:45:29.183: - mediaSessionInfo v=0 o=- 1178 2900 IN IP4 10.10.1.84 s=SCCP Call c=IN IP4 10.10.1.84 m=audio 31736 RTP/AVP 18
Nov 14 09:45:29.183: Nov 14 09:45:29.183: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:29.183: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:29.183: send EV_MEDIAATTACHED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:29.183: //CTI/CC:ccm_handle_call_event type 82 ConnAddr 4922429236541060 Nov 14 09:45:29.187: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.187: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.187: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.187: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:29.187: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:29.187: //CTI/CC: final ccb count 1 Nov 14 09:45:29.187: //CTI/CC:ccm_drive_fsm state_curr 1 event 82 Nov 14 09:45:29.187: //CTI/CC: Eventual selection 3 Nov 14 09:45:29.187: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 82 Nov 14 09:45:29.187: //CTI/CC:ccm_delete_ccb Nov 14 09:45:29.187: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:29.187: //CTI/CC: final ccb count 0 Nov 14 09:45:29.187: //CTI/SM:sm_process_ccm_event event 82 Nov 14 09:45:29.187: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:29.187: //CTI/SM: to return 32434E40 Nov 14 09:45:29.187: //CTI/PI:pi_send_cc_event event 82 context_id 145309 Nov 14 09:45:29.187: //CTI/PI:pi_build_event event 82 Nov 14 09:45:29.187: //CTI/XI:cti2csta:1048 cti_ev_type_t(82) Nov 14 09:45:29.187: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:29.187: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:29.187: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:29.187: //CTI/XI:cti2csta:2075 csta_EventType_t(40) Nov 14 09:45:29.187: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(684), message(684) <?xml version="1.0" encoding="UTF-8"?> <MediaAttachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>receive</flowDirection> </mediaSessionInfo>VP 1810.1.84 </mediaConnectionInfo> </MediaAttachedEvent> Nov 14 09:45:29.187: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBAA31 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263929 CSeq: 103 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 684
Nov 14 09:45:29.959: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:29.963: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:29.963: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:29.963: //CTI/PI: got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:29.963: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:29.963: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:29.963: //CTI/SM: to return 32434E40 Nov 14 09:45:29.963: //CTI/SM: got CTI_EV_ACK, callID 145309 Nov 14 09:45:29.963: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:29.963: //CTI/CM: Nov 14 09:45:29.963: //CTI/CM: Nov 14 09:45:29.963: //CTI/MD:-- trigger 22, callID 145314, dn 4922429236541060, reason 698941440, result 1 Nov 14 09:45:29.963: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.963: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.963: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.963: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:29.963: //CTI/CM: dstCallID 145313 Nov 14 09:45:29.963: //CTI/MD: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:29.963: //CTI/MD: * cti_cmm_crs_proc_tr_call_mediaattached Nov 14 09:45:29.963: //CTI/MD: parm --> callID 145314, line_info 323CED60, call_inst 2C590384 Nov 14 09:45:29.963: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.963: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:29.963: //CTI/CM:find_gcidinfo_node Nov 14 09:45:29.963: //CTI/CM: target_node 2A687540 Nov 14 09:45:29.967: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.967: //CTI/CM: to return callID 145314 Nov 14 09:45:29.967: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:29.967: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.967: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.967: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.967: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:29.967: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.967: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:29.967: //CTI/CM:find_gcidinfo_node Nov 14 09:45:29.967: //CTI/CM: target_node 2A687540 Nov 14 09:45:29.967: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.967: //CTI/CM: to return callID 145314 Nov 14 09:45:29.967: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:29.967: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.967: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.967: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.967: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:29.967: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_MEDIAATTACHED Nov 14 09:45:29.967: <== CMM show CTI event ==> Nov 14 09:45:29.967: - Calling Nov 14 09:45:29.967: - Calling attr 0 Nov 14 09:45:29.967: - Calling tag 0 Nov 14 09:45:29.967: - Calling MAC Nov 14 09:45:29.967: - Called 4922429236541060 Nov 14 09:45:29.967: - Called attr 1 Nov 14 09:45:29.967: - Called tag 11 Nov 14 09:45:29.967: - Called MAC 0014F29CD919 Nov 14 09:45:29.967: - ConnAddr 4922429236541060 Nov 14 09:45:29.967: - ConnAddr attr 1 Nov 14 09:45:29.967: - ConnAddr tag 11 Nov 14 09:45:29.967: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:29.967: - type 82 Nov 14 09:45:29.967: - Cause 1 normal Nov 14 09:45:29.967: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:29.967: - EV_MEDIAATTACHED Nov 14 09:45:29.967: - mediaServiceType 26 Nov 14 09:45:29.967: - flowDirection 0 Nov 14 09:45:29.967: - mediaSessionInfo v=0 o=- 1178 2901 IN IP4 10.10.1.84 s=SCCP Call c=IN IP4 10.10.1.84 m=audio 31736 RTP/AVP 18
Nov 14 09:45:29.967: Nov 14 09:45:29.967: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:29.967: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:29.967: send EV_MEDIAATTACHED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:29.979: //CTI/CC:ccm_handle_call_event type 82 ConnAddr 4922429236541060 Nov 14 09:45:29.979: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:29.979: //CTI/CM: target_node 323CED54 Nov 14 09:45:29.979: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:29.979: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:29.979: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:29.979: //CTI/CC: final ccb count 1 Nov 14 09:45:29.979: //CTI/CC:ccm_drive_fsm state_curr 1 event 82 Nov 14 09:45:29.979: //CTI/CC: Eventual selection 3 Nov 14 09:45:29.979: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 82 Nov 14 09:45:29.979: //CTI/CC:ccm_delete_ccb Nov 14 09:45:29.979: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:29.979: //CTI/CC: final ccb count 0 Nov 14 09:45:29.979: //CTI/SM:sm_process_ccm_event event 82 Nov 14 09:45:29.979: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:29.979: //CTI/SM: to return 32434E40 Nov 14 09:45:29.979: //CTI/PI:pi_send_cc_event event 82 context_id 145309 Nov 14 09:45:29.979: //CTI/PI:pi_build_event event 82 Nov 14 09:45:29.979: //CTI/XI:cti2csta:1048 cti_ev_type_t(82) Nov 14 09:45:29.979: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:29.979: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:29.979: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:29.979: //CTI/XI:cti2csta:2075 csta_EventType_t(40) Nov 14 09:45:29.979: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(685), message(685) <?xml version="1.0" encoding="UTF-8"?> <MediaAttachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>transmit</flowDirection> </mediaSessionInfo>VP 1810.1.84 </mediaConnectionInfo> </MediaAttachedEvent> Nov 14 09:45:29.991: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBB52D From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263929 CSeq: 104 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 685
qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# qs-cucme01# Nov 14 09:45:41.047: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:41.047: //CTI/CM: Nov 14 09:45:41.047: //CTI/CM: Nov 14 09:45:41.047: //CTI/MD:-- trigger 23, callID 145314, dn 4922429236541060, reason 699671160, result 729096804 Nov 14 09:45:41.047: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.047: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.047: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.047: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:41.047: //CTI/CM: dstCallID 145313 Nov 14 09:45:41.047: //CTI/MD: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:41.047: //CTI/MD: * cti_cmm_crs_proc_tr_call_mediadetached Nov 14 09:45:41.047: //CTI/MD: parm --> callID 145314, line_info 323CED60, call_inst 2C590384 Nov 14 09:45:41.047: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.047: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.047: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.047: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.047: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.051: //CTI/CM: to return callID 145314 Nov 14 09:45:41.051: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.051: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.051: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.051: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.051: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.051: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.051: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.051: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.051: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.051: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.051: //CTI/CM: to return callID 145314 Nov 14 09:45:41.051: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.051: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.051: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.051: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.051: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.051: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_MEDIADETACHED Nov 14 09:45:41.051: <== CMM show CTI event ==> Nov 14 09:45:41.051: - Calling Nov 14 09:45:41.051: - Calling attr 0 Nov 14 09:45:41.051: - Calling tag 0 Nov 14 09:45:41.051: - Calling MAC Nov 14 09:45:41.051: - Called 4922429236541060 Nov 14 09:45:41.051: - Called attr 1 Nov 14 09:45:41.051: - Called tag 11 Nov 14 09:45:41.051: - Called MAC 0014F29CD919 Nov 14 09:45:41.051: - ConnAddr 4922429236541060 Nov 14 09:45:41.051: - ConnAddr attr 1 Nov 14 09:45:41.051: - ConnAddr tag 11 Nov 14 09:45:41.051: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:41.051: - type 83 Nov 14 09:45:41.051: - Cause 1 normal Nov 14 09:45:41.051: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.051: - EV_MEDIADETACHED Nov 14 09:45:41.051: - mediaServiceType 26 Nov 14 09:45:41.051: - flowDirection 1 Nov 14 09:45:41.051: Nov 14 09:45:41.051: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:41.051: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:41.051: send EV_MEDIADETACHED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:41.051: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:41.051: //CTI/CM: Nov 14 09:45:41.051: //CTI/CM: Nov 14 09:45:41.051: //CTI/MD:-- trigger 23, callID 145314, dn 4922429236541060, reason 0, result 817647680 Nov 14 09:45:41.051: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.055: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.055: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.055: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:41.055: //CTI/CM: dstCallID 145313 Nov 14 09:45:41.055: //CTI/MD: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:41.055: //CTI/MD: * cti_cmm_crs_proc_tr_call_mediadetached Nov 14 09:45:41.055: //CTI/MD: parm --> callID 145314, line_info 323CED60, call_inst 2C590384 Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.055: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.055: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.055: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.055: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.055: //CTI/CM: to return callID 145314 Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.055: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.055: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.055: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.055: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.055: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.055: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.055: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.055: //CTI/CM: to return callID 145314 Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.055: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.055: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.055: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.055: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.055: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_MEDIADETACHED Nov 14 09:45:41.055: <== CMM show CTI event ==> Nov 14 09:45:41.055: - Calling Nov 14 09:45:41.055: - Calling attr 0 Nov 14 09:45:41.055: - Calling tag 0 Nov 14 09:45:41.055: - Calling MAC Nov 14 09:45:41.055: - Called 4922429236541060 Nov 14 09:45:41.055: - Called attr 1 Nov 14 09:45:41.055: - Called tag 11 Nov 14 09:45:41.055: - Called MAC 0014F29CD919 Nov 14 09:45:41.055: - ConnAddr 4922429236541060 Nov 14 09:45:41.055: - ConnAddr attr 1 Nov 14 09:45:41.055: - ConnAddr tag 11 Nov 14 09:45:41.055: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:41.055: - type 83 Nov 14 09:45:41.055: - Cause 1 normal Nov 14 09:45:41.055: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.055: - EV_MEDIADETACHED Nov 14 09:45:41.055: - mediaServiceType 26 Nov 14 09:45:41.055: - flowDirection 0 Nov 14 09:45:41.055: Nov 14 09:45:41.055: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:41.055: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:41.055: send EV_MEDIADETACHED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:41.059: //CTI/CC:ccm_handle_call_event type 83 ConnAddr 4922429236541060 Nov 14 09:45:41.059: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.059: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.059: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.059: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:41.059: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:41.059: //CTI/CC: final ccb count 1 Nov 14 09:45:41.059: //CTI/CC:ccm_drive_fsm state_curr 1 event 83 Nov 14 09:45:41.059: //CTI/CC: Eventual selection 4 Nov 14 09:45:41.059: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 83 Nov 14 09:45:41.059: //CTI/CC:ccm_delete_ccb Nov 14 09:45:41.059: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:41.059: //CTI/CC: final ccb count 0 Nov 14 09:45:41.059: //CTI/CC:ccm_handle_call_event type 83 ConnAddr 4922429236541060 Nov 14 09:45:41.059: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.059: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.059: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.059: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:41.059: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:41.059: //CTI/CC: final ccb count 1 Nov 14 09:45:41.063: //CTI/CC:ccm_drive_fsm state_curr 1 event 83 Nov 14 09:45:41.063: //CTI/CC: Eventual selection 4 Nov 14 09:45:41.063: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 83 Nov 14 09:45:41.063: //CTI/CC:ccm_delete_ccb Nov 14 09:45:41.063: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:41.063: //CTI/CC: final ccb count 0 Nov 14 09:45:41.063: //CTI/SM:sm_process_ccm_event event 83 Nov 14 09:45:41.063: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.063: //CTI/SM: to return 32434E40 Nov 14 09:45:41.063: //CTI/PI:pi_send_cc_event event 83 context_id 145309 Nov 14 09:45:41.063: //CTI/PI:pi_build_event event 83 Nov 14 09:45:41.063: //CTI/XI:cti2csta:1048 cti_ev_type_t(83) Nov 14 09:45:41.063: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:41.063: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.063: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.063: //CTI/XI:cti2csta:2075 csta_EventType_t(41) Nov 14 09:45:41.063: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(548), message(548) <?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>receive</flowDirection> </mediaConnectionInfo> </MediaDetachedEvent> Nov 14 09:45:41.063: //CTI/SM:sm_process_ccm_event event 83 Nov 14 09:45:41.063: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.063: //CTI/SM: to return 32434E40 Nov 14 09:45:41.063: //CTI/PI:pi_send_cc_event event 83 context_id 145309 Nov 14 09:45:41.063: //CTI/PI:pi_build_event event 83 Nov 14 09:45:41.063: //CTI/XI:cti2csta:1048 cti_ev_type_t(83) Nov 14 09:45:41.063: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:41.063: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.063: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.063: //CTI/XI:cti2csta:2075 csta_EventType_t(41) Nov 14 09:45:41.063: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(549), message(549) <?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>transmit</flowDirection> </mediaConnectionInfo> </MediaDetachedEvent> Nov 14 09:45:41.063: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBC1A42 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263941 CSeq: 105 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: app qs-cucme01#lication/csta+xml Content-Disposition: signal;handling=required Content-Length: 548
<?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>receive</flowDirection></mediaConnectionInfo></MediaDetachedEvent> Nov 14 09:45:41.183: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBC1A42 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF Call-ID: 13212639154633@10.10.2.2 CSeq: 105 INFO Content-Length: 0 Content-Type: application/csta+xml
Nov 14 09:45:41.183: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:41.183: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:41.183: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:41.183: //CTI/PI: got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:41.183: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:41.183: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.183: //CTI/SM: to return 32434E40 Nov 14 09:45:41.183: //CTI/SM: got CTI_EV_ACK, callID 145309 Nov 14 09:45:41.199: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBD128D From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263941 CSeq: 106 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 549
<?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>transmit</flowDirection></mediaConnectionInfo></MediaDetachedEvent> Nov 14 09:45:41.203: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:41.203: //CTI/CM: Nov 14 09:45:41.203: //CTI/CM: Nov 14 09:45:41.203: //CTI/CM:-- trigger 16, callID 145313, dn 2242939783, reason 16, result 0 Nov 14 09:45:41.203: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.203: //CTI/CM: target_node 2AB98014 Nov 14 09:45:41.203: //CTI/CM: - dn 2242939783
Nov 14 09:45:41.203: //CTI/CM: CallEntry 3001C078 Nov 14 09:45:41.203: //CTI/CM: dstCallID -1 Nov 14 09:45:41.203: //CTI/CM: line_info 2AB98020, dn 2242939783 Nov 14 09:45:41.203: //CTI/CM: * cti_cmm_crs_proc_tr_rpt_disconn Nov 14 09:45:41.203: //CTI/CM: callID = 145313, CG ,CG =2242939783 CD = 4922429236541060, GCID =00000000-00000000-00000000-00000000 Nov 14 09:45:41.203: //CTI/CM: * is_only_one_callinst_with_dn_and_gcid Nov 14 09:45:41.203: //CTI/CM: callid 145313, dn = 2242939783, gcid =00000000-00000000-00000000-00000000 Nov 14 09:45:41.203: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.203: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.203: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.203: //CTI/CM: Cause = A(10), inst cause = A Nov 14 09:45:41.203: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.203: //CTI/CM: target_node 2AB98014 Nov 14 09:45:41.203: //CTI/CM: - dn 2242939783
Nov 14 09:45:41.203: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 2242939783 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.203: //CTI/CM:cmm_find_callid_from_gcid dn 2242939783 Nov 14 09:45:41.203: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.203: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.203: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.203: //CTI/CM: to return callID 145313 Nov 14 09:45:41.203: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 2242939783 callID 145313 Nov 14 09:45:41.203: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.203: //CTI/CM: target_node 2AB98014 Nov 14 09:45:41.203: //CTI/CM: - dn 2242939783
Nov 14 09:45:41.203: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag -1 mac no mac Nov 14 09:45:41.203: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.207: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.207: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.207: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.207: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.207: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM: to return callID 145314 Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.207: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.207: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.207: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 2242939783 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM:cmm_find_callid_from_gcid dn 2242939783 Nov 14 09:45:41.207: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.207: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.207: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM: to return callID 145313 Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 2242939783 callID 145313 Nov 14 09:45:41.207: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.207: //CTI/CM: target_node 2AB98014 Nov 14 09:45:41.207: //CTI/CM: - dn 2242939783
Nov 14 09:45:41.207: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag -1 mac no mac Nov 14 09:45:41.207: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_CONN_CLEARED Nov 14 09:45:41.207: //CTI/CM:decrease_gcid_ref_count 145313 Nov 14 09:45:41.207: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.207: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.207: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM: count = 1 Nov 14 09:45:41.207: //CTI/CM: delete_callinst 2C58EF6C, callID = 145313 Nov 14 09:45:41.207: //CTI/CM: freeing instance from CllEntry 3001C078 Nov 14 09:45:41.207: //CTI/CM:cti_is_sccp_endpoint DN 2242939783 Nov 14 09:45:41.207: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.207: //CTI/CM: target_node 2AB98014 Nov 14 09:45:41.207: //CTI/CM: - dn 2242939783
Nov 14 09:45:41.207: //CTI/CM:free 2242939783 line_info 2AB98020 Nov 14 09:45:41.207: //CTI/CM:cti_cmm_notify_trigger Nov 14 09:45:41.207: //CTI/CM: Nov 14 09:45:41.207: //CTI/CM: Nov 14 09:45:41.207: //CTI/CM:-- trigger 16, callID 145314, dn 4922429236541060, reason 16, result 0 Nov 14 09:45:41.207: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.207: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.207: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.207: //CTI/CM: CallEntry 30022F90 Nov 14 09:45:41.207: //CTI/CM: dstCallID -1 Nov 14 09:45:41.207: //CTI/CM: line_info 323CED60, dn 4922429236541060 Nov 14 09:45:41.207: //CTI/CM: * cti_cmm_crs_proc_tr_rpt_disconn Nov 14 09:45:41.207: //CTI/CM: callID = 145314, CD ,CG =2242939783 CD = 4922429236541060, GCID =00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM: * is_only_one_callinst_with_dn_and_gcid Nov 14 09:45:41.207: //CTI/CM: callid 145314, dn = 4922429236541060, gcid =00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.207: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.207: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.207: //CTI/CM: Cause = A(10), inst cause = A Nov 14 09:45:41.207: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.211: //CTI/CM: target_node 0 Nov 14 09:45:41.211: //CTI/CM:Lineinfo node Search 2242939783 FAILED Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 2242939783 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM:cmm_find_callid_from_gcid dn 2242939783 Nov 14 09:45:41.211: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.211: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.211: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM: to return callID -1 Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID fail in getting CallID Nov 14 09:45:41.211: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.211: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.211: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.211: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.211: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.211: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM: to return callID 145314 Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.211: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.211: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.211: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 4922429236541060 gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM:cmm_find_callid_from_gcid dn 4922429236541060 Nov 14 09:45:41.211: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.211: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.211: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: //CTI/CM: to return callID 145314 Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 4922429236541060 callID 145314 Nov 14 09:45:41.211: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.211: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.211: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.211: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 11 mac 0014F29CD919 Nov 14 09:45:41.211: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_CONN_CLEARED Nov 14 09:45:41.211: <== CMM show CTI event ==> Nov 14 09:45:41.211: - Calling 2242939783 Nov 14 09:45:41.211: - Calling attr 1 Nov 14 09:45:41.211: - Calling tag -1 Nov 14 09:45:41.211: - Calling MAC Nov 14 09:45:41.211: - Called 4922429236541060 Nov 14 09:45:41.211: - Called attr 1 Nov 14 09:45:41.211: - Called tag 11 Nov 14 09:45:41.211: - Called MAC 0014F29CD919 Nov 14 09:45:41.211: - ConnAddr 4922429236541060 Nov 14 09:45:41.211: - ConnAddr attr 1 Nov 14 09:45:41.211: - ConnAddr tag 11 Nov 14 09:45:41.211: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:41.211: - type 69 Nov 14 09:45:41.211: - Cause 10 normalClearing Nov 14 09:45:41.211: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.211: - EV_CONN_CLEARED Nov 14 09:45:41.211: - Cause 10(normalClearing) Nov 14 09:45:41.211: - releasing_device Nov 14 09:45:41.211: - releasing_device attr0 Nov 14 09:45:41.211: - direction 0 Nov 14 09:45:41.211: - localConnectionState Null Nov 14 09:45:41.211: Nov 14 09:45:41.211: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:41.211: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:41.211: send EV_CONN_CLEARED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:41.211: //CTI/CM:decrease_gcid_ref_count 145314 Nov 14 09:45:41.211: //CTI/CM:find_gcidinfo_node Nov 14 09:45:41.211: //CTI/CM: target_node 2A687540 Nov 14 09:45:41.211: //CTI/CM: - gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.215: //CTI/CM:delete_gcid_node Nov 14 09:45:41.215: //CTI/CM:find_lineinfo_node finding number 2242939783 Nov 14 09:45:41.215: //CTI/CM: target_node 0 Nov 14 09:45:41.215: //CTI/CM:Lineinfo node Search 2242939783 FAILED Nov 14 09:45:41.215: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.215: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.215: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.215: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_CONN_DROPPED Nov 14 09:45:41.215: <== CMM show CTI event ==> Nov 14 09:45:41.215: - Calling 2242939783 Nov 14 09:45:41.215: - Calling attr 1 Nov 14 09:45:41.215: - Calling tag 0 Nov 14 09:45:41.215: - Calling MAC Nov 14 09:45:41.215: - Called 4922429236541060 Nov 14 09:45:41.215: - Called attr 1 Nov 14 09:45:41.215: - Called tag 0 Nov 14 09:45:41.215: - Called MAC Nov 14 09:45:41.215: - ConnAddr 4922429236541060 Nov 14 09:45:41.215: - ConnAddr attr 1 Nov 14 09:45:41.215: - ConnAddr tag 11 Nov 14 09:45:41.215: - ConnAddr MAC 0014F29CD919 Nov 14 09:45:41.215: - type 70 Nov 14 09:45:41.215: - Cause 10 normalClearing Nov 14 09:45:41.215: - Gcid 00000000-00000000-00000000-00000000 Nov 14 09:45:41.215: - EV_CONN_DROPPED Nov 14 09:45:41.215: - Cause 10(normalClearing) Nov 14 09:45:41.215: - localConnectionState Null Nov 14 09:45:41.215: Nov 14 09:45:41.215: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo subscription id 145309 dn_tag 0 Nov 14 09:45:41.215: //CTI/CM:cmm_is_hairpin_leg number 4922429236541060 callID 145314 Nov 14 09:45:41.215: send EV_CONN_DROPPED on number 4922429236541060 to ccm. sub id 145309 Nov 14 09:45:41.215: //CTI/CM: delete_callinst 2C590384, callID = 145314 Nov 14 09:45:41.215: //CTI/CM: freeing instance from CllEntry 30022F90 Nov 14 09:45:41.215: //CTI/CC:ccm_handle_call_event type 69 ConnAddr 4922429236541060 Nov 14 09:45:41.215: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.215: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.215: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.215: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:41.215: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:41.215: //CTI/CC: final ccb count 1 Nov 14 09:45:41.215: //CTI/CC:ccm_drive_fsm state_curr 1 event 69 Nov 14 09:45:41.215: //CTI/CC: Eventual selection 5 Nov 14 09:45:41.215: //CTI/CC:Fsm_AnyState_AnyCallEvent state_curr 1 event 69 Nov 14 09:45:41.215: //CTI/CC:ccm_delete_ccb Nov 14 09:45:41.215: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:41.215: //CTI/CC: final ccb count 0 Nov 14 09:45:41.215: //CTI/CC:ccm_handle_call_event type 70 ConnAddr 4922429236541060 Nov 14 09:45:41.215: //CTI/CM:find_lineinfo_node finding number 4922429236541060 Nov 14 09:45:41.219: //CTI/CM: target_node 323CED54 Nov 14 09:45:41.219: //CTI/CM: - dn 4922429236541060
Nov 14 09:45:41.219: //CTI/CC:ccm_find_ccb_by_gcid parent 0 Nov 14 09:45:41.219: //CTI/CC:ccm_append_ccb ccb 2ABFFF44 Nov 14 09:45:41.219: //CTI/CC: final ccb count 1 Nov 14 09:45:41.219: //CTI/CC:ccm_drive_fsm state_curr 1 event 70 Nov 14 09:45:41.219: //CTI/CC: Eventual selection 12 Nov 14 09:45:41.219: //CTI/CC:Fsm_AnyState_AnyEvent state_curr 1 event 70 Nov 14 09:45:41.219: //CTI/CC:ccm_delete_ccb Nov 14 09:45:41.219: //CTI/CC: ccb 2ABFFF44 released Nov 14 09:45:41.219: //CTI/CC: final ccb count 0 Nov 14 09:45:41.219: //CTI/SM:sm_process_ccm_event event 69 Nov 14 09:45:41.219: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.219: //CTI/SM: to return 32434E40 Nov 14 09:45:41.219: //CTI/PI:pi_send_cc_event event 69 context_id 145309 Nov 14 09:45:41.219: //CTI/PI:pi_build_event event 69 Nov 14 09:45:41.219: //CTI/XI:cti2csta:1048 cti_ev_type_t(69) Nov 14 09:45:41.219: //CTI/XI:cti2csta_cause: cti_cause = 10 Nov 14 09:45:41.219: //CTI/XI:cti2csta_xrefID: xrefID (54D) Nov 14 09:45:41.219: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.219: //CTI/XI:cti2csta_DeviceID: attr(1,tel number(4922429236541060) phone-context=(0,) device=(0014F29CD919) Nov 14 09:45:41.219: //CTI/XI:cti2csta:2075 csta_EventType_t(28) Nov 14 09:45:41.219: //CTI/XML:csta_xml_builder: XML message evt->protocol(1) buffer size(2000), return length(452), message(452) <?xml version="1.0" encoding="UTF-8"?> <ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <droppedConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID> </droppedConnection> <releasingDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier> </releasingDevice> <cause>normalClearing</cause> </ConnectionClearedEvent> Nov 14 09:45:41.223: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBD128D To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF Call-ID: 13212639154633@10.10.2.2 CSeq: 106 INFO Content-Length: 0 Content-Type: application/csta+xml
Nov 14 09:45:41.223: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:41.235: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:41.235: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:41.235: //CTI/PI: got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:41.235: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:41.235: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.235: //CTI/SM: to return 32434E40 Nov 14 09:45:41.235: //CTI/SM: got CTI_EV_ACK, callID 145309 Nov 14 09:45:41.235: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Sent: INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBE12AC From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 Date: Mon, 14 Nov 2011 09:45:06 GMT Call-ID: 13212639154633@10.10.2.2 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1321263941 CSeq: 107 INFO Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required Content-Length: 452
<?xml version="1.0" encoding="UTF-8"?> <ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>0000054D</monitorCrossRefID> <droppedConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></droppedConnection> <releasingDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></releasingDevice> <cause>normalClearing</cause></ConnectionClearedEvent> Nov 14 09:45:41.235: //145309/2AB066E9959C/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 Ok Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK2DBE12AC To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds51e5444 From: <sip:4922429236541060@10.40.0.13>;tag=6184E5A0-8EF Call-ID: 13212639154633@10.10.2.2 CSeq: 107 INFO Content-Length: 0 Content-Type: application/csta+xml
Nov 14 09:45:41.235: //145309/2AB066E9959C/CCAPI/cc_api_call_info_ack: Info Digits=, Info Complete=FALSE, Interface=0x3133A8C8, Data Bitmask=0x0, Call Id=145309 Nov 14 09:45:41.239: //CTI/PI:cti_frontend_proc [2379D]: received CC Event [20]: CC_EV_CALL_INFO_ACK Nov 14 09:45:41.239: //CTI/PI:pi_process_service_event event 20 Nov 14 09:45:41.239: //CTI/PI: qs-cucme01# got CC_EV_CALL_INFO_ACK callID 145309 Nov 14 09:45:41.239: //CTI/SM:sm_handle_cc_service event 81 Nov 14 09:45:41.239: //CTI/SM:sm_find_scb_node_by_context context_id 145309 Nov 14 09:45:41.239: //CTI/SM: to return 32434E40 Nov 14 09:45:41.239: //CTI/SM: got CTI_EV_ACK, callID 145309
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 14-11-2011 04:08:07 AM Hi Konstantin,
could you please send us the logs for both directions by enabling following debugs.
debug ccsip message debug cti all
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 14-11-2011 07:19:07 AM Hi Konstantin,
thanks for sending the logs we will analyze the logs and get back to you.
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 15-11-2011 04:11:16 AM Hi Konstantin,
from the logs found that called number is associated with more than one device, could you please let us know which device you registerd with application for monitor.
please send us the running config and also let us know what protocol is used If it is incoming from PSTN ?
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 15-11-2011 08:57:37 AM Hi Raghavendra,
the monitored device MAC is 0014F29CD919 (directory number 4922429236541060). The protocol is H.323.
Here is our running config:
Current configuration : 18625 bytes ! ! No configuration change since last restart version 15.1 service timestamps debug datetime msec service timestamps log datetime msec service password-encryption ! hostname qs-cucme01 ! boot-start-marker boot-end-marker ! ! logging buffered 51200 warnings ! no aaa new-model ! no ipv6 cef ip source-route ip cef ! ! ! ! ! no ip domain lookup ip domain name ipfuie.local multilink bundle-name authenticated ! ! ! ! ! crypto pki token default removal timeout 0 ! crypto pki trustpoint TP-self-signed-4151246049 enrollment selfsigned subject-name cn=IOS-Self-Signed-Certificate-4151246049 revocation-check none rsakeypair TP-self-signed-4151246049 ! ! crypto pki certificate chain TP-self-signed-4151246049 certificate self-signed 01 3082024F 308201B8 A0030201 02020101 300D0609 2A864886 F70D0101 04050030 31312F30 2D060355 04031326 494F532D 53656C66 2D536967 6E65642D 43657274 69666963 6174652D 34313531 32343630 3439301E 170D3131 30363036 31393232 32395A17 0D323030 31303130 30303030 305A3031 312F302D 06035504 03132649 4F532D53 656C662D 5369676E 65642D43 65727469 66696361 74652D34 31353132 34363034 3930819F 300D0609 2A864886 F70D0101 01050003 818D0030 81890281 8100C608 FA755DFD 49FDA3D7 F887682B 4BEE5287 B1EB4645 10A4EDED C3EB048C 04C243D6 A2963B39 B55B4FBD 1868B7A4 C8ADE245 E1779132 FEBB5664 87819CE6 C179F0D1 28B88354 A6A3EF5D 0DFFD3D4 A2D971E3 F8538743 DDA6C6D4 73E1DE3F 595A6A6C C4BC95D0 A8BFAA0D 3C7A7F4C B042CFB4 1C1502FD 4E249E24 19BA41CD 64130203 010001A3 77307530 0F060355 1D130101 FF040530 030101FF 30220603 551D1104 1B301982 17796F75 726E616D 652E796F 7572646F 6D61696E 2E636F6D 301F0603 551D2304 18301680 1400FF34 A13F6810 6BCC984B C03DF144 07DB8131 F7301D06 03551D0E 04160414 00FF34A1 3F68106B CC984BC0 3DF14407 DB8131F7 300D0609 2A864886 F70D0101 04050003 81810089 C3449DA4 2AFB90B9 5384D798 DB171B7B 323EDD50 7485E4AD 1BAC0194 AD24B221 E4220A95 4F94F832 6DD75564 15786446 B3F4C3A7 21F83CDD 9716B952 51BF5042 D30EA5B7 30A69097 25F8C3BD 2F920EAE 01D1E4D3 BD68B046 1433FDBD 4744B508 8261597E 8599128B 23192391 2F23C431 0D556BE6 D5FE324A 0948E424 064526 quit voice-card 0 dsp services dspfarm ! ! ! voice service voip no cti shutdown mode border-element allow-connections h323 to h323 allow-connections h323 to sip allow-connections sip to h323 allow-connections sip to sip no supplementary-service h225-notify cid-update redirect ip2ip fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none h323 h225 signal overlap call preserve sip bind control source-interface GigabitEthernet0/0 bind media source-interface GigabitEthernet0/0 rel1xx disable header-passing registrar server expires max 120 min 60 bearer-capability clear-channel udi listen-port non-secure 5062 no call service stop ! voice class codec 1 codec preference 1 g711ulaw codec preference 2 g711alaw ! voice class h323 1 h225 timeout tcp establish 3 call preserve ! ! voice register global mode cme source-address 10.40.0.13 port 5060 max-dn 25 max-pool 10 authenticate register authenticate realm ICS date-format D/M/Y tftp-path flash: logo ICS_QS_LAB file text create profile sync 0006040515407149 ntp-server 10.100.0.1 mode directedbroadcast ! voice register session-server 1 keepalive 60 register-id kc_test cti-aware ! voice register dn 1 number 4922429236543000 label +4922429236543000 ! voice register dn 2 number 4922429236543001 label +4922429236543001 ! voice register dn 3 number 4922429236543002 label +4922429236543002 ! voice register dn 4 number 4922429236543003 label +4922429236543003 ! voice register dn 5 number 4922429236543004 label +4922429236543004 ! voice register pool 1 id mac 0000.0000.3000 number 1 dn 1 dtmf-relay rtp-nte username 4922429236543000 password 4922429236543000 codec g711ulaw ! voice register pool 2 id mac 0000.0000.3001 number 1 dn 2 dtmf-relay rtp-nte username 4922429236543001 password 4922429236543001 codec g711ulaw ! voice register pool 3 id mac 0018.19C5.19AE number 1 dn 3 dtmf-relay rtp-nte username 4922429236543002 password 4922429236543002 codec g711ulaw ! voice register pool 4 id mac 0000.0000.3003 number 1 dn 4 dtmf-relay rtp-nte username 4922429236543003 password 4922429236543003 codec g711ulaw ! voice register pool 5 id mac 0000.0000.3004 number 1 dn 5 dtmf-relay rtp-nte username 4922429236543004 password 4922429236543004 codec g711ulaw ! ! ! voice translation-rule 1 rule 1 /^.00/ // type any international plan any isdn rule 2 /^.0/ // type any national plan any isdn rule 3 /^./ // type any subscriber plan any isdn ! voice translation-rule 4 rule 1 /^/ /00/ type national national rule 2 /^/ /000/ type international international rule 3 /^/ /0/ type subscriber subscriber ! ! voice translation-profile PSTNin translate calling 4 ! voice translation-profile PSTNout translate called 1 ! ! license udi pid CISCO2901/K9 sn FCZ152490WG license accept end user agreement license boot module c2900 technology-package uck9 hw-module pvdm 0/0 ! ! ! ! redundancy ! ! ip ssh version 2 ! ! ! ! interface Loopback0 ip address 10.1.2.3 255.255.255.0 ! interface Embedded-Service-Engine0/0 no ip address shutdown ! interface GigabitEthernet0/0 description $ETH-LAN$$ETH-SW-LAUNCH$$INTF-INFO-GE 0/0$ ip address 10.40.0.13 255.255.0.0 duplex auto speed auto h323-gateway voip bind srcaddr 10.40.0.13 ! interface GigabitEthernet0/1 no ip address shutdown duplex auto speed auto ! ip forward-protocol nd ! ip http server ip http access-class 23 ip http authentication local ip http secure-server ip http timeout-policy idle 60 life 86400 requests 10000 ! ip route 0.0.0.0 0.0.0.0 10.40.0.1 ! ! ixi transport http response size 64 no shutdown request outstanding 1 request timeout 60 ! ixi application cme no shutdown ! ! snmp-server community public RO snmp-server enable traps snmp authentication linkdown linkup coldstart warmstart snmp-server enable traps vrrp snmp-server enable traps transceiver all snmp-server enable traps ds1 snmp-server enable traps call-home message-send-fail server-fail snmp-server enable traps tty snmp-server enable traps eigrp snmp-server enable traps ospf state-change snmp-server enable traps ospf errors snmp-server enable traps ospf retransmit snmp-server enable traps ospf lsa snmp-server enable traps ospf cisco-specific state-change nssa-trans-change snmp-server enable traps ospf cisco-specific state-change shamlink interface snmp-server enable traps ospf cisco-specific state-change shamlink neighbor snmp-server enable traps ospf cisco-specific errors snmp-server enable traps ospf cisco-specific retransmit snmp-server enable traps ospf cisco-specific lsa snmp-server enable traps gatekeeper snmp-server enable traps xgcp snmp-server enable traps license snmp-server enable traps envmon snmp-server enable traps ethernet cfm cc mep-up mep-down cross-connect loop config snmp-server enable traps ethernet cfm crosscheck mep-missing mep-unknown service-up snmp-server enable traps flash insertion removal snmp-server enable traps c3g snmp-server enable traps ds3 snmp-server enable traps adslline snmp-server enable traps vdsl2line snmp-server enable traps icsudsu snmp-server enable traps isdn call-information snmp-server enable traps isdn layer2 snmp-server enable traps isdn chan-not-avail snmp-server enable traps isdn ietf snmp-server enable traps ds0-busyout snmp-server enable traps ds1-loopback snmp-server enable traps energywise snmp-server enable traps vstack snmp-server enable traps mac-notification snmp-server enable traps bgp snmp-server enable traps isis snmp-server enable traps rf snmp-server enable traps aaa_server snmp-server enable traps atm subif snmp-server enable traps cef resource-failure peer-state-change peer-fib-state-change inconsistency snmp-server enable traps memory bufferpeak snmp-server enable traps cnpd snmp-server enable traps config-copy snmp-server enable traps config snmp-server enable traps config-ctid snmp-server enable traps entity snmp-server enable traps fru-ctrl snmp-server enable traps resource-policy snmp-server enable traps event-manager snmp-server enable traps frame-relay multilink bundle-mismatch snmp-server enable traps frame-relay snmp-server enable traps frame-relay subif snmp-server enable traps hsrp snmp-server enable traps ipmulticast snmp-server enable traps msdp snmp-server enable traps mvpn snmp-server enable traps nhrp nhs snmp-server enable traps nhrp nhc snmp-server enable traps nhrp nhp snmp-server enable traps nhrp quota-exceeded snmp-server enable traps pim neighbor-change rp-mapping-change invalid-pim-message snmp-server enable traps pppoe snmp-server enable traps cpu threshold snmp-server enable traps rsvp snmp-server enable traps syslog snmp-server enable traps l2tun session snmp-server enable traps l2tun pseudowire status snmp-server enable traps vtp snmp-server enable traps ipsla snmp-server enable traps dial snmp-server enable traps dsp card-status snmp-server enable traps dsp oper-state snmp-server enable traps vrfmib vrf-up vrf-down vnet-trunk-up vnet-trunk-down snmp-server enable traps ccme snmp-server enable traps srst snmp-server enable traps voice snmp-server enable traps dnis snmp-server host 10.10.1.1 version 2c public snmp-server host 10.40.0.13 version 2c public snmp-server host 172.23.22.135 version 2c public tftp-server flash:SIP001819C519AE.cnf tftp-server flash:c2900-universalk9-mz.SPA.151-4.M1.bin ! control-plane ! ! ccm-manager music-on-hold ! ! mgcp profile default ! sccp local GigabitEthernet0/0 sccp ccm 10.40.0.12 identifier 1 version 7.0 sccp ccm 10.40.0.11 identifier 2 version 7.0 sccp ! sccp ccm group 1 associate ccm 1 priority 1 associate ccm 2 priority 2 associate profile 2 register HWSWmtpCUCME01 associate profile 1 register xcode01 ! dspfarm profile 1 transcode codec g729abr8 codec g729ar8 codec g711alaw codec g711ulaw maximum sessions 10 associate application SCCP shutdown ! dspfarm profile 2 mtp codec g711ulaw maximum sessions software 500 associate application SCCP ! dial-peer voice 1000 pots incoming called-number . direct-inward-dial ! dial-peer voice 1001 voip incoming called-number . voice-class codec 1 dtmf-relay h245-alphanumeric no vad ! dial-peer voice 1 voip description cucm1 destination-pattern 492242923654....$ progress_ind setup enable 3 session target ipv4:10.40.0.11 voice-class codec 1 voice-class h323 1 dtmf-relay h245-alphanumeric no vad ! dial-peer voice 2 voip description cucm2 preference 1 destination-pattern 492242923654....$ progress_ind setup enable 3 session target ipv4:10.40.0.12 voice-class codec 1 voice-class h323 1 dtmf-relay h245-alphanumeric no vad ! dial-peer voice 100 voip description DB 3-S Service Rufnummern destination-pattern 49224292365410..$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.11 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 101 voip description DB 3-S Service Rufnummern preference 1 destination-pattern 49224292365410..$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.12 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 6550 voip description *** SIP Trunk Notebook KC *** destination-pattern 492242923654655.$ session protocol sipv2 session target ipv4:10.10.2.2 dtmf-relay rtp-nte codec g711ulaw no vad ! dial-peer voice 4000 voip description SIP Trunk to CS2SaP huntstop destination-pattern 492242923654550.$ session protocol sipv2 session target ipv4:10.40.0.91:5062 session transport udp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 3 voip destination-pattern 0T progress_ind setup enable 3 session target ipv4:172.23.70.18 voice-class codec 1 voice-class h323 1 dtmf-relay h245-alphanumeric no vad ! dial-peer voice 70051 voip description SIP Test KC destination-pattern 4922429236547005$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.11 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 70052 voip description SIP Test KC preference 1 destination-pattern 4922429236547005$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.12 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 47551 voip description MiL Test destination-pattern 49224292365447..$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.11 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! dial-peer voice 47552 voip description MiL Test preference 1 destination-pattern 4922429236547..$ progress_ind setup enable 3 session protocol sipv2 session target ipv4:10.40.0.12 session transport tcp voice-class codec 1 dtmf-relay rtp-nte no vad ! ! num-exp 1... 4922429236541... num-exp 9... 4922429236549... num-exp 6... 4922429236546... num-exp 3... 4922429236543... num-exp 7... 4922429236547... num-exp 41..$ 4922429236544... presence presence call-list watcher all ! gateway timer receive-rtp 1200 ! sip-ua ! ! ! gatekeeper shutdown ! ! telephony-service no auto-reg-ephone xml user kc password XXXXX 15 max-ephones 25 max-dn 50 preference 10 no-reg ip source-address 10.40.0.13 port 2000 system message DB3S BHF Gw aktiv url authentication http://10.40.0.13/CCMCIP/authenticate.asp time-zone 23 time-format 24 date-format dd-mm-yy keepalive 60 max-conferences 8 gain -6 call-forward pattern .T moh music-on-hold.au multicast moh 239.1.1.1 port 16384 route 10.40.0.13 10.1.2.3 dn-webedit transfer-system full-consult transfer-pattern .T secondary-dialtone 0 fac custom ephone-hunt join #2 fac custom ephone-hunt cancel #3 create cnf-files version-stamp 7960 Oct 26 2011 11:13:01 ! ! ephone-template 1 softkeys remote-in-use Newcall CBarge softkeys hold Resume Newcall softkeys idle Redial Newcall HLog Gpickup Cfwdall Dnd softkeys seized Endcall Redial Cfwdall softkeys alerting Endcall Callback softkeys connected Endcall Hold Park HLog Trnsfer TrnsfVM Confrn ConfList LiveRcd softkeys ringing Answer Dnd HLog busy-trigger-per-button 7 ! ! ephone-dn 1 octo-line number 4922429236549876 no-reg primary label Mirco Linden description +4922429236549876 name Mirco Linden preference 10 allow watch ephone-hunt login ! ! ephone-dn 2 octo-line number 4922429236549878 no-reg primary label Mirco Linden description +4922429236549878 name Mirco Linden preference 10 allow watch ephone-hunt login ! ! ephone-dn 3 octo-line number 4922429236549875 no-reg primary label Jens Hennes description +4922429236549875 name Jens Hennes preference 10 allow watch ephone-hunt login ! ! ephone-dn 4 octo-line number 4922429236549874 no-reg primary label Jens Hennes description +4922429236549874 name Jens Hennes preference 10 allow watch ephone-hunt login ! ! ephone-dn 5 octo-line number 4922429236546500 no-reg primary label Konstantin Chrispens description +4922429236546500 name Konstantin Chrispens preference 10 allow watch ephone-hunt login ! ! ephone-dn 7 octo-line number 4922429236541055 no-reg primary label 3-S Service description +4922429236541055 name 3-S Service preference 10 allow watch ephone-hunt login ! ! ephone-dn 8 octo-line number 4922429236541056 no-reg primary label Notruf description +4922429236541056 name Notruf preference 10 allow watch ephone-hunt login ! ! ephone-dn 9 octo-line number 4922429236541057 no-reg primary label Informationsruf description +4922429236541057 name Informationsruf preference 10 allow watch ephone-hunt login ! ! ephone-dn 10 octo-line number 4922429236541058 no-reg primary label Stoerung NRS description +4922429236541058 name Stvrung Notrufsaeule preference 10 allow watch ephone-hunt login ! ! ephone-dn 11 octo-line number 4922429236541060 no-reg primary description +4922429236541060 name KC-Tel 1 cti watch preference 10 allow watch ephone-hunt login ! ! ephone-dn 12 octo-line number 4922429236541061 no-reg primary description +4922429236541061 name KC-Tel 2 cti watch preference 10 allow watch ephone-hunt login ! ! ephone-dn 13 octo-line number 4922429236541062 no-reg primary description +4922429236541062 name KC-Tel 3 cti watch preference 10 allow watch ephone-hunt login ! ! ephone-dn 14 octo-line number 4922429236541063 no-reg primary description +4922429236541063 name KC-Tel 4 cti watch preference 10 allow watch ephone-hunt login ! ! ephone-dn 49 number 8880 no-reg primary park-slot timeout 30 limit 5 preference 10 ! ! ephone-dn 50 number 8881 no-reg primary park-slot timeout 30 limit 5 preference 10 ! ! ephone 1 mac-address 0027.13B4.3E65 ephone-template 1 username "mil1" password cisco type CIPC button 1:1 2:7 3:8 4:9 button 5:10 pin 12345 ! ! ! ephone 2 mac-address 0817.3514.D84D ephone-template 1 username "mil2" password cisco type 7962 button 1:2 2:7 3:8 4:9 button 5:10 pin 12345 ! ! ! ephone 3 mac-address 1CDF.0F45.F7E6 ephone-template 1 max-calls-per-button 2 type 6941 button 1:3 2:7 3:8 4:9 button 5:10 pin 12345 ! ! ! ephone 4 mac-address 0011.21D9.B376 ephone-template 1 type 7960 button 1:4 2:7 3:8 4:9 button 5:10 pin 12345 ! ! ! ephone 5 mac-address 0025.8418.F4DD ephone-template 1 type 7942 button 1:5 pin 12345 ! ! ! ephone 6 mac-address 0014.F29C.D919 ephone-template 1 type 7975 button 1:11 pin 12345 ! ! ! ephone 7 mac-address 0012.D91F.9795 ephone-template 1 type 7975 button 1:12 pin 12345 ! ! ! ephone 8 mac-address 001D.A290.6088 ephone-template 1 type 7961 button 1:13 pin 12345 ! ! ! ephone 9 mac-address 0025.84A1.F7AB ephone-template 1 type 7942 button 1:14 pin 12345 ! ! ! ! line con 0 exec-timeout 0 0 logging synchronous login local line aux 0 line 2 no activation-character no exec transport preferred none transport input all transport output pad telnet rlogin lapb-ta mop udptn v120 ssh stopbits 1 line vty 0 4 exec-timeout 0 0 privilege level 15 logging synchronous login local transport input ssh line vty 5 15 exec-timeout 0 0 privilege level 15 logging synchronous login local transport input ssh ! scheduler allocate 20000 1000 ntp server 172.23.70.18 end
Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 16-11-2011 12:23:09 AM Hi Konstantin,
when you answered the call ,Is call established successfully without receiving answered event.
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 16-11-2011 02:40:34 AM Hi Raghavendra,
yes the call is established successfully and both parties can talk with each other. But there are no notifications about answering and termination of the call.
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 18-11-2011 04:55:21 AM Hi Raghavendra,
I was not logging to a file before so the time stamps of the log won't correspond to the time of the CUCME log. I hope this is not a problem.
The scenario to produce the log output was the same as before: I placed a call from 2242939783 (PSTN) to 4922429236541060 (internal), answered it and dropped it again.
This is the resulting log file output:
[2011-11-18 10:44:59,291] INFO com.cisco.cme.cti.ProviderManager provider manager:starSipStack. com.cisco.cme.cti.siputil.SipManager@1d6fbb3 [2011-11-18 10:44:59,369] DEBUG com.cisco.cme.cti.CMEProvider provider init [2011-11-18 10:45:02,786] DEBUG com.cisco.cme.cti.CMEProvider CME_Provider-Provider try to dispatch event CME_Provider-Provider-INITIATING-Event to [-Terminal-, Mac=null)]
[2011-11-18 10:45:02,786] DEBUG com.cisco.cme.cti.CMEProvider CME_Provider-Provider try to dispatch event CME_Provider-Provider-INITIATING-Event to de.conet.ics.cti.impl.cucme.jtapi.CmeListener@19cc014 [2011-11-18 10:45:03,083] DEBUG com.cisco.cme.cti.siputil.DsSipRegistrationInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~1 To: <sip:kc_test@10.40.0.13>;tag=761E49AC-1614 From: <sip:kc_test@10.40.0.13>;tag=dsd1295813 Call-ID: 13216095029271@10.40.0.13 CSeq: 2 REGISTER Content-Length: 0 Date: Fri, 18 Nov 2011 09:45:02 GMT Server: Cisco-SIPGateway/IOS-12.x X-cisco-referenceID: 213E83F5 Contact: <sip:kc_test@10.10.2.2:5070;transport=tcp>;expires=60 Expires: 60
[2011-11-18 10:45:03,083] DEBUG com.cisco.cme.cti.CMEProvider CME_Provider-Provider try to dispatch event CME_Provider-Provider-IN_SERVICE-Event to [-Terminal-, Mac=null)]
[2011-11-18 10:45:03,083] INFO com.cisco.cme.cti.CMEAbstractTerminal Provider is in service, initing [2011-11-18 10:45:03,083] DEBUG com.cisco.cme.cti.CMEProvider CME_Provider-Provider try to dispatch event CME_Provider-Provider-IN_SERVICE-Event to de.conet.ics.cti.impl.cucme.jtapi.CmeListener@19cc014 [2011-11-18 10:45:03,661] INFO com.cisco.cme.xml.sdk.CMEXmlHttp <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <SOAP-ENV:Envelope xmlns="cisco_cme_xml_namespace"> <Body> <axl> <request> <ISgetDevice> <ISKeyword>all</ISKeyword> </ISgetDevice> </request> </axl> </Body> </SOAP-ENV:Envelope>
[2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.CMECSTAAbstractSession lastRequest after: null [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.CMECSTAAbstractSession System status is normal, request GetFeature [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- final for INFO ------------------ [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~5 To: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E From: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb Call-ID: 13216095039893@10.10.2.2 CSeq: 3 INFO Content-Length: 1163 Date: Fri, 18 Nov 2011 09:45:03 GMT Server: Cisco-SIPGateway/IOS-12.x Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <GetCSTAFeaturesResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <supportedServices> <systemStatServList> <requestSystemStatus/> <systemStatus/></systemStatServList> <monitoringServList> <monitorStart/> <monitorStop/></monitoringServList> <snapshotServList> <snapshotDevice/></snapshotServList> <callControlServList> <answerCall/> <clearConnection/> <consultationCall/> <deflectCall/> <directedPickupCall/> <holdCall/> <makeCall/> <parkCall/> <retrieveCall/> <singleStepTransfer/> <transferCall/></callControlServList> <logicalServList> <getDoNotDisturb/> <getForwarding/> <setDoNotDisturb/> <setForwarding/></logicalServList></supportedServices> <supportedEvents> <callControlEvtsList> <connectionCleared/> <delivered/> <diverted/> <established/> <failed/> <held/> <originated/> <queued/> <retrieved/> <transferred/></callControlEvtsList> <callAssociatedEvtsList> <serviceCompletionFailure/></callAssociatedEvtsList> <mediaEvtsList> <mediaAttached/> <mediaDetached/></mediaEvtsList> <logicalEvtsList> <doNotDisturb/> <forwarding/></logicalEvtsList></supportedEvents></GetCSTAFeaturesResponse> [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of final for INFO--------------- [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:04,020] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory RECEIVED getCSTAFeaturesResponse [2011-11-18 10:45:04,036] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive Response... [2011-11-18 10:45:04,036] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: pendingRequest is: CMERequest: type=CAP_Get_CSTA_FEATUREbody= <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <GetCSTAFeatures xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>
[2011-11-18 10:45:04,036] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: response is : CAP_Get_CSTA_FEATURE [2011-11-18 10:45:04,036] INFO com.cisco.cme.cti.CMECSTAAbstractSession session.setPendingRequest: session=[Term: 0014F29CD919, Addr:4922429236541060] MonRef: null
[2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.CMECSTAAbstractSession lastRequest after: null [2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.CMECSTAAbstractSession System status is normal, request GetFeature [2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- final for INFO ------------------ [2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~9 To: <sip:4922429236541061@10.40.0.13>;tag=761E4D70-4E0 From: CME_SDK <sip:4922429236541061_0012D91F9795@csta-sdk>;tag=ds81115bfb Call-ID: 13216095040364@10.10.2.2 CSeq: 3 INFO Content-Length: 1163 Date: Fri, 18 Nov 2011 09:45:03 GMT Server: Cisco-SIPGateway/IOS-12.x Contact: <sip:4922429236541061@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <GetCSTAFeaturesResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <supportedServices> <systemStatServList> <requestSystemStatus/> <systemStatus/></systemStatServList> <monitoringServList> <monitorStart/> <monitorStop/></monitoringServList> <snapshotServList> <snapshotDevice/></snapshotServList> <callControlServList> <answerCall/> <clearConnection/> <consultationCall/> <deflectCall/> <directedPickupCall/> <holdCall/> <makeCall/> <parkCall/> <retrieveCall/> <singleStepTransfer/> <transferCall/></callControlServList> <logicalServList> <getDoNotDisturb/> <getForwarding/> <setDoNotDisturb/> <setForwarding/></logicalServList></supportedServices> <supportedEvents> <callControlEvtsList> <connectionCleared/> <delivered/> <diverted/> <established/> <failed/> <held/> <originated/> <queued/> <retrieved/> <transferred/></callControlEvtsList> <callAssociatedEvtsList> <serviceCompletionFailure/></callAssociatedEvtsList> <mediaEvtsList> <mediaAttached/> <mediaDetached/></mediaEvtsList> <logicalEvtsList> <doNotDisturb/> <forwarding/></logicalEvtsList></supportedEvents></GetCSTAFeaturesResponse> [2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of final for INFO--------------- [2011-11-18 10:45:04,052] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:04,067] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory RECEIVED getCSTAFeaturesResponse [2011-11-18 10:45:04,067] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive Response... [2011-11-18 10:45:04,067] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: pendingRequest is: CMERequest: type=CAP_Get_CSTA_FEATUREbody= <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <GetCSTAFeatures xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>
[2011-11-18 10:45:04,067] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: response is : CAP_Get_CSTA_FEATURE [2011-11-18 10:45:04,067] INFO com.cisco.cme.cti.CMECSTAAbstractSession session.setPendingRequest: session=[Term: 0012D91F9795, Addr:4922429236541061] MonRef: null
[2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession lastRequest after: null [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession System status is normal, request GetFeature [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- final for INFO ------------------ [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~13 To: <sip:4922429236541062@10.40.0.13>;tag=761E4D90-1385 From: CME_SDK <sip:4922429236541062_001DA2906088@csta-sdk>;tag=ds81115bfb Call-ID: 13216095040675@10.10.2.2 CSeq: 3 INFO Content-Length: 1163 Date: Fri, 18 Nov 2011 09:45:03 GMT Server: Cisco-SIPGateway/IOS-12.x Contact: <sip:4922429236541062@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <GetCSTAFeaturesResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <supportedServices> <systemStatServList> <requestSystemStatus/> <systemStatus/></systemStatServList> <monitoringServList> <monitorStart/> <monitorStop/></monitoringServList> <snapshotServList> <snapshotDevice/></snapshotServList> <callControlServList> <answerCall/> <clearConnection/> <consultationCall/> <deflectCall/> <directedPickupCall/> <holdCall/> <makeCall/> <parkCall/> <retrieveCall/> <singleStepTransfer/> <transferCall/></callControlServList> <logicalServList> <getDoNotDisturb/> <getForwarding/> <setDoNotDisturb/> <setForwarding/></logicalServList></supportedServices> <supportedEvents> <callControlEvtsList> <connectionCleared/> <delivered/> <diverted/> <established/> <failed/> <held/> <originated/> <queued/> <retrieved/> <transferred/></callControlEvtsList> <callAssociatedEvtsList> <serviceCompletionFailure/></callAssociatedEvtsList> <mediaEvtsList> <mediaAttached/> <mediaDetached/></mediaEvtsList> <logicalEvtsList> <doNotDisturb/> <forwarding/></logicalEvtsList></supportedEvents></GetCSTAFeaturesResponse> [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of final for INFO--------------- [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory RECEIVED getCSTAFeaturesResponse [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive Response... [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: pendingRequest is: CMERequest: type=CAP_Get_CSTA_FEATUREbody= <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <GetCSTAFeatures xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>
[2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: response is : CAP_Get_CSTA_FEATURE [2011-11-18 10:45:04,083] INFO com.cisco.cme.cti.CMECSTAAbstractSession session.setPendingRequest: session=[Term: 001DA2906088, Addr:4922429236541062] MonRef: null
[2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession lastRequest after: null [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession System status is normal, request GetFeature [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- final for INFO ------------------ [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~17 To: <sip:4922429236541063@10.40.0.13>;tag=761E4DAC-24C7 From: CME_SDK <sip:4922429236541063_002584A1F7AB@csta-sdk>;tag=ds81115bfb Call-ID: 13216095040986@10.10.2.2 CSeq: 3 INFO Content-Length: 1163 Date: Fri, 18 Nov 2011 09:45:03 GMT Server: Cisco-SIPGateway/IOS-12.x Contact: <sip:4922429236541063@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <GetCSTAFeaturesResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <supportedServices> <systemStatServList> <requestSystemStatus/> <systemStatus/></systemStatServList> <monitoringServList> <monitorStart/> <monitorStop/></monitoringServList> <snapshotServList> <snapshotDevice/></snapshotServList> <callControlServList> <answerCall/> <clearConnection/> <consultationCall/> <deflectCall/> <directedPickupCall/> <holdCall/> <makeCall/> <parkCall/> <retrieveCall/> <singleStepTransfer/> <transferCall/></callControlServList> <logicalServList> <getDoNotDisturb/> <getForwarding/> <setDoNotDisturb/> <setForwarding/></logicalServList></supportedServices> <supportedEvents> <callControlEvtsList> <connectionCleared/> <delivered/> <diverted/> <established/> <failed/> <held/> <originated/> <queued/> <retrieved/> <transferred/></callControlEvtsList> <callAssociatedEvtsList> <serviceCompletionFailure/></callAssociatedEvtsList> <mediaEvtsList> <mediaAttached/> <mediaDetached/></mediaEvtsList> <logicalEvtsList> <doNotDisturb/> <forwarding/></logicalEvtsList></supportedEvents></GetCSTAFeaturesResponse> [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of final for INFO--------------- [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory RECEIVED getCSTAFeaturesResponse [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive Response... [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: pendingRequest is: CMERequest: type=CAP_Get_CSTA_FEATUREbody= <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <GetCSTAFeatures xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>
[2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: response is : CAP_Get_CSTA_FEATURE [2011-11-18 10:45:04,114] INFO com.cisco.cme.cti.CMECSTAAbstractSession session.setPendingRequest: session=[Term: 002584A1F7AB, Addr:4922429236541063] MonRef: null
[2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMEConnectionState CMEConnectionState.setState: state [NULL -> MOUNTED] [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECall ------After------: [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECall [-Call-, GCID=null, state=ACTIVE, CallConns=( Conn[0]=[-Connection-, address=2242939783, type=CALLING, state=MOUNTED)] , Conn[1]=[-Connection-, address=4922429236541060, type=CALLED, state=MOUNTED)] )]
[2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECall ------After End------: [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: topo finished [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECall CMECall.dispatch: Receive notification... [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMECall CMECall.dispatch: Receive CALL_RINGING notification... [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMEConnection CMEConnection.dispatch... [2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMEConnection CMEConnection.displatch: not my event. conn= [-Connection-, address=2242939783, type=CALLING, state=MOUNTED)] event= EVCall=[-Call-, GCID=null, state=ACTIVE, CallConns=( Conn[0]=[-Connection-, address=2242939783, type=CALLING, state=MOUNTED)] , Conn[1]=[-Connection-, address=4922429236541060, type=CALLED, state=MOUNTED)] )] EVConn=[-Connection-, address=4922429236541060, type=CALLED, state=MOUNTED)]
[2011-11-18 10:45:21,029] INFO com.cisco.cme.cti.CMEConnection CMEConnection.dispatch... [2011-11-18 10:45:21,044] INFO com.cisco.cme.cti.CMEConnection CMEConnection:isMyEvent() connEvent.getConnection() equals mine, this is surely my event [2011-11-18 10:45:21,044] INFO com.cisco.cme.cti.CMETerminalConnection CMETerminalConnection.dispatch...[-TC-,state=( IDLE, IDLE ), terminal=0014F29CD919, connection=[-Connection-, address=4922429236541060, type=CALLED, state=MOUNTED)] )]
[2011-11-18 10:45:21,044] INFO com.cisco.cme.cti.CMETerminalConnection CMETerminalConnection.displatch: -------CMETerminalConnection.FSM Starts------- [2011-11-18 10:45:21,044] INFO com.cisco.cme.cti.CMETerminalConnectionState CMETerminalConnectionState.idle_CallConnectionRinging... [2011-11-18 10:45:21,044] INFO com.cisco.cme.cti.CMETerminalConnectionState CMETerminalConnectionState.setState: ccState [IDLE -> CC_RINGING] [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMETerminalConnectionState CMETerminalConnectionState.setState: state [IDLE -> RINGING] [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMETerminalConnection CMETerminalConnection.displatch: -------CMETerminalConnection.FSM Ends-------- [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnection CMEConnection.displatch: -------CMEConnection.FSM------- [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnectionState <Conn_FSM_Start>: [-Connection-, address=4922429236541060, type=CALLED, state=MOUNTED)]
[2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnectionState Conn FSM: mountedHandler [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnectionState Conn FSM: mounted_CallConnectionRinging [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnectionState CMEConnectionState.setState: state [MOUNTED -> RINGING] [2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnectionState </Conn_FSM_Start> : [-Connection-, address=4922429236541060, type=CALLED, state=RINGING)]
[2011-11-18 10:45:21,060] INFO com.cisco.cme.cti.CMEConnection CMEConnection.displatch: -------CMEConnection.FSM Ends-------- [2011-11-18 10:45:28,159] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:28,159] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:28,159] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35B52269 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 102 INFO Content-Length: 798 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609528 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <EstablishedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <establishedConnection> <deviceID>tel:4922429236541060;phone-context=dialstring;device=0014F29CD919</deviceID></establishedConnection> <answeringDevice> <deviceIdentifier>tel:4922429236541060;phone-context=dialstring;device=0014F29CD919</deviceIdentifier></answeringDevice> <callingDevice> <deviceIdentifier>tel:2242939783</deviceIdentifier></callingDevice> <calledDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></calledDevice> <lastRedirectionDevice> <notRequired/></lastRedirectionDevice> <localConnectionInfo>connected</localConnectionInfo> <cause>normal</cause></EstablishedEvent> [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory com.cisco.cme.cti.jaxbgen.ecma323ed3.LocalDeviceID [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.event.CMECallNotification CallNotification: call is null [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive notification... [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: group=EVENTGROUP_CSTA_CALL_NOTIFICATION [2011-11-18 10:45:28,160] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch[CALL_ANSWERED]: call is null, let's make the topo... [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35B61CF8 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 103 INFO Content-Length: 684 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609528 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <MediaAttachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>receive</flowDirection> <mediaSessionInfo>v=0 o=- 1359 3432 IN IP4 10.10.1.84 s=SCCP Call c=IN IP4 10.10.1.84 m=audio 19576 RTP/AVP 18 </mediaSessionInfo></mediaConnectionInfo></MediaAttachedEvent> [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory EventFactory:createEvent: this is MediaAttachedEvent_ [2011-11-18 10:45:28,316] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: Unrecognized event:null [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35B71FF3 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 104 INFO Content-Length: 685 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609528 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <MediaAttachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>transmit</flowDirection> <mediaSessionInfo>v=0 o=- 1359 3433 IN IP4 10.10.1.84 s=SCCP Call c=IN IP4 10.10.1.84 m=audio 19576 RTP/AVP 18 </mediaSessionInfo></mediaConnectionInfo></MediaAttachedEvent> [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory EventFactory:createEvent: this is MediaAttachedEvent_ [2011-11-18 10:45:28,363] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: Unrecognized event:null [2011-11-18 10:45:31,951] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:31,951] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:31,951] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35B821B7 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 105 INFO Content-Length: 548 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609531 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>receive</flowDirection></mediaConnectionInfo></MediaDetachedEvent> [2011-11-18 10:45:31,952] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:31,952] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:31,952] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory EventFactory:createEvent: this is MediaDetachedEvent_ [2011-11-18 10:45:31,952] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: Unrecognized event:null [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35B9956 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 106 INFO Content-Length: 549 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609531 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <MediaDetachedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <mediaConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></mediaConnection> <mediaDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></mediaDevice> <mediaServiceType>soundCaptureTransmitADPCM</mediaServiceType> <mediaConnectionInfo> <flowDirection>transmit</flowDirection></mediaConnectionInfo></MediaDetachedEvent> [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory EventFactory:createEvent: this is MediaDetachedEvent_ [2011-11-18 10:45:31,968] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: Unrecognized event:null [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl Request received [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl ------------------- request ------------------ [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl INFO sip:4922429236541060_0014F29CD919@10.10.2.2:5070;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.40.0.13:5062;branch=z9hG4bK35BA2030 Max-Forwards: 69 To: CME_SDK <sip:4922429236541060_0014F29CD919@csta-sdk>;tag=ds81115bfb From: <sip:4922429236541060@10.40.0.13>;tag=761E4D3C-1C6E Call-ID: 13216095039893@10.10.2.2 CSeq: 107 INFO Content-Length: 452 Date: Fri, 18 Nov 2011 09:45:03 GMT User-Agent: Cisco-SIPGateway/IOS-12.x Timestamp: 1321609531 Contact: <sip:4922429236541060@10.40.0.13:5062;transport=tcp> Content-Type: application/csta+xml Content-Disposition: signal;handling=required
<?xml version="1.0" encoding="UTF-8"?> <ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> <monitorCrossRefID>00000645</monitorCrossRefID> <droppedConnection> <deviceID>tel:4922429236541060;device=0014F29CD919</deviceID></droppedConnection> <releasingDevice> <deviceIdentifier>tel:4922429236541060;device=0014F29CD919</deviceIdentifier></releasingDevice> <cause>normalClearing</cause></ConnectionClearedEvent> [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.siputil.DsSipInviteDialogNonInviteProcessInterfaceImpl --------------- end of request --------------- [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session: dispatch... [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.cstaxmlutil.EventFactory com.cisco.cme.cti.jaxbgen.ecma323ed3.LocalDeviceID [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.event.CMECallNotification CallNotification: call is null [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: Receive notification... [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.CMECSTAAbstractSession Session.dispatch: group=EVENTGROUP_CSTA_CALL_NOTIFICATION [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.CMECSTAAbstractSession CMECSTAAbstractSession:dispath: notificationEven=CALL_CONNECTION_DISCONNECTED [2011-11-18 10:45:31,984] INFO com.cisco.cme.cti.CMECSTAAbstractSession CMECSTAAbstractSession:dispath[CALL_CONNECTION_DISCONNECTED]: call = null [2011-11-18 10:45:33,091] DEBUG com.cisco.cme.cti.siputil.DsSipRegistrationInterfaceImpl SIP/2.0 200 OK Via: SIP/2.0/TCP 10.10.2.2:5070;branch=z9hG4bKbNgrg7+fG.OAhvJLBPm5NQ~~19 To: <sip:kc_test@10.40.0.13>;tag=761EBEDC-1183 From: <sip:kc_test@10.40.0.13>;tag=dsd1295813 Call-ID: 13216095029271@10.40.0.13 CSeq: 3 REGISTER Content-Length: 0 Date: Fri, 18 Nov 2011 09:45:32 GMT Server: Cisco-SIPGateway/IOS-12.x X-cisco-referenceID: 213E83F5 Contact: <sip:kc_test@10.10.2.2:5070;transport=tcp>;expires=60 Expires: 60
[2011-11-18 10:45:33,091] DEBUG com.cisco.cme.cti.CMEProvider CMEProvider:onRegistrationSucceeded:CiscoCrossReferenceID is :213E83F5 Expires value is:60
Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 18-11-2011 12:37:56 AM Hi Konstantin,
sorry for late reply, i have forwarded your logs to engineer for analysis he is requesting SDK Logs could you please send us the SDK logs(CMESDK.log).
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 21-11-2011 12:48:20 AM Hi Konstantin,
Please add below cli to your CME Config and see if it resolves your issue.
voice service voip gcid
Thanks, Raghavendra
Subject: RE: New Message from Raghavendra Gutty Veeranagappa in UC Express Services Replied by: Hao-Ju Tseng on 21-11-2011 03:06:00 AM Hi,
gcid is not supported on H323 protocol. Adding the CLI will not help the CSTA to generate the gcid with H323. And the CLI is for a different cmm module, not cti cmm module.
Thanks,
Howard
From: Cisco Developer Community Forums [mailto:cdicuser@developer.cisco.com] Sent: Sunday, November 20, 2011 9:48 PM To: cdicuser@developer.cisco.com Subject: New Message from Raghavendra Gutty Veeranagappa in UC Express Services Interface - Technical Questions: RE: Missing notifications for calls originated from PSTN
Raghavendra Gutty Veeranagappa has created a new message in the forum "Technical Questions":
-------------------------------------------------------------- Hi Konstantin,
Please add below cli to your CME Config and see if it resolves your issue.
voice service voip gcid
Thanks, Raghavendra -- To respond to this post, please click the following link:
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 28-11-2011 08:35:23 AM Hi,
sorry for the late reply. I was unfit for work last week.
the suggestion with gcid did not solve the issue.
Have you tried to reproduce this scenario? What else can be the reason?
Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 30-11-2011 04:42:04 AM Hi Raghavendra, find the logs with "gcid" enabled attached. I noticed that there is no global call id (GCID) delivered with the CSTA messages. Could that be the reason? Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 30-11-2011 05:32:04 AM Hi Raghavendra, the new log is attached. Thanks, Konstantin
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 29-11-2011 10:58:21 PM Hi Konstantin,
could you please send us the SDK logs and debug logs.please add the logs as attachements.
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 30-11-2011 05:22:23 AM Hi Konstantin,
please send us the complete logs by enabling following debugs debug ccsip message debug cti all
yes, there is no GCID provided from CME. So SDK created a new call object DelieveredEvent and EstablishedEvent.
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Raghavendra Gutty Veeranagappa on 02-12-2011 05:06:04 AM Hi Konstantin,
I have checked with the developer he is saying "CME will generate gcid for any incoming calls except H323. All outgoing calls will have gcid though. So H323 is not supported in CSTA"
Thanks, Raghavendra
Subject: RE: Missing notifications for calls originated from PSTN Replied by: Konstantin Chrispens on 02-12-2011 08:22:41 AM Hi Raghavendra,
we switched from H323 to SIP and now I can see the notifications. Thank you for the support!
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: