[cisco-voip] SIP trunk dropping calls
Ali El Moussaoui
mousawi.ali at gmail.com
Fri Dec 4 17:45:07 EST 2009
Hello,
We installed an IPIP gateway and am trying one of the SIP legs using CSIM
but i am not able to figure out why calls are failing although all sip
messages seems correct.
The full trace is attacehd i appreciate any help.
Regards,
Ali
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20091205/ceaadd52/attachment.html>
-------------- next part --------------
=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2009.12.05 00:37:08 =~=~=~=~=~=~=~=~=~=~=~=
csim: called number = 66555352361746, loop count = 1 ping count = 0
*Dec 4 22:40:15.923: //11/000000000000/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Dec 4 22:40:15.923: //11/000000000000/SIP/Info/ccsip_call_setup_request:
This is a TDM-IP call: callID= 11, peer_callID = -1
*Dec 4 22:40:15.923: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id B to table
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/act_idle_continue_call_setup:
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 10
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 11, channels 0x0 caps 0x0
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIValidateGtd: No rawMsg from CCAPI
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Dec 4 22:40:15.923: //11/D397A6AB8020/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x642220F8 key=D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 212.98.158.186
*Dec 4 22:40:15.927: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17224 for stream 1
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Dec 4 22:40:15.927: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 204.8.108.60,Port 5060, Transport 1, SentBy Port 5060
*Dec 4 22:40:15.927: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: msg=0x6438F084, addr=204.8.108.60, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x60CA2B54
*Dec 4 22:40:15.927: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x6438F084
*Dec 4 22:40:15.931: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x6438F084, addr=204.8.108.60, port=5060, connId=1 for UDP
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
*Dec 4 22:40:15.931: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 11) to the VOIP RTP library
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 212.98.158.186
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 212.98.158.186, lport = 17224, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
src_callid = 11, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY
media_ip_addr = 0.0.0.0
*Dec 4 22:40:15.931: //11/D397A6AB8020/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
*Dec 4 22:40:15.931: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:66555352361746 at 204.8.108.60:5060 SIP/2.0
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>
Date: Fri, 04 Dec 2009 22:40:15 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Supported: 100rel,timer,replaces
Min-SE: 1800
Cisco-Guid: 3549931179-3764130270-2149621641-636064345
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1259966415
Contact: <sip:212.98.158.186:5060>
Expires: 180
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 420
v=0
o=CiscoSystemsSIP-GW-UserAgent 9687 1285 IN IP4 212.98.158.186
s=SIP Call
c=IN IP4 212.98.158.186
t=0 0
m=audio 17224 RTP/AVP 18 8 0 15 4 101 19
c=IN IP4 212.98.158.186
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:15 G728/8000
a=rtpmap:4 G723/8000
a=fmtp:4 bitrate=6.3;annexa=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:19 CN/8000
*Dec 4 22:40:16.107: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 204.8.108.60:5060
*Dec 4 22:40:16.107: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Dec 4 22:40:16.111: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:37:13 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Timestamp: 1259966415
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Dec 4 22:40:16.111: //11/D397A6AB8020/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*Dec 4 22:40:23.339: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 204.8.108.60:5060
*Dec 4 22:40:23.339: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Dec 4 22:40:23.339: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:37:13 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Timestamp: 1259966415
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Require: 100rel
RSeq: 9651
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Allow-Events: telephone-event
Contact: <sip:66555352361746 at 204.8.108.60:5060>
Content-Disposition: session;handling=required
Content-Type: application/sdp
Content-Length: 283
v=0
o=CiscoSystemsSIP-GW-UserAgent 1997 9057 IN IP4 204.8.108.60
s=SIP Call
c=IN IP4 204.8.108.60
t=0 0
m=audio 30648 RTP/AVP 18 19 101
c=IN IP4 204.8.108.60
a=rtpmap:18 G729/8000
a=rtpmap:19 CN/8000
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
*Dec 4 22:40:23.339: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetGtdBody: No valid GTD body found.
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Media/sipSPISelectCodecVersion: g729br8 flavor of g729 codec will be used
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPIDoAudioNegotiation: Codec (g729br8) Negotiation Successful on Static Payload for m-line 1
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved.
*Dec 4 22:40:23.339: //11/D397A6AB8020/SIP/Info/sipSPIDoDTMFRelayNegotiation: Payload type (101) is reserved for requested dtmf relay mode.
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
*Dec 4 22:40:23.343: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=18, codec_bytes=20, codec=g729br8, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=204.8.108.60, dest_port=30648
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sipSPIDoQoSNegotiation: SDP body with media description
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g729r8, bytes :20
Preferred DTMF relay : rtp-nte
Preferred NTE payload : 101
Early Media : No
Delayed Media : No
Bridge Done : No
New Media : No
DSP DNLD Reqd : No
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 212.98.158.186
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Callid : 11
Negotiated Codec : g729br8, bytes :20
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101
Negotiated CN payload : 19
Media Srce Addr/Port : 212.98.158.186:17224
Media Dest Addr/Port : 204.8.108.60:30648
*Dec 4 22:40:23.343: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/HandleSIP1xxSessionProgress: ccsip_api_call_cut_progress returned: SIP_SUCCESS
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: msg=0x6438F2C4, addr=204.8.108.60, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x60CA3528
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x6438F2C4
*Dec 4 22:40:23.343: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x6438F2C4, addr=204.8.108.60, port=5060, connId=1 for UDP
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/Info/sentPrack: Sent Prack Request, starting PrackTimer
*Dec 4 22:40:23.343: //11/D397A6AB8020/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Dec 4 22:40:23.347: //11/D397A6AB8020/SIP/Info/HandleSIP1xxSessionProgress: Transaction active. Facilities will be queued.
*Dec 4 22:40:23.347: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:66555352361746 at 204.8.108.60:5060 SIP/2.0
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK1510D0
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:40:15 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
CSeq: 102 PRACK
RAck: 9651 101 INVITE
Max-Forwards: 70
Content-Length: 0
*Dec 4 22:40:23.487: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 204.8.108.60:5060
*Dec 4 22:40:23.487: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Dec 4 22:40:23.491: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK1510D0
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:37:20 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 PRACK
Content-Length: 0
*Dec 4 22:40:23.491: //11/D397A6AB8020/SIP/Info/act_recdproc_new_message_response: Transaction Complete. Lock on Facilities released.
*Dec 4 22:40:43.347: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Info/sipSPIValidateGtd: No rawMsg from CCAPI
*Dec 4 22:40:43.347: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIUcccause_to_sipcause: Unknown PSTN cause code from CCAPI:0
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Error/ccsip_set_release_src_and_iec: Release source not rcvd from Sess App
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Transport/sipSPISendCancel: Sending CANCEL to the transport layer
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: msg=0x6438F084, addr=204.8.108.60, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x60CA3B3C
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendM
csim: loop = 1, failed = 1
csim: call attempted = 1, setup failed = 1, tone failed = 0
IPIPGW#essage: Proceedable for sending msg immediately
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x6438F084
*Dec 4 22:40:43.347: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x6438F084, addr=204.8.108.60, port=5060, connId=1 for UDP
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/Info/sentCancelDisconnecting: Sent Cancel Request, starting CancelWaitResponseTimer
*Dec 4 22:40:43.347: //11/D397A6AB8020/SIP/State/sipSPIChangeState: 0x642220F8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Dec 4 22:40:43.347: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
CANCEL sip:66555352361746 at 204.8.108.60:5060 SIP/2.0
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>
Date: Fri, 04 Dec 2009 22:40:15 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
CSeq: 101 CANCEL
Max-Forwards: 70
Timestamp: 1259966443
Content-Length: 0
*Dec 4 22:40:43.527: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 204.8.108.60:5060
*Dec 4 22:40:43.531: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Dec 4 22:40:43.531: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From:
IPIPGW#<sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>
Date: Fri, 04 Dec 2009 22:37:40 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Timestamp: 1259966443
Content-Length: 0
CSeq: 101 CANCEL
*Dec 4 22:40:43.531: //11/D397A6AB8020/SIP/Info/sipSPIIcpifUpdate: CallState: 3 Playout: 0 DiscTime:233160 ConnTime 0
*Dec 4 22:40:43.607: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 204.8.108.60:5060
*Dec 4 22:40:43.607: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Dec 4 22:40:43.607: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:37:40 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Timestamp: 1259966415
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: msg=0x6438F084, addr=204.8.108.60, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x60CA3DC8
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Dec 4 22:40:43.607: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x6438F084, addr=204.8.108.60, port=5060, connId=0 for UDP
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/Info/sentErrorResponseCallClose: Sent Error Response since the Gw is Shutdown
*Dec 4 22:40:43.607: //11/D397A6AB8020/SIP/State
IPIPGW#/sipSPIChangeState: 0x642220F8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Dec 4 22:40:43.611: //11/D397A6AB8020/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x642220F8
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number :
Called Number : 66555352361746
Source IP Address (Sig ): 212.98.158.186
Destn SIP Req Addr:Port : 204.8.108.60:5060
Destn SIP Resp Addr:Port : 204.8.108.60:5060
Destination Name : 204.8.108.60
*Dec 4 22:40:43.611: //11/D397A6AB8020/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g729br8
Negotiated Codec Bytes : 20
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101
Source IP Address (Media): 212.98.158.186
Source IP Port (Media): 17224
Destn IP Address (Media): 204.8.108.60
Destn IP Port (Media): 30648
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Dec 4 22:40:43.611: //11/D397A6AB8020/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 0
Disconnect Cause (SIP) : 487
*Dec 4 22:40:43.611: //11/D397A6AB8020/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id B
*Dec 4 22:40:43.611: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:66555352361746 at 204.8.108.60:5060 SIP/2.0
Via: SIP/2.0/UDP 212.98.158.186:5060;branch=z9hG4bK14203C
From: <sip:212.98.158.186>;tag=232804-1E69
To: <sip:66555352361746 at 204.8.108.60>;tag=FFDB5CD4-1902
Date: Fri, 04 Dec 2009 22:40:15 GMT
Call-ID: D397A6AB-E05C11DE-80229F89-25E99259 at 212.98.158.186
Max-Forwards: 70
CSeq: 101 ACK
Content-Length: 0
More information about the cisco-voip
mailing list