Solved: Re: No Ringback tone on outbound calls using SIP

marahman78 · ‎02-27-2014

When i try to make call outside, i'm not getting any ringback tone. I enabled the debugs and checked that sip server sends 180 with SDP. When I enter "disable-early-media 180" command under sip-ua, I received ringback tone but once the other party picks up the call, the call is disconnected. Then i've to revert back the config.

Call Flow:

IP Phone-->CUCM-->H323-->VGW-->SIP-->PSTN

Debugs:

*Feb 27 08:46:02.362: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Sent:

INVITE sip:0508662733@10.205.20.50:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6692692

Remote-Party-ID: "DMM-4001" <sip:8664001@172.29.33.214>;party=calling;screen=yes;privacy=off

From: "DMM-4001" <sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>

Date: Thu, 27 Feb 2014 08:46:02 GMT

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 2147832294-2164121904-3623963142-0169816986

User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1393490762

Contact: <sip:8664001@172.29.33.214:5060>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 274

v=0

o=CiscoSystemsSIP-GW-UserAgent 6959 9302 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 23346 RTP/AVP 8 101 19

c=IN IP4 172.29.33.214

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

*Feb 27 08:46:02.374: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6692692

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>

CSeq: 101 INVITE

Content-Length: 0

*Feb 27 08:46:05.286: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6692692

Record-Route: <sip:10.205.20.50:5060;transport=udp;lr>

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

CSeq: 101 INVITE

Contact: <sip:0508662733@10.205.20.50:5060;user=phone>

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Require: 100rel

RSeq: 1

Content-Length: 191

Content-Type: application/sdp

v=0

o=- 6256032 6256032 IN IP4 10.205.20.50

s=SBC call

c=IN IP4 10.205.20.50

t=0 0

m=audio 19822 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

*Feb 27 08:46:05.286: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Sent:

PRACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK66A2094

From: "DMM-4001" <sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

Date: Thu, 27 Feb 2014 08:46:02 GMT

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

CSeq: 102 PRACK

RAck: 1 101 INVITE

Route: <sip:10.205.20.50:5060;transport=udp;lr>

Allow-Events: telephone-event

Max-Forwards: 70

Content-Length: 0

*Feb 27 08:46:05.346: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK66A2094

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

CSeq: 102 PRACK

Content-Length: 0

*Feb 27 08:46:08.266: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6692692

Record-Route: <sip:10.205.20.50:5060;transport=udp;lr>

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

CSeq: 101 INVITE

Contact: <sip:0508662733@10.205.20.50:5060;user=phone>

Content-Length: 0

*Feb 27 08:46:08.266: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Sent:

ACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK66B3D1

From: "DMM-4001" <sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

Date: Thu, 27 Feb 2014 08:46:02 GMT

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

Route: <sip:10.205.20.50:5060;transport=udp;lr>

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

*Feb 27 08:46:08.266: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Sent:

BYE sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK66C6BC

From: "DMM-4001" <sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

Date: Thu, 27 Feb 2014 08:46:02 GMT

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2

Max-Forwards: 70

Route: <sip:10.205.20.50:5060;transport=udp;lr>

Timestamp: 1393490768

CSeq: 103 BYE

Reason: Q.850;cause=96

P-RTP-Stat: PS=0,OS=0,PR=148,OR=23680,PL=0,JI=0,LA=0,DU=774526

Content-Length: 0

*Feb 27 08:46:08.326: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

OPTIONS sip:8664001@172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKa514swpc84s5wphphk885a2ksT02690

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

To: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

CSeq: 1 OPTIONS

Accept: application/sdp

Max-Forwards: 70

Content-Length: 0

*Feb 27 08:46:08.326: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 481 Call Leg/Transaction Does Not Exist

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKa514swpc84s5wphphk885a2ksT02690

From: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

To: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

CSeq: 1 OPTIONS

Content-Length: 0

*Feb 27 08:46:08.326: //42814/800551E6D801/SIP/Msg/ccsipDisplayMsg:

Received:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK66C6BC

Call-ID: 6BF9D4B7-9EC211E3-AFCBA890-B43FD7C0@172.29.33.214

From: "DMM-4001"<sip:8664001@172.29.33.214>;tag=2E2A405C-1762

To: <sip:0508662733@10.205.20.50>;tag=sbc08055f28a2w3-CC-36

CSeq: 103 BYE

Content-Length: 0

*Feb 27 08:46:11.314: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:

Received:

OPTIONS sip:172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKcwsww3878pp1a4ah2s6hp1h2hT29750

Call-ID: isbca3c3f3455hw6a4a5fs4c1412ss7acfkk@SoftX3000

From: <sip:172.29.33.214:5060>;tag=sbc08034wcpf732

To: <sip:172.29.33.214>

CSeq: 1 OPTIONS

Max-Forwards: 70

Content-Length: 0

*Feb 27 08:46:11.314: //42815/714FCCBFAFCC/SIP/Msg/ccsipDisplayMsg:

Sent:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKcwsww3878pp1a4ah2s6hp1h2hT29750

From: <sip:172.29.33.214:5060>;tag=sbc08034wcpf732

To: <sip:172.29.33.214>;tag=2E2A6354-19B6

Date: Thu, 27 Feb 2014 08:46:11 GMT

Call-ID: isbca3c3f3455hw6a4a5fs4c1412ss7acfkk@SoftX3000

Server: Cisco-SIPGateway/IOS-15.2.4.M2

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 10

SAB01-VG01-07# 0rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 375

v=0

o=CiscoSystemsSIP-GW-UserAgent 4675 6774 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 0 RTP/AVP 18 0 8 9 4 2 15

c=IN IP4 172.29.33.214

m=image 0 udptl t38

c=IN IP4 172.29.33.214

a=T38FaxVersion:0

a=T38MaxBitRate:9600

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

Manish Prasad · ‎02-27-2014

180 SDP body is ignored and VG is looking for SDP in OK message.

44191/80717D80FE01/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.

But telco is not sending SDP in OK ,thats the reason why call is getting disconnected with the error of missing parameters.

Either You can ask telco to send media in 200OK or don't use media disable 180 and ask telco to look at their media server as its not relaying ringback with180 message.

Rate all useful post

Sent from Cisco Technical Support iPhone App

View solution in original post

Manish Prasad · ‎02-27-2014

Hi,

When you disable early media then ringback tone will be generated locally (through annunciator). Now the call is getting disconnected with error code 96 which indicates missing information from Telco side.

Enable early media and then send across debug ccsip message. We need to compare and find which information is missing in 180 ringing ( with early media disabled logs).

Rate all the helpful post.

Thanks

Manish

marahman78 · ‎02-27-2014

Debugs (Enable early media)

*Feb 27 12:06:10.037: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0508662733@10.205.20.50:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D014C7

Remote-Party-ID: "DMM-4001" <8664001>;party=calling;screen=yes;privacy=off

From: "DMM-4001" <8664001>;tag=2EE17958-1864

To: <0508662733>

Date: Thu, 27 Feb 2014 12:06:10 GMT

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 0013831131-1730998576-4060177414-0169816986

User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1393502770

Contact: <8664001>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 274

v=0

o=CiscoSystemsSIP-GW-UserAgent 3594 3425 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 23718 RTP/AVP 8 101 19

c=IN IP4 172.29.33.214

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

*Feb 27 12:06:10.045: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D014C7

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2EE17958-1864

To: <0508662733>

CSeq: 101 INVITE

Content-Length: 0

*Feb 27 12:06:12.945: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D014C7

Record-Route: <10.205.20.50:5060>

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

CSeq: 101 INVITE

Contact: <0508662733>

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Require: 100rel

RSeq: 1

Content-Length: 191

Content-Type: application/sdp

v=0

o=- 8763785 8763785 IN IP4 10.205.20.50

s=SBC call

c=IN IP4 10.205.20.50

t=0 0

m=audio 44482 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

*Feb 27 12:06:12.945: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D1202A

From: "DMM-4001" <8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

Date: Thu, 27 Feb 2014 12:06:10 GMT

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

CSeq: 102 PRACK

RAck: 1 101 INVITE

Route: <10.205.20.50:5060>

Allow-Events: telephone-event

Max-Forwards: 70

Content-Length: 0

*Feb 27 12:06:12.985: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D1202A

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

CSeq: 102 PRACK

Content-Length: 0

*Feb 27 12:06:15.105: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D014C7

Record-Route: <10.205.20.50:5060>

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

CSeq: 101 INVITE

Contact: <0508662733>

Content-Length: 0

*Feb 27 12:06:15.105: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D21E10

From: "DMM-4001" <8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

Date: Thu, 27 Feb 2014 12:06:10 GMT

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

Route: <10.205.20.50:5060>

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

*Feb 27 12:06:15.145: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:8664001@172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKcwp23a3aa4s71f88c82656h4kT16331

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: <0508662733>;tag=sbc08026s5f7hk1-CC-38

To: "DMM-4001"<8664001>;tag=2EE17958-1864

CSeq: 1 OPTIONS

Accept: application/sdp

Max-Forwards: 70

Content-Length: 0

*Feb 27 12:06:15.145: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKcwp23a3aa4s71f88c82656h4kT16331

From: <0508662733>;tag=sbc08026s5f7hk1-CC-38

To: "DMM-4001" <8664001>;tag=2EE17958-1864

Date: Thu, 27 Feb 2014 12:06:15 GMT

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

Server: Cisco-SIPGateway/IOS-15.2.4.M2

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 484

v=0

o=CiscoSystemsSIP-GW-UserAgent 3594 3425 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 23718 RTP/AVP 8 101 19

c=IN IP4 172.29.33.214

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

m=image 23718 udptl t38

c=IN IP4 172.29.33.214

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

*Feb 27 12:06:18.837: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D3A73

From: "DMM-4001" <8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

Date: Thu, 27 Feb 2014 12:06:15 GMT

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2

Max-Forwards: 70

Route: <10.205.20.50:5060>

Timestamp: 1393502778

CSeq: 103 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=254,OS=40640,PR=295,OR=47200,PL=0,JI=0,LA=0,DU=3

Content-Length: 0

*Feb 27 12:06:18.885: //43639/00D30BDBF201/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6D3A73

Call-ID: 611BA8BC-9EDE11E3-B375A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2EE17958-1864

To: <0508662733>;tag=sbc08026s5f7hk1-CC-38

CSeq: 103 BYE

Content-Length: 0

*Feb 27 12:06:20.661: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKc152s4fp2ks3wp55ff12a3636T22122

Call-ID: isbc5c8a7hwfkh767kk8c3kak1p6fha4kkph@SoftX3000

From: <172.29.33.214:5060>;tag=sbc0804286wapch

To: <172.29.33.214>

CSeq: 1 OPTIONS

Max-Forwards: 70

Content-Length: 0

*Feb 27 12:06:20.661: //43641/6770BFCBB377/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bKc152s4fp2ks3wp55ff12a3636T22122

From: <172.29.33.214:5060>;tag=sbc0804286wapch

To: <172.29.33.214>;tag=2EE1A2D8-51C

Date: Thu, 27 Feb 2014 12:06:20 GMT

Call-ID: isbc5c8a7hwfkh767kk8c3kak1p6fha4kkph@SoftX3000

Server: Cisco-SIPGateway/IOS-15.2.4.M2

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 100

Manish Prasad · ‎02-27-2014

Can you disable early offer and then test a call (keep early media enabled). Send logs in attachment.

voice service voip

no early-offer forced

And also share relevant section of router config (sip and dial-peer ) .

Thanks

Manish

marahman78 · ‎02-27-2014

I disabled early-offer forced and enabled " no disabled-early-media 180". But it didn't work.

Logs:

*Feb 27 13:23:18.333: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0508662733@10.205.20.50:5060 SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F5502

Remote-Party-ID: "DMM-4001" <8664001>;party=calling;screen=yes;privacy=off

From: "DMM-4001" <8664001>;tag=2F28189C-21C5

To: <0508662733>

Date: Thu, 27 Feb 2014 13:23:18 GMT

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 2150311330-2084499760-4244729606-0169816986

User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Max-Forwards: 70

Timestamp: 1393507398

Contact: <8664001>

Expires: 180

Allow-Events: telephone-event

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 273

v=0

o=CiscoSystemsSIP-GW-UserAgent 483 2666 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 23832 RTP/AVP 8 101 19

c=IN IP4 172.29.33.214

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

*Feb 27 13:23:18.341: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F5502

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2F28189C-21C5

To: <0508662733>

CSeq: 101 INVITE

Content-Length: 0

*Feb 27 13:23:21.413: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F5502

Record-Route: <10.205.20.50:5060>

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2F28189C-21C5

To: <0508662733>;tag=sbc0804685h3p87-CC-23

CSeq: 101 INVITE

Contact: <0508662733>

Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER

Require: 100rel

RSeq: 1

Content-Length: 191

Content-Type: application/sdp

v=0

o=- 9223791 9223791 IN IP4 10.205.20.50

s=SBC call

c=IN IP4 10.205.20.50

t=0 0

m=audio 20258 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

*Feb 27 13:23:21.413: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F61831

From: "DMM-4001" <8664001>;tag=2F28189C-21C5

To: <0508662733>;tag=sbc0804685h3p87-CC-23

Date: Thu, 27 Feb 2014 13:23:18 GMT

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

CSeq: 102 PRACK

RAck: 1 101 INVITE

Route: <10.205.20.50:5060>

Allow-Events: telephone-event

Max-Forwards: 70

Content-Length: 0

*Feb 27 13:23:21.473: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F61831

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2F28189C-21C5

To: <0508662733>;tag=sbc0804685h3p87-CC-23

CSeq: 102 PRACK

Content-Length: 0

*Feb 27 13:23:23.833: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F5502

Record-Route: <10.205.20.50:5060>

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: "DMM-4001"<8664001>;tag=2F28189C-21C5

To: <0508662733>;tag=sbc0804685h3p87-CC-23

CSeq: 101 INVITE

Contact: <0508662733>

Content-Length: 0

*Feb 27 13:23:23.833: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F714B3

From: "DMM-4001" <8664001>;tag=2F28189C-21C5

To: <0508662733>;tag=sbc0804685h3p87-CC-23

Date: Thu, 27 Feb 2014 13:23:18 GMT

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

Route: <10.205.20.50:5060>

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: telephone-event

Content-Length: 0

*Feb 27 13:23:23.893: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:8664001@172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bK64p68fss4452pp3p5fpp553s8T41433

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: <0508662733>;tag=sbc0804685h3p87-CC-23

To: "DMM-4001"<8664001>;tag=2F28189C-21C5

CSeq: 1 OPTIONS

Accept: application/sdp

Max-Forwards: 70

Content-Length: 0

*Feb 27 13:23:23.893: //43943/802B25A2FD01/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bK64p68fss4452pp3p5fpp553s8T41433

From: <0508662733>;tag=sbc0804685h3p87-CC-23

To: "DMM-4001" <8664001>;tag=2F28189C-21C5

Date: Thu, 27 Feb 2014 13:23:23 GMT

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

Server: Cisco-SIPGateway/IOS-15.2.4.M2

CSeq: 1 OPTIONS

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Allow-Events: telephone-event

Accept: application/sdp

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Content-Type: application/sdp

Content-Length: 483

v=0

o=CiscoSystemsSIP-GW-UserAgent 483 2666 IN IP4 172.29.33.214

s=SIP Call

c=IN IP4 172.29.33.214

t=0 0

m=audio 23832 RTP/AVP 8 101 19

c=IN IP4 172.29.33.214

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=rtpmap:19 CN/8000

a=ptime:20

m=image 23832 udptl t38

c=IN IP4 172.29.33.214

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:320

a=T38FaxUdpEC:t38UDPRedundancy

*Feb 27 13:23:25.493: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:8664001@172.29.33.214:5060 SIP/2.0

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bK3s4ac1saa5p757148h5586k76T41438

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

From: <0508662733>;tag=sbc0804685h3p87-CC-23

To: "DMM-4001"<8664001>;tag=2F28189C-21C5

CSeq: 2 BYE

Reason: Q.850;cause=16;text="normal call clearing"

Max-Forwards: 70

Content-Length: 0

*Feb 27 13:23:25.493: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.205.20.50:5060;branch=z9hG4bK3s4ac1saa5p757148h5586k76T41438

From: <0508662733>;tag=sbc0804685h3p87-CC-23

To: "DMM-4001" <8664001>;tag=2F28189C-21C5

Date: Thu, 27 Feb 2014 13:23:25 GMT

Call-ID: 27C8EC2B-9EE911E3-B4C1A890-B43FD7C0@172.29.33.214

Server: Cisco-SIPGateway/IOS-15.2.4.M2

CSeq: 2 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=197,OS=31520,PR=204,OR=32640,PL=0,JI=0,LA=0,DU=1

Content-Length: 0

Config:

voice service voip
mode border-element
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
redirect ip2ip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-throug h g711alaw
h323
call preserve limit-media-detection
sip
transport switch udp tcp

dial-peer voice 900 voip
description ** Outgoing >> SIP Trunk **
translation-profile outgoing SIP-OUT
destination-pattern .T
session protocol sipv2
session target ipv4:10.205.20.50:5060
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
dtmf-relay rtp-nte h245-signal h245-alphanumeric

sip-ua
retry invite 3
retry bye 3
retry cancel 3
timers trying 300
sip-server ipv4:10.205.20.50:5060

Nishant Savalia · ‎02-27-2014

Hi,

Just for the knowledge....have you enabled outbound fast start on H323 GW?

Ensure that you have MTP in MRG -----> MRGL----->H.323 GW.
Reset the gateway.

Try above with your default configuration.

And the best part is to replace H.323 GW with the SIP Trunk to avoid H.323-SIP interoperability issue.

Regards,
Nishant Savalia

Regards, Nishant Savalia

marahman78 · ‎02-27-2014

Yes, Outbound fast start is enabled and MTP is also enabled.....There are some security concerns from our SIP provider, that's why we didn't create any SIP Trunk.

Manish Prasad · ‎02-27-2014

In ccsip message, its look like there is no missing information in 180 with disable-early-media.

Can you collect log for all sip message (debug ccsip all) with disable-early-media 180 and attach it please . Need to look at which call leg is creating problem.

Thanks

Manish

marahman78 · ‎02-27-2014

Find the attached (debug ccsip all) logs with disable-early-media 180.

*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x294C228) with key=[40777] to table
*Feb 27 14:41:07.181: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
*Feb 27 14:41:07.181: //-1/000000000000/SIP/Info/ccsip_iwf_init:
*Feb 27 14:41:07.181: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
*Feb 27 14:41:07.181: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
*Feb 27 14:41:07.181: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Error/ccsip_ipip_media_forking_read_from_TDContainer:
MF: Unable to read data from TD Container..
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Error/ccsip_ipip_media_forking_forked_leg_config:
MF: TD container cannot be read/container is NULL. Setting of forked call leg failed..
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_sip_md_snr:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_sip_ed_snr:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_sip_md:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_sip_ed:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/sip_iwf_h323_set_mode_hdlr:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/sip_iwf_h323_set_mode_hdlr: Setting SPI mode to SIP-H323
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_h323_container
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_FLOW_MODE
*Feb 27 14:41:07.181: //44191/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200000
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 0, codec 6 channel 23856, ip 10.10.33.11:23856 params 0x2B34790 caps 0x10012C8
*Feb 27 14:41:07.181: //44191/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200000
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.205.20.50 target_port : 5060

*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [900]
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id AC9F to table
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 44191
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=44190
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Error/ccsip_ipip_media_forking_update_preferred_codec:
MF: Not a Forked SIP leg..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Incoming: No defer BYE for last
call stats
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIGetCallConfig: Initilise the DSCP policy
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/populate_vcc_data: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/preprocessSetup:
This is a not a SIGO Call -, could be DM call
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_INIT_CALL_SETUP
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sip_iwf_h323_init_call_setup_hdlr:
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sip_iwf_h323_copy_channelInfo_to_sdp:
callid 44191, channels 0x15BC09B8 caps 0x10012C8
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_copy_and_init_extended_caps_info: Peer cap provided: callid = 44191, peer dtmf = 0peer t38 version = 0 peer t38 maxBitRate = 14400
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_get_event_data: Ans=0, Mid-call=0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CHNL_IND
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISrtpTranscoder:
Checking if transcoder is needed for SRTP-RTP
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPISrtpTranscoder: Checking if peer leg is SRTP
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIAudioLevelAdjustmentTranscoder: Entry..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIAudioLevelAdjustmentTranscoder: AudioLevelAdjustment do not need xcoder
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_codec_byte_transrating: NOT SIP-SIP CALL. Will be addressed in future.
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPICodecTranscoder: No body else needs xcoder. OFFER_ALL is intact
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_CopyChannelInfoToCodecList: Codec added 6
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Check between Equalset & Subset..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/codec_found: codecs[i] = 6 & codec = 6 are same..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Match found.. continue for next codec..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: This is Subset
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPICodecTranscoder: Codec set type is 2
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/codec_found: codecs[i] = 6 & codec = 6 are same..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIUpdatePreferredCodec: num_chans = 1
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_ResetXcoder: Post to state machine..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 17
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_process_event: IPIP media service in use, deferring event
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_vcc_ResetXcoder: Posting event 188 to peer leg
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Error/sipSPI_ipip_vcc_ResetXcoder:
Failed to post 188 event..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIXcoderNotNeededRevertFlowMode: Xcoder is not needed
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711alaw bw 64000 index 0
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711alaw bw 80000
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIBwCacCalcMaxFaxBandwidth: No T.38 Fax negotiated
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipipms_peer_chnl_ind_hdlr: CHNL IND audio bw 80000 bps video bw 0 bps fax bw 0 bps
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_get_video_channel_index: Peer Video Channel Index = 0, Total=1
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_INIT_XCODER_RESERVED
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_preprocess_call_setup: Container with extended caps previewinfo for peer callid 44190 removed
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:07.181: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.29.33.214
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_add_forking_stream: MF: Not a forked SPI leg..
*Feb 27 14:41:07.181: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_update_forking_stream: MF: Not a forked SPI leg..
SIP: (44191) Group (a= group line) attribute, level 65535 instance 1 not found.
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 23858 for stream 1
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIDoBearerCapToCodecMapping: Bearer capability to Codec Mapping: DISABLED
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIAddSDPPayloadAttributes:
max_event 16
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_IDLE
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIProcessRtpSessions: No active streams.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: peer_callID = 44190
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Error/ccsip_validate_peer_call_state_for_initial_call:
peer ccb is not available
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: call xfer scenario or SIP line, skip peer media_dir : 0
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps accounted bw 0 bps
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/1 bw 80
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 900
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x294C228 key=6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.205.20.50,Port 5060, Transport 1, SentBy Port 5060
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:2A94AA4
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_handle_sent_sdp:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_process_event:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_SENT
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_is_invite_offer_valid: - 1
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_iwf_handle_network_event:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SENT_SDP
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/is_call_leg_do:
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIgetRegistrarHost: registrar is not configured
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not an Forked leg..
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
SIP: (44191) Group (a= group line) attribute, level 65535 instance 1 not found.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPISendInvite: Associated container=0x2A94AA4 to Invite
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: msg=0x100673C, addr=10.205.20.50, port=5060, sentBy_port=0, local_addr=, is_req=1, transport=1, switch=0, callBack=0x88E6C00
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.205.20.50, rport:5060 with laddr:

*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x294C228 with connection=0x15493344 context list
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x100673C
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x100673C, addr=10.205.20.50, port=5060, local_addr=, connId=2 for UDP
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued.
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ADDING
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIAddStream:
set stream_callid from ccb->ccCallID:0xAC9F, media_type:0
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 44191) to the VOIP RTP library
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.29.33.214
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUpdateRtcpSession:
ccb->flags != LOOPBACK

*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 172.29.33.214, lport = 23858, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
src_callid = 44191, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY
media_ip_addr = - , vrf tableid = 0 media_addr_type = 1 negotiated_bandwidth (kbps) = 0
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPICreateRtpSession: sess: 1BC9E10 do_rtcp:0
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Media/sipSPICreateRtpSession: stun is disabled
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUpdateRtcpSession: Not able to Associate DSCP Profile with GCCB dscp_policy = 0x0, IS_SIPSPI_MODE_IN_SIP_SIP = 0 dscpPolicySeviceBlock = 0x0 , stream->qos_info = 0x0
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: Voice quality monitoring is not enabled for this RTP session due to sdp passthru enabled
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=44191
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUpdateRtcpSession:
DTMF inb/oob disabled
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/sipSPIChangeStreamState: Stream (callid = 44191) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIUpdateCallEntry:
Call 44191 set InfoType to SPEECH
*Feb 27 14:41:07.185: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 14 for event 70
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Info/sipSPIResetXcoder: Xcoder resource already cleaned up
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*Feb 27 14:41:07.185: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0508662733@10.205.20.50:5060 SIP/2.0Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F949DRemote-Party-ID: "DMM-4001" <8664001>;party=calling;screen=yes;privacy=offFrom: "DMM-4001" <8664001>;tag=2F6F5654-1392To: <0508662733>Date: Thu, 27 Feb 2014 14:41:07 GMTCall-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214Supported: 100rel,timer,resource-priority,replaces,sdp-anatMin-SE: 1800Cisco-Guid: 2154921344-3092312368-4261507078-0169816986User-Agent: Cisco-SIPGateway/IOS-15.2.4.M2Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTERCSeq: 101 INVITEMax-Forwards: 70Timestamp: 1393512067Contact: <8664001>Expires: 180Allow-Events: telephone-eventContent-Type: application/sdpContent-Disposition: session;handling=requiredContent-Length: 274v=0o=CiscoSystemsSIP-GW-UserAgent 2527 6605 IN IP4 172.29.33.214s=SIP Callc=IN IP4 172.29.33.214t=0 0m=audio 23858 RTP/AVP 8 101 19c=IN IP4 172.29.33.214a=rtpmap:8 PCMA/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-16a=rtpmap:19 CN/8000a=ptime:20
*Feb 27 14:41:07.193: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.205.20.50]:5060, local_address:[ - ]
*Feb 27 14:41:07.193: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 27 14:41:07.193: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Feb 27 14:41:07.193: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 27 14:41:07.193: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 TryingVia: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F949DCall-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214From: "DMM-4001"<8664001>;tag=2F6F5654-1392To: <0508662733>CSeq: 101 INVITEContent-Length: 0
*Feb 27 14:41:07.193: //44191/80717D80FE01/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
*Feb 27 14:41:07.193: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.205.20.50]:5060, local_address:[ - ]
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 RingingVia: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F949DRecord-Route: <10.205.20.50:5060>Call-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214From: "DMM-4001"<8664001>;tag=2F6F5654-1392To: <0508662733>;tag=sbc08045p7cah51-CC-35CSeq: 101 INVITEContact: <0508662733>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFERRequire: 100relRSeq: 1Content-Length: 191Content-Type: application/sdpv=0o=- 7988013 7988013 IN IP4 10.205.20.50s=SBC callc=IN IP4 10.205.20.50t=0 0m=audio 37300 RTP/AVP 8 101a=rtpmap:8 PCMA/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-15
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/ccsip_api_call_alert: SDP in 180 will be ignored if present: No early media cut through
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 900
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 0 bps
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/HandleSIP1xxRinging: ccsip_api_call_alert returned: SIP_SUCCESS
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.205.20.50:5060>
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/sipSPISendPrack: Associated container=0x2A93E9C to Prack
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: msg=0x13A2F264, addr=10.205.20.50, port=5060, sentBy_port=0, local_addr=, is_req=1, transport=1, switch=0, callBack=0x88E7C08
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.205.20.50, rport:5060 with laddr:

*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x294C228 is already on connection=0x15493344 context_list
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x13A2F264
*Feb 27 14:41:10.153: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x13A2F264, addr=10.205.20.50, port=5060, local_addr=, connId=2 for UDP
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/sentPrack: Sent Prack Request, starting PrackTimer
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Info/HandleSIP1xxRinging: Transaction active. Facilities will be queued.
*Feb 27 14:41:10.153: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:0508662733@10.205.20.50:5060;user=phone SIP/2.0Via: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6FA24BFrom: "DMM-4001" <8664001>;tag=2F6F5654-1392To: <0508662733>;tag=sbc08045p7cah51-CC-35Date: Thu, 27 Feb 2014 14:41:07 GMTCall-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214CSeq: 102 PRACKRAck: 1 101 INVITERoute: <10.205.20.50:5060>Allow-Events: telephone-eventMax-Forwards: 70Content-Length: 0
*Feb 27 14:41:10.213: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.205.20.50]:5060, local_address:[ - ]
*Feb 27 14:41:10.213: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 27 14:41:10.213: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Feb 27 14:41:10.213: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK6FA24B from via branch list
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OKVia: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6FA24BCall-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214From: "DMM-4001"<8664001>;tag=2F6F5654-1392To: <0508662733>;tag=sbc08045p7cah51-CC-35CSeq: 102 PRACKContent-Length: 0
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Info/act_recdproc_new_message_response: Transaction Complete. Lock on Facilities released.
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Info/ccsip_api_update_allowed: UPDATE(2) allowed
*Feb 27 14:41:10.213: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_handle_update_allowed:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 17
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: Xcoder resource already cleaned up
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:15BC0DC8
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 183)
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_SET_MODE
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_md_snr:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_ed_snr:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_md:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_ed:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_h32x_in_set_mode:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_h323_in_set_mode:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/sip_iwf_h323_set_mode_hdlr:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/sip_iwf_h323_set_mode_hdlr: Setting SPI mode to SIP-H323
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_h323_container
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_cc_return_code: Mapping SIP_IWF_SUCCESS
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_SET_FLOW_MODE
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_fa2ft_md_flow_mode_transition:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.269: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 27 14:41:12.269: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x2946290) with key=[40778] to table
*Feb 27 14:41:12.269: //-1/000000000000/SIP/Info/ccsip_offer_ans_init:
*Feb 27 14:41:12.269: //-1/000000000000/SIP/Info/ccsip_iwf_init:
*Feb 27 14:41:12.269: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init:
*Feb 27 14:41:12.269: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry...
*Feb 27 14:41:12.269: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
*Feb 27 14:41:12.269: //44193/000000000000/SIP/State/sipSPIChangeState: 0x2946290 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Feb 27 14:41:12.269: //44193/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information
*Feb 27 14:41:12.269: //44193/000000000000/SIP/Error/ccsip_ipip_media_forking_read_from_TDContainer:
MF: Unable to read data from TD Container..
*Feb 27 14:41:12.269: //44193/000000000000/SIP/Error/ccsip_ipip_media_forking_forked_leg_config:
MF: TD container cannot be read/container is NULL. Setting of forked call leg failed..
*Feb 27 14:41:12.269: //44193/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.269: //44193/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_mode_sip_sip_md_snr:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_mode_sip_sip_ed_snr:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_mode_sip_sip_md:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_mode_sip_sip_ed:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/sip_iwf_sip_ed_set_mode_hdlr:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/sip_iwf_sip_ed_set_mode_hdlr: Setting SPI mode to SIP-SIP
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_FLOW_MODE
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_fa2fa_flow_mode_transition:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_ft2fa_flow_mode_transition:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/is_fa2ft_flow_mode_transition:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev:
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 0, codec 6 channel 37278, ip 10.205.20.50:37278 params 0x2B3437C caps 0x2B7CA14
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 1, codec 5 channel 37278, ip 10.205.20.50:37278 params 0x2B3437C caps 0x2B7CA14
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 2, codec 12 channel 37278, ip 10.205.20.50:37278 params 0x2B3437C caps 0x2B7CA14
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 3, codec 0 channel 37278, ip 10.205.20.50:37278 params 0x2B3437C caps 0x2B7CA14
*Feb 27 14:41:12.273: //44193/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.205.20.50 target_port : 5060

*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [2] in dial-peer [900]
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 183)
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_SET_MODE
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_md_snr:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_ed_snr:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_md:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_get_int_type_frm_set_mode_ev:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/is_mode_sip_sip_ed:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sip_iwf_sip_ed_set_mode_hdlr:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sip_iwf_sip_ed_set_mode_hdlr: Setting SPI mode to SIP-SIP
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_cc_return_code: Mapping SIP_IWF_SUCCESS
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_SET_FLOW_MODE
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/is_flow_mode_due_to_set_mode:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sip_iwf_common_defer_event_hdlr:
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id ACA1 to table
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 44193
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_read_peer_config_info: Mid-call Config info for peer leg is 0x0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_read_peer_config_info: Config info for peer leg is 0x0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=44184
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Error/ccsip_ipip_media_forking_update_preferred_codec:
MF: Not a Forked SIP leg..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Incoming: No defer BYE for last
call stats
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIGetCallConfig: Initilise the DSCP policy
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/populate_vcc_data: Using Voice Class Codec, tag = 1 and offer-all is = FALSE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/preprocessSetup:
This is a not a SIGO Call -, could be DM call
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_INIT_CALL_SETUP
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_iwf_sip_ed_init_call_setup_hdlr:
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 27
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_iwf_sip_ed_init_call_setup_hdlr: Trombone service is not active inthis leg
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_iwf_sip_ed_init_call_setup_hdlr: No video caps posted by peer
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_iwf_sip_copy_channelInfo_to_sdp:
callid 44193, channels 0x15A6B890 caps 0x2B7CA14
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_copy_and_init_extended_caps_info: Peer cap provided: callid = 44193, peer dtmf = 6peer t38 version = 0 peer t38 maxBitRate = 14400
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_iwf_sip_copy_channelInfo_to_sdp: received media_direction = 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp:
We are either escalating, orno stream found for this m-line index:1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp:
We are either escalating, orno stream found for this m-line index:1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp:
We are either escalating, orno stream found for this m-line index:1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp:
We are either escalating, orno stream found for this m-line index:1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Ans=0, Mid-call=0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CHNL_IND
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISrtpTranscoder:
Checking if transcoder is needed for SRTP-RTP
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPISrtpTranscoder: Checking if peer leg is SRTP
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAudioLevelAdjustmentTranscoder: Entry..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAudioLevelAdjustmentTranscoder: AudioLevelAdjustment do not need xcoder
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_codec_byte_transrating: codec class not supported in xrating scenario, return FALSE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPICodecTranscoder: No body else needs xcoder. OFFER_ALL is intact
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CopyChannelInfoToCodecList: Codec added 6
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CopyChannelInfoToCodecList: Codec added 5
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CopyChannelInfoToCodecList: Codec added 12
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CopyChannelInfoToCodecList: Codec added 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Check between Equalset & Subset..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 6 & codec = 6 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Match found.. continue for next codec..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 5 & codec = 5 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Match found.. continue for next codec..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 12 & codec = 12 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Match found.. continue for next codec..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 16 & codec = 0 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: Match found.. continue for next codec..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_CheckCodecSetType: This is Equal set
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPICodecTranscoder: Codec set type is 1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 6 & codec = 6 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 5 & codec = 5 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 12 & codec = 12 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found:
Codec to be matched: 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/codec_found: codecs[i] = 16 & codec = 0 are same..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIUpdatePreferredCodec: num_chans = 4
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_ResetXcoder: Post to state machine..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 17
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_process_event: IPIP media service in use, deferring event
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_vcc_ResetXcoder: Posting event 188 to peer leg
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 188)
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: CC_EV_H245_RESET_XCODE_STREAM: peer ID 44193, event = 188 type = 1
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 17
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/sipSPIResetXcoder: Xcoder resource already cleaned up
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*Feb 27 14:41:12.273: //44184/027C87E5B5BA/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIXcoderNotNeededRevertFlowMode: Xcoder is not needed
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711alaw bw 64000 index 0
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711alaw bw 80000
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacCalcMaxFaxBandwidth: No T.38 Fax negotiated
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipipms_peer_chnl_ind_hdlr: CHNL IND audio bw 80000 bps video bw 0 bps fax bw 0 bps
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_get_video_channel_index: Peer Video Channel Index = 0, Total=4
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_INIT_XCODER_RESERVED
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_upd_stats_disc: No defer BYE for last call stats
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_setOptionTagInfo: Option Tag Rel1xx value is 0
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_media_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 172.29.33.214
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_forking_add_forking_stream: MF: Not a forked SPI leg..
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_ipip_media_forking_update_forking_stream: MF: Not a forked SPI leg..
SIP: (44193) Group (a= group line) attribute, level 65535 instance 1 not found.
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 23862 for stream 1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIDoBearerCapToCodecMapping: Bearer capability to Codec Mapping: DISABLED
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAddSDPPayloadAttributes:
max_event 15
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_IDLE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Media/sipSPIProcessRtpSessions: No active streams.
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: peer_callID = 44184
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/ccsip_validate_peer_call_state_for_initial_call: peer_ccb is in STATE_RECD_INVITE state
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_gw_pre_setup_update_stream_media_direction: media direction = 0
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps accounted bw 0 bps
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/1 bw 80
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 80000
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 900
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPI_ipip_set_diversion_header: No diversion header recvd from container
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: ip_best_local_address 172.29.33.214 for SIP
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: return addr 172.29.33.214
*Feb 27 14:41:12.273: //44193/027C87E5B5BA/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 27 14:41:12.273: //-1/xxxxxxxxxxxx/SIP/Info/resolve_sig_ip_address_to_bind: calling reg_invoke_ip_first_hop()
*Feb 27 14:41:12.353: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x29521C0 from the connection=0x15493344 context list
*Feb 27 14:41:12.353: //44184/027C87E5B5BA/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Feb 27 14:41:12.353: //44184/027C87E5B5BA/SIP/Info/ccsip_offer_ans_delete:
*Feb 27 14:41:12.353: //44184/027C87E5B5BA/SIP/Info/ccsip_iwf_delete:
*Feb 27 14:41:12.353: //44184/027C87E5B5BA/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x29521C0
*Feb 27 14:41:12.353: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[40775]
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.205.20.50]:5060, local_address:[ - ]
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OKVia: SIP/2.0/UDP 172.29.33.214:5060;branch=z9hG4bK6F949DRecord-Route: <10.205.20.50:5060>Call-ID: 6A3C38D-9EF411E3-B5C6A890-B43FD7C0@172.29.33.214From: "DMM-4001"<8664001>;tag=2F6F5654-1392To: <0508662733>;tag=sbc08045p7cah51-CC-35CSeq: 101 INVITEContact: <0508662733>Content-Length: 0
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIhandle200OKInvite: Transaction active. Facilities will be queued.
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIhandle200OKInvite: *** This ccb is the parent
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_iwf_handle_generic_event:
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_iwf_process_event:
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_h323_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_CALL_BRIDGE
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentSDP: No SDP found in inbound container
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Error/ccsip_api_call_connect_media:
Destination SDP Body unavailable
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:96, category:96
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[44191], src[6]
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIhandle200OKInvite: ccsip_api_call_connect_media returned: SIP_INTERNAL_ERR
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(96) for outgoing call
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG:
No Inbound Container Created !!!
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931:
No Inbound Container Created !!!
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_handle_call_active:
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_process_event:
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.205.20.50:5060>
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPISendAck: Associated container=0x2A93FFC to Ack
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: msg=0x2B8B570, addr=10.205.20.50, port=5060, sentBy_port=0, local_addr=, is_req=1, transport=1, switch=0, callBack=0x0
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.205.20.50, rport:5060 with laddr:

*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x294C228 is already on connection=0x15493344 context_list
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2B8B570
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2B8B570, addr=10.205.20.50, port=5060, local_addr=, connId=2 for UDP
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIHandle200OKforinviteFailure: Sent ACK for 2xx Response in Error Case
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddRouteHeaders: status = TRUE Route: <10.205.20.50:5060>
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPISendBye: Associated container=0x2A95B24 to Bye
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: msg=0x15AEC31C, addr=10.205.20.50, port=5060, sentBy_port=0, local_addr=, is_req=1, transport=1, switch=0, callBack=0x88E8184
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.205.20.50, rport:5060 with laddr:

*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x294C228 is already on connection=0x15493344 context_list
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x15AEC31C
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x15AEC31C, addr=10.205.20.50, port=5060, local_addr=, connId=2 for UDP
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/State/sipSPIChangeState: 0x294C228 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_call_statistics: Requesting stats for callid=44191
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_call_statistics: Stats request failed for callid=44191, dstCallID=-1, rc=-7
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 27 14:41:13.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
*Feb 27 14:41:13.033: //44191/80717D80FE01/SIP/Info/sipSPIBwCacReleaseDialPeerBw: bwcac dial-peer bw 80 Kbps released for tag 900 active bw 0 Kbps
*Feb 27 14:41:13.073: //44191/80717D80FE01/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x294C228
*Feb 27 14:41:13.073: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[40777]
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id AC97
*Feb 27 14:41:15.441: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[40774] removed.
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2958158 key=isbc2wa2ks7871pw6cawch8p825147p31828@SoftX3000
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 27 14:41:15.441: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x15492A4C, addr=10.205.20.50, port=5060, local_addr=, unregistering context=0x2958158
*Feb 27 14:41:15.441: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x2958158 from the connection=0x15493344 context list
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/ccsip_offer_ans_delete:
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/ccsip_iwf_delete:
*Feb 27 14:41:15.441: //44183/F87C1AC0B5B9/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x2958158

Manish Prasad · ‎02-27-2014

180 SDP body is ignored and VG is looking for SDP in OK message.

44191/80717D80FE01/SIP/Info/ccsip_api_call_alert: SDP Body either absent or ignored in 180 RINGING:- will wait for 200 OK to do negotiation.

But telco is not sending SDP in OK ,thats the reason why call is getting disconnected with the error of missing parameters.

Either You can ask telco to send media in 200OK or don't use media disable 180 and ask telco to look at their media server as its not relaying ringback with180 message.

Rate all useful post

Sent from Cisco Technical Support iPhone App

marahman78 · ‎03-01-2014

Thanks Manish... I'll check with our telco...