Buy or Renew
Buy or Renew
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
6922
Views
5
Helpful
2
Replies

Cube Sip Trunk Everything perfect except inbound calls 6 second drop help !

Go to solution
DenysAdams
Level 1
Level 1

‎05-25-2016 03:37 AM - edited ‎03-17-2019 07:01 AM

Hi Support Community.

I have a Lab Setup that im working on Cube Sip Services

Outbound calls are working 100% 

Inbound calls fail after roughly 6 seconds, for that time period i do get two way audio however.

I suspect i have a setting i need to configure in terms of some sort of keep alive , invite ack .
but im unable to spot or resolve the problem I would greatly appreciate any feedback.

I have a provided a copy of my config, also love to know of any best practises anyone can recommend.

Building configuration...


Current configuration : 3696 bytes
!
! Last configuration change at 12:20:22 UTC Tue May 24 2016
version 15.1
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Cisco2811
!
boot-start-marker
boot system flash:c2800nm-adventerprisek9_ivs_li-mz.151-4.M10.bin
boot-end-marker
!
!
! card type command needed for slot/vwic-slot 0/2
enable secret 5 xxxxx
!
no aaa new-model
!
!
dot11 syslog
ip source-route
!
!
ip cef
!
!
!
ip domain name cerberios.com
ip name-server 192.168.0.250
ip name-server 8.8.8.8
no ipv6 cef
!
multilink bundle-name authenticated
!
!
!
!
!
!
!
voice service voip
ip address trusted list
ipv4 103.193.166.0 255.255.255.0
ipv4 192.168.0.9
ipv4 103.193.166.37
ipv4 xxxx
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
sip
!
voice class codec 1
codec preference 1 g729r8
codec preference 2 g711alaw
codec preference 3 g711ulaw
!
!
!
!
voice translation-rule 1
rule 1 /03......../ /104xxxx/
!
!
voice translation-profile SIPout
translate calling 1
!
!
voice-card 0
dspfarm
dsp services dspfarm
!
crypto pki token default removal timeout 0
!
!
!
!
license udi pid CISCO2811 sn FHK1405F0TE
archive
log config
hidekeys
username xxxx privilege 15 secret 5 xxxxx
!
redundancy
!
!
!
!
!
!
!
!
!
!
interface FastEthernet0/0
no ip address
duplex auto
speed auto
!
interface FastEthernet0/1
ip address 192.168.0.41 255.255.255.0
duplex auto
speed auto
!
ip default-gateway 192.168.0.250
ip forward-protocol nd
no ip http server
no ip http secure-server
!
!
ip route 0.0.0.0 0.0.0.0 192.168.0.250
!
nls resp-timeout 1
cpd cr-id 1
!
!
!
!
!
!
control-plane
!
!
!
!
mgcp profile default
!
sccp local FastEthernet0/1
sccp ccm 192.168.0.41 identifier 1 version 7.0
sccp
!
sccp ccm group 1
bind interface FastEthernet0/1
associate ccm 1 priority 1
associate profile 1 register CUBEXCODE_1
keepalive timeout 10
switchback method graceful
switchback interval 1
!
dspfarm profile 1 transcode
codec g711ulaw
codec g711alaw
codec g729ar8
codec g729abr8
codec g722-64
maximum sessions 5
associate application SCCP
!
dial-peer voice 1 voip
description ***outbound dialpeer to ITSP***
translation-profile outgoing SIPout
preference 2
destination-pattern .T
session protocol sipv2
session target dns:sip.telecube.com.au
voice-class codec 1
voice-class sip bind control source-interface FastEthernet0/1
voice-class sip bind media source-interface FastEthernet0/1
dtmf-relay rtp-nte
no vad
!
dial-peer voice 2 voip
description inbound from CUCM
preference 1
destination-pattern 035915940.
session protocol sipv2
session target ipv4:192.168.0.9
voice-class codec 1
voice-class sip bind control source-interface FastEthernet0/1
voice-class sip bind media source-interface FastEthernet0/1
dtmf-relay rtp-nte
no vad
!
!
sip-ua
credentials username 104xxxx password 7 xxxxxxxxxxxxxxxxx realm sip.telecube.com.au
authentication username 104xxxx password 7 xxxxxxxxxxxxxxxx
retry invite 2
retry response 3
retry bye 3
retry prack 6
retry register 2
timers expires 300000
registrar dns:sip.telecube.com.au expires 3600
sip-server dns:sip.telecube.com.au
!
!
!
gatekeeper
shutdown
!
!
telephony-service
sdspfarm units 1
sdspfarm transcode sessions 5
sdspfarm tag 1 CUBEXCODE_1
max-ephones 10
max-dn 10
ip source-address 192.168.0.41 port 2000
max-conferences 8 gain -6
transfer-system full-consult
!
!
!
line con 0
line aux 0
line vty 0 4
password cisco
login
transport input all
!
scheduler allocate 20000 1000
end


DEBUG ccsip calls, ccsip error, ccsip messages, ccsip states, voice ccapi error, voice ccapi inout

please let me know if you need more info or logs. Appreciate the help greatly !

also could you please outline the error in the logs just so i know what to look for in the future. Thanks Kindly !


*May 25 10:50:30.957: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:0359159401@103.193.166.11 SIP/2.0
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0
Via: SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
Max-Forwards: 69
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>
Contact: <sip:0418656729@103.193.166.37:5060>
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
User-Agent: Asterisk (Telecube) PBX
Date: Wed, 25 May 2016 10:29:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
X-Telecube-Event-Id: 1241464172158199876
X-Telecube-DID-Number: 0359159401
P-Asserted-Identity: "61418656729" <sip:0418656729@103.193.166.37>
Content-Type: application/sdp
Content-Length: 315

v=0
o=root 437668023 437668023 IN IP4 103.193.166.37
s=Asterisk (Telecube) PBX
c=IN IP4 103.193.166.37
t=0 0
m=audio 11918 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

*May 25 10:50:30.957: //-1/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*May 25 10:50:30.965: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*May 25 10:50:30.965: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:4BD449D4
*May 25 10:50:30.965: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_LEG_BY_LEG, SE Value:0, SE Refresher:none, Min-SE Value:1800, flags:2001
SIP: (5013) Attribute mid, level 1 instance 1 not found.
*May 25 10:50:30.969: //5013/558275C9A851/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
*May 25 10:50:30.969: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_RCVD
*May 25 10:50:30.969: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_RCVD_SDP
*May 25 10:50:30.969: //5013/558275C9A851/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:
failed to update call entry
*May 25 10:50:30.969: //5013/558275C9A851/SIP/Error/sipSPI_ipip_update_codec_params_in_channelInfo:
failed to update call entry
*May 25 10:50:30.969: //5013/558275C9A851/SIP/Error/sipSPI_ipip_update_call_entry:
failed to update call entry
*May 25 10:50:30.969: //-1/xxxxxxxxxxxx/CCAPI/cc_set_post_tagdata:
CALL_ERROR; Avlist Set Is Failed
*May 25 10:50:30.969: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_ipip_set_channel_count: Unable to set CHANNEL_COUNT for callid 5013
*May 25 10:50:30.969: //5013/558275C9A851/SIP/Error/sip_iwf_sip_copy_sdp_to_channelInfo: Channel count is not set at this point. Not SIP-SIP or SET_MODE is not done.
*May 25 10:50:30.969: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT
*May 25 10:50:30.969: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_RCVD
*May 25 10:50:30.973: //-1/558275C9A851/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=0418656729
----- ccCallInfo IE subfields -----
cisco-ani=0418656729
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=0359159401
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*May 25 10:50:30.973: //-1/558275C9A851/CCAPI/cc_api_call_setup_ind_common:
Interface=0x4AC0F6C0, Call Info(
Calling Number=0418656729,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=0359159401(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=5013
*May 25 10:50:30.973: //-1/558275C9A851/CCAPI/ccCheckClipClir:
In: Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*May 25 10:50:30.973: //-1/558275C9A851/CCAPI/ccCheckClipClir:
Out: Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*May 25 10:50:30.977: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.977: :cc_get_feature_vsa malloc success
*May 25 10:50:30.977: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.977: cc_get_feature_vsa count is 1
*May 25 10:50:30.977: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.977: :FEATURE_VSA attributes are: feature_name:0,feature_time:1292235696,feature_id:2244
*May 25 10:50:30.977: //5013/558275C9A851/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=0359159401(TON=Unknown, NPI=Unknown))
*May 25 10:50:30.977: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*May 25 10:50:30.977: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:4BD3EFC4
*May 25 10:50:30.977: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_RECD_INVITE, SUBSTATE_NONE)
*May 25 10:50:30.981: //5013/558275C9A851/CCAPI/cc_process_call_setup_ind:
Event=0x4B6EC1F0
*May 25 10:50:30.981: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 0359159401
*May 25 10:50:30.981: //5013/558275C9A851/CCAPI/ccCallSetContext:
Context=0x4D389A24
*May 25 10:50:30.981: //5013/558275C9A851/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 5013 with tag 1 to app "_ManagedAppProcess_Default"
*May 25 10:50:30.981: //5013/558275C9A851/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*May 25 10:50:30.981: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
Unsupported MLPP Service Domain Network 0
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
Invalid MLPP Information
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
Unsupported MLPP Service Domain Network 0
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
Invalid MLPP Information
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:
Unsupported MLPP Service Domain Network 0
*May 25 10:50:30.985: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:
Invalid MLPP Information
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=2, Params=0x4D37DFB4, Progress Indication=NULL(0)
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccCheckClipClir:
In: Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccCheckClipClir:
Out: Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccCallSetupRequest:
Destination Pattern=035915940., Called Number=0359159401, Digit Strip=FALSE
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccCallSetupRequest:
Calling Number=0418656729(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=0359159401(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=61418656729
Account Number=0418656729, Final Destination Flag=TRUE,
Guid=558275C9-219D-11E6-A851-A914AEFF3C37, Outgoing Dial-peer=2
*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=0418656729
----- ccCallInfo IE subfields -----
cisco-ani=0418656729
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=0359159401
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*May 25 10:50:30.989: //5013/558275C9A851/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x4AC0F6C0, Interface Type=3, Destination=, Mode=0x0,
Call Params(Calling Number=0418656729,(Calling Name=61418656729)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=0359159401(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*May 25 10:50:30.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.989: :cc_get_feature_vsa malloc success
*May 25 10:50:30.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.989: cc_get_feature_vsa count is 2
*May 25 10:50:30.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*May 25 10:50:30.989: :FEATURE_VSA attributes are: feature_name:0,feature_time:1292237040,feature_id:2245
*May 25 10:50:30.993: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_FLOW_MODE
*May 25 10:50:30.993: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:30.993: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SET_MODE
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SET_FLOW_MODE
*May 25 10:50:30.993: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:30.993: //5014/558275C9A851/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
*May 25 10:50:30.993: //5014/558275C9A851/CCAPI/ccCallSetContext:
Context=0x4D37DF64
*May 25 10:50:30.993: //5013/558275C9A851/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=2
*May 25 10:50:30.997: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_INIT_CALL_SETUP
*May 25 10:50:30.997: //5014/000000000000/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp: We are either escalating, orno stream found for this m-line index:1
*May 25 10:50:30.997: //5014/000000000000/SIP/Error/sip_iwf_sip_copy_channelInfo_to_sdp: We are either escalating, orno stream found for this m-line index:1
*May 25 10:50:30.997: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CHNL_IND
*May 25 10:50:30.997: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_INIT_XCODER_RESERVED
*May 25 10:50:30.997: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*May 25 10:50:30.997: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:31.001: //5014/558275C9A851/CCAPI/cc_api_call_proceeding:
Interface=0x4AC0F6C0, Progress Indication=NULL(0)
*May 25 10:50:31.001: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
*May 25 10:50:31.001: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:4BD3C7E4
*May 25 10:50:31.001: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_LEG_BY_LEG, SE Value:0, SE Refresher:none, Min-SE Value:1800, flags:2001
*May 25 10:50:31.001: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_SENT
*May 25 10:50:31.001: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SENT_SDP
*May 25 10:50:31.005: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
*May 25 10:50:31.005: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
*May 25 10:50:31.005: //5014/000000000000/SIP/Event/sipSPICreateRpid: Received Octet3A=0x80 -> Setting ;screen=no ;privacy=off
SIP: (5014) Group (a= group line) attribute, level 65535 instance 1 not found.
*May 25 10:50:31.005: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
*May 25 10:50:31.005: //5014/000000000000/SIP/State/sipSPIChangeStreamState: Stream (callid = 5014) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
*May 25 10:50:31.009: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0

*May 25 10:50:31.009: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:0359159401@192.168.0.9:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CC125A
Remote-Party-ID: "61418656729" <sip:0418656729@192.168.0.41>;party=calling;screen=no;privacy=off
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>
Date: Wed, 25 May 2016 10:50:31 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 1434613193-0563941862-2823923988-2935962679
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1464173431
Contact: <sip:0418656729@192.168.0.41:5060>
Expires: 300
Allow-Events: telephone-event
Max-Forwards: 68
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 259

v=0
o=CiscoSystemsSIP-GW-UserAgent 8942 7872 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 18736 RTP/AVP 0 8 101
c=IN IP4 192.168.0.41
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

*May 25 10:50:31.017: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CC125A
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>
Date: Wed, 25 May 2016 10:29:19 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
CSeq: 101 INVITE
Allow-Events: presence
Content-Length: 0

*May 25 10:50:31.017: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*May 25 10:50:31.021: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CC125A
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>;tag=5104~12d5d499-38ad-47af-a90a-d75e386b62e1-33197460
Date: Wed, 25 May 2016 10:29:19 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Server: Cisco-CUCM11.0
Supported: X-cisco-srtp-fallback
Supported: Geolocation
Session-ID: 1638c7ae5bec5b028a6170ba33197461;remote=48d77df2be0b6171b34f8a3e7bab5104
P-Asserted-Identity: "denys - 0359159401" <sip:0359159401@192.168.0.9>
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.9>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.9:5060>
Content-Length: 0

*May 25 10:50:31.021: //5014/000000000000/SIP/Error/ccsip_alert_parent_or_child: Unable to add unsupported
hdrs to container
*May 25 10:50:31.021: //5014/558275C9A851/CCAPI/cc_api_call_alert:
Interface=0x4AC0F6C0, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*May 25 10:50:31.025: //5014/558275C9A851/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
*May 25 10:50:31.025: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
*May 25 10:50:31.025: //5013/558275C9A851/CCAPI/ccCallAlert:
Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*May 25 10:50:31.025: //5013/558275C9A851/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
*May 25 10:50:31.025: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
*May 25 10:50:31.025: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*May 25 10:50:31.025: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:180, container:4BD3D654
*May 25 10:50:31.029: //5013/558275C9A851/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
*May 25 10:50:31.029: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE) to (STATE_SENT_ALERTING, SUBSTATE_NONE)
*May 25 10:50:31.029: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.41>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.41:5060>
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0

*May 25 10:50:33.189: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CC125A
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>;tag=5104~12d5d499-38ad-47af-a90a-d75e386b62e1-33197460
Date: Wed, 25 May 2016 10:29:19 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
CSeq: 101 INVITE
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Supported: replaces
Server: Cisco-CUCM11.0
Supported: X-cisco-srtp-fallback
Supported: Geolocation
Session-Expires: 1800;refresher=uas
Require: timer
Session-ID: 1638c7ae5bec5b028a6170ba33197461;remote=48d77df2be0b6171b34f8a3e7bab5104
P-Asserted-Identity: "denys - 0359159401" <sip:0359159401@192.168.0.9>
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.9>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.9:5060>
Content-Type: application/sdp
Content-Length: 243

v=0
o=CiscoSystemsCCM-SIP 5104 1 IN IP4 192.168.0.9
s=SIP Call
c=IN IP4 192.168.0.15
b=TIAS:64000
b=CT:64
b=AS:64
t=0 0
m=audio 24724 RTP/AVP 0 101
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

*May 25 10:50:33.193: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_CALL_BRIDGE
*May 25 10:50:33.193: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.193: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*May 25 10:50:33.193: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:200, container:4BD41EDC
*May 25 10:50:33.193: //5014/000000000000/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_PASS_ST_PARAMS, SE Value:1800, SE Refresher:uas, Min-SE Value:1800, flags:121
SIP: Attribute mid, level 1 instance 1 not found.
*May 25 10:50:33.197: //5014/000000000000/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
*May 25 10:50:33.197: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_INVITE_RESP_SDP_RCVD
*May 25 10:50:33.197: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_RCVD_SDP
*May 25 10:50:33.197: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_ANS_RCVD
*May 25 10:50:33.197: //5014/558275C9A851/CCAPI/cc_set_post_tagdata:
CALL_ERROR; Avlist Removal Is Failed
*May 25 10:50:33.197: //5014/558275C9A851/CCAPI/cc_api_caps_ind:
Destination Interface=0x0, Destination Call Id=-1, Source Call Id=5014,
Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,
Modem=0x0, Codec Bytes=20, Signal Type=2)
*May 25 10:50:33.201: //5014/558275C9A851/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*May 25 10:50:33.201: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_PEER_CAPS
*May 25 10:50:33.201: //5013/558275C9A851/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=5014, Source Call Id=5013,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=1)
*May 25 10:50:33.201: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CAPS_IND
*May 25 10:50:33.201: //5013/558275C9A851/CCAPI/cc_api_caps_ack:
Destination Interface=0x0, Destination Call Id=5014, Source Call Id=5013,
Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=1)
*May 25 10:50:33.201: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.201: //5014/558275C9A851/CCAPI/cc_api_event_indication:
Event=171, Call Id=5014
*May 25 10:50:33.201: //5014/558275C9A851/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
*May 25 10:50:33.201: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_IWF
*May 25 10:50:33.201: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_DONE
*May 25 10:50:33.201: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_ANSWER_COMPLETE
*May 25 10:50:33.201: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
*May 25 10:50:33.201: //5014/558275C9A851/CCAPI/cc_api_call_connected:
Interface=0x4AC0F6C0, Data Bitmask=0x81000001, Progress Indication=NULL(0),
Connection Handle=0
*May 25 10:50:33.205: //5014/558275C9A851/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_ANSWER_COMPLETE, event:E_SIP_DIALOG_ESTD
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_CALL_ACTIVE
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.205: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_GEN_SERVICE
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_mid_dialog_container
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_MID_DIALOG_IDLE
*May 25 10:50:33.205: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_NONE)
*May 25 10:50:33.205: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_TRIGGER_LOCAL_FA_MEDIA
*May 25 10:50:33.205: //5014/000000000000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x4BA76A80
State of The Call : STATE_ACTIVE
TCP Sockets Used : NO
Calling Number : 0418656729
Called Number : 0359159401
Source IP Address (Sig ): 192.168.0.41
Destn SIP Req Addr:Port : 192.168.0.9:5060
Destn SIP Resp Addr:Port : 192.168.0.9:5060
Destination Name : 192.168.0.9

*May 25 10:50:33.209: //5014/000000000000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 192.168.0.41
Source IP Port (Media): 18736
Destn IP Address (Media): 192.168.0.15
Destn IP Port (Media): 24724
Orig Destn IP Address:Port (Media): [ - ]:0

*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_UPDATE_STREAM_CONTEXT
*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_PEER_CAPS_ACK,
*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_PEER_CAPS_ACK,
*May 25 10:50:33.209: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.209: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_PEER_CHANNEL_ACK
*May 25 10:50:33.209: //5013/558275C9A851/SIP/Error/sipSPIUpdateRtpSession: voip_rtp_update_callinfo returns FALSE
*May 25 10:50:33.209: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_PEER_CHNL_ACK
*May 25 10:50:33.209: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.209: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.213: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_CALL_ACTIVE
*May 25 10:50:33.213: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.213: //5013/558275C9A851/CCAPI/ccConferenceCreate:
(confID=0x4C01987C, callID1=0x1395, gcid=5583AE19-219D11E6-A854A914-AEFF3C37, tag=0x0)
*May 25 10:50:33.213: //5014/558275C9A851/CCAPI/ccConferenceCreate:
(confID=0x4C01987C, callID2=0x1396, gcid=5583AE19-219D11E6-A854A914-AEFF3C37, tag=0x0)
*May 25 10:50:33.213: //5013/558275C9A851/CCAPI/ccConferenceCreate:
Conference Id=0x4C01987C, Call Id1=5013, Call Id2=5014, Tag=0x0
*May 25 10:50:33.213: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.213: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.213: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.213: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.213: //5013/558275C9A851/CCAPI/ccConferenceCreate:

*May 25 10:50:33.213: ccConferenceCreate: ret1=0, codecMask1=1, bytes1=160, negot1=1, dtmf1=6
ret2=0, codecMask2=1, bytes2=160, negot2=1, dtmf2=6,
tx_dynamic_pt1=0, rx_dynamic_pt1=0, codec_mode1=0, params_bitmap1 =0
tx_dynamic_pt2=0, rx_dynamic_pt2=0, codec_mode2=0, params_bitmap2 =0
*May 25 10:50:33.213: //5013/558275C9A851/CCAPI/ccConferenceCreate:
delay media to slow start case, codec negotation is not done
*May 25 10:50:33.213: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SET_MODE
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_early_dialog_container
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_CALL_BRIDGE
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.217: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.217: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.217: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.217: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.217: //5013/558275C9A851/SIP/State/sipSPIChangeStreamState: Stream (callid = 5013) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
*May 25 10:50:33.217: //5013/558275C9A851/CCAPI/cc_api_bridge_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5013,
Destination Call Id=5014, Disposition=0x0, Tag=0x0
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_SET_MODE
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sip_mid_dialog_container
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_CALL_BRIDGE
*May 25 10:50:33.217: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.221: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.221: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.221: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:33.221: cc_api_get_xcode_stream : 4702
*May 25 10:50:33.221: //5014/000000000000/SIP/State/sipSPIChangeStreamState: Stream (callid = 5014) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
*May 25 10:50:33.221: //5014/558275C9A851/CCAPI/cc_api_bridge_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5014,
Destination Call Id=5013, Disposition=0x0, Tag=0x0
*May 25 10:50:33.221: //5013/558275C9A851/CCAPI/cc_generic_bridge_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5014,
Destination Call Id=5013, Disposition=0x0, Tag=0x0
*May 25 10:50:33.221: //-1/xxxxxxxxxxxx/CCAPI/cc_exchange_calls_mlpp_info:
Unable too Get MLPP nformation for callID 5013
*May 25 10:50:33.221: //5013/558275C9A851/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x407, Destination Call Id=5014)
*May 25 10:50:33.221: //5014/558275C9A851/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x407, Destination Call Id=5013)
*May 25 10:50:33.221: //5013/558275C9A851/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x407, Call Id1=5013, Call Id2=5014
*May 25 10:50:33.225: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:0359159401@192.168.0.9:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CD45B
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>;tag=5104~12d5d499-38ad-47af-a90a-d75e386b62e1-33197460
Date: Wed, 25 May 2016 10:50:31 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

*May 25 10:50:33.225: //5013/558275C9A851/CCAPI/ccCallConnect:
Progress Indication=NULL(0), Data Bitmask=0x81000001
*May 25 10:50:33.225: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_CALL_CONNECT
*May 25 10:50:33.225: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*May 25 10:50:33.225: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*May 25 10:50:33.225: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_CONNECT
*May 25 10:50:33.225: //5013/558275C9A851/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
*May 25 10:50:33.229: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP
*May 25 10:50:33.229: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:200, container:4BD38534
*May 25 10:50:33.233: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLPrintTDContainer: Peer-Event: E_STSL_PASS_ST_PARAMS, SE Value:1800, SE Refresher:uas, Min-SE Value:1800, flags:121
*May 25 10:50:33.233: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_RCVD, event:E_SIP_INVITE_RESP_SDP_SENT
*May 25 10:50:33.233: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_early_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_PEER_EVENT, event:E_SIP_IWF_EV_SENT_SDP
*May 25 10:50:33.233: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_DONE
*May 25 10:50:33.233: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_ANSWER_COMPLETE
*May 25 10:50:33.233: //5013/558275C9A851/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
SIP: (5013) Group (a= group line) attribute, level 65535 instance 1 not found.
*May 25 10:50:33.237: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_SENT_ALERTING, SUBSTATE_NONE) to (STATE_SENT_SUCCESS, SUBSTATE_NONE)
*May 25 10:50:33.237: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.41>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.41:5060>
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-12.x
Session-Expires: 1800;refresher=uas
Require: timer
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 246

v=0
o=CiscoSystemsSIP-GW-UserAgent 807 5526 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 16778 RTP/AVP 0 101
c=IN IP4 192.168.0.41
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

*May 25 10:50:33.765: //5013/558275C9A851/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
SIP: (5013) Group (a= group line) attribute, level 65535 instance 1 not found.
*May 25 10:50:33.769: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.41>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.41:5060>
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-12.x
Session-Expires: 1800;refresher=uas
Require: timer
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 246

v=0
o=CiscoSystemsSIP-GW-UserAgent 807 5526 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 16778 RTP/AVP 0 101
c=IN IP4 192.168.0.41
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

*May 25 10:50:34.797: //5013/558275C9A851/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
SIP: (5013) Group (a= group line) attribute, level 65535 instance 1 not found.
*May 25 10:50:34.797: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.41>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.41:5060>
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-12.x
Session-Expires: 1800;refresher=uas
Require: timer
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 246

v=0
o=CiscoSystemsSIP-GW-UserAgent 807 5526 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 16778 RTP/AVP 0 101
c=IN IP4 192.168.0.41
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

*May 25 10:50:36.793: //5013/558275C9A851/SIP/Event/sipSPICreateRpid: Received Octet3A=0x81 -> Setting ;screen=yes ;privacy=off
SIP: (5013) Group (a= group line) attribute, level 65535 instance 1 not found.
*May 25 10:50:36.797: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.193.166.11:5060;branch=z9hG4bKe43e.1bfaf007.0,SIP/2.0/UDP 103.193.166.37:5060;received=103.193.166.37;branch=z9hG4bK33433ef2;rport=5060
From: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
To: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Remote-Party-ID: "denys - 0359159401" <sip:0359159401@192.168.0.41>;party=called;screen=yes;privacy=off
Contact: <sip:0359159401@192.168.0.41:5060>
Record-Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Supported: replaces
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-12.x
Session-Expires: 1800;refresher=uas
Require: timer
Supported: timer
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 246

v=0
o=CiscoSystemsSIP-GW-UserAgent 807 5526 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 16778 RTP/AVP 0 101
c=IN IP4 192.168.0.41
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

*May 25 10:50:40.793: //5013/558275C9A851/SIP/Error/act_sentsucc_wait_ack: Out of retries
*May 25 10:50:40.793: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG: No Inbound Container Created !!!
*May 25 10:50:40.793: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931: No Inbound Container Created !!!
*May 25 10:50:40.793: //5013/558275C9A851/SIP/Error/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Invalid CCB/Data/Container
*May 25 10:50:40.793: //5013/558275C9A851/CCAPI/cc_api_call_disconnected:
Cause Value=102, Interface=0x4AC0F6C0, Call Id=5013
*May 25 10:50:40.793: //5013/558275C9A851/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=102, Retry Count=0)
*May 25 10:50:40.793: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_SENT_SUCCESS, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*May 25 10:50:40.793: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*May 25 10:50:40.797: //5013/558275C9A851/CCAPI/ccConferenceDestroy:
Conference Id=0x407, Tag=0x0
*May 25 10:50:40.797: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
*May 25 10:50:40.797: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5013/558275C9A851/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*May 25 10:50:40.797: //5013/558275C9A851/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5013,
Destination Call Id=5014, Disposition=0x0, Tag=0x0
*May 25 10:50:40.797: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
*May 25 10:50:40.797: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5013/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.797: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.797: //5014/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*May 25 10:50:40.801: cc_api_get_xcode_stream : 4702
*May 25 10:50:40.801: //5014/000000000000/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
*May 25 10:50:40.801: //5014/558275C9A851/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5014,
Destination Call Id=5013, Disposition=0x0, Tag=0x0
*May 25 10:50:40.801: //5013/558275C9A851/CCAPI/cc_generic_bridge_done:
Conference Id=0x407, Source Interface=0x4AC0F6C0, Source Call Id=5014,
Destination Call Id=5013, Disposition=0x0, Tag=0x0
*May 25 10:50:40.801: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:0418656729@103.193.166.37:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CEC9B
From: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
To: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
Date: Wed, 25 May 2016 10:50:30 GMT
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Route: <sip:103.193.166.11;lr;did=e74.b69b5224>
Timestamp: 1464173440
CSeq: 101 BYE
Reason: Q.850;cause=86
P-RTP-Stat: PS=374,OS=59840,PR=371,OR=59360,PL=0,JI=0,LA=0,DU=7
Content-Length: 0

*May 25 10:50:40.805: //5013/558275C9A851/CCAPI/ccCallDisconnect:
Cause Value=102, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=102)
*May 25 10:50:40.805: //5013/558275C9A851/CCAPI/ccCallDisconnect:
Cause Value=102, Call Entry(Responsed=TRUE, Cause Value=102)
*May 25 10:50:40.805: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*May 25 10:50:40.805: //5014/558275C9A851/CCAPI/ccCallDisconnect:
Cause Value=102, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*May 25 10:50:40.805: //5014/558275C9A851/CCAPI/ccCallDisconnect:
Cause Value=102, Call Entry(Responsed=TRUE, Cause Value=102)
*May 25 10:50:40.805: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*May 25 10:50:40.805: //5013/558275C9A851/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
*May 25 10:50:40.809: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*May 25 10:50:40.809: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
BYE sip:0359159401@192.168.0.9:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CF186F
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>;tag=5104~12d5d499-38ad-47af-a90a-d75e386b62e1-33197460
Date: Wed, 25 May 2016 10:50:31 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 70
Timestamp: 1464173440
CSeq: 102 BYE
Reason: Q.850;cause=102
P-RTP-Stat: PS=372,OS=59520,PR=378,OR=60480,PL=0,JI=0,LA=0,DU=7
Content-Length: 0

*May 25 10:50:41.009: //5014/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.41:5060;branch=z9hG4bK6CF186F
From: "61418656729" <sip:0418656729@sip.telecube.com.au>;tag=91DEBCC-185D
To: <sip:0359159401@192.168.0.9>;tag=5104~12d5d499-38ad-47af-a90a-d75e386b62e1-33197460
Date: Wed, 25 May 2016 10:29:28 GMT
Call-ID: 55892E1F-219D11E6-A857A914-AEFF3C37@192.168.0.41
Server: Cisco-CUCM11.0
CSeq: 102 BYE
Content-Length: 0

*May 25 10:50:41.013: //5014/558275C9A851/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4AC0F6C0, Tag=0x0, Call Id=5014,
Call Entry(Disconnect Cause=102, Voice Class Cause Code=0, Retry Count=0)
*May 25 10:50:41.013: //5014/558275C9A851/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*May 25 10:50:41.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*May 25 10:50:41.013: :cc_free_feature_vsa freeing 4D05F8E8
*May 25 10:50:41.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*May 25 10:50:41.013: vsacount in free is 1
*May 25 10:50:41.013: //5014/000000000000/SIP/State/sipSPIChangeState: 0x4BA76A80 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*May 25 10:50:41.013: //5014/000000000000/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x4BA76A80
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 0418656729
Called Number : 0359159401
Source IP Address (Sig ): 192.168.0.41
Destn SIP Req Addr:Port : 192.168.0.9:5060
Destn SIP Resp Addr:Port : 192.168.0.9:5060
Destination Name : 192.168.0.9

*May 25 10:50:41.013: //5014/000000000000/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 192.168.0.41
Source IP Port (Media): 18736
Destn IP Address (Media): 192.168.0.15
Destn IP Port (Media): 24724
Orig Destn IP Address:Port (Media): [ - ]:0

*May 25 10:50:41.013: //5014/000000000000/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 102
Disconnect Cause (SIP) : 200

*May 25 10:50:41.021: //5013/558275C9A851/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.41:5060;received=49.177.58.109;branch=z9hG4bK6CEC9B
From: <sip:0359159401@103.193.166.11>;tag=91DEBE0-478
To: "61418656729" <sip:0418656729@103.193.166.37>;tag=as75513f08
Call-ID: 14d4c315453e08912a0a7b9a04cd0024@103.193.166.37:5060
CSeq: 101 BYE
Server: Asterisk (Telecube) PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

*May 25 10:50:41.025: //5013/558275C9A851/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x4AC0F6C0, Tag=0x0, Call Id=5013,
Call Entry(Disconnect Cause=102, Voice Class Cause Code=0, Retry Count=0)
*May 25 10:50:41.025: //5013/558275C9A851/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*May 25 10:50:41.025: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*May 25 10:50:41.025: :cc_free_feature_vsa freeing 4D05F3A8
*May 25 10:50:41.025: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*May 25 10:50:41.025: vsacount in free is 0
*May 25 10:50:41.025: //5013/558275C9A851/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*May 25 10:50:41.025: //5013/558275C9A851/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x4BA38CD8
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 0418656729
Called Number : 0359159401
Source IP Address (Sig ): 192.168.0.41
Destn SIP Req Addr:Port : 103.193.166.11:5060
Destn SIP Resp Addr:Port : 103.193.166.11:5060
Destination Name : 103.193.166.11

*May 25 10:50:41.025: //5013/558275C9A851/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : g711ulaw
Negotiated Codec Bytes : 160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101 (tx), 101 (rx)
Source IP Address (Media): 192.168.0.41
Source IP Port (Media): 16778
Destn IP Address (Media): 103.193.166.37
Destn IP Port (Media): 11918
Orig Destn IP Address:Port (Media): [ - ]:0

*May 25 10:50:41.025: //5013/558275C9A851/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 86
Disconnect Cause (SIP) : 200

Cisco2811#
*May 25 10:51:25.281: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:192.168.0.41:5060 SIP/2.0
Via: SIP/2.0/TCP 192.168.0.9:5060;branch=z9hG4bK12c57c4c02c5
From: <sip:192.168.0.9>;tag=1723005953
To: <sip:192.168.0.41>
Date: Wed, 25 May 2016 10:30:13 GMT
Call-ID: a8beae00-74517eb5-12b2-900a8c0@192.168.0.9
User-Agent: Cisco-CUCM11.0
CSeq: 101 OPTIONS
Contact: <sip:192.168.0.9:5060;transport=tcp>
Max-Forwards: 0
Content-Length: 0

*May 25 10:51:25.281: //-1/75E3A602A858/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*May 25 10:51:25.285: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_OPTIONS_RESP
*May 25 10:51:25.285: //5015/75E3A602A858/SIP/State/sipSPIChangeState: 0x4BA38CD8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
*May 25 10:51:25.289: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.0.9:5060;branch=z9hG4bK12c57c4c02c5
From: <sip:192.168.0.9>;tag=1723005953
To: <sip:192.168.0.41>;tag=91EBFD0-75B
Date: Wed, 25 May 2016 10:51:25 GMT
Call-ID: a8beae00-74517eb5-12b2-900a8c0@192.168.0.9
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 169

v=0
o=CiscoSystemsSIP-GW-UserAgent 5832 9003 IN IP4 192.168.0.41
s=SIP Call
c=IN IP4 192.168.0.41
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 192.168.0.41



 

1 person had this problem
Labels:
0 Helpful
1 Accepted Solution

Accepted Solutions

Go to solution
Vivek Batra
VIP Alumni
VIP Alumni

‎05-25-2016 05:01 AM

Hi,

As you can see CUBE is retransmitting 200 OK to provider and waiting for ACK however there is no ACK from provider and hence CUBE is terminating the call.

You can check any network issues to 103.193.166.11 or check with provider if receiving 200 OK or not.

- Vivek

View solution in original post

2 Replies 2

Go to solution
Vivek Batra
VIP Alumni
VIP Alumni

‎05-25-2016 05:01 AM

Hi,

As you can see CUBE is retransmitting 200 OK to provider and waiting for ACK however there is no ACK from provider and hence CUBE is terminating the call.

You can check any network issues to 103.193.166.11 or check with provider if receiving 200 OK or not.

- Vivek

Go to solution
uthayaman elangovan
Level 1
Level 1

‎05-29-2019 05:17 AM

Hope the issue got fixed. Kindly send me the resolution to fix the issue

0 Helpful
Customers Also Viewed These Support Documents