- Cisco Community
- Technology and Support
- Collaboration
- IP Telephony and Phones
- Re: Issues with DTMF
- Subscribe to RSS Feed
- Mark Topic as New
- Mark Topic as Read
- Float this Topic for Current User
- Bookmark
- Subscribe
- Mute
- Printer Friendly Page
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-24-2013 01:26 AM - edited 03-16-2019 03:54 PM
Hi,
DTMF is not working and I need help to understand what to look for. Voice gateway is connected to service provider via SIP. Config is copied below. I have also posted DEBUG CCSIP below. Thanks.
===CONFIG
voice-card 0
dspfarm
dsp services dspfarm
!
!
voice call send-alert
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
redirect ip2ip
sip
no call service stop
!
!
voice class codec 1
codec preference 1 g711alaw
!
!
voice translation-rule 1
rule 1 /^2169\(3..\)$/ /7\1/
!
voice translation-rule 2
rule 1 /^7\(3..\)$/ /2169\1/
!
voice translation-rule 9
rule 1 /^9\([2-8]......\)$/ /\1/
rule 2 /^9\(0........\)$/ /\1/
rule 3 /^9\(05........\)$/ /\1/
rule 4 /^900/ /00/
rule 5 /^9800/ /800/
rule 6 /^99200/ /9200/
rule 7 /^90.$/ /\0/
!
!
voice translation-profile CALLER
translate calling 2
!
voice translation-profile EXT
translate called 1
!
voice translation-profile OUTGOING
translate called 9
!
!
archive
log config
hidekeys
!
!
!
interface GigabitEthernet0/0
ip address XX.XXX.XXX.XXX 255.255.255.252
duplex auto
speed auto
media-type rj45
!
interface GigabitEthernet0/1
ip address XX.XXX.XXX.XXX 255.255.255.128
duplex auto
speed auto
media-type rj45
!
ip route 0.0.0.0 0.0.0.0 XX.XXX.XX.XXX
!
!
ip http server
no ip http secure-server
!
!
!
control-plane
!
!
!
voice-port 0/1/0
!
voice-port 0/1/1
!
voice-port 0/1/2
!
voice-port 0/1/3
!
!
!
sccp local GigabitEthernet0/1
sccp ccm 10.104.160.30 identifier 1 priority 1
sccp ccm 10.104.160.29 identifier 2 priority 2
sccp
!
sccp ccm group 1
bind interface GigabitEthernet0/1
associate ccm 1 priority 1
associate ccm 2 priority 2
associate profile 1 register ISARAA3001-TRAN
associate profile 2 register CFB_SARAA
!
dspfarm profile 1 transcode
codec g711ulaw
codec g711alaw
codec g729ar8
codec g729abr8
codec g729r8
maximum sessions 12
associate application SCCP
!
dspfarm profile 2 conference
codec g711ulaw
codec g711alaw
codec g729ar8
codec g729abr8
codec g729r8
codec g729br8
maximum sessions 4
associate application SCCP
!
!
dial-peer voice 7300 voip
translation-profile outgoing EXT
destination-pattern 21693..
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
session protocol sipv2
session target ipv4:10.104.160.29
incoming called-number .
dtmf-relay rtp-nte
no vad
!
dial-peer voice 7301 voip
translation-profile outgoing EXT
preference 2
destination-pattern 21693..
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
session protocol sipv2
session target ipv4:10.104.160.30
dtmf-relay rtp-nte
no vad
!
dial-peer voice 9 voip
description Outside Numbers
translation-profile outgoing OUTGOING
destination-pattern 9T
session protocol sipv2
session target ipv4:10.200.7.157
session transport udp
dtmf-relay rtp-nte
codec g711alaw
!
!
sip-ua
disable-early-media 180
retry notify 6
timers notify 100
!
!
!
telephony-service
srst mode auto-provision all
srst dn line-mode dual
max-conferences 12 gain -6
transfer-system full-consult
====END CONFIG====
=====DEBUG CCSIP=====
ISARAA3001#TERM MON
ISARAA3001#DEBUG CCSIP ?
all Enable all SIP debugging traces
calls Enable CCSIP SPI calls debugging trace
error Enable SIP error debugging trace
events Enable SIP events debugging trace
info Enable SIP info debugging trace
media Enable SIP media debugging trace
messages Enable CCSIP SPI messages debugging trace
preauth Enable SIP preauth debugging traces
states Enable CCSIP SPI states debugging trace
transport Enable SIP transport debugging traces
ISARAA3001#DEBUG CCSIP ALL
This may severely impact system performance. Continue? [confirm]
All SIP Call tracing is enabled
ISARAA3001#
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3411
*Feb 24 07:29:05.689: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13319] removed.
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x706FBA78 key=isbcho2tcpa4skc4cfdhhss7kfk4u7hh4ett@SoftX3000
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:05.689: //13329/A122A4CAB50B/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 706FBA78
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK7k7bhpeuhuhdsfbdupb4dkdosT17251
Call-ID: isbcukudohtbtbpu2c2desoecucauue2oebe@SoftX3000
From: <sip:10.66.7.218:5060>;tag=sbc080574cdd4pe
To: <sip:10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70BE0234) with key=[13323] to table
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:13.805: //-1/B90BDBB3B513/SIP/State/sipSPIChangeState: 0x70BE0234 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/State/sipSPIChangeState: 0x70BE0234 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70BE0234 key=isbcukudohtbtbpu2c2desoecucauue2oebe@SoftX3000
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3415 to table
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCC31C to Options Response
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Transport/sipSPITransportSendMessage: msg=0x70862A60, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:13.805: //13333/B90BDBB3B513/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:13.805: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70862A60, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 24 07:29:13.809: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK7k7bhpeuhuhdsfbdupb4dkdosT17251
From: <sip:10.66.7.218:5060>;tag=sbc080574cdd4pe
To: <sip:10.66.7.218>;tag=FC6F5FC-237F
Date: Sun, 24 Feb 2013 07:29:13 GMT
Call-ID: isbcukudohtbtbpu2c2desoecucauue2oebe@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 162
v=0
o=CiscoSystemsSIP-GW-UserAgent 1354 97 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=55029, connid=1, transport=TCP
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:909@10.106.78.7:5060;transport=tcp SIP/2.0
Date: Sun, 24 Feb 2013 07:21:36 GMT
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770133
P-Asserted-Identity: "Aslam Shaik" <sip:2169301@10.104.160.30>
Content-Length: 0
User-Agent: Cisco-CUCM7.0
To: <sip:909@10.106.78.7>;tag=FC6770C-256B
Call-ID: d15e0580-1291bf80-b126-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb0418e1300
CSeq: 102 BYE
Max-Forwards: 70
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:18.581: //13330/A541F139B50C/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x706F7100
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[13330], src[2]
*Feb 24 07:29:18.581: //13330/A541F139B50C/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:18.581: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 24 07:29:18.581: //13330/A541F139B50C/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for incoming call
*Feb 24 07:29:18.581: //13330/A541F139B50C/SIP/State/sipSPIChangeState: 0x706F7100 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPISendByeResponse: Associated container=0x70FCB458 to Bye Response
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Transport/sipSPISendByeResponse: Sending BYE Response to the transport layer
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Transport/sipSPITransportSendMessage: msg=0x70862A60, addr=10.104.160.30, port=55029, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x615E7F34
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70862A60, addr=10.104.160.30, port=55029, connId=1 for TCP
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sentByeResponse: Sent 200ok to the BYE, tearing down the call
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:26470212 ConnTime 26466490
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/State/sipSPIChangeState: 0x706F7100 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x706F7100
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 2169301
Called Number : 909
Source IP Address (Sig ): 10.106.78.7
Destn SIP Req Addr:Port : 10.104.160.30:5060
Destn SIP Resp Addr:Port : 10.104.160.30:55029
Destination Name : 10.104.160.30
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 10.106.78.7
Source IP Port (Media): 16722
Destn IP Address (Media): 10.106.71.9
Destn IP Port (Media): 29212
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 16
Disconnect Cause (SIP) : 200
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3412
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13320] removed.
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x706F7100 key=d15e0580-1291bf80-b126-1ea0680a@10.104.160.30909
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x706F7100 key=d15e0580-1291bf80-b126-1ea0680a@10.104.160.30FC6770C-256B
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:18.585: //13330/A541F139B50C/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 706F7100
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[13320]
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPITransportSendMessage: gcb has cleanedup, Done with send operations for this context
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Info/sipSPIPushOrigRequestContainerIntoHolder: Request Container Holder is above threshold...trimming
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <sip:10.200.7.157:5060;transport=udp;lr>
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Info/sipSPISendBye: Associated container=0x70FCC100 to Bye
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipSPITransportSendMessage: msg=0x67D46814, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E7CB8
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x67D46814
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x67D46814, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
*Feb 24 07:29:18.585: //13331/A541F139B50C/SIP/State/sipSPIChangeState: 0x70BE26F0 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb0418e1300
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770133
To: <sip:909@10.106.78.7>;tag=FC6770C-256B
Date: Sun, 24 Feb 2013 07:29:18 GMT
Call-ID: d15e0580-1291bf80-b126-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 BYE
Reason: Q.850;cause=16
Content-Length: 0
*Feb 24 07:29:18.585: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:909@10.200.7.157:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBA29B
From: "Aslam Shaik" <sip:2169301@10.66.7.218>;tag=FC67450-2477
To: <sip:909@10.200.7.157>;tag=sbc0803caec2fa7-CC-54
Date: Sun, 24 Feb 2013 07:28:41 GMT
Call-ID: A5428D61-7D8A11E2-B511A0DC-22837DDA@10.66.7.218
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Route: <sip:10.200.7.157:5060;transport=udp;lr>
Timestamp: 1361690958
CSeq: 103 BYE
Reason: Q.850;cause=16
Content-Length: 0
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBA29B
Record-Route: <sip:10.200.7.157:5060;transport=udp;lr>
Call-ID: A5428D61-7D8A11E2-B511A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC67450-2477
To: <sip:909@10.200.7.157>;tag=sbc0803caec2fa7-CC-54
CSeq: 103 BYE
Content-Length: 0
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:26470218 ConnTime 26466490
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/State/sipSPIChangeState: 0x70BE26F0 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x70BE26F0
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 2169301
Called Number : 909
Source IP Address (Sig ): 10.66.7.218
Destn SIP Req Addr:Port : 10.200.7.157:5060
Destn SIP Resp Addr:Port : 10.200.7.157:5060
Destination Name : 10.200.7.157
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 10.66.7.218
Source IP Port (Media): 16526
Destn IP Address (Media): 10.200.7.157
Destn IP Port (Media): 43188
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 16
Disconnect Cause (SIP) : 200
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3413
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13321] removed.
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70BE26F0 key=A5428D61-7D8A11E2-B511A0DC-22837DDA@10.66.7.218
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:18.645: //13331/A541F139B50C/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 70BE26F0
*Feb 24 07:29:18.645: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[13321]
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3414
*Feb 24 07:29:25.745: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13322] removed.
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x706F02CC key=isbc2o2pfkpafdep2b7tbcudppu7uotkdtbu@SoftX3000
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:25.745: //13332/AD16F22AB512/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 706F02CC
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=55029, connid=1, transport=TCP
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:909@10.106.78.7:5060 SIP/2.0
Date: Sun, 24 Feb 2013 07:22:22 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770174
Allow-Events: presence
P-Asserted-Identity: "Aslam Shaik" <sip:2169301@10.104.160.30>
Supported: timer,resource-priority,replaces
Min-SE: 1800
Remote-Party-ID: "Aslam Shaik" <sip:2169301@10.104.160.30>;party=calling;screen=yes;privacy=off
Content-Length: 0
User-Agent: Cisco-CUCM7.0
To: <sip:909@10.106.78.7>
Contact: <sip:2169301@10.104.160.30:5060;transport=tcp>
Expires: 180
Call-ID: ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb1154cb28d
CSeq: 101 INVITE
Session-Expires: 1800
Max-Forwards: 70
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70BEDE9C) with key=[13324] to table
*Feb 24 07:29:26.693: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BEDE9C : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x6652E25C) found for sip_user: 909
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPISetInfoFromRpid: Received current remote name: Aslam Shaik, current remote number: 2169301
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPISetInfoFromRpid: Received ;screen=yes ;privacy=off -> Setting Octet3A 0x81, extended_privacy 0x00
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70BEDE9C key=ecc91080-1291bfae-b127-1ea0680a@10.104.160.30909
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 909
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 2169301
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name Aslam Shaik, number 2169301, Calling oct3 0x00, oct_3a 0x81, Called number 909
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIGetCallConfig: Peer tag 7300 matched for incoming call
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIContinueNewMsgInvite: Calling name Aslam Shaik, number 2169301, Calling oct3 0x00, oct_3a 0x81, ext_priv 0x00, Called number 909, oct3 0x00
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Feb 24 07:29:26.697: //-1/C0BB059AB514/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPINegotiateSessionExpires:
Session-Expires value: 1800 refresher: 3
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPINegotiateSessionExpires: Min-SE Header: 1800
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIGetContentSDP: No SDP found in inbound container
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIDoQoSNegotiation: No SDP body
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 13334, sdp 0x6727EE10 channels 0x70BEEF6C
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/copy_channels:
callId 13334 size 0 ptr 0x7006DED8)
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Delayed media case...creating new stream
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.106.78.7
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18340 for stream 1
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=18340
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPI_ipip_store_channel_info: negotiated dtmf not available, using dialpeer config dtmf = 6
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/ccsip_set_bearer_capability:
Bearer Capability: Speech (0x00)
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS_DELAYED_MEDIA
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3416 to table
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x67C96CB0, addr=10.104.160.30, port=55029, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x00000000
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:26.697: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x67C96CB0, addr=10.104.160.30, port=55029, connId=1 for TCP
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BEDE9C : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)
*Feb 24 07:29:26.697: //13334/C0BB059AB514/SIP/Info/sipSPIProcessContactInfo: Previous Hop 10.104.160.30:5060
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x70BE4BAC) with key=[13325] to table
*Feb 24 07:29:26.701: //13335/000000000000/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:29:26.701: //13335/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Feb 24 07:29:26.701: //13335/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: NULL peer_channel - Delayed Media: peer_channels = 0x0, peer_param = 0x70FDEE54, callID= 13335, peer_callID = 13334
*Feb 24 07:29:26.701: //13335/000000000000/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 24 07:29:26.701: //13335/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIGetOutboundHostAndDestHost: CCSIP: target_host : 10.200.7.157 target_port : 5060
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler:
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 147)
*Feb 24 07:29:26.701: //13334/C0BB059AB514/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 13335 chans 0x710D0020 event 147 flags 0x40201C 0x300 0x201 data 0x710D0020
*Feb 24 07:29:26.701: //13334/C0BB059AB514/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 13335 chans 0x710D0020 event 147 flags 0x40201C 0x300 0x201 data 0x710D0020, type = 1
*Feb 24 07:29:26.701: //13334/C0BB059AB514/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3417 to table
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIGetCallConfig: Not using Voice Class Codec
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 13335, channels 0x0 caps 0x66CCDF10
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/act_idle_continue_call_setup:
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70BE4BAC key=C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sipSPISendInvite: Associated container=0x70FCBD7C to Invite
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x70862640, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E6EC8
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x70862640
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70862640, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
*Feb 24 07:29:26.701: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
*Feb 24 07:29:26.701: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:909@10.200.7.157:5060 SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBB1891
Remote-Party-ID: "Aslam Shaik" <sip:2169301@10.66.7.218>;party=calling;screen=yes;privacy=off
From: "Aslam Shaik" <sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>
Date: Sun, 24 Feb 2013 07:29:26 GMT
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
Cisco-Guid: 3233482138-2106200546-3038027996-579042778
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1361690966
Contact: <sip:2169301@10.66.7.218:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
Content-Length: 0
*Feb 24 07:29:26.705: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb1154cb28d
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770174
To: <sip:909@10.106.78.7>
Date: Sun, 24 Feb 2013 07:29:26 GMT
Call-ID: ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0
*Feb 24 07:29:26.713: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:26.713: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:26.713: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:26.713: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:26.713: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBB1891
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>
CSeq: 101 INVITE
Content-Length: 0
*Feb 24 07:29:26.713: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBB1891
Record-Route: <sip:10.200.7.157:5060;transport=udp;lr>
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
CSeq: 101 INVITE
Contact: <sip:909@10.200.7.157:5060;user=phone>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Require: 100rel
RSeq: 1
Content-Length: 369
Content-Type: application/sdp
v=0
o=- 5830125 5830125 IN IP4 10.200.7.157
s=SBC call
c=IN IP4 10.200.7.157
t=0 0
m=audio 43538 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Info/ccsip_api_call_alert: SDP in 180 will be ignored if present: No early media cut through
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateRawMsg: No GTD passed.
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Info/HandleSIP1xxRinging: ccsip_api_call_alert returned: SIP_SUCCESS
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (SIP_STATE_SEND_PRACK, SUBSTATE_NONE)
*Feb 24 07:29:27.297: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Info/act_handle_app_media_event: method = 105 state = 23
*Feb 24 07:29:27.297: //13335/C0BB059AB514/SIP/Info/act_handle_app_media_event: Received media sip event SIP_REQUEST_ANSWER
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (SIP_STATE_SEND_PRACK, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <sip:10.200.7.157:5060;transport=udp;lr>
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x70861CD0, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E7910
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x70861CD0
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861CD0, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Info/sentPrack: Sent Prack Request, starting PrackTimer
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 24 07:29:27.301: //13335/C0BB059AB514/SIP/Info/sact_app_media_event_send_request: Transaction active. Facilities will be queued.
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_MEDIA_EVENT
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:909@10.200.7.157:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBC20AB
From: "Aslam Shaik" <sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
Date: Sun, 24 Feb 2013 07:29:26 GMT
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
CSeq: 102 PRACK
RAck: 1 101 INVITE
Route: <sip:10.200.7.157:5060;transport=udp;lr>
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 5
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x70BEDE9C key=ecc91080-1291bfae-b127-1ea0680a@10.104.160.30FC72AB4-2604
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Info/ccsip_api_update_allowed: UPDATE(1) allowed
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Transport/sipSPISendInviteResponse: Sending 180 Response to the Transport Layer
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x70861CD0, addr=10.104.160.30, port=55029, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x615E77D0
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x70861CD0, addr=10.104.160.30, port=55029, connId=1 for TCP
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/Info/sentInviteResponse18x: Sent a 18x Response
*Feb 24 07:29:27.301: //13334/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BEDE9C : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_SENT_ALERTING, SUBSTATE_NONE)
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29
*Feb 24 07:29:27.301: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb1154cb28d
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770174
To: <sip:909@10.106.78.7>;tag=FC72AB4-2604
Date: Sun, 24 Feb 2013 07:29:26 GMT
Call-ID: ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
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: <sip:909@10.106.78.7>;party=called;screen=no;privacy=off
Contact: <sip:909@10.106.78.7:5060;transport=tcp>
Content-Length: 0
*Feb 24 07:29:27.925: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:27.925: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:27.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:27.925: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:27.925: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBC20AB
Record-Route: <sip:10.200.7.157:5060;transport=udp;lr>
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
CSeq: 102 PRACK
Content-Length: 0
*Feb 24 07:29:27.925: //13335/C0BB059AB514/SIP/Info/act_recdproc_new_message_response: Transaction Complete. Lock on Facilities released.
*Feb 24 07:29:27.925: //13335/C0BB059AB514/SIP/Info/ccsip_api_update_allowed: UPDATE(2) allowed
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBB1891
Record-Route: <sip:10.200.7.157:5060;transport=udp;lr>
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
CSeq: 101 INVITE
Contact: <sip:909@10.200.7.157:5060;user=phone>
Content-Length: 369
Content-Type: application/sdp
v=0
o=- 5830125 5830126 IN IP4 10.200.7.157
s=SBC call
c=IN IP4 10.200.7.157
t=0 0
m=audio 43538 RTP/AVP 8 0 18 4 2 98 99 97
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:98 G726-40/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
a=fmtp:18 annexb=yes
*Feb 24 07:29:27.929: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 24 07:29:27.929: //13335/C0BB059AB514/SIP/Info/sipSPIhandle200OKInvite: Transaction active. Facilities will be queued.
*Feb 24 07:29:27.929: //13335/C0BB059AB514/SIP/Info/sipSPIhandle200OKInvite: *** This ccb is the parent
*Feb 24 07:29:27.929: //13334/C0BB059AB514/SIP/Info/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 13334/13335
*Feb 24 07:29:27.929: //13334/C0BB059AB514/SIP/Info/sipSPIUupdateCcCallIds: Old streamcallid=-1, new streamcallid=13334
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Error/sipSPIProcessCallInfoHeader: Call-Info header with for Unsolicited Notify Absent,Disabling Unsolicited Notifies
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPICompareRespMediaInfo: No Comparsion needed as 18x response SDP is either absent or ignored
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoAudioNegotiation: Codec (g711alaw) Negotiation Successful on Static Payload for m-line 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPICheckDynPayloadUse: Dynamic payload(97) could not be reserved.
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIReserveRtpNtePayload: Requested DTMF-RELAY payload (97) is reserved by another application
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMF-RELAY option list!
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIStreamTypeAndDtmfRelay: DTMF Relay mode: Inband Voice
*Feb 24 07:29:27.933: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Feb 24 07:29:27.933: //13335/C0BB059AB514/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
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=8, codec_bytes=160, codec=g711alaw, dtmf_relay=inband-voice
stream_type=voice-only (0), dest_ip_address=10.200.7.157, dest_port=43538
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g711alaw, 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
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 13335 peer 13334 flags 0x400001
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 13335, sdp 0x7006DD58 channels 0x70BE5C7C
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/copy_channels:
callId 13335 size 0 ptr 0x671FE5F0)
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw
*Feb 24 07:29:27.933: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=0,stream->negotiated_codec_bytes=160, coverted ptime=20 stream->mline_index=1, media_ndx=1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 6 ptype 8 time 20, bytes 160 as channel 0 mline 1 ss 0 10.200.7.157:43538
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline 1
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Media/sipSPISelectCodecVersion: Codec (g729br8) is not in preferred list
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact codec match not configured, using interoperable codec g729r8 pre-ietf
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g729r8 pre-ietf
*Feb 24 07:29:27.933: //13335/C0BB059AB514/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 4 mline 1ontent-Length: 176
v=0
o=CiscoSystemsSIP-GW-UserAgent 9046 1043 IN IP4 10.66.7.218
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 17136 RTP/AVP 8
c=IN IP4 10.66.7.218
a=rtpmap:8 PCMA/8000
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:2169301@10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKee2ccfdohbc2o2hsobphoh2uoT09431
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
To: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
CSeq: 1 OPTIONS
Accept: application/sdp
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:29.317: //13335/C0BB059AB514/SIP/Info/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:29:29.317: //13335/C0BB059AB514/SIP/Info/sact_active_new_message_request: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:29:29.317: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:29:29.317: //13335/C0BB059AB514/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCBB9C to Options Response
*Feb 24 07:29:29.321: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x66CD12A0, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x00000000
*Feb 24 07:29:29.321: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:29.321: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:29.321: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CD12A0, addr=10.200.7.157, port=5060, connId=0 for UDPw-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 176
v=0
o=CiscoSystemsSIP-GW-UserAgent 9046 1043 IN IP4 10.66.7.218
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 17136 RTP/AVP 8
c=IN IP4 10.66.7.218
a=rtpmap:8 PCMA/8000
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKhbaft7a2khpk2h7cc4ubhc4coT09494
Call-ID: isbct7oo4sef24kchshbcdc74ohec7h4ukud@SoftX3000
From: <sip:10.66.7.218:5060>;tag=sbc0803dkscoe4e
To: <sip:10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x706F4C44) with key=[13326] to table
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:33.865: //-1/C500C53CB51A/SIP/State/sipSPIChangeState: 0x706F4C44 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/State/sipSPIChangeState: 0x706F4C44 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x706F4C44 key=isbct7oo4sef24kchshbcdc74ohec7h4ukud@SoftX3000
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3418 to table
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCB6EC to Options Response
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Transport/sipSPITransportSendMessage: msg=0x66CD12A0, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:33.865: //13336/C500C53CB51A/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CD12A0, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 24 07:29:33.865: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKhbaft7a2khpk2h7cc4ubhc4coT09494
From: <sip:10.66.7.218:5060>;tag=sbc0803dkscoe4e
To: <sip:10.66.7.218>;tag=FC74458-32D
Date: Sun, 24 Feb 2013 07:29:33 GMT
Call-ID: isbct7oo4sef24kchshbcdc74ohec7h4ukud@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 2450 9402 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3415
*Feb 24 07:29:45.805: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13323] removed.
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70BE0234 key=isbcukudohtbtbpu2c2desoecucauue2oebe@SoftX3000
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:45.805: //13333/B90BDBB3B513/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 70BE0234
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x7095EC90, addr=10.104.160.30, port=55029, connid=1, transport=TCP
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:909@10.106.78.7:5060;transport=tcp SIP/2.0
Date: Sun, 24 Feb 2013 07:22:22 GMT
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770174
P-Asserted-Identity: "Aslam Shaik" <sip:2169301@10.104.160.30>
Content-Length: 0
User-Agent: Cisco-CUCM7.0
To: <sip:909@10.106.78.7>;tag=FC72AB4-2604
Call-ID: ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb35bd62fbf
CSeq: 102 BYE
Max-Forwards: 70
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x70BEDE9C
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.104.160.30,Port 55029, Transport 2, SentBy Port 5060
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[13334], src[2]
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for incoming call
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BEDE9C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPISendByeResponse: Associated container=0x70FCE860 to Bye Response
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Transport/sipSPISendByeResponse: Sending BYE Response to the transport layer
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x66CBBECC, addr=10.104.160.30, port=55029, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x615E7F34
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CBBECC, addr=10.104.160.30, port=55029, connId=1 for TCP
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sentByeResponse: Sent 200ok to the BYE, tearing down the call
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:26473393 ConnTime 26471147
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BEDE9C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x70BEDE9C
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number : 2169301
Called Number : 909
Source IP Address (Sig ): 10.106.78.7
Destn SIP Req Addr:Port : 10.104.160.30:5060
Destn SIP Resp Addr:Port : 10.104.160.30:55029
Destination Name : 10.104.160.30
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 10.106.78.7
Source IP Port (Media): 18340
Destn IP Address (Media): 10.106.71.9
Destn IP Port (Media): 17482
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 16
Disconnect Cause (SIP) : 200
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3416
*Feb 24 07:29:50.397: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13324] removed.
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70BEDE9C key=ecc91080-1291bfae-b127-1ea0680a@10.104.160.30909
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70BEDE9C key=ecc91080-1291bfae-b127-1ea0680a@10.104.160.30FC72AB4-2604
*Feb 24 07:29:50.397: //13334/C0BB059AB514/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:50.401: //13334/C0BB059AB514/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 70BEDE9C
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[13324]
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPITransportSendMessage: gcb has cleanedup, Done with send operations for this context
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Info/sipSPIPushOrigRequestContainerIntoHolder: Request Container Holder is above threshold...trimming
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <sip:10.200.7.157:5060;transport=udp;lr>
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Info/sipSPISendBye: Associated container=0x70FCE0A4 to Bye
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: msg=0x66CD12A0, addr=10.200.7.157, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x615E7CB8
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x66CD12A0
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CD12A0, addr=10.200.7.157, port=5060, connId=1 for UDP
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
*Feb 24 07:29:50.401: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.104.160.30:5060;branch=z9hG4bKeeb35bd62fbf
From: "Aslam Shaik" <sip:2169301@10.104.160.30>;tag=895022dc-e465-496e-9fcb-fa8b46f34648-47770174
To: <sip:909@10.106.78.7>;tag=FC72AB4-2604
Date: Sun, 24 Feb 2013 07:29:50 GMT
Call-ID: ecc91080-1291bfae-b127-1ea0680a@10.104.160.30
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 BYE
Reason: Q.850;cause=16
Content-Length: 0
*Feb 24 07:29:50.401: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:909@10.200.7.157:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBEFF
From: "Aslam Shaik" <sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
Date: Sun, 24 Feb 2013 07:29:29 GMT
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Route: <sip:10.200.7.157:5060;transport=udp;lr>
Timestamp: 1361690990
CSeq: 103 BYE
Reason: Q.850;cause=16
Content-Length: 0
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.66.7.218:5060;branch=z9hG4bKBEFF
Record-Route: <sip:10.200.7.157:5060;transport=udp;lr>
Call-ID: C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
From: "Aslam Shaik"<sip:2169301@10.66.7.218>;tag=FC7285C-1503
To: <sip:909@10.200.7.157>;tag=sbc0803ebcsac72-CC-56
CSeq: 103 BYE
Content-Length: 0
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:26473398 ConnTime 26471146
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/State/sipSPIChangeState: 0x70BE4BAC : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x70BE4BAC
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 2169301
Called Number : 909
Source IP Address (Sig ): 10.66.7.218
Destn SIP Req Addr:Port : 10.200.7.157:5060
Destn SIP Resp Addr:Port : 10.200.7.157:5060
Destination Name : 10.200.7.157
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 8 (tx), 8 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 10.66.7.218
Source IP Port (Media): 17136
Destn IP Address (Media): 10.200.7.157
Destn IP Port (Media): 43538
Orig Destn IP Address:Port (Media): 0.0.0.0:0
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 16
Disconnect Cause (SIP) : 200
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3417
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13325] removed.
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x70BE4BAC key=C0BBA1C2-7D8A11E2-B519A0DC-22837DDA@10.66.7.218
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:29:50.453: //13335/C0BB059AB514/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 70BE4BAC
*Feb 24 07:29:50.453: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[13325]
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK4u47dts7f2hsce7ffabfk7ucoT10902
Call-ID: isbcucpbfftcdftdteodbdfb2k2udt7sca74@SoftX3000
From: <sip:10.66.7.218:5060>;tag=sbc0806cecth7bb
To: <sip:10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x67DD72FC) with key=[13327] to table
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:53.925: //-1/D0F5AEC4B51B/SIP/State/sipSPIChangeState: 0x67DD72FC : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/State/sipSPIChangeState: 0x67DD72FC : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x67DD72FC key=isbcucpbfftcdftdteodbdfb2k2udt7sca74@SoftX3000
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 3419 to table
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCB854 to Options Response
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Transport/sipSPITransportSendMessage: msg=0x66CC9354, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:29:53.925: //13337/D0F5AEC4B51B/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CC9354, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 24 07:29:53.925: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK4u47dts7f2hsce7ffabfk7ucoT10902
From: <sip:10.66.7.218:5060>;tag=sbc0806cecth7bb
To: <sip:10.66.7.218>;tag=FC792B4-1D60
Date: Sun, 24 Feb 2013 07:29:53 GMT
Call-ID: isbcucpbfftcdftdteodbdfb2k2udt7sca74@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 1047 3746 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3418
*Feb 24 07:30:05.865: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13326] removed.
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x706F4C44 key=isbct7oo4sef24kchshbcdc74ohec7h4ukud@SoftX3000
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:30:05.865: //13336/C500C53CB51A/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 706F4C44
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKepu2pdahaau2ca2da77aoehfcT09870
Call-ID: isbcpe2ua7fskatahph2ecko7f7fcea4stoa@SoftX3000
From: <sip:10.66.7.218:5060>;tag=sbc0803aa2ftddk
To: <sip:10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x67DCBB50) with key=[13328] to table
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:13.985: //-1/DCEA984DB51C/SIP/State/sipSPIChangeState: 0x67DCBB50 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/State/sipSPIChangeState: 0x67DCBB50 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x67DCBB50 key=isbcpe2ua7fskatahph2ecko7f7fcea4stoa@SoftX3000
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 341A to table
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCBC50 to Options Response
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Transport/sipSPITransportSendMessage: msg=0x66CC9354, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:30:13.985: //13338/DCEA984DB51C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CC9354, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 24 07:30:13.985: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bKepu2pdahaau2ca2da77aoehfcT09870
From: <sip:10.66.7.218:5060>;tag=sbc0803aa2ftddk
To: <sip:10.66.7.218>;tag=FC7E110-9E1
Date: Sun, 24 Feb 2013 07:30:13 GMT
Call-ID: isbcpe2ua7fskatahph2ecko7f7fcea4stoa@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 1408 4436 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
ISARAA3001#
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 3419
*Feb 24 07:30:25.925: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[13327] removed.
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x67DD72FC key=isbcucpbfftcdftdteodbdfb2k2udt7sca74@SoftX3000
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 24 07:30:25.925: //13337/D0F5AEC4B51B/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 67DD72FC
ISARAA3001#
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpSocketReads: Msg enqueued for SPI with IP addr: 10.200.7.157:5060
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:10.66.7.218:5060 SIP/2.0
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK27bkoh4d74bbbu4ukt2tkhdkbT11306
Call-ID: isbcfbohsf4utpesahdth77h2ucehdkceu4b@SoftX3000
From: <sip:10.66.7.218:5060>;tag=sbc0806cc7uceat
To: <sip:10.66.7.218>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x67DC9694) with key=[13329] to table
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:34.033: //-1/E8DDAF62B51D/SIP/State/sipSPIChangeState: 0x67DC9694 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 24 07:30:34.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.200.7.157,Port 5060, Transport 1, SentBy Port 5060
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.66.7.218
*Feb 24 07:30:34.037: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sact_idle_new_message_options: ccsip_api_options_ind returned: SIP_SUCCESS
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/State/sipSPIChangeState: 0x67DC9694 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x67DC9694 key=isbcfbohsf4utpesahdth77h2ucehdkceu4b@SoftX3000
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 341B to table
*Feb 24 07:30:34.037: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 38
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Info/sipSPISendOptionsResponse: Associated container=0x70FCB7A0 to Options Response
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Transport/sipSPITransportSendMessage: msg=0x66CC9354, addr=10.200.7.157, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x615E9E98
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 24 07:30:34.037: //13339/E8DDAF62B51D/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 24 07:30:34.037: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x66CC9354, addr=10.200.7.157, port=5060, connId=0 for UDP
*Feb 24 07:30:34.037: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.200.7.157:5060;branch=z9hG4bK27bkoh4d74bbbu4ukt2tkhdkbT11306
From: <sip:10.66.7.218:5060>;tag=sbc0806cc7uceat
To: <sip:10.66.7.218>;tag=FC82F60-269A
Date: Sun, 24 Feb 2013 07:30:34 GMT
Call-ID: isbcfbohsf4utpesahdth77h2ucehdkceu4b@SoftX3000
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 OPTIONS
Supported: 100rel,resource-priority,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 164
v=0
o=CiscoSystemsSIP-GW-UserAgent 3594 5165 IN IP4 10.106.78.7
s=SIP Call
c=IN IP4 10.66.7.218
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 15 3
c=IN IP4 10.66.7.218
Solved! Go to Solution.
- Labels:
-
Other IP Telephony
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-25-2013 04:22 AM
Much appreciate your time and efforts to resolve this issue.
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-25-2013 04:24 AM
No probs ashley..Glad its working now.
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
06-24-2022 07:10 AM
Hello @Ayodeji Okanlawon I have a similar issue with my provider, could you help me?
I received this invite:
un 24 09:17:23.050: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:+xxxxxx@xxxxx;user=phone SIP/2.0
Via: SIP/2.0/UDP 10.14.0.141:5060;branch=z9hG4bK1x94rbzo4bzzb4r0bryxb7390T00371
Call-ID: isbcxw7ab3004wy0779zi7ooi0byixr7wy01@B.5.102.xxxx.xx.xx
From: "+xxxxxxx"<sip:+xxxxxx@xxx.xx.xx;noa=national;srvattri=national>;tag=sbc0511w5sg0rxp
To: <sip:+xxxxxxx@10.14.3.194:5060;transport=udp;user=phone>
CSeq: 1 INVITE
Allow: INVITE,ACK,BYE,CANCEL,UPDATE,INFO,PRACK,NOTIFY,REFER,SUBSCRIBE,OPTIONS,MESSAGE
P-Charging-Vector: icid-value=DFE7801000-0624-09174700;orig-ioi=xxxx.xx.xx;term-ioi=xx.xx.xx
Max-Forwards: 65
Supported: timer,100rel,histinfo,in-band-dtmf,early-session
Session-Expires: 1800
Min-SE: 600
P-Asserted-Identity: <tel:+xxxxxx>
Contact: <sip:10.14.0.141:5060;Dpt=eb6a-200>
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session
v=0
o=- 707023 707023 IN IP4 10.14.0.142
s=SBC call
c=IN IP4 10.14.0.142
t=0 0
m=audio 31948 RTP/AVP 8 18 0 108
a=fmtp:18 annexb=yes
a=rtpmap:108 telephone-event/16000
a=fmtp:108 0-15
a=ptime:20
a=maxptime:40
a=sendrecv
As you mention, I get DTMF information in a non-standard payload, in this case 108.
Is it possible to change something?
Guillermo
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
06-24-2022 08:06 AM
Another question, why I received a=rtpmap:108 telephone-event/16000, this high bitrate
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
06-24-2022 08:39 AM
Please create your own post instead of asking help on 9 years old post, that on top of things is marked as solved, as that would likely yield you a better chance of getting help with this.
- « Previous
-
- 1
- 2
- Next »
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide