cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2772
Views
0
Helpful
5
Replies

Two Cisco CME and Call transfers between sites are not working.

viki
Level 1
Level 1

Call transfer are not working between  two sites while blind transfers are working fine .

I mean Call transfer like “Attn Transfer—Attended call transfer. The user answers the call before

transferring it”

and

Blind Transfer like “Blind call transfer. The user transfers the call without speaking to the caller”

I am running two CME connected together via GRE tunell. I’m using Linksys SPA922,942 phone on both sites.

Site A is C2621XM 12.4(15)T10 – CME:4.1 IP: 10.0.1.4/24 numbers 150x 

Site B is C2811 12.4(23)T2 – CME:7.1         IP: 10.10.1.4/24 numbers 155x

Sites are connected via GRE tunnel,  and voice traffic is handling by

dial-peer voice 300 voip

description ** Internal call TO_OTHER_SITES via tunel **

destination-pattern 150T  (or 155T to other)

voice-class codec 1

voice-class sip dtmf-relay force rtp-nte

session protocol sipv2

session target ipv4:10.0.1.4  (on other side this is 10.10.1.4)

I can make basic calls between site using short numbers 15xx or normal long numbers 2234615xx

Examples:

1 : Site A  ext. 1501 ---->  Site B ext.1551  this is OK

2:  Site A ext. 1501 ----> calling siteB ext.1551 (configured redirect to site B 1552) this is OK

3: SiteA ext.1501 -à calling siteB ext. 1551 (configured redirect to site A 1502) this is OK

4: Site A ext.1501à calling siteB ext 1551, phone 1551 is answered , conversations begin, than receptionist needs to transfer this call back to site A to 1552 without attendant (Blind Transfer) - this is OK

But when I want to use Transfer (Attenden Transfer) between two site this is not working. (but they are working inside site)

5: Site A ext 1501 -> calling siteB ext 1551, phone 1551 is answered, conversations begin, than receptionist needs to transfer this call back to site A witho announcement – this is FAILD

Configuration on both side are exactly the same.

SITEA:

voice service voip

allow-connections h323 to h323

allow-connections sip to sip

supplementary-service h450.12

no supplementary-service sip moved-temporarily

no supplementary-service sip refer

fax protocol cisco

sip     

  registrar server expires max 3600 min 120

!        

!        

!        

voice class codec 1

codec preference 1 g729r8

codec preference 2 g729br8

voice register global

mode cme

source-address 10.10.1.4 port 5060

max-dn 20

max-pool 20

dialplan-pattern 1 2234615.. extension-length 4 no-reg

file text

create profile sync 0304593554212627

!        

voice register dn  10

number 1550

no-reg  

!        

voice register dn  11

number 1551

no-reg  

voice register pool  10

id mac 000E.083C.3BE0

number 1 dn 10

voice-class codec 1

username 1550 password password

!        

voice register pool  11

id mac 000E.083C.3BE0

number 1 dn 11

voice-class codec 1

username 1551 password password

In my opinion this is relate to

supplementary-service h450.12

no supplementary-service sip moved-temporarily

no supplementary-service sip refer

but I couldn’t figure it out.

If need any external debugs, lets me know.

Here is how dial-peers are dynamic created example from site B. (similar to A)

dial-peer hunt 0

             AD                                    PRE PASS                OUT

TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT

100    voip  up   up                                0  syst sip-server         

200    voip  up   up             0T                 0  syst sip-server         

300    voip  up   up             150T           0  syst ipv4:10.0.1.4      

101    voip  up   up                                0  syst                    

102    voip  up   up                                0  syst                    

40001  voip  up   up             1551               0  syst ipv4:10.10.1.2:5061

40002  voip  up   up             223461551          0  syst ipv4:10.10.1.2:5061

40003  voip  up   up             1550               0  syst ipv4:10.10.1.2:5060

40004  voip  up   up             223461550          0  syst ipv4:10.10.1.2:5060

40005  voip  up   up             1552               0  syst ipv4:10.10.1.3:5060

40006  voip  up   up             223461552          0  syst ipv4:10.10.1.3:5060

5 Replies 5

jfernandorosa
Level 1
Level 1

Hi Viki,

You could make the transfer before and stopped working? Or has ever been possible?

Regards.

John.

No, from the beginning this is not possible. This is totally new project.

In meanwhile I changed CME4,1 to CME7.1 and there is little progress. I can make Transfers between sites, but I can hear only in one direction.

When I try to do Blind Transfer between sites - this works but after 30seconds connection is dropped. I tested few different phones (SIP LINKSYS) and problem is exactly the same. Andy ideas what kind for debugs can show me where the problem might be ?

Viki,

Enable debug a ccsip all, and initiate a call between the CME, and take me to collect the logs stating the numbers of origin and destination.

The problem may have several origins, we will try to filter.

Regards.

John

Please find followed debg. I'm calling from 1501 to 1500 then press (TRANSFER) to 1502. attend the person and again TRANSFER (so this is attended transfer)

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2011.02.16 16:41:16 =~=~=~=~=~=~=~=~=~=~=~=
lab1#sh dial-peer voice summary
dial-peer hunt 0
             AD                                    PRE PASS                OUT
TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT
100    voip  up   up                                0  syst sip-server         
200    voip  up   up             0T                 0  syst sip-server         
300    voip  up   up             2234615T           0  syst ipv4:10.10.1.4     
301    voip  up   up             15..               0  syst ipv4:10.10.1.4     
40005  voip  up   up             1501               0  syst ipv4:10.0.1.1:5062 
40007  voip  up   up             1502               0  syst ipv4:10.0.1.130:5060
40003  voip  up   up             1500               0  syst ipv4:10.0.1.142:5060
40001  voip  up   up             1501               0  syst ipv4:10.0.1.2:5060 
40004  voip  up   up             223461500          0  syst ipv4:10.0.1.142:5060
40006  voip  up   up             223461501          0  syst ipv4:10.0.1.1:5062       <<------- THIS is old one. shoud expire
40002  voip  up   up             223461501          0  syst ipv4:10.0.1.2:5060 
40008  voip  up   up             223461502          0  syst ipv4:10.0.1.130:5060
lab1#
lab1#
lab1#
lab1#
lab1#ter m,   on
% Console already monitors
lab1#debn
lab1#debn  
lab1#debug cs
lab1#debug csi
lab1#debug csi
lab1#debug csi         acc
lab1#debug access-expression                                                                         deb
lab1#debug ccs
lab1#debug ccsi
lab1#debug ccsip ?
  all        Enable all SIP debugging traces
  calls      Enable CCSIP SPI calls debugging trace
  error      Enable SIP error debugging trace
  events     Enable SIP events debugging trace
  info       Enable SIP info debugging trace
  media      Enable SIP media debugging trace
  messages   Enable CCSIP SPI messages debugging trace
  preauth    Enable SIP preauth debugging traces
  states     Enable CCSIP SPI states debugging trace
  transport  Enable SIP transport debugging traces

lab1#debug ccsip all
This may severely impact system performance. Continue? [confirm]
All SIP Call tracing is enabled
lab1#
*Feb 16 16:07:22.799: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:07:22.799: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:22.799: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:22.799: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:1500@10.0.1.4 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-e614a76d

From: "K:1552" <1501>;tag=20bdafd57c91432do0

To: "K:1500" <1500>

Call-ID: 3328af5-1d1d228d@10.0.1.2: 101 INVITE

CSeq

Max-Forwards: 70

Contact: "K:1552" <1501>

Expires: 240

User-Agent: Linksys/SPA922-5.2.8

Content-Length: 385

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: replaces

Content-Type: application/sdp

v=0

o=- 34431 34431 IN IP4 10.0.1.2

s=-

c=IN IP4 10.0.1.2

t=0 0

m=audio 16460 RTP/AVP 18 8 0 2 4 96 97 98 101

a=rtpmap:18 G729a/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:2 G726-32/8000

a=rtpmap:4 G723/8000

a=rtpmap:96 G726-40/8000

a=rtpmap:97 G726-24/8000

a=rtpmap:98 G726-16/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=ptime:30

a=sendrecv


*Feb 16 16:07:22.799: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x47D5E7DC60
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x45D30224) found for sip_user: 1500
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x47D5E7DC key=3328af5-1d1d228d@10.0.1.21500) with key=[31] to table
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:07:22.803: //-1/AC434346803C/SIP/State/sipSPIChangeState: 0x47D5E7DC : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 16 16:07:22.803: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 50
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 1500
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 223461501
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name K:1552, number 223461501, Calling oct3 0x00, oct_3a 0x80, Called number 1500
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 223461501
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIGetCallConfig: Peer tag 40006 matched for incoming call
*Feb 16 16:07:22.807: //-1/AC434346803C/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 16 16:07:22.811: //-1/AC44346803C/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled34346803C/SIP/Info/sipSPIGetCallConfig: Using Voo/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
*Feb 16 16:07:22.811: //35/AC434346803C/SIP/Info/sipSPIProcessReplacesHeader: No replaces hdr found
*Feb 16 16:07:22.811: //35/AC434346803C/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
SIP: (35) Attribute mid, level 1 instance 1 not found.
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4ice Class Codec, tag = 1
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled
*Feb 16 16:07:22.811: //-1/AC43
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIContinueNewMsgInvite: Calling name K:1552, number 223461501, Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called number 1500, oct3 0x00
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Feb 16 16:07:22.811: //-1/AC434346803C/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 16 16:07:22.811: //35/AC434346803C/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
*Feb 16 16:07:22.811: //35/AC434346803C/SIP/Inf
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPISelectCodecVersion: g729br8 flavor of g729 codec will be used
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIDoAudioNegotiation: Codec (g729br8) Negotiation Successful on Static Payload for m-line 1
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIDoPtimeNegotiation: One ptime attribute found - value:30
*Feb 16 16:07:22.815: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g729br8 ptime :30, codecbytes: 30
*Feb 16 16:07:22.815: //-1/xxxxxxxxxxxx/SIP/Info/convert_cod//35/AC434346803C/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=18, codec_bytes=20, codec=g729br8, dtmf_relay=inband-voice
stream_type=voice-only (0), dest_ip_address=10.0.1.2, dest_port=16460ec_bytes_to_ptime: Values :Codec: g729br8 codecbytes :20, ptime: 20
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:30, Negotiated ptime:20 Negotiated codec bytes: 20 for codec g729br8
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMF-RELAY option list!
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPIStreamTypeAndDtmfRelay: DTMF Relay mode: Inband Voice
nband-voice
Preferred NTE payload  : 101
Early Media            : No
Delayed Media          : No
Bridge Done            : No
New Media              : No
DSP DNLD Reqd          : No

*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr*Feb 16 16:07:22.815: //-1/xxxxxxxxxxxx/SIP/Info1/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
*Feb 16 16:07:22.815:
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/State/sipSPIChangeStreamState: Stream (callid =  -1)  State changed from (STREAM_DEAD) to (STREAM_ADDING)
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec        : g729r8, bytes :20
Preferred  DTMF relay  : i
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 35 peer 0 flags 0x201 state STATE_IDLE
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 35, sdp 0x4885C74C channels 0x47D5FA7C
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/copy_channels:
callId 35 size 0 ptr 0x47A740A4)
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Media/sipSPISelectCodecVersion: g729br8 flavor of g729 codec will be used
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g729br8
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 12

*Feb 16 16:07:22.815: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :CodecelInfo:
failed to update call entry
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 12 ptype 18 time 20, bytes 20  as channel 0 mline 1 ss 1 10.0.1.2:16460
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1: g729br8 codecbytes :20, ptime: 20
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=20,stream->negotiated_codec_bytes=20, coverted ptime=20 stream->mline_index=1, media_ndx=1
*Feb 16 16:07:22.815: //35/AC434346803C/SIP/Error/sipSPI_ipip_copy_sdp_to_chann
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 6

*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:07:22.819: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711alaw ptime :30, codecbytes: 240
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 6 ptype 8 time 30, bytes 240  as channel 1 mline 1 ss 1 10.0.1.2:16460
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 5

*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:07:22.819: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :30, codecbytes: 240
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 30, bytes 240  as channel 2 mline 1 ss 1 10.0.1.2:16460
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 2 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g726r32
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 4
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 4 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Media/sipSPISelectCodecVersion: Codec (No Codec   ) is not in preferred list
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact codec match not configured, using interoperable codec g723r63
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g723r63
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 8

*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:07:22.819: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g723r63 ptime :30, codecbytes: 24
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 8 ptype 4 time 30, bytes 24  as channel 3 mline 1 ss 1 10.0.1.2:16460
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 96 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 97 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 3
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 98 mline 1
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 2

*Feb 16 16:07:22.819: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:07:22.819: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g726r16 ptime :30, codecbytes: 60
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 2 ptype 98 time 30, bytes 60  as channel 4 mline 1 ss 1 10.0.1.2:16460
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 6

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 5

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 8
_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 12

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 2

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = -1

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 35 flags 0x100 state STATE_IDLE
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer: ccb->flags 0xC, ccb->pld.flags_ipip 0x201

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/copy_channels:
callId 35 size 1152 ptr 0x476BF060)
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer:
CCSIP: Unable to report channel ind
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/ccsip_update_srtp_caps:  5054: Posting Remote SRTP caps to other callleg.
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPI_ipip_report_media_to_peer: do cc_api_caps_ind()
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Media/sipSPIUpdCallWithSdpInfo:
   Stream type            : voice-only
   Media line             : 1
   State                  : STREAM_ADDING (2)
   Stream address type    : 1
   Callid                 : -1
   Negotiated Codec       : g729br8, bytes :20
   Nego. Codec payload    : 18 (tx), 18 (rx)
   Negotiated DTMF relay  : inband-voice
   Negotiated NTE payload : 0 (tx), 0 (rx)
   Negotiated CN payload  : 0
   Media Srce Addr/Port   : [10.0.1.4]:0
   Media Dest Addr/Port   : [10.0.1.2]:16460

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPIHandleInviteMedia:
Negotiated Codec       : g729br8, bytes :20
Preferred Codec        : g729r8, bytes :20
Preferred  DTMF relay 1 : 0
Preferred  DTMF relay 2 : 0
Negotiated DTMF relay   : 0
Preferred and Negotiated NTE payloads: 101 0
Preferred and Negotiated NSE payloads: 100 0
Preferred and Negotiated Modem Relay: 0 0
Preferred and Negotiated Modem Relay GwXid: 1 0

*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: Entry
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPIDoStreamQoSNegotiation: Best effort
*Feb 16 16:07:22.823: //35/AC434346803C/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 16 16:07:22.823: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18254 for stream 1
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=18254
*Feb 16 16:07:22.827: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 18254
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIUpdateSrcSdpVariablePart:
SIP update src sdp, negoitated codec 12, payload type 18

*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 3328af5-1d1d228d@10.0.1.2
*Feb 16 16:07:22.827: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 0,
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIShrlCall: Check peer: 40006 for Shared-Line call, callid: 35
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/ccsip_set_bearer_capability:
   Bearer Capability: Speech (0x00)
*Feb 16 16:07:22.827: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Feb 16 16:07:22.827: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Feb 16 16:07:22.827: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
*Feb 16 16:07:22.827: //35/AC434346803C/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 23 to table
*Feb 16 16:07:22.831: //35/AC434346803C/SIP/Transport/sipSPITransportSendMessage: msg=0x4885C7A0, addr=10.0.1.2, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x00000000
*Feb 16 16:07:22.831: //35/AC434346803C/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 16 16:07:22.831: //35/AC434346803C/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 16 16:07:22.831: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4885C7A0, addr=10.0.1.2, port=5060, connId=0 for UDP
*Feb 16 16:07:22.831: //35/AC434346803C/SIP/State/sipSPIChangeState: 0x47D5E7DC : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_NONE)
*Feb 16 16:07:22.831: //35/AC434346803C/SIP/Info/sipSPIProcessContactInfo: Previous Hop 10.0.1.2:5060
*Feb 16 16:07:22.835: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*Feb 16 16:07:22.839: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x47D63A98) with key=[32] to table
*Feb 16 16:07:22.839: //36/000000000000/SIP/State/sipSPIChangeState: 0x47D63A98 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 0, codec 12 channel 16460, ip 10.0.1.2:16460  params 0x4883BA18 caps 0x483E46F4
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 1, codec 6 channel 16460, ip 10.0.1.2:16460  params 0x4883BA18 caps 0x483E46F4
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 2, codec 5 channel 16460, ip 10.0.1.2:16460  params 0x4883BA18 caps 0x483E46F4
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 3, codec 8 channel 16460, ip 10.0.1.2:16460  params 0x4883BA18 caps 0x483E46F4
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 4, codec 2 channel 16460, ip 10.0.1.2:16460  params 0x4883BA18 caps 0x483E46F4
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 16 16:07:22.839: //36/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 16 16:07:22.839: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.0.1.142 target_port : 5060

*Feb 16 16:07:22.839: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 16 16:07:22.839: //36/AC434346803C/SIP/Info/ccsip_call_setup_request: Incrementing call counter in dial-peer [40003]
*Feb 16 16:07:22.839: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: 
*Feb 16 16:07:22.839: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 162)
*Feb 16 16:07:22.839: //35/AC434346803C/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 36 chans 0x48772870 event 162 flags 0x40001C 0x100 0x601 data 0x48772870
*Feb 16 16:07:22.839: //35/AC434346803C/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 36 chans 0x48772870 event 162 flags 0x40001C 0x100 0x601 data 0x48772870, type = 1
*Feb 16 16:07:22.839: //35/AC434346803C/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 16 16:07:22.843: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 16 16:07:22.843: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
*Feb 16 16:07:22.843: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 24 to table
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/preprocessSetup:
This is a not a SIGO Call -, could be DM call
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_call_setup: No video caps posted by peer
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_call_setup: xcoder high-density disabled
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_call_setup: Flow Mode set to FLOW_THROUGH
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 36, channels 0x48328CC8 caps 0x483E46F4
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: Peer cap provided: callid = 36, peer dtmf = 0
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: callid = 36, peer not doing RFC2833, peer dtmf = 0, enable NTE_ASSUMED
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.843: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4
*Feb 16 16:07:22.847: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 17762 for stream 1
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPISelectDynamicPayload: Unused Dynamic Payload Mask = D10BFFCC
*Feb 16 16:07:22.847: //36/AC434346803C/SIP/Info/sipSPISelectDynamicPayload: Selecting Dynamic Payload : 98 for Codec: g726r16
*Feb 16 16:07:22.851: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Media/sipSPIProcessRtpSessions: No active streams.
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPIShrlGetInstanceInfo: Obtained the call instance 0 for non-shared-line '1500' with callid: 36
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPI_ipip_set_diversion_header: No diversion header recvd from container
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/act_idle_continue_call_setup:
*Feb 16 16:07:22.851: //36/AC434346803C/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 16 16:07:22.855: //36/AC434346803C/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.
*Feb 16 16:07:22.855: //36/AC434346803C/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x47D63A98 key=AC4A9657-391D11E0-80429854-7A1AA247@10.0.1.4
*Feb 16 16:07:22.855: //36/AC434346803C/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = AC4A9657-391D11E0-80429854-7A1AA247@10.0.1.4
*Feb 16 16:07:22.855: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.142,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:07:22.855: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1297872442

Contact: <1501>

Expires: 180

Allow-Events: telephone-event

Max-Forwards: 69

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 347

v=0

o=CiscoSystemsSIP-GW-UserAgent 6007 919 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 17762 RTP/AVP 18 8 0 4 98 19

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:4 G723/8000

a=fmtp:4 bitrate=6.3;annexa=no

a=rtpmap:98 G726-16/8000

a=rtpmap:19 CN/8000


*Feb 16 16:07:23.383: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:23.383: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:23.383: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:23.383: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:23.387: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-e614a76d

From: "K:1552" <1501>;tag=20bdafd57c91432do0

To: "K:1500" <1500>;tag=BC4CC-203A

Date: Wed, 16 Feb 2011 16:07:22 GMT

Call-ID: 3328af5-1d1d228d@10.0.1.2: 101 INVITE

CSeq

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

Allow-Events: telephone-event

Contact: <1500>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:07:24.591: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:24.591: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:24.591: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:24.591: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:24.595: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 203

v=0

o=CiscoSystemsSIP-GW-UserAgent 7203 3261 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 18254 RTP/AVP 18

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=ptime:20


*Feb 16 16:07:25.199: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:07:25.203: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:25.203: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:27.247: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:27.247: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:27.247: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:27.247: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-53e033b4

From: <1500>;tag=cd7594a93cb48673i0

To: "K:1552" <1501>;tag=BC2AC-54B

Date: Wed, 16 Feb 2011 16:07:27 GMT

Call-ID: AC4A9657-391D11E0-80429854-7A1AA247@10.0.1.4: 101 INVITE

CSeq

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:07:27.367: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:07:27.367: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:27.367: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:27.367: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:27.371: //35/AC434346803C/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x47D5E7DC
*Feb 16 16:07:27.371: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
PGateway/IOS-12.x

Content-Type: application/sdp

Content-Length: 214

v=0

o=CiscoSystemsSIP-GW-UserAgent 6007 920 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 17762 RTP/AVP 18

c=IN IP4 10.0.1.4

a=recvonly

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=ptime:20


*Feb 16 16:07:27.931: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:27.935: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:27.935: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:29.627: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:29.627: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:29.627: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:29.627: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:1502@10.0.1.4 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-3da4ea74

From: "K:1500" <1500>;tag=54b38c67df91c46co0

To: "T:1502" <1502>

Call-ID: 68d88c43-4d8f6640@10.0.1.142: 101 INVITE

CSeq

Max-Forwards: 70

Contact: "K:1500" <1500>

Expires: 240

User-Agent: Linksys/SPA962-6.1.5(a)

Content-Length: 389

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: replaces

Content-Type: application/sdp

v=0

o=- 35624 35, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1297872449

Contact: <1500>

Expires: 180

Allow-Events: telephone-event

Max-Forwards: 69

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 348

v=0

o=CiscoSystemsSIP-GW-UserAgent 8956 8507 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 19418 RTP/AVP 18 8 0 4 98 19

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:4 G723/8000

a=fmtp:4 bitrate=6.3;annexa=no

a=rtpmap:98 G726-16/8000

a=rtpmap:19 CN/8000

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-3da4ea74

From: "K:1500" <1500>;tag=54b38c67df91c46co0

To: "T:1502" <1502>;tag=BE00C-12A2

Date: Wed, 16 Feb 2011 16:07:29 GMT

Call-ID: 68d88c43-4d8f6640@10.0.1.142: 101 INVITE

CSeq

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

Allow-Events: telephone-event

Contact: <1502>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:07:31.423: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.130]:5060
*Feb 16 16:07:31.479: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:31.479: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:31.479: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:31.479: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

*Feb 16 16:07:31.483: //38/B055227C8043/SIP/Info/sipSPICreateAndStartRtpTimer: Media Inactivity Timer is disabled.
*Feb 16 16:07:31.483: //38/B055227C8043/SIP/Info/sipSPIProcessHoldTimerForCall: Media IP Addr 10.0.1.142, RTCP Type 3
*Feb 16 16:07:31.483: //38/B055227C8043/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:07:33.571: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:07:33.571: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:33.571: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:33.571: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
REFER sip:1501@10.0.1.4:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-21234aa7

From: <1500>;tag=cd7594a93cb48673i0

To: "K:1552" <1501>;tag=BC2AC-54B

Referred-By: "K:1500" <1500>

Call-ID: AC4A9657-391D11E0-80429854-7A1AA247@10.0.1.4: 102 REFER

CSeq

Max-Forwards: 70

Contact: "K:1500" <1500>

Refer-To: <1502>

User-Agent: Linksys/SPA962-6.1.5(a)

Content-Length: 0


*Feb 16 16:07:33.571: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:33.695: //36/AC434346803C/SIP/Info/ccsip_qos_cleanup: Entry
*Feb 16 16:07:33.695: //36/AC434346803C/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Feb 16 16:07:33.695: //36/AC434346803C/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 47D63A98
*Feb 16 16:07:33.699: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[32]
*Feb 16 16:07:39.443: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.130]:5060
*Feb 16 16:07:39.443: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:39.443: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:39.443: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:1500@10.0.1.4:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.130:5060;branch=z9hG4bK-736de791

From: <1502>;tag=ab00cd41dcef458i0

To: "K:1500" <1500>;tag=BDD54-1397

Call-ID: B05C74F4-391D11E0-80499854-7A1AA247@10.0.1.4: 101 BYE

CSeq

Max-Forwards: 70

User-Agent: Linksys/SPA922-5.2.8

Content-Length: 0


*Feb 16 16:07:39.443: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:39.447: //39/B055227C8043/SIP/Info/sipSPICheckFromToRequest: Found matching CB 47D6E010
*Feb 16 16:07:39.447: //39/B055227C8043/SIP/Info/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table

*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.130,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.130,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[39], src[4]
*Feb 16 16:07:39.447: //39/B055227C8043/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 16 16:07:39.447: //39/B055227C8043/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for outgoing call
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Feb 16 16:07:39.447: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Feb 16 16:07:39.447: //39/B055227C8043/SIP/State/sipSPIChangeState: 0x47D6E010 : State change from (STATE_ACTIVE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 16 16:07:39.451: //35/AC434346803C/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:48405C9C
*Feb 16 16:07:39.451: //39/B055227C8043/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:486C23D4
*Feb 16 16:07:39.451: //35/AC434346803C/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
*Feb 16 16:07:39.451: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 16 16:07:39.451: //39/B055227C8043/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
*Feb 16 16:07:39.451: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 16 16:07:39.451: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 16 16:07:39.451: //35/AC434346803C/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:07:39.451: //35/AC434346803C/SIP/Info/sipSPIPushOrigRequestContainerIntoHolder: Request Container Holder is above threshold...trimming
*Feb 16 16:07:39.451: //35/AC434346803C/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:07:39.455: //35/AC434346803C/SIP/Info/sipSPISendBye: Associated container=0x4883AEA0 to ByeSIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.1.130:5060;branch=z9hG4bK-736de791

From: <1502>;tag=ab00cd41dcef458i0

To: "K:1500" <1500>;tag=BDD54-1397

Date: Wed, 16 Feb 2011 16:07:39 GMT

Call-ID: B05C74F4-391D11E0-80499854-7A1AA247@10.0.1.4: Cisco-SIPGateway/IOS-12.x

Server

CSeq: 101 BYE

Reason: Q.850;cause=16

Content-Length: 0


*Feb 16 16:07:40.031: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:07:40.031: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:07:40.031: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:07:40.031: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:07:40.031: //35/AC434346803C/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x47D5E7DC
*Feb 16 16:07:40.035: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

*Feb 16 16:08:08.871: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing: Holder=0x4874899C Connection=0x46FB6F80, addr=0.0.0.1, port=167772418, connid=0 has been REFRESHED
*Feb 16 16:08:08.871: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x4874899C,addr=10.0.1.2
*Feb 16 16:08:11.459: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[34] removed.
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x47D6E010 key=B05C74F4-391D11E0-80499854-7A1AA247@10.0.1.4
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPIStopRequestPendingTimer: Stopping Request Pending Timer
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/ccsip_qos_cleanup: Entry
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Feb 16 16:08:11.459: //39/B055227C8043/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 47D6E010
*Feb 16 16:08:12.699: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing: Holder=0x48185C10 Connection=0x4755A4D8, addr=0.0.0.1, port=167772546, connid=0 has been REFRESHED
*Feb 16 16:08:12.699: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x48185C10,addr=10.0.1.130
lab1#
lab1#
lab1#
lab1#
lab1#no deb
lab1#no debug all
All possible debugging has been turned off
lab1#
*Feb 16 16:08:22.095: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceHandleConnAgeing: Holder=0x4842D5AC Connection=0x4762F610, addr=0.0.0.1, port=167772558, connid=0 has been REFRESHED
*Feb 16 16:08:22.095: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportSetAgeingTimer: Aging timer initiated for holder=0x4842D5AC,addr=10.0.1.142

********************************************************************************

And to comprare this is tranfser without attend (blind transfer)

lab1#
lab1#
lab1#
lab1#debug ccsip all               no debug all                  debug ccsip all               ter mon                       sh dial-peer voice summary
dial-peer hunt 0
             AD                                    PRE PASS                OUT
TAG    TYPE  MIN  OPER PREFIX    DEST-PATTERN      FER THRU SESS-TARGET    STAT PORT
100    voip  up   up                                0  syst sip-server         
200    voip  up   up             0T                 0  syst sip-server         
300    voip  up   up             2234615T           0  syst ipv4:10.10.1.4     
301    voip  up   up             15..               0  syst ipv4:10.10.1.4     
40005  voip  up   up             1501               0  syst ipv4:10.0.1.1:5062 
40007  voip  up   up             1502               0  syst ipv4:10.0.1.130:5060
40003  voip  up   up             1500               0  syst ipv4:10.0.1.142:5060
40001  voip  up   up             1501               0  syst ipv4:10.0.1.2:5060 
40004  voip  up   up             223461500          0  syst ipv4:10.0.1.142:5060
40006  voip  up   up             223461501          0  syst ipv4:10.0.1.1:5062 
40002  voip  up   up             223461501          0  syst ipv4:10.0.1.2:5060 
40008  voip  up   up             223461502          0  syst ipv4:10.0.1.130:5060
lab1#
lab1#
lab1#
lab1#
lab1#
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:1500@10.0.1.4 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-a16f35c

From: "K:1552" <1501>;tag=651fc9f3f22ac44o0

To: "K:1500" <1500>

Call-ID: 58a9b2df-ed11f848@10.0.1.2: 101 INVITE

CSeq

Max-Forwards: 70

Contact: "K:1552" <1501>

Expires: 240

User-Agent: Linksys/SPA922-5.2.8

Content-Length: 385

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: replaces

Content-Type: application/sdp

v=0

o=- 95004 95004 IN IP4 10.0.1.2

s=-

c=IN IP4 10.0.1.2

t=0 0

m=audio 16464 RTP/AVP 18 8 0 2 4 96 97 98 101

a=rtpmap:18 G729a/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:2 G726-32/8000

a=rtpmap:4 G723/8000

a=rtpmap:96 G726-40/8000

a=rtpmap:97 G726-24/8000

a=rtpmap:98 G726-16/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=ptime:30

a=sendrecv


*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Fe0
*Feb 16 16:17:28.519: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x45D30224) found for sip_user: 1500
*Feb 16 16:17:28.519: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 16 16:17:28.519: //-1/154B93498058/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Feb 16 16:17:28.519: //-1/154B93498058/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x47D0BC1C key=58a9b2df-ed11f848@10.0.1.21500b 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x47D0BC1C) with key=[45] to table
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:17:28.515: //-1/154B93498058/SIP/State/sipSPIChangeState: 0x47D0BC1C : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
*Feb 16 16:17:28.515: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:17:28.519: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 16 16:17:28.519: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 506
*Feb 16 16:17:28.519: //-1/154B93498058/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on carrier id
*Feb 16 16:17:28.519: //-1/154B93498058/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 1500
*Feb 16 16:17:28.519: //-1/154B93498058/SIP/Info/sipSPIMatchSrcIpGroup: Match not found on destination pattern: 223461593498058/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled01
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:17:28.527: //-1/154B93498058/SIP/Info/sipSPIContinueNewMsgInvite: Calling name K:1552, number 223461501, Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called number 1500, oct3 0x00
*Feb 16 16:17:28.527: //-1/154B93498058/SIP/Info/sipSPIContinueNewMsgInvite: Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE
*Feb 16 16:17:28.527: //-1/154B93498058/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0Info/ccsipUpdateIncomingCallParams: ccCallInfo: Calling name K:1552, number 223461501, Calling oct3 0x00, oct_3a 0x80, Called number 1500
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : 223461501
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Peer tag 40006 matched for incoming call
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
*Feb 16 16:17:28.523: //-1/154B93498058/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled
*Feb 16 16:17:28.523: //-1/154B
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIProcessDiversionHeader: No diversion headers recvd from app container
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIProcessReplacesHeader: No replaces hdr found
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIDoMediaNegotiation: Number of m-lines = 1
SIP: (53) Attribute mid, level 1 instance 1 not found.
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Medinfo/convert_ptime_to_codec_bytes: Values :Codec: g729br8 ptime :30, codecbytes: 30
*Feb 16 16:17:28.527: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g729br8 codecbytes :20, ptime: 20a/sipSPISelectCodecVersion: g729br8 flavor of g729 codec will be used
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIDoAudioNegotiation: Codec (g729br8) Negotiation Successful on Static Payload for m-line 1
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIDoPtimeNegotiation: One ptime attribute found - value:30
*Feb 16 16:17:28.527: //-1/xxxxxxxxxxxx/SIP/I
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:30, Negotiated ptime:20 Negotiated codec bytes: 20 for codec g729br8
*Feb 16 16:17:28.527: //53/154B93498058/SIP/Info/sipSPIDoDTMFRelayNegotiation: m-line index 1
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPIDoDTMFRelayNegotiation: Requested DTMF-RELAY option(s) not found in Preferred DTMfrom (STREAM_DEAD) to (STREAM_ADDING)
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec        : g729r8, bytes :20
Preferred  DTMF relay  : inband-voice
Preferred NTE payload  : 101
Early Media            : No
Delayed Media          : No
Bridge Done            : No
New Media              : No
DSP DNLD Reqd          : No

*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addrF-RELAY option list!
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPIStr
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 53 peer 0 flags 0x201 state STATE_IDLE
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
CallID 53, sdp 0x486F5244 channels 0x47D0CEBC
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/copy_channels:
callId 53 size 0 ptr 0x46E8DFAC)
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 18 mline 1eamTypeAndDtmfRelay: DTMF Relay mode: Inband Voice
*Feb 16 16:17:28.531: //-1/xxxxxxxxxxxx/SIP/Info/sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=18, codec_bytes=20, codec=g729br8, dtmf_relay=inband-voice
stream_type=voice-only (0), dest_ip_address=10.0.1.2, dest_port=16464
*Feb 16 16:17:28.531: //53/154B93498058/SIP/State/sipSPIChangeStreamState: Stream (callid =  -1)  State changed
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Media/sipSPISelectCodecVersion: g729br8 flavor of g729 codec will be used
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g729br8
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 12

*Feb 16 16:17:28.531: //-1/xxxxxxxxxxxx/SIP/Info/convert_codec_bytes_to_ptime: Values :Codec: g729br8 codecbytes :20, ptime: 20
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation done: stream->negotiated_ptime=20,stream->negotiated_codec_bytes=20, coverted ptime=20 stream->mline_index=1, media_ndx=1
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 12 ptype 18 time 20, bytes 20  as channel 0 mline 1 ss 1 10.0.1.2:16464
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 8 mline 1
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711alaw
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 6

*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:17:28.531: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711alaw ptime :30, codecbytes: 240
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 6 ptype 8 time 30, bytes 240  as channel 1 mline 1 ss 1 10.0.1.2:16464
*Feb 16 16:17:28.531: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 0 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g711ulaw
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 5

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:17:28.535: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :30, codecbytes: 240
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 5 ptype 0 time 30, bytes 240  as channel 2 mline 1 ss 1 10.0.1.2:16464
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 2 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g726r32
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 4
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 4 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Media/sipSPISelectCodecVersion: Codec (No Codec   ) is not in preferred list
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: An exact codec match not configured, using interoperable codec g723r63
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Selecting codec g723r63
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 8

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:17:28.535: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g723r63 ptime :30, codecbytes: 24
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 8 ptype 4 time 30, bytes 24  as channel 3 mline 1 ss 1 10.0.1.2:16464
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 96 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 97 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 3
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 98 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: ADD AUDIO CODEC 2

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Media negotiation NOT done, get ptime from sdp: ptime=30, media_ndx=1
*Feb 16 16:17:28.535: //-1/xxxxxxxxxxxx/SIP/Info/convert_ptime_to_codec_bytes: Values :Codec: g726r16 ptime :30, codecbytes: 60
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Error/sipSPI_ipip_copy_sdp_to_channelInfo:
failed to update call entry
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Adding codec 2 ptype 98 time 30, bytes 60  as channel 4 mline 1 ss 1 10.0.1.2:16464
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo:
Hndl ptype 101 mline 1
*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 12

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 6

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 5

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 8

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = 2

*Feb 16 16:17:28.535: //53/154B93498058/SIP/Info/sipSPI_ipip_copy_sdp_to_channelInfo: Copy sdp to channel- AFTER CODEC FILTERING: ccb->pld.ipip_caps.codecInfo[channel_ndx].codec = -1

*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer:
callId 53 flags 0x100 state STATE_IDLE
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer:
Report initial call media
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer: ccb->flags 0xC, ccb->pld.flags_ipip 0x201

*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/copy_channels:
callId 53 size 1152 ptr 0x483EDAAC)
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer:
CCSIP: Unable to report channel ind
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/ccsip_update_srtp_caps:  5054: Posting Remote SRTP caps to other callleg.
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_report_media_to_peer: do cc_api_caps_ind()
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Media/sipSPIUpdCallWithSdpInfo:
   Stream type            : voice-only
   Media line             : 1
   State                  : STREAM_ADDING (2)
   Stream address type    : 1
   Callid                 : -1
   Negotiated Codec       : g729br8, bytes :20
   Nego. Codec payload    : 18 (tx), 18 (rx)
   Negotiated DTMF relay  : inband-voice
   Negotiated NTE payload : 0 (tx), 0 (rx)
   Negotiated CN payload  : 0
   Media Srce Addr/Port   : [10.0.1.4]:0
   Media Dest Addr/Port   : [10.0.1.2]:16464

*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIHandleInviteMedia:
Negotiated Codec       : g729br8, bytes :20
Preferred Codec        : g729r8, bytes :20
Preferred  DTMF relay 1 : 0
Preferred  DTMF relay 2 : 0
Negotiated DTMF relay   : 0
Preferred and Negotiated NTE payloads: 101 0
Preferred and Negotiated NSE payloads: 100 0
Preferred and Negotiated Modem Relay: 0 0
Preferred and Negotiated Modem Relay GwXid: 1 0

*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: Entry
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIDoStreamQoSNegotiation: Best effort
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 16 16:17:28.539: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18844 for stream 1
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIUpdateSrcSdpFixedPart: Reserving rtp port for stream 1, src_port=18844
*Feb 16 16:17:28.539: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 18844
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIUpdateSrcSdpVariablePart:
SIP update src sdp, negoitated codec 12, payload type 18

*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIAddBillingInfoToCcb: sipCallId for billing records = 58a9b2df-ed11f848@10.0.1.2
*Feb 16 16:17:28.539: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentCPA: No CPA found in inbound container
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPIProcessCPA: No x-cisco-cpa content found
*Feb 16 16:17:28.539: //53/154B93498058/SIP/Info/sipSPI_ipip_store_channel_info: Store channelInfo in CallInfo
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Info/sipSPI_ipip_store_channel_info: dtmf negotiation done, storing negotiated dtmf = 0,
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Info/sipSPIShrlCall: Check peer: 40006 for Shared-Line call, callid: 53
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Info/ccsip_set_bearer_capability:
   Bearer Capability: Speech (0x00)
*Feb 16 16:17:28.543: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Feb 16 16:17:28.543: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Feb 16 16:17:28.543: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICreateNewRawMsg: No Data to form The Raw Message

*Feb 16 16:17:28.543: //53/154B93498058/SIP/Info/sipSPIContinueNewMsgInvite: ccsip_api_call_setup_ind returned: SIP_SUCCESS
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 35 to table
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Transport/sipSPITransportSendMessage: msg=0x4688DF0C, addr=10.0.1.2, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x00000000
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 16 16:17:28.543: //53/154B93498058/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 16 16:17:28.543: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4688DF0C, addr=10.0.1.2, port=5060, connId=0 for UDP
*Feb 16 16:17:28.543: //53/154B93498058/SIP/State/sipSPIChangeState: 0x47D0BC1C : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_NONE)
*Feb 16 16:17:28.547: //53/154B93498058/SIP/Info/sipSPIProcessContactInfo: Previous Hop 10.0.1.2:5060
*Feb 16 16:17:28.551: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-a16f35c

From: "K:1552" <1501>;tag=651fc9f3f22ac44o0

To: "K:1500" <1500>

Date: Wed, 16 Feb 2011 16:17:28 GMT

Call-ID: 58a9b2df-ed11f848@10.0.1.2: 101 INVITE

CSeq

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:17:28.551: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*Feb 16 16:17:28.555: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x47CF1E70) with key=[46] to table
*Feb 16 16:17:28.555: //54/000000000000/SIP/State/sipSPIChangeState: 0x47CF1E70 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 0, codec 12 channel 16464, ip 10.0.1.2:16464  params 0x4883BD88 caps 0x48514960
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 1, codec 6 channel 16464, ip 10.0.1.2:16464  params 0x4883BD88 caps 0x48514960
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 2, codec 5 channel 16464, ip 10.0.1.2:16464  params 0x4883BD88 caps 0x48514960
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 3, codec 8 channel 16464, ip 10.0.1.2:16464  params 0x4883BD88 caps 0x48514960
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request:
This a IPIP call: Chan 4, codec 2 channel 16464, ip 10.0.1.2:16464  params 0x4883BD88 caps 0x48514960
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 16 16:17:28.559: //54/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 400000
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.0.1.142 target_port : 5060

*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/ccsip_call_setup_request: Incrementing call counter in dial-peer [40003]
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: 
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: switch(ev.ev_id: 162)
*Feb 16 16:17:28.559: //53/154B93498058/SIP/Info/ccsip_event_handler:
ccsip_event_handler: peer ID 54 chans 0x48772870 event 162 flags 0x40001C 0x100 0x601 data 0x48772870
*Feb 16 16:17:28.559: //53/154B93498058/SIP/Info/ccsip_event_handler:
ccsip_event_handler: CC_EV_H245_SET_MODE: peer ID 54 chans 0x48772870 event 162 flags 0x40001C 0x100 0x601 data 0x48772870, type = 1
*Feb 16 16:17:28.559: //53/154B93498058/SIP/Info/ccsip_gw_set_sipspi_mode: Setting SPI mode to SIP-SIP
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_event_handler: CC_R_SUCCESS_WITH_CONFIRMED
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 3
*Feb 16 16:17:28.559: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 36 to table
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1
*Feb 16 16:17:28.559: //54/154B93498058/SIP/Media/sipSPICopyPeerDataToCCB: Firewall traversal is not enabled
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: xcoder high-density disabled
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: Flow Mode set to FLOW_THROUGH
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/preprocessSetup:
This is a not a SIGO Call -, could be DM call
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_call_setup: No video caps posted by peer
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_call_setup: xcoder high-density disabled
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_call_setup: Flow Mode set to FLOW_THROUGH
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp:
callid 54, channels 0x483FCB64 caps 0x48514960
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: Peer cap provided: callid = 54, peer dtmf = 0
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPI_ipip_copy_channelInfo_to_sdp: callid = 54, peer not doing RFC2833, peer dtmf = 0, enable NTE_ASSUMED
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 12
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 6
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 5
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 8
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/codec_found:
Codec to be matched: 2
*Feb 16 16:17:28.563: //54/154B93498058/SIP/Info/sipSPIDtmfTranscoder: Return upon SCCP version 0
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.0.1.4
*Feb 16 16:17:28.567: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18694 for stream 1
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPISelectDynamicPayload: Unused Dynamic Payload Mask = D10BFFCC
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPISelectDynamicPayload: Selecting Dynamic Payload : 98 for Codec: g726r16
*Feb 16 16:17:28.567: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Media/sipSPIProcessRtpSessions: sipSPIProcessRtpSessions
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Media/sipSPIProcessRtpSessions: No active streams.
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sip_gw_pre_setup_add_sdp_container: SDP container added
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPIShrlGetInstanceInfo: Obtained the call instance 0 for non-shared-line '1500' with callid: 54
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
*Feb 16 16:17:28.567: //54/154B93498058/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container
*Feb 16 16:17:28.571: //54/154B93498058/SIP/Info/sipSPI_ipip_set_diversion_header: No diversion header recvd from container
*Feb 16 16:17:28.571: //54/154B93498058/SIP/Info/act_idle_continue_call_setup:
*Feb 16 16:17:28.571: //54/154B93498058/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0
*Feb 16 16:17:28.571: //54/154B93498058/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table.E, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1297873048

Contact: <1501>

Expires: 180

Allow-Events: telephone-event

Max-Forwards: 69

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 348

v=0

o=CiscoSystemsSIP-GW-UserAgent 3831 3627 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 18694 RTP/AVP 18 8 0 4 98 19

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:4 G723/8000

a=fmtp:4 bitrate=6.3;annexa=no

a=rtpmap:98 G726-16/8000

a=rtpmap:19 CN/8000


*Feb 16 16:17:29.099: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:29.099: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:29.099: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:29.099: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:17:29.103: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-a16f35c

From: "K:1552" <1501>;tag=651fc9f3f22ac44o0

To: "K:1500" <1500>;tag=1502E0-19F1

Date: Wed, 16 Feb 2011 16:17:28 GMT

Call-ID: 58a9b2df-ed11f848@10.0.1.2: 101 INVITE

CSeq

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

Allow-Events: telephone-event

Contact: <1500>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:17:30.187: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:30.187: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:30.187: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:30.187: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:17:30.187: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 203

v=0

o=CiscoSystemsSIP-GW-UserAgent 5731 8187 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 18844 RTP/AVP 18

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=ptime:20


*Feb 16 16:17:30.807: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:17:30.811: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:30.811: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:32.131: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:32.131: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:32.131: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:32.131: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-e6fd2e25

From: <1500>;tag=cdda9004a39bb76di0

To: "K:1552" <1501>;tag=1500C0-1129

Date: Wed, 16 Feb 2011 16:17:32 GMT

Call-ID: 15541EAA-391F11E0-805E9854-7A1AA247@10.0.1.4: 101 INVITE

CSeq

Allow-Events: telephone-event

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:17:32.279: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:17:32.279: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1SIPGateway/IOS-12.x

Content-Type: application/sdp

Content-Length: 215

v=0

o=CiscoSystemsSIP-GW-UserAgent 3831 3628 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 18694 RTP/AVP 18

c=IN IP4 10.0.1.4

a=recvonly

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=ptime:20


*Feb 16 16:17:33.071: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:33.071: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:33.075: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:34.751: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:34.751: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:34.751: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:34.751: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
E, NOTIFY, INFO, REGISTER

CSeq: 101 INVITE

Timestamp: 1297873054

Contact: <1500>

Expires: 180

Allow-Events: telephone-event

Max-Forwards: 69

Content-Type: application/sdp

Content-Disposition: session;handling=required

Content-Length: 348

v=0

o=CiscoSystemsSIP-GW-UserAgent 8015 7732 IN IP4 10.0.1.4

s=SIP Call

c=IN IP4 10.0.1.4

t=0 0

m=audio 17188 RTP/AVP 18 8 0 4 98 19

c=IN IP4 10.0.1.4

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=yes

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:4 G723/8000

a=fmtp:4 bitrate=6.3;annexa=no

a=rtpmap:98 G726-16/8000

a=rtpmap:19 CN/8000

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.0.1.142:5060;branch=z9hG4bK-e5bdde2f

From: "K:1500" <1500>;tag=dd6f6776781e7907o0

To: "T:1502" <1502>;tag=151A34-871

Date: Wed, 16 Feb 2011 16:17:34 GMT

Call-ID: 9ad32f62-6765720b@10.0.1.142: 101 INVITE

CSeq

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

Allow-Events: telephone-event

Contact: <1502>

Server: Cisco-SIPGateway/IOS-12.x

Content-Length: 0


*Feb 16 16:17:35.807: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.142]:5060
*Feb 16 16:17:35.811: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:35.811: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:35.811: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:

*Feb 16 16:17:36.431: //53/154B93498058/SIP/Media/sipSPIUpdCallWithSdpInfo:
   Stream type            : voice-only
   Media line             : 1
   State                  : STREAM_ACTIVE (5)
   Stream address type    : 1
   Callid                 : 53
   Negotiated Codec       : g729br8, bytes :20
   Nego. Codec payload    : 18 (tx), 18 (rx)
   Negotiated DTMF relay  : inband-voice
   Negotiated NTE payload : 0 (tx), 0 (rx)
   Negotiated CN payload  : 0
   Media Srce Addr/Port   : [10.0.1.4]:18844
   Media Dest Addr/Port   : [10.0.1.2]:16464

*Feb 16 16:17:37.115: //53/154B93498058/SIP/Info/sipSPICacheCallerIDEvent: Caching Caller ID event
*Feb 16 16:17:37.827: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:17:37.827: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:17:37.827: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:17:37.827: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite DialogACK sip:1501@10.0.1.2:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.4:5060;branch=z9hG4bK4B260C

From: "K:1500" <1500>;tag=1502E0-19F1

To: "K:1552" <1501>;tag=651fc9f3f22ac44o0

Date: Wed, 16 Feb 2011 16:17:37 GMT

Call-ID: 58a9b2df-ed11f848@10.0.1.2: 70

Max-Forwards

CSeq: 103 ACK

Allow-Events: telephone-event

Content-Length: 0


*Feb 16 16:18:13.203: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.2]:5060
*Feb 16 16:18:13.203: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:18:13.203: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:18:13.203: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
BYE sip:1500@10.0.1.4:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-7d5975f7

From: "K:1552" <1501>;tag=651fc9f3f22ac44o0

To: "K:1500" <1500>;tag=1502E0-19F1

Call-ID: 58a9b2df-ed11f848@10.0.1.2: 102 BYE

CSeq

Max-Forwards: 70

User-Agent: Linksys/SPA922-5.2.8

Content-Length: 0


*Feb 16 16:18:13.203: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:18:13.207: //53/154B93498058/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x47D0BC1C
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.0.1.2,Port 5060, Transport 1, SentBy Port 5060
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[53], src[2]
*Feb 16 16:18:13.207: //53/154B93498058/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container
*Feb 16 16:18:13.207: //53/154B93498058/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for incoming call
*Feb 16 16:18:13.207: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container
*Feb 16 16:18:13.211: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container
*Feb 16 16:18:13.211: //53/154B93498058/SIP/State/sipSPIChangeState: 0x47D0BC1C : State change from (STATE_ACTIVE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 16 16:18:13.211: //53/154B93498058/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:485F08C8
*Feb 16 16:18:13.211: //57/19031C44805F/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:488967C4
*Feb 16 16:18:13.211: //53/154B93498058/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
*Feb 16 16:18:13.211: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 16 16:18:13.211: //57/19031C44805F/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
*Feb 16 16:18:13.211: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Feb 16 16:18:13.215: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Info/sipSPISendByeResponse: Associated container=0x4883AF50 to Bye Response
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Transport/sipSPISendByeResponse: Sending BYE Response to the transport layer
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Transport/sipSPITransportSendMessage: msg=0x48182168, addr=10.0.1.2, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x416567EC
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 16 16:18:13.215: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x48182168, addr=10.0.1.2, port=5060, connId=0 for UDP
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Info/sentByeResponse: Sent 200ok to the BYE, tearing down the call
*Feb 16 16:18:13.215: //53/154B93498058/SIP/Info/sipSPIIcpifUpdate: CallState: 3 Playout: 0 DiscTime:142109 ConnTime 137810
*Feb 16 16:18:13.219: //53/154B93498058/SIP/State/sipSPIChangeState: 0x47D0BC1C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)
*Feb 16 16:18:13.219: //53/154B93498058/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x47D0BC1C
State of The Call        : STATE_DEAD
TCP Sockets Used         : NO
Calling Number           : 223461501
Called Number            : 1500
Source IP Address (Sig  ): 10.0.1.4
Destn SIP Req Addr:Port  : 10.0.1.2:5060
Destn SIP Resp Addr:Port : 10.0.1.2:5060
Destination Name         : 10.0.1.2

*Feb 16 16:18:13.219: //53/154B93498058/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream             : 1
Negotiated Codec         : g729br8
Negotiated Codec Bytes   : 20
Nego. Codec payload      : 18 (tx), 18 (rx)
Negotiated Dtmf-relay    : 0
Dtmf-relay Payload       : 0 (tx), 0 (rx)
Source IP Address (Media): 10.0.1.4
Source IP Port    (Media): 18844
Destn  IP Address (Media): 10.0.1.2
Destn  IP Port    (Media): 16464
Orig Destn IP Address:Port (Media): [ - ]:0

*Feb 16 16:18:13.219: //53/154B93498058/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC)    : 16
Disconnect Cause (SIP)   : 200

*Feb 16 16:18:13.219: //53/154B93498058/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 35
*Feb 16 16:18:13.219: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 7
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Info/sipSPIStopHoldTimer: Stopping hold timer
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Info/sipSPISendBye: Associated container=0x4883BCB8 to Bye
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipSPISendBye: Sending BYE to the transport layer
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipSPITransportSendMessage: msg=0x489090BC, addr=10.0.1.130, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x41656564
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Feb 16 16:18:13.219: //57/19031C44805F/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x489090BC
*Feb 16 16:18:13.219: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x489090BC, addr=10.0.1.130, port=5060, connId=5 for UDP
*Feb 16 16:18:13.223: //57/19031C44805F/SIP/Info/sentByeDisconnecting: Sent Bye Request, starting DisconnectTimer
*Feb 16 16:18:13.223: //57/19031C44805F/SIP/State/sipSPIChangeState: 0x47CE78F8 : State change from (STATE_ACTIVE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Feb 16 16:18:13.223: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.1.2:5060;branch=z9hG4bK-7d5975f7

From: "K:1552" <1501>;tag=651fc9f3f22ac44o0

To: "K:1500" <1500>;tag=1502E0-19F1

Date: Wed, 16 Feb 2011 16:18:13 GMT

Call-ID: 58a9b2df-ed11f848@10.0.1.2: Cisco-SIPGateway/IOS-12.x

Server

CSeq: 102 BYE

Reason: Q.850;cause=16

Content-Length: 0


*Feb 16 16:18:13.223: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:1502@10.0.1.130:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.1.4:5060;branch=z9hG4bK4C1CD7

From: "K:1500" <1500>;tag=151918-2540

To: <1502>;tag=1807f1f122f9ae6i0

Date: Wed, 16 Feb 2011 16:17:34 GMT

Call-ID: 190A6F54-391F11E0-80659854-7A1AA247@10.0.1.4: Cisco-SIPGateway/IOS-12.x

User-Agent

Max-Forwards: 70

Timestamp: 1297873093

CSeq: 102 BYE

Reason: Q.850;cause=16

Content-Length: 0


*Feb 16 16:18:13.243: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.0.1.130]:5060
*Feb 16 16:18:13.243: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
*Feb 16 16:18:13.243: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Feb 16 16:18:13.243: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Feb 16 16:18:13.243: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK

To: <1502>;tag=1807f1f122f9ae6i0

From: "K:1500" <1500>;tag=151918-2540

Call-ID: 190A6F54-391F11E0-80659854-7A1AA247@10.0.1.4: 102 BYE

CSeq

Via: SIP/2.0/UDP 10.0.1.4:5060;branch=z9hG4bK4C1CD7

Timestamp: 1297873093

Server: Linksys/SPA922-5.2.8

Content-Length: 0


*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Info/sipSPICheckResponse: non-INVITE response with no RSEQ - do not disable IS_REL1XX
*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Info/sipSPIIcpifUpdate: CallState: 4 Playout: 0 DiscTime:142112 ConnTime 138496
*Feb 16 16:18:13.247: //57/19031C44805F/SIP/State/sipSPIChangeState: 0x47CE78F8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)
*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x47CE78F8
State of The Call        : STATE_DEAD
TCP Sockets Used         : NO
Calling Number           : 1500
Called Number            : 1502
Source IP Address (Sig  ): 10.0.1.4
Destn SIP Req Addr:Port  : 10.0.1.130:5060
Destn SIP Resp Addr:Port : 10.0.1.130:5060
Destination Name         : 10.0.1.130

*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream             : 1
Negotiated Codec         : g729br8
Negotiated Codec Bytes   : 20
Nego. Codec payload      : 18 (tx), 18 (rx)
Negotiated Dtmf-relay    : 0
Dtmf-relay Payload       : 0 (tx), 0 (rx)
Source IP Address (Media): 10.0.1.4
Source IP Port    (Media): 17188
Destn  IP Address (Media): 10.0.1.130
Destn  IP Port    (Media): 16406
Orig Destn IP Address:Port (Media): [ - ]:0

*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC)    : 16
Disconnect Cause (SIP)   : 200

*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 39
*Feb 16 16:18:13.247: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[48] removed.
*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table.
*Feb 16 16:18:13.247: //57/19031C44805F/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x47CE78F8 key=190A6F54-391F11E0-80659854-7A1AA247@10.0.1.4
*Feb 16 16:18:13.251: //57/19031C44805F/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Feb 16 16:18:13.251: //57/19031C44805F/SIP/Info/sipSPIStopRequestPendingTimer: Stopping Request Pending Timer
*Feb 16 16:18:13.251: //57/19031C44805F/SIP/Info/ccsip_qos_cleanup: Entry
*Feb 16 16:18:13.251: //57/19031C44805F/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Feb 16 16:18:13.251: //57/19031C44805F/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 47CE78F8
*Feb 16 16:18:13.251: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[48]
lab1#
lab1#
lab1#

And my config:

voice service voip
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
fax protocol cisco
sip
  registrar server expires max 3600 min 120
!
!
!
voice class codec 1
codec preference 1 g729r8
codec preference 2 g729br8
codec preference 3 g711alaw
codec preference 4 g723r63
codec preference 5 g726r16
codec preference 6 g711ulaw
!
voice register global
mode cme
source-address 10.0.1.4 port 5060
max-dn 20
max-pool 20
dialplan-pattern 1 2234615.. extension-length 4 no-reg
no forwarding local
file text
create profile sync 0304593554212626
!
voice register dn  10
number 1500
no-reg
!
voice register dn  11
number 1501
no-reg
!
voice register dn  12
number 1502
no-reg
!
voice register dn  13
number 1503
no-reg
!
voice register pool  10
id mac 000E.08D9.5A39
number 1 dn 10
voice-class codec 1
username 1500 password 123qwe
!
voice register pool  11
id mac 000E.08D9.5A39
number 1 dn 11
voice-class codec 1
username 1501 password 123qwe
!
voice register pool  12
id mac 000E.08DB.36B8
number 1 dn 12
voice-class codec 1
voice register pool  13
id mac 001F.C6DD.BBDC
number 1 dn 13
voice-class codec 1
username 1513 password 123qwe

IOS: c2800nm-ipvoicek9-mz.124-24.T3.bin

platform 2811

thanks !

Hello,

How did you solve this problem. We are facing almost similar problem.

Regards