Jun 19 18:59:38.532: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 77.92.159.36:5060 Jun 19 18:59:38.532: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000 Jun 19 18:59:38.532: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: INVITE sip:2000@88.255.26.145 SIP/2.0 Via: SIP/2.0/UDP 77.92.159.36:5060;branch=z9hG4bK13ae77bc;rport From: "5610" ;tag=as2e58dbae To: Contact: Call-ID: 12aa7b6c60b4f7546dd0f2930de58116@172.16.1.6 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Thu, 19 Jun 2008 18:59:38 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 259 v=0 o=root 2510 2510 IN IP4 172.16.1.6 s=session c=IN IP4 77.92.159.36 t=0 0 m=audio 17902 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv Jun 19 18:59:38.536: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 77.92.159.36,Port 5060, Transport 1, SentBy Port 5060 Jun 19 18:59:38.536: //-1/B351E2868016/SIP/State/sipSPIChangeState: 0x83BBD2EC : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) Jun 19 18:59:38.540: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 77.92.159.36,Port 5060, Transport 1, SentBy Port 5060 Jun 19 18:59:38.540: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone ath to SIP default timezone = GMT Jun 19 18:59:38.540: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 77.92.159.36,Port 5060, Transport 1, SentBy Port 5060 Jun 19 18:59:38.540: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_validate_own_ip_addr: ReqLine IP addr does not match with host IP addr Jun 19 18:59:38.540: //-1/B351E2868016/SIP/Error/sact_idle_new_message_invite: Invalid URL in incoming INVITE Jun 19 18:59:38.544: //-1/B351E2868016/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:100, category:100 Jun 19 18:59:38.544: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[-1], src[6] Jun 19 18:59:38.544: //-1/B351E2868016/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table. Jun 19 18:59:38.544: //-1/B351E2868016/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x83BBD2EC key=12aa7b6c60b4f7546dd0f2930de58116@172.16.1.62000 Jun 19 18:59:38.544: //10/B351E2868016/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id A to table Jun 19 18:59:38.548: //10/B351E2868016/SIP/Transport/sipSPITransportSendMessage: msg=0x83D97000, addr=77.92.159.36, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x80A6BD58 Jun 19 18:59:38.548: //10/B351E2868016/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately Jun 19 18:59:38.548: //10/B351E2868016/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 Jun 19 18:59:38.548: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x83D97000, addr=77.92.159.36, port=5060, connId=0 for UDP Jun 19 18:59:38.548: //10/B351E2868016/SIP/Info/sentErrResDisconnecting: Sent an 3456XX Error Response Jun 19 18:59:38.548: //10/B351E2868016/SIP/State/sipSPIChangeState: 0x83BBD2EC : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE) Jun 19 18:59:38.552: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 400 Bad Request - 'Invalid IP Address' Via: SIP/2.0/UDP 77.92.159.36:5060;branch=z9hG4bK13ae77bc;rport From: "5610" ;tag=as2e58dbae To: ;tag=6D0530-A08 Date: Thu, 19 Jun 2008 18:59:38 GMT Call-ID: 12aa7b6c60b4f7546dd0f2930de58116@172.16.1.6 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 INVITE Allow-Events: telephone-event Content-Length: 0 Jun 19 18:59:38.600: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 77.92.159.36:5060 Jun 19 18:59:38.600: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000 Jun 19 18:59:38.600: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: ACK sip:2000@88.255.26.145 SIP/2.0 Via: SIP/2.0/UDP 77.92.159.36:5060;branch=z9hG4bK13ae77bc;rport From: "5610" ;tag=as2e58dbae To: ;tag=6D0530-A08 Contact: Call-ID: 12aa7b6c60b4f7546dd0f2930de58116@172.16.1.6 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 Jun 19 18:59:38.604: //10/B351E2868016/SIP/Info/sipSPIFindCcbUASReqTable: *****CCB found in UAS Request table. ccb=0x83BBD2EC Jun 19 18:59:38.604: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 77.92.159.36,Port 5060, Transport 1, SentBy Port 5060 Jun 19 18:59:38.604: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone ath to SIP default timezone = GMT Jun 19 18:59:38.608: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 77.92.159.36,Port 5060, Transport 1, SentBy Port 5060 Jun 19 18:59:38.608: //10/B351E2868016/SIP/State/sipSPIChangeState: 0x83BBD2EC : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) Jun 19 18:59:38.608: //10/B351E2868016/SIP/Call/sipSPICallInfo: The Call Setup Information is: Call Control Block (CCB) : 0x83BBD2EC State of The Call : STATE_DEAD TCP Sockets Used : NO Calling Number : Called Number : 2000 Source IP Address (Sig ): 192.168.106.3 Destn SIP Req Addr:Port : 77.92.159.36:0 Destn SIP Resp Addr:Port : 77.92.159.36:5060 Destination Name : 77.92.159.36 Jun 19 18:59:38.608: //10/B351E2868016/SIP/Call/sipSPICallInfo: Disconnect Cause (CC) : 100 Disconnect Cause (SIP) : 200 Jun 19 18:59:38.608: //10/B351E2868016/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id A Jun 19 18:59:38.608: //10/B351E2868016/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table. Jun 19 18:59:38.608: //10/B351E2868016/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x83BBD2EC key=12aa7b6c60b4f7546dd0f2930de58116@172.16.1.62000 Jun 19 18:59:38.608: //10/B351E2868016/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table. Jun 19 18:59:38.612: //10/B351E2868016/SIP/Error/sipSPIUdeleteCcbFromTable: Cannot delete from table. Search key is NULL Jun 19 18:59:38.612: //10/B351E2868016/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd Jun 19 18:59:38.612: //10/B351E2868016/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 83BBD2EC