Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: Entry
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIDoStreamQoSNegotiation: Best effort
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16654 for stream 1
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=16654
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 16654
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUpdateSrcSdpVariablePart:
SIP update src sdp, negoitated codec 6, payload type 8 Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Negotiated method of dtmf relayand pyld: 6 101
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 4E72DF4FAEE780002D0D2_62.231.191.7
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_api_call_setup_ind: Headers from INVITE added to callInfo container
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 6,
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIShrlCall: Check peer: 256 for Shared-Line call, callid: 10420
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_set_bearer_capability:
Bearer Capability: Speech (0x00)
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Sep 16 12:31:59.727 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
Sep 16 12:31:59.727 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 28B4 to table
Sep 16 12:31:59.731 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: msg=0x7499DC98, addr=62.231.191.7, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x00000000
Sep 16 12:31:59.731 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Sep 16 12:31:59.731 OMSST: //10420/08B529EDA426/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Sep 16 12:31:59.731 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7499DC98, addr=62.231.191.7, port=5060, connId=0 for UDP
Sep 16 12:31:59.731 OMSST: //10420/08B529EDA426/SIP/State/sipSPIChangeState: 0x6A58F8FC : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)
Sep 16 12:31:59.731 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIProcessContactInfo: Previous Hop 62.231.191.7:5060
Sep 16 12:31:59.731 OMSST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
Sep 16 12:31:59.735 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
Sep 16 12:31:59.735 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 162)
Sep 16 12:31:59.735 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 10421 chans 0x74B7A920 event 162 flags 0x40001C 0x100 0x601 data 0x74B7A920
Sep 16 12:31:59.735 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 10421 chans 0x74B7A920 event 162 flags 0x40001C 0x100 0x601 data 0x74B7A920, type = 1
Sep 16 12:31:59.735 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-TDM
Sep 16 12:31:59.735 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
Sep 16 12:31:59.739 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
Sep 16 12:31:59.743 OMSST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 62.231.191.7:5060;branch=z9hG4bK_a3Kr_1316151119X000307DA
From: "9134212511"<sip:9134212511@62.231.191.7:5060>;tag=1316151119000585BD
To: "920163"<sip:920163@10.251.128.21>
Date: Fri, 16 Sep 2011 05:31:59 GMT
Call-ID: 4E72DF4FAEE780002D0D2_62.231.191.7
CSeq: 100 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Info/sip_gw_video_handle_alert: Video caps are not detected in the caps posted by peer leg
Sep 16 12:31:59.747 OMSST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
Sep 16 12:31:59.747 OMSST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
Sep 16 12:31:59.747 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 5
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Error/sipSPIAddCiscoGcid: Fatal Error in parsing CCB/Msg
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode
Sep 16 12:31:59.747 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIStoreTunnelData: Container /RawMessage Absent
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x6A58F8FC key=4E72DF4FAEE780002D0D2_62.231.191.7D49A0B0-198E
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPISendInviteResponse: Sending 180 Response to the Transport Layer
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: msg=0x7499DC98, addr=62.231.191.7, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x618553BC
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Sep 16 12:31:59.747 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7499DC98, addr=62.231.191.7, port=5060, connId=0 for UDP
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/Info/sentInviteResponse18x: Sent a 18x Response
Sep 16 12:31:59.747 OMSST: //10420/08B529EDA426/SIP/State/sipSPIChangeState: 0x6A58F8FC : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_SENT_ALERTING, SUBSTATE_NONE)
Sep 16 12:31:59.751 OMSST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 62.231.191.7:5060;branch=z9hG4bK_a3Kr_1316151119X000307DA
From: "9134212511"<sip:9134212511@62.231.191.7:5060>;tag=1316151119000585BD
To: "920163"<sip:920163@10.251.128.21>;tag=D49A0B0-198E
Date: Fri, 16 Sep 2011 05:31:59 GMT
Call-ID: 4E72DF4FAEE780002D0D2_62.231.191.7
CSeq: 100 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "Test" <sip:3599@10.251.128.21>;party=called;screen=no;privacy=off
Contact: <sip:920163@10.251.128.21:5060>
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0
Sep 16 12:31:59.751 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
Sep 16 12:32:02.751 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_call_forward:
ccsip_call_forward
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_call_forward: Call forward target num 3354
Sep 16 12:32:02.751 OMSST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_FORWARD
Sep 16 12:32:02.751 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 28
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_spi_create_call_forward_contact_list: Calling peeridb not found
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_spi_create_call_forward_contact_list: Call forward dpeer tag: 257
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_spi_add_redirect_contact: found voip peer, using session target as contact
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_spi_add_redirect_contact: Calling peeridb not found
Sep 16 12:32:02.751 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_spi_create_call_forward_contact_list: Number of redirect contacts added: 1
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Info/act_spi_call_forward:
Performing call forward
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Info/act_spi_call_forward:
Sending 3xx response
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Info/sipSPISendInviteResponse: Associated container=0x6C2689A0 to Invite Response 302
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: msg=0x67E28AD8, addr=62.231.191.7, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x618550E0
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x67E28AD8, addr=62.231.191.7, port=5060, connId=0 for UDP
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Info/sentInviteResponseRedMovedTemp: Sent an 3456XX Error Response
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for incoming call
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[10420], src[6]
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_set_release_source_for_peer: Failed AV set
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG: No Inbound Container Created !!!
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931: No Inbound Container Created !!!
Sep 16 12:32:02.755 OMSST: //10420/08B529EDA426/SIP/State/sipSPIChangeState: 0x6A58F8FC : State change from (STATE_SENT_ALERTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Sep 16 12:32:02.755 OMSST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 302 Moved Temporarily
Via: SIP/2.0/UDP 62.231.191.7:5060;branch=z9hG4bK_a3Kr_1316151119X000307DA
From: "9134212511"<sip:9134212511@62.231.191.7:5060>;tag=1316151119000585BD
To: "920163"<sip:920163@10.251.128.21>;tag=D49A0B0-198E
Date: Fri, 16 Sep 2011 05:31:59 GMT
Call-ID: 4E72DF4FAEE780002D0D2_62.231.191.7
CSeq: 100 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Diversion: ;reason=no-answer;counter=1
Cisco-Gcid: 08B5C615-DF5C-11E0-A429-EE7859B2A178
Contact: <sip:3354@10.1.8.130>
Content-Length: 0
Sep 16 12:32:02.759 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.231.191.7]:5060
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:920163@10.251.128.21 SIP/2.0
Via: SIP/2.0/UDP 62.231.191.7:5060;branch=z9hG4bK_a3Kr_1316151119X000307DA
To: "920163"<sip:920163@10.251.128.21>;tag=D49A0B0-198E
From: "9134212511"<sip:9134212511@62.231.191.7:5060>;tag=1316151119000585BD
Call-ID: 4E72DF4FAEE780002D0D2_62.231.191.7
CSeq: 100 ACK
Max-Forwards: 70
Content-Length: 0
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
Sep 16 12:32:02.759 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x6A58F8FC
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 62.231.191.7,Port 5060, Transport 1, SentBy Port 5060
Sep 16 12:32:02.759 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone OMSST to SIP default timezone = GMT
Sep 16 12:32:02.763 OMSST: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 62.231.191.7,Port 5060, Transport 1, SentBy Port 5060
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:22293208 ConnTime 0
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/State/sipSPIChangeState: 0x6A58F8FC : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x6A58F8FC
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 9134212511
Called Number : 920163
Source IP Address (Sig ): 10.251.128.21
Destn SIP Req Addr:Port : 62.231.191.7:5060
Destn SIP Resp Addr:Port : 62.231.191.7:5060
Destination Name : 62.231.191.7
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 10.251.128.21
Source IP Port (Media): 16654
Destn IP Address (Media): 62.231.191.5
Destn IP Port (Media): 31542
Orig Destn IP Address:Port (Media): [ - ]:0
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 16
Disconnect Cause (SIP) : 302
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 28B4
Sep 16 12:32:02.763 OMSST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[2489] removed.
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x6A58F8FC key=4E72DF4FAEE780002D0D2_62.231.191.7920163
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x6A58F8FC key=4E72DF4FAEE780002D0D2_62.231.191.7D49A0B0-198E
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/ccsip_qos_cleanup: Entry
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Sep 16 12:32:02.763 OMSST: //10420/08B529EDA426/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 6A58F8FC
Почему звонок отбивается?