Jul 10 17:21:30.774: Received: INVITE sip:214020@192.168.11.120 SIP/2.0 Via: SIP/2.0/UDP 192.168.11.124:5060;branch=z9hG4bK138ca844;rport From: "214058" ;tag=as0bd73b6f To: Contact: Call-ID: 1673992d7afe5f6722bb045b453be9cd@192.168.11.124 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Tue, 10 Jul 2007 17:21:32 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 356 v=0 o=root 8154 8154 IN IP4 192.168.11.124 s=session c=IN IP4 192.168.11.124 t=0 0 m=audio 11250 RTP/AVP 8 111 112 0 3 101 a=rtpmap:8 PCMA/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv Jul 10 17:21:30.774: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 192.168.11.124:5060 Jul 10 17:21:30.778: CCSIP-SPI-CONTROL: sipSPISipIncomingMsg Jul 10 17:21:30.778: 0x2BD8938 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) Jul 10 17:21:30.778: CCSIP-SPI-CONTROL: act_idle_new_message Jul 10 17:21:30.778: CCSIP-SPI-CONTROL: Clock Time Zone is UTC, same as GMT: Using GMT Jul 10 17:21:30.782: sip_stats_method Jul 10 17:21:30.782: CCSIP-SPI-CONTROL: sact_idle_new_message_invite Jul 10 17:21:30.782: CCSIP-SPI-CONTROL: sipSPIUASSessionTimer Jul 10 17:21:30.786: ****Adding to UAS Request table. ccb=0x02BD8938 key=1673992d7afe5f6722bb045b453be9cd@192.168.11.124214020 Jul 10 17:21:30.786: CCSIP-SPI-CONTROL: sipSPIMatchSrcIpGroup Jul 10 17:21:30.786: CCSIP-SPI-CONTROL: sipSPIMatchSrcIpGroup: Match not found on carrier id Jul 10 17:21:30.786: CCSIP-SPI-CONTROL: sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 214020 Jul 10 17:21:30.786: CCSIP-SPI-CONTROL: sipSPIMatchSrcIpGroup: Match not found on destination pattern: 214058 Jul 10 17:21:30.786: CCSIP-SPI-CONTROL: sipSPIContinueNewMsgInvite Jul 10 17:21:30.786: sipSPIGetGtdBody: No valid GTD body found. Jul 10 17:21:30.786: UpdateSIPQ931Params: Calling Oct3: 0x00 Jul 10 17:21:30.786: UpdateSIPQ931Params: Calling Oct3a: 0x80 Jul 10 17:21:30.786: UpdateSIPQ931Params: Called Oct3: 0x80 Jul 10 17:21:30.786: Received ;screen= ;privacy= -> Setting Octet3A=0x80 Jul 10 17:21:30.790: sipSPIContinueNewMsgInvite:Not Using Voice Class Codec Jul 10 17:21:30.790: sipSPICopyPeerDataToCCB: From CLI: Modem NSE payload = 100, Passthrough = 0,Modem relay = 0, Gw-Xid = 1 SPRT latency 200, SPRT Retries = 12, Dict Size = 1024 String Len = 32, Compress dir = 3 Jul 10 17:21:30.790: sipSPIHandleInviteMedia Jul 10 17:21:30.790: sipSPIDoMediaNegotiation: number of m lines is 1 Jul 10 17:21:30.790: Dynamic Payload :111 in SDP Body Jul 10 17:21:30.790: Dynamic Payload :112 in SDP Body Jul 10 17:21:30.790: Dynamic Payload :101 in SDP Body Jul 10 17:21:30.790: sipSPIDoAudioNegotiation: No matching voice codec found for m-line 1 Jul 10 17:21:30.790: sipSPIDoDTMFRelayNegotiation: m-line index 1 Jul 10 17:21:30.790: sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMF-RELAY option list! Jul 10 17:21:30.794: sipSPIStreamTypeAndDtmfRelay: ERROR - no voice codec and no dtmf-relay match Jul 10 17:21:30.794: sipSPIStreamTypeAndDtmfRelay: DTMF Relay mode : Inband Voice Jul 10 17:21:30.794: sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0 Jul 10 17:21:30.794: sipSPIDoAudioNegotiation: Media negotiation failed for m-line 1 Jul 10 17:21:30.794: sipSPIDoMediaNegotiation: ERROR - no valid fax or audio streams Jul 10 17:21:30.794: sipSPIHandleInviteMedia: Media Negotiation failed for an incoming call - Sending 488 Jul 10 17:21:30.794: CCSIP-SPI-CONTROL: sipRequestError Jul 10 17:21:30.794: CCSIP-SPI-CONTROL: sipSPISendInviteResponse Jul 10 17:21:30.794: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE Jul 10 17:21:30.794: sip_stats_status_code Jul 10 17:21:30.798: ****Deleting from UAS Request table. ccb=0x02BD8938 key=1673992d7afe5f6722bb045b453be9cd@192.168.11.124214020 Jul 10 17:21:30.798: ****Adding to UAS Request table. ccb=0x02BD8938 key=1673992d7afe5f6722bb045b453be9cd@192.168.11.124214020 Jul 10 17:21:30.798: adding call id 75 to table Jul 10 17:21:30.798: 0x2BD8938 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE) Jul 10 17:21:30.798: Sent: SIP/2.0 488 Not Acceptable Media Via: SIP/2.0/UDP 192.168.11.124:5060;branch=z9hG4bK138ca844;rport From: "214058" ;tag=as0bd73b6f To: ;tag=A121A05-C90 Date: Tue, 10 Jul 2007 17:21:30 GMT Call-ID: 1673992d7afe5f6722bb045b453be9cd@192.168.11.124 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 INVITE Allow-Events: telephone-event Content-Length: 0 Jul 10 17:21:30.802: Received: ACK sip:214020@192.168.11.120 SIP/2.0 Via: SIP/2.0/UDP 192.168.11.124:5060;branch=z9hG4bK138ca844;rport From: "214058" ;tag=as0bd73b6f To: ;tag=A121A05-C90 Contact: Call-ID: 1673992d7afe5f6722bb045b453be9cd@192.168.11.124 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 Jul 10 17:21:30.802: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 192.168.11.124:5060 Jul 10 17:21:30.806: Received: BYE sip:214020@192.168.11.120 SIP/2.0 Via: SIP/2.0/UDP 192.168.11.124:5060;branch=z9hG4bK0781cc08;rport From: "214058" ;tag=as0bd73b6f To: ;tag=A121A05-C90 Call-ID: 1673992d7afe5f6722bb045b453be9cd@192.168.11.124 CSeq: 103 BYE User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 Jul 10 17:21:30.806: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 192.168.11.124:5060 Jul 10 17:21:30.806: *****CCB found in UAS Request table. ccb=0x02BD8938 Jul 10 17:21:30.806: CCSIP-SPI-CONTROL: act_disconnecting_new_message Jul 10 17:21:30.810: CCSIP-SPI-CONTROL: sact_disconnecting_new_message_request Jul 10 17:21:30.810: CCSIP-SPI-CONTROL: Clock Time Zone is UTC, same as GMT: Using GMT Jul 10 17:21:30.810: sip_stats_method Jul 10 17:21:30.810: CCSIP-SPI-CONTROL: sipSPICallCleanup Jul 10 17:21:30.810: 0x2BD8938 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE) Jul 10 17:21:30.810: The Call Setup Information is : Call Control Block (CCB) : 0x2BD8938 State of The Call : STATE_DEAD TCP Sockets Used : NO Calling Number : 214058 Called Number : 214020 Number of Media Streams : 1 Jul 10 17:21:30.810: Media Stream 1 Negotiated Codec : g729ar8 Negotiated Codec Bytes : 0 Negotiated Dtmf-relay : 0 Dtmf-relay Payload : 0 Source IP Address (Media): 0.0.0.0 Source IP Port (Media): 0 Destn IP Address (Media): 0.0.0.0 Destn IP Port (Media): 11250 Jul 10 17:21:30.810: Orig Destn IP Address:Port (Media): 0.0.0.0:0 Jul 10 17:21:30.810: Source IP Address (Sig ): 192.168.11.120 Destn SIP Req Addr:Port : 192.168.11.124:0 Destn SIP Resp Addr:Port : 192.168.11.124:5060 Destination Name : 192.168.11.124 Jul 10 17:21:30.814: Disconnect Cause (CC) : 16 Disconnect Cause (SIP) : 200 Jul 10 17:21:30.814: ****Deleting from UAS Request table. ccb=0x02BD8938 key=1673992d7afe5f6722bb045b453be9cd@192.168.11.124214020 Jul 10 17:21:30.814: ****Deleting from UAS Response table. ccb=0x02BD8938 key= Jul 10 17:21:30.814: sipSPIUdeleteCcbFromTable: Entry not found for search key Jul 10 17:21:30.814: Removing call id 75 Jul 10 17:21:30.814: sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd Jul 10 17:21:30.814: freeing ccb 2BD8938 Jul 10 17:21:30.818: CCSIP-SPI-CONTROL: sipSPISipIncomingMsg Jul 10 17:21:30.818: CCSIP-SPI-CONTROL: sipSPISipIncomingMsg : Invalid method for this state (STATE_IDLE): BYE Jul 10 17:21:30.818: CCSIP-SPI-CONTROL: sipSPISendErrorRespNoCCB Jul 10 17:21:30.818: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE Jul 10 17:21:30.818: sip_stats_status_code Jul 10 17:21:30.822: Sent: SIP/2.0 481 Call Leg/Transaction Does Not Exist Via: SIP/2.0/UDP 192.168.11.124:5060;branch=z9hG4bK0781cc08;rport From: "214058" ;tag=as0bd73b6f To: ;tag=A121A05-C90 Call-ID: 1673992d7afe5f6722bb045b453be9cd@192.168.11.124 CSeq: 103 BYE Content-Length: 0