[cisco-voip] Outbound Dial with Carrier SIP Trunk
Wilusz, Mike
mikewilusz at pricechopper.com
Thu Oct 2 10:31:01 EDT 2008
Hey All,
We're in the process of trialing a SIP trunk solution from our provider,
PAETEC. I followed the Cisco docs for configuring a CUBE, but we can
only receive calls and not place them. The setup is CUCM 6.1(2) -> SIP
Trunk -> CUBE (2811) -> SIP Trunk -> PAETEC SIP Gateway. I'm very new
to SIP and am trying to analyze the output from debug ccsip all.
Nothing is jumping out at me as the exact reason why the calls outbound
from our CUCM aren't working. Anybody have any ideas?
=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2008.10.02 10:21:09
=~=~=~=~=~=~=~=~=~=~=~=
NETEDGE-SIP-CUBE#
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg:
gConnTab=0x476D8634, addr=10.50.120.33, port=44276, connid=1,
transport=TCP
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:18773402555 at 10.50.1.6:5060 SIP/2.0
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-Info:
<sip:10.50.120.33:5060>;method="NOTIFY;Event=telephone-event;Duration=50
0"
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER,
SUBSCRIBE, NOTIFY, PUBLISH
From: "Mike"
<sip:5183551103 at 10.50.120.33>;tag=6ee9a97d-a25a-4e0a-bd4f-5cffc119218f-3
1871160
Allow-Events: presence, kpml
Supported: timer,replaces
Min-SE: 1800
Remote-Party-ID: "Mike"
<sip:5183551103 at 10.50.120.33>;party=calling;screen=yes;privacy=off
Content-Length: 0
User-Agent: Cisco-CUCM6.1
To: <sip:18773402555 at 10.50.1.6>
Contact: <sip:5183551103 at 10.50.120.33:5060;transport=tcp>;video;audio
Expires: 180
Call-ID: 5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Via: SIP/2.0/TCP 10.50.120.33:5060;branch=z9hG4bK1052da2f9139
CSeq: 101 INVITE
Session-Expires: 1800
Max-Forwards: 70
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable:
Added context(0x48413E08) with key=[10] to table
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
Transaction Address 10.50.120.33,Port 44276, Transport 2, SentBy Port
5060
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x48413E08 : State change from (STATE_NONE, SUBSTATE_NONE) to
(STATE_IDLE, SUBSTATE_NONE)
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
Transaction Address 10.50.120.33,Port 44276, Transport 2, SentBy Port
5060
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader:
Converting TimeZone EST to SIP default timezone = GMT
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
Transaction Address 10.50.120.33,Port 44276, Transport 2, SentBy Port
5060
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP
dialpeer (peer=0x46477F4C) found for sip_user: 18773402555
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPISetInfoFromRpid:
Received current remote name: Mike, current remote number: 5183551103
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPISetInfoFromRpid:
Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81,
extended_privacy 0x00
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD
found in inbound container
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToUASReqTable:
****Adding to UAS Request table.
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToTable: Added
to table. ccb=0x48413E08
key=5f97c880-8e41d8de-1e855-2178320a at 10.50.120.3318773402555
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIMatchSrcIpGroup: Match
not found on carrier id
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIMatchSrcIpGroup: Match
not found on Incoming called number: 18773402555
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIMatchSrcIpGroup: Match
not found on destination pattern: 5183551103
Oct 2 10:21:18:
//-1/36ECCAF0801A/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo:
Calling name Mike, number 5183551103, Calling oct3 0x00, oct_3a 0x81,
Called number 18773402555
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig:
Precondition tag absent in Require/Supported header
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: Non
dial peer leg - using RTP Supported Codecs
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 18
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 0
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 8
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 4
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 2
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 15
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: RTP
Preferred Codecs supported by GW 3
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIContinueNewMsgInvite:
Calling name Mike, number 5183551103, Calling oct3 0x00, oct_3a 0x81,
ext_priv 0x00, Called number 18773402555, oct3 0x00
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIContinueNewMsgInvite:
Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE,
next_tgrp NONE
Oct 2 10:21:18: //-1/36ECCAF0801A/SIP/Info/sipSPIRscmsmAvail: Value
returned by check is = 0
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Info/sipSPINegotiateSessionExpires:
Session-Expires value: 1800 refresher: 3
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Info/sipSPINegotiateSessionExpires: Min-SE Header:
1800
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIProcessCallInfoHeader:
Callinfo parsed - Destination url = <sip:10.50.120.33:5060>, duration =
500
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIGetContentSDP: No SDP
found in inbound container
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIDoQoSNegotiation: No
SDP body
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPICanSetFallbackFlag:
Local Fallback is not active
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 9, sdp 0x487D9FAC channels 0x48414ED8
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIUpdateSrcSdpFixedPart:
Delayed media case...creating new stream
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Media/sipSPISetMediaSrcAddr: Media
src addr for stream 1 = 10.50.1.6
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort:
reserved port 18702 for stream 1
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIUpdateSrcSdpFixedPart:
Reserving rtp port for stream 1, src_port=18702
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPISelectDynamicPayload:
Unused Dynamic Payload Mask = FFFFFFFF
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPISelectDynamicPayload:
Selecting Dynamic Payload : 96 for Codec: g726r32
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIAddBillingInfoToCcb:
sipCallId for billing records =
5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_store_channel_info: Store
channelInfo in CallInfo
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_store_channel_info: negotiated
dtmf not available, using dialpeer config dtmf = 0
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD
passed.
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/ccsip_set_bearer_capability:
Bearer Capability: Speech (0x00)
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIContinueNewMsgInvite:
ccsip_api_call_setup_ind returned: SIP_SUCCESS_DELAYED_MEDIA
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIUaddccCallIdToTable:
Adding call id 9 to table
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage:
msg=0x46C34C68, addr=10.50.120.33, port=44276, sentBy_port=5060,
is_req=0, transport=2, switch=0, callBack=0x00000000
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage: Proceedable
for sending msg immediately
Oct 2 10:21:18:
//9/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: switch
transport is 0
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
send for msg=0x46C34C68, addr=10.50.120.33, port=44276, connId=1 for TCP
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x48413E08 : State change from (STATE_IDLE, SUBSTATE_NONE) to
(STATE_RECD_INVITE, SUBSTATE_NONE)
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/sipSPIProcessContactInfo:
Previous Hop 10.50.120.33:5060
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable:
Added context(0x484162C4) with key=[11] to table
Oct 2 10:21:18: //10/000000000000/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_NONE, SUBSTATE_NONE) to
(STATE_IDLE, SUBSTATE_NONE)
Oct 2 10:21:18: //10/000000000000/SIP/Info/ccsip_call_setup_request:
Before processing SETUP REQccb->pld.flags_ipip = 200
Oct 2 10:21:18: //10/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: NULL peer_channel - Delayed Media: peer_channels =
0x0, peer_param = 0x486A8A68, callID= 10, peer_callID = 9
Oct 2 10:21:18: //10/000000000000/SIP/Info/ccsip_gw_set_sipspi_mode:
Setting SPI mode to SIP-SIP
Oct 2 10:21:18: //10/000000000000/SIP/Info/ccsip_call_setup_request:
After processing SETUP REQccb->pld.flags_ipip = 400000
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Info/sipSPIGetOutboundHostAndDestHost: CCSIP:
target_host : 64.206.114.6 target_port : 5060
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
switch(ev.ev_id: 147)
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 10 chans 0x46BDDA90 event 147 flags
0x40201C 0x300 0x201 data 0x46BDDA90
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 10 chans 0x46BDDA90
event 147 flags 0x40201C 0x300 0x201 data 0x46BDDA90, type = 1
Oct 2 10:21:18: //9/36ECCAF0801A/SIP/Info/ccsip_gw_set_sipspi_mode:
Setting SPI mode to SIP-SIP
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
CC_R_SUCCESS_WITH_CONFIRMED
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 3
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 2
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIUaddccCallIdToTable:
Adding call id A to table
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig:
preferred_codec set[0] type :No Codec bytes: 0
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPICanSetFallbackFlag:
Local Fallback is not active
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIGetCallConfig: Not
using Voice Class Codec
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 10, channels 0x0 caps 0x46C3F460
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP:
copy target_host to outbound_host
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIAddMLPPServicesInfo:
No MLP Info available on incoming leg
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Info/act_idle_continue_call_setup:
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIRscmsmAvail: Value
returned by check is = 0
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToUACTable:
****Adding to UAC table.
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToTable: Added
to table. ccb=0x484162C4
key=36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPIUsetBillingProfile:
sipCallId for billing records =
36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog
Transaction Address 64.206.114.6,Port 5060, Transport 1, SentBy Port
5060
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader:
Converting TimeZone EST to SIP default timezone = GMT
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost:
registrar host retrieved
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Event/sipSPICreateRpid: Received
Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/addAllowHeaderKpml: KPML
Subscriptions allowed.
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sipSPISendInvite: Associated
container=0x481D0FA4 to Invite
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Transport/sipSPISendInvite:
Sending Invite to the transport layer
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the
Global configuration, Switch Transport is FALSE
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage:
msg=0x46C33BFC, addr=64.206.114.6, port=5060, sentBy_port=0, is_req=1,
transport=1, switch=0, callBack=0x4159936C
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage: Proceedable
for sending msg immediately
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: switch
transport is 0
Oct 2 10:21:18:
//10/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: Set to send
the msg=0x46C33BFC
Oct 2 10:21:18:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
send for msg=0x46C33BFC, addr=64.206.114.6, port=5060, connId=2 for UDP
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/Info/sentInviteRequest: Sent
Invite in state STATE_IDLE
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest:
Transaction active. Facilities will be queued.
Oct 2 10:21:18: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_IDLE, SUBSTATE_NONE) to
(STATE_SENT_INVITE, SUBSTATE_NONE)
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:18773402555 at 64.206.114.6:5060 SIP/2.0
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
Remote-Party-ID: "Mike"
<sip:5183551103 at 63.138.94.70>;party=calling;screen=yes;privacy=off
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
Cisco-Guid: 921488112-2411991517-2149232624-1770771834
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER,
SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1222957278
Contact: <sip:5183551103 at 63.138.94.70:5060>
Expires: 180
Allow-Events: kpml, telephone-event
Max-Forwards: 69
Content-Length: 0
Oct 2 10:21:18: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.50.120.33:5060;branch=z9hG4bK1052da2f9139
From: "Mike"
<sip:5183551103 at 10.50.120.33>;tag=6ee9a97d-a25a-4e0a-bd4f-5cffc119218f-3
1871160
To: <sip:18773402555 at 10.50.1.6>
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-ID: 5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0
Oct 2 10:21:19: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:19:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:19:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:19: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:19: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Oct 2 10:21:19: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to
(STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD
found in inbound container
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info
header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIDoMediaNegotiation:
Number of m-lines = 1
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIDoAudioNegotiation:
Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIDoPtimeNegotiation:
One ptime attribute found - value:20
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec:
g711ulaw ptime :20, codecbytes: 160
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec:
g711ulaw codecbytes :160, ptime: 20
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPIDoPtimeNegotiation:
Offered ptime:20, Negotiated ptime:20 Negotiated codec bytes: 160 for
codec g711ulaw
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPICheckDynPayloadUse:
Dynamic payload(101) could not be reserved.
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF
relay option
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPIDoDTMFRelayNegotiation: Case of partial
named event(NE) match in fmtp list of events.
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE
payload from X-cap = 0
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sip_select_modem_relay_params: X-tmr not
present in SDP. Disable modem relay
Oct 2 10:21:20:
//10/36ECCAF0801A/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
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIDoAudioNegotiation:
Codec negotiation successful for media line 1
payload_type=0, codec_bytes=160, codec=g711ulaw, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=64.206.114.6,
dest_port=20046
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/State/sipSPIChangeStreamState:
Stream (callid = -1) State changed from (STREAM_DEAD) to
(STREAM_ADDING)
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIDoQoSNegotiation: SDP
body with media description
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g711ulaw, bytes :160
Preferred DTMF relay : rtp-nte
Preferred NTE payload : 101
Early Media : No
Delayed Media : Yes
Bridge Done : No
New Media : No
DSP DNLD Reqd : No
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetMediaSrcAddr:
Media src addr for stream 1 = 63.138.94.70
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 10 peer 9 flags 0x400001
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 10, sdp 0x47A5E0E8 channels 0x48417394
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline 1
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISelectCodecVersion:
Codec (g729r8) is not in preferred list
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact
codec match not configured, using interoperable codec g729r8 pre-ietf
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Selecting codec g729r8 pre-ietf
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Selecting codec g711ulaw
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec:
g711ulaw codecbytes :160, ptime: 20
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media
negotiation done:
stream->negotiated_ptime=20,stream->negotiated_codec_bytes=160, coverted
ptime=20 stream->mline_index=1, media_ndx=1
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 20, bytes 160 as channel 0 mline 1 ss 1
64.206.114.6:20046
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Selecting codec g711alaw
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 98 mline 1
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: setting
ipip_caps DTMF to RFC2833: callid = 10, dtmf = 6
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/ccsip_get_rtcp_session_parameters: CURRENT
VALUES: stream_callid=-1, current_seq_num=0x80B
Oct 2 10:21:20:
//10/36ECCAF0801A/SIP/Info/ccsip_get_rtcp_session_parameters: NEW
VALUES: stream_callid=-1, current_seq_num=0x0
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/ccsip_do_caps_ind: Load DSP
with codec: g711ulaw, Bytes=160
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: 0
Active Streams
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: Number
of active streams is zero (0)!
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=0,caps.stream[0].stream_type=0xFFFF,
caps.stream_list.xmitFunc=
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo:
??unknown??, caps.stream_list.context=
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: 0x0
(gccb)
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPISetDTMFRelayMode: Set
DSP for dtmf-relay = CC_CAP_DTMF_RELAY_NTE_AND_OOB with rx payload =
101, tx payload = 101
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sip_set_modem_caps:
Preferred (or the one that came from DSM) modem relay=0, from CLI
config=0
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sip_set_modem_caps:
Disabling Modem Relay...
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sip_set_modem_caps:
Negotiation already Done. Set negotiated Modem caps and generate SDP
Xcap list
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sip_set_modem_caps: Modem
Relay & Passthru both disabled
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sip_set_modem_caps: nse
payload = 0, ptru mode = 0, ptru-codec=0, redundancy=0, xid=0, relay=0,
sprt-retry=12, latecncy=200, compres-dir=3, dict=1024, strnlen=32
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_caps_ind:
destCallID=9, srcCallID=10,
peer_ccb->call_info.currentLocalName=Mike,
peer_ccb->call_info.currentRemoteName=,
ccb->call_info.currentLocalName=,
ccb->call_info.currentRemoteName=Mike
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_caps_ind: Negotiation
not done yet:: Load DSP with preferred codec : g729r8, Bytes=20,
payload: 18
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_caps_ind: Set forking
flag to 0x0
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_set_modem_caps: Preferred
(or the one that came from DSM) modem relay=0, from CLI config=0
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_set_modem_caps: Disabling
Modem Relay...
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_generate_sdp_xcaps_list:
Modem Relay and T38 disabled. X-cap not needed
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_set_modem_caps:
Negotiation NOT yet Done. Set the configured values and generate SDP
Xcap list
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_set_modem_caps: Modem
Relay & Passthru both disabled
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sip_set_modem_caps: nse
payload = 100, ptru mode = 0, ptru-codec=0, redundancy=0, xid=1,
relay=0, sprt-retry=12, latecncy=200, compres-dir=3, dict=1024,
strnlen=32
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: 0
Active Streams
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: Number
of active streams is zero (0)!
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=0,caps.stream[0].stream_type=0xFFFF,
caps.stream_list.xmitFunc=
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo:
??unknown??, caps.stream_list.context=
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Media/sipSPISetStreamInfo: 0x0
(gccb)
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_caps_ind: Load DSP with
codec : g729r8, Bytes=20, payload = 18
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_caps_ind:
ccsip_caps_ind: ccb->pld.flags_ipip = 0x400003
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/ccsip_caps_ack: Set forking
flag to 0x0
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/copy_channels:
callId 10 size 108 ptr 0x46C33DA8)
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
switch(ev.ev_id: 141)
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 10 chans 0x46C33DA8 event 141 flags
0x40201C 0x300 0x400003 data 0x46C33DA8
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_OPEN_CHANNEL_IND: peer ID 10 chans
0x46C33DA8 event 141 flags 0x40201C 0x300 0x400003 data 0x46C33DA8
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_NEW_MEDIA
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_event_handler:
ccsip_event_handler: set event->type = SIPSPI_EV_CC_NEW_MEDIA!: peer ID
10 chans 0x46C33DA8 event 141 flags 0x40201C 0x300 0x400003 data
0x46C33DA8
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
CC_R_SUCCESS_WITH_CONFIRMED
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Callid : -1
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101 (tx), 101 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : 63.138.94.70:0
Media Dest Addr/Port : 64.206.114.6:20046
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD
passed.
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
ccsip_api_call_cut_progress returned: SIP_SUCCESS
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_RECD_PROCEEDING,
SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING,
SUBSTATE_NONE)
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
to (SIP_STATE_SEND_PRACK, SUBSTATE_NONE)
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 26
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_handle_channel_info:
CCSIP:callID 9 ft: 1, inc 4, 64.206.114.6:20046, codec 5
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 9, channels 0x46C33DA8 caps 0x46C40A74
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: Peer cap
provided: callid = 9, peer dtmf = 6
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
pref dtmf 101
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPIProcessMediaChanges:
sipSPIProcessMediaChanges
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_CALL_PROGRESS
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_bridge: confID = 3,
srcCallID = 9, dstCallID = 10
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPIUupdateCcCallIds: Old
src/dest ccCallids: -1/-1, new src/dest ccCallids: 9/10
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPIUupdateCcCallIds: Old
streamcallid=-1, new streamcallid=9
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_bridge: xcoder_attached
= 0, xmitFunc = 1122887004, ccb xmitFunc = 1122887004
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_bridge: ccsip_bridge:
Delayed Media case
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/ccsip_bridge: confID = 3,
srcCallID = 10, dstCallID = 9
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIUupdateCcCallIds: Old
src/dest ccCallids: -1/-1, new src/dest ccCallids: 10/9
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/sipSPIUupdateCcCallIds: Old
streamcallid=-1, new streamcallid=10
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/ccsip_bridge:
xcoder_attached = 0, xmitFunc = 1122887004, ccb xmitFunc = 1122887004
Oct 2 10:21:20: //10/36ECCAF0801A/SIP/Info/ccsip_bridge: ccsip_bridge:
Delayed Media case
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 4
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToUASRespTable:
****Adding to UAS Response table.
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPIUaddCcbToTable: Added
to table. ccb=0x48413E08
key=5f97c880-8e41d8de-1e855-2178320a at 10.50.120.3320AC10-12BA
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/ccsip_api_update_allowed:
UPDATE(1) allowed
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Event/sipSPICreateRpid: Received
Octet3A=0x00 -> Setting ;screen=no ;privacy=off
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sipSPISendInviteResponse:
Associated container=0x481D0914 to Invite Response 183
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Transport/sipSPISendInviteResponse: Sending 183
Response to the Transport Layer
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage:
msg=0x46C413B4, addr=10.50.120.33, port=44276, sentBy_port=5060,
is_req=0, transport=2, switch=0, callBack=0x41599C74
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage: Proceedable
for sending msg immediately
Oct 2 10:21:20:
//9/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: switch
transport is 0
Oct 2 10:21:20:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
send for msg=0x46C413B4, addr=10.50.120.33, port=44276, connId=1 for TCP
Oct 2 10:21:20: //9/36ECCAF0801A/SIP/Info/sentInviteResponse18x: Sent a
18x Response
Oct 2 10:21:20: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/TCP 10.50.120.33:5060;branch=z9hG4bK1052da2f9139
From: "Mike"
<sip:5183551103 at 10.50.120.33>;tag=6ee9a97d-a25a-4e0a-bd4f-5cffc119218f-3
1871160
To: <sip:18773402555 at 10.50.1.6>;tag=20AC10-12BA
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-ID: 5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER,
SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "Mike"
<sip:18773402555 at 10.50.1.6>;party=called;screen=no;privacy=off
Contact: <sip:18773402555 at 10.50.1.6:5060;transport=tcp>
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 399
v=0
o=CiscoSystemsSIP-GW-UserAgent 9884 8478 IN IP4 10.50.1.6
s=SIP Call
c=IN IP4 10.50.1.6
t=0 0
m=audio 18702 RTP/AVP 18 0 8 4 96 15 3 19
c=IN IP4 10.50.1.6
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=fmtp:4 bitrate=6.3;annexa=no
a=rtpmap:96 G726-32/8000
a=rtpmap:15 G728/8000
a=rtpmap:3 GSM/8000
a=rtpmap:19 CN/8000
Oct 2 10:21:21: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:21:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:21:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:21: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:21: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:21: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:22: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:22:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:22:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:22: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:22: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:22: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:24: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:24:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:24:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:24: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:24: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:24: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:28: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:28:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:28:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:28: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:28: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:28: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:36: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:36:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:36:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:36: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:36: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:36: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:40:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer
initiated for holder=0x487DABDC,addr=10.50.120.33
Oct 2 10:21:51: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:51:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:51:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:51: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:51: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBSCRIBE, NOTIFY,
PRACK, INFO, REFER, UPDATE
Require: 100rel
RSeq: 496112450
Contact: <sip:18773402555 at 64.206.114.6:5060;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 331
v=0
o=- 3431946114 3431946114 IN IP4 64.206.114.6
s=-
c=IN IP4 64.206.114.6
t=0 0
m=audio 20046 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:51: //10/36ECCAF0801A/SIP/Info/HandleSIP1xxSessionProgress:
Dropping 183 - Duplicate or Unexpected
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg
enqueued for SPI with IP addr: 64.206.114.6:5060
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 500 Server Internal Error
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
CSeq: 101 INVITE
Timestamp: 1222957278
Server: DC-SIP/2.0
Supported: timer
Contact: <sip:18773402555 at 10.254.1.7:51104;transport=udp>
Expires: 180
Content-Type: application/sdp
Content-Length: 327
v=0
o=- 3431946114 3431946114 IN IP4 172.25.0.2
s=-
c=IN IP4 172.25.0.2
t=0 0
m=audio 14112 RTP/AVP 18 0 8 98 101
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:98 clearmode/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD
found in inbound container
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Transport/sipSPISendAck: Sending
ACK to the transport layer
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the
Global configuration, Switch Transport is FALSE
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage:
msg=0x487EF9CC, addr=64.206.114.6, port=5060, sentBy_port=5060,
is_req=0, transport=1, switch=0, callBack=0x00000000
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage: Proceedable
for sending msg immediately
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: switch
transport is 0
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
send for msg=0x487EF9CC, addr=64.206.114.6, port=5060, connId=0 for UDP
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Info/act_recdproc_new_message_response: Received a
4/5/6xx message with StatusCode: 500
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer:
ownCallId[10], src[4]
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Info/sipSPIInitiateDisconnect:
Initiate call disconnect(41) for outgoing call
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (SIP_STATE_SEND_PRACK, SUBSTATE_NONE) to
(STATE_DISCONNECTING, SUBSTATE_NONE)
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:18773402555 at 64.206.114.6:5060 SIP/2.0
Via: SIP/2.0/UDP 63.138.94.70:5060;branch=z9hG4bK421B7
From: "Mike" <sip:5183551103 at 64.206.114.6>;tag=20A56C-78A
To: <sip:18773402555 at 64.206.114.6>;tag=127.0.0.15060+1+8dab0005+129460d5
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-ID: 36EFD851-8FC411DD-801FAFF0-698BD57A at 63.138.94.70
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: kpml, telephone-event
Content-Length: 0
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 7
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Info/sipSPIIcpifUpdate:
CallState: 3 Playout: 0 DiscTime:217310 ConnTime 0
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x484162C4 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to
(STATE_DEAD, SUBSTATE_NONE)
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x484162C4
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 5183551103
Called Number : 18773402555
Source IP Address (Sig ): 63.138.94.70
Destn SIP Req Addr:Port : 64.206.114.6:5060
Destn SIP Resp Addr:Port : 64.206.114.6:5060
Destination Name : 64.206.114.6
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 63.138.94.70
Source IP Port (Media): 0
Destn IP Address (Media): 64.206.114.6
Destn IP Port (Media): 20046
Orig Destn IP Address:Port (Media): 0.0.0.0:0
Oct 2 10:21:52: //10/36ECCAF0801A/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 41
Disconnect Cause (SIP) : 500
Oct 2 10:21:52:
//10/36ECCAF0801A/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call
id A
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued
event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 3 for event 7
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/act_recdinvite_disconnect:
Performing disconnect
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPISendInviteResponse:
Associated container=0x481D0770 to Invite Response 503
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage:
msg=0x46C3B15C, addr=10.50.120.33, port=44276, sentBy_port=5060,
is_req=0, transport=2, switch=0, callBack=0x4159981C
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Transport/sipSPITransportSendMessage: Proceedable
for sending msg immediately
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Transport/sipTransportLogicSendMsg: switch
transport is 0
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting
send for msg=0x46C3B15C, addr=10.50.120.33, port=44276, connId=1 for TCP
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sentErrResDisconnecting: Sent
an 3456XX Error Response
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x48413E08 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to
(STATE_DISCONNECTING, SUBSTATE_NONE)
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/TCP 10.50.120.33:5060;branch=z9hG4bK1052da2f9139
From: "Mike"
<sip:5183551103 at 10.50.120.33>;tag=6ee9a97d-a25a-4e0a-bd4f-5cffc119218f-3
1871160
To: <sip:18773402555 at 10.50.1.6>;tag=20AC10-12BA
Date: Thu, 02 Oct 2008 14:21:18 GMT
Call-ID: 5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=41
Content-Length: 0
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event:
ccsip_spi_get_msg_type returned: 2 for event 1
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg:
context=0x00000000
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg:
gConnTab=0x476D8634, addr=10.50.120.33, port=44276, connid=1,
transport=TCP
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:18773402555 at 10.50.1.6:5060 SIP/2.0
Date: Thu, 02 Oct 2008 14:21:18 GMT
From: "Mike"
<sip:5183551103 at 10.50.120.33>;tag=6ee9a97d-a25a-4e0a-bd4f-5cffc119218f-3
1871160
Allow-Events: presence, kpml
Content-Length: 0
To: <sip:18773402555 at 10.50.1.6>;tag=20AC10-12BA
Call-ID: 5f97c880-8e41d8de-1e855-2178320a at 10.50.120.33
Via: SIP/2.0/TCP 10.50.120.33:5060;branch=z9hG4bK1052da2f9139
CSeq: 101 ACK
Max-Forwards: 70
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor:
Checking Invite Dialog
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIFindCcbUASRespTable:
*****CCB found in UAS Response table. ccb=0x48413E08
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
Transaction Address 10.50.120.33,Port 44276, Transport 2, SentBy Port
5060
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader:
Converting TimeZone EST to SIP default timezone = GMT
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq
Transaction Address 10.50.120.33,Port 44276, Transport 2, SentBy Port
5060
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIIcpifUpdate: CallState:
2 Playout: 0 DiscTime:217312 ConnTime 0
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/State/sipSPIChangeState:
0x48413E08 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to
(STATE_DEAD, SUBSTATE_NONE)
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x48413E08
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 5183551103
Called Number : 18773402555
Source IP Address (Sig ): 10.50.1.6
Destn SIP Req Addr:Port : 10.50.120.33:5060
Destn SIP Resp Addr:Port : 10.50.120.33:44276
Destination Name : 10.50.120.33
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : No Codec
Negotiated Codec Bytes : 0
Nego. Codec payload : 255 (tx), 255 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 10.50.1.6
Source IP Port (Media): 18702
Destn IP Address (Media): 0.0.0.0
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): 0.0.0.0:0
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 41
Disconnect Cause (SIP) : 503
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call
id 9
Oct 2 10:21:52:
//-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for
key=[10] removed.
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting
from UAS Request table.
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIUdeleteCcbFromTable:
Deleting from table. ccb=0x48413E08
key=5f97c880-8e41d8de-1e855-2178320a at 10.50.120.3318773402555
Oct 2 10:21:52:
//9/36ECCAF0801A/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting
from UAS Response table.
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIUdeleteCcbFromTable:
Deleting from table. ccb=0x48413E08
key=5f97c880-8e41d8de-1e855-2178320a at 10.50.120.3320AC10-12BA
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIFlushEventBufferQueue:
There are 0 events on the internal queue that are going to be free'd
Oct 2 10:21:52: //9/36ECCAF0801A/SIP/Info/sipSPIUfreeOneCCB: Freeing
ccb 48413E08
Oct 2 10:21:52: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable:
NO context for key[10]
NETEDGE-SIP-CUBE#
Mike Wilusz, CCNA
Networking Systems Programmer
Price Chopper Supermarkets / The Golub Corporation
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20081002/963aa339/attachment-0001.html>
More information about the cisco-voip
mailing list