[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