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
4042
Views
0
Helpful
2
Replies

CUCME - SIP/2.0 482 Loop Detected to viatalk

Djurre Woudstra
Level 1
Level 1

‎02-22-2016 08:12 AM - edited ‎03-18-2019 11:49 AM

Hi All,

I'm trying to setup call manager express with via talk and i'm able to do an out bound call, but get disconnected after 30 second with a "SIP/2.0 482 Loop Detected" error.

Any help is greatly appreciated

Kind regards

DJ

version 15.3
service nagle
service tcp-keepalives-in
service tcp-keepalives-out
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname MED-DC-UCME01
!
boot-start-marker
boot system flash:/c3900e-universalk9-mz.SPA.153-3.M6.bin
boot-end-marker
!
aqm-register-fnf
!
! card type command needed for slot/vwic-slot 0/0
!
aaa new-model
!
!
aaa authentication login default local
!
!
!
!
!
aaa session-id common
clock timezone EST -5 0
clock summer-time EDT recurring
!
!
!
!
!


!
ip dhcp excluded-address 10.227.35.1 10.227.35.10
!
ip dhcp pool vlan35-voice
network 10.227.35.0 255.255.255.0
default-router 10.227.35.1
dns-server 10.227.10.20 192.168.1.21
option 150 ip 10.227.35.4
lease 7
!
!
!
ip cef
no ipv6 cef
!
!
multilink bundle-name authenticated
!
!
!
!
!
!
voice-card 0
dspfarm
dsp services dspfarm
!
!
!
voice service voip
ip address trusted list
ipv4 10.1.1.1
ipv4 66.147.235.3
ipv4 174.133.67.154
ipv4 173.231.3.114
ipv4 216.120.237.24
ipv4 192.168.11.40
ipv4 0.0.0.0 0.0.0.0
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
sip
bind control source-interface BVI255
bind media source-interface BVI255
registrar server expires max 250 min 200
outbound-proxy dns:bumblebee.vtnoc.net
no call service stop
!
voice class codec 1
codec preference 1 g729br8
codec preference 2 g711ulaw
codec preference 3 g711alaw
codec preference 4 g729r8
!
!
voice register global
mode cme
source-address 10.227.35.4 port 5060
load 7941 SCCP41.9-2-1S
load 7941GE SCCP41.9-2-1S
load 7942 SCCP42.9-2-1S
load 7961 SCCP41.9-2-1S
load 7961GE SCCP41.9-2-1S
load 7962 SCCP42.9-2-1S
authenticate register
tftp-path flash:
create profile sync 0086021255010478
!
voice hunt-group 1 parallel
final 2004
list 2009,2004
timeout 20
pilot 2000
!
!
!
!
voice translation-rule 1
rule 1 /9911/ /911/
rule 2 /9411/ /411/
!
voice translation-rule 2
rule 1 /9+/ //
!
voice translation-rule 3
rule 1 /9[2-9]........./ /1/
!
voice translation-rule 4
rule 1 /91+/ /1/
!
voice translation-rule 5
rule 1 /9011+/ /011/
!
voice translation-rule 6
rule 1 /178xxxxxxxx/ /2009/
!
voice translation-rule 7
rule 1 /9+/ //
!
!
voice translation-profile Incoming
translate calling 7
translate called 6
!
voice translation-profile International
translate calling 7
translate called 5
!
voice translation-profile Local10
translate calling 7
translate called 3
!
voice translation-profile Local7
translate calling 7
translate called 2
!
voice translation-profile LongDistance
translate calling 7
translate called 4
!
voice translation-profile Services
translate calling 7
translate called 1
!
!
!
license udi pid C3900-SPE250/K9 sn FOC18500YE4
license boot module c3900e technology-package securityk9
license boot module c3900e technology-package uck9
license boot module c3900e technology-package datak9
!
!
hw-module pvdm 0/0
!
file privilege 0
!
redundancy
!
!
!
!
!
track 100 ip sla 100 reachability
!
!
class-map match-all L3-to-L2_VoIP-Cntrl
match ip dscp af31
class-map match-all L3-to-L2_VoIP-RTP
match ip dscp ef
class-map match-all SIP
match protocol sip
class-map match-all RTP
match protocol rtp
!
policy-map output-L3-to-L2
class L3-to-L2_VoIP-RTP
set cos 5
class L3-to-L2_VoIP-Cntrl
set cos 3
policy-map EthOut
class RTP
!
!
!
!
!
!
bridge irb
!
!
!
!
interface GigabitEthernet0/0
no ip address
ip virtual-reassembly in
duplex auto
speed auto
no cdp enable
!
interface GigabitEthernet0/1
no ip address
duplex auto
speed auto
!
interface GigabitEthernet0/2
no ip address
duplex auto
speed auto
!
interface GigabitEthernet0/2.11
encapsulation dot1Q 11
bridge-group 11
!
interface GigabitEthernet0/2.35
encapsulation dot1Q 35
bridge-group 35
!
interface GigabitEthernet0/2.255
encapsulation dot1Q 255
bridge-group 255
!
interface GigabitEthernet0/3
no ip address
duplex auto
speed auto
!
interface GigabitEthernet0/3.11
encapsulation dot1Q 11
bridge-group 11
!
interface GigabitEthernet0/3.35
encapsulation dot1Q 35
bridge-group 35
!
interface GigabitEthernet0/3.255
encapsulation dot1Q 255
bridge-group 255
!
interface BVI11
ip address 10.227.11.4 255.255.255.0
ip nat inside
ip virtual-reassembly in
!
interface BVI35
ip address 10.227.35.4 255.255.255.0
ip nat inside
ip virtual-reassembly in
!
interface BVI255
ip address 12.x.x.x 255.255.255.248
!
!
ip forward-protocol nd
!
ip http server
ip http authentication local
ip http secure-server
ip http timeout-policy idle 60 life 86400 requests 10000
ip http path flash:/cmegui
!
ip route 0.0.0.0 0.0.0.0 12.x.x.x
ip route 10.0.0.0 255.0.0.0 10.227.11.1
ip route 192.168.0.0 255.255.0.0 10.227.11.1
!
ip access-list standard remote-ssh-in
remark ----ssh in from cayman office----
permit 10.0.0.0 0.255.255.255
permit 172.16.0.0 0.15.255.255
permit 192.168.0.0 0.0.255.255
permit 74.x.x.x 0.0.0.7
ip access-list standard rfc1918-no-nat
deny 10.0.0.0 0.255.255.255
deny 172.16.0.0 0.15.255.255
deny 192.168.0.0 0.0.255.255
permit any
!
!
nls resp-timeout 1
cpd cr-id 1
!
tftp-server flash:/phoneloads/7941-7961/apps41.9-2-1TH1-13.sbn alias apps41.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7941-7961/cnu41.9-2-1TH1-13.sbn alias cnu41.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7941-7961/cvm41sccp.9-2-1TH1-13.sbn alias cvm41sccp.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7941-7961/dsp41.9-2-1TH1-13.sbn alias dsp41.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7941-7961/jar41sccp.9-2-1TH1-13.sbn alias jar41sccp.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7941-7961/SCCP41.9-2-1S.loads alias SCCP41.9-2-1S.loads
tftp-server flash:/phoneloads/7941-7961/term41.default.loads alias term41.default.loads
tftp-server flash:/phoneloads/7941-7961/term61.default.loads alias term61.default.loads
tftp-server flash:/phoneloads/7940-7960/P0030801SR02.bin alias P0030801SR02.bin
tftp-server flash:/phoneloads/7940-7960/P0030801SR02.loads alias P0030801SR02.loads
tftp-server flash:/phoneloads/7940-7960/P0030801SR02.sb2 alias P0030801SR02.sb2
tftp-server flash:/phoneloads/7940-7960/P0030801SR02.sbn alias P0030801SR02.sbn
tftp-server flash:/phoneloads/7940-7960/P0030801SR02.txt alias P0030801SR02.txt
tftp-server flash:/phoneloads/7942-7962/apps42.9-2-1TH1-13.sbn alias apps42.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7942-7962/cnu42.9-2-1TH1-13.sbn alias cnu42.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7942-7962/cvm42sccp.9-2-1TH1-13.sbn alias cvm42sccp.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7942-7962/dsp42.9-2-1TH1-13.sbn alias dsp42.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7942-7962/jar42sccp.9-2-1TH1-13.sbn alias jar42sccp.9-2-1TH1-13.sbn
tftp-server flash:/phoneloads/7942-7962/SCCP42.9-2-1S.loads alias SCCP42.9-2-1S.loads
tftp-server flash:/phoneloads/7942-7962/term42.default.loads alias term42.default.loads
tftp-server flash:/phoneloads/7942-7962/term62.default.loads alias term62.default.loads
!
!
!
control-plane
!
bridge 11 protocol ieee
bridge 11 route ip
bridge 35 protocol ieee
bridge 35 route ip
bridge 255 protocol ieee
bridge 255 route ip
!
!
!
!
!
!
mgcp behavior rsip-range tgcp-only
mgcp behavior comedia-role none
mgcp behavior comedia-check-media-src disable
mgcp behavior comedia-sdp-force disable
!
mgcp profile default
!
!
dspfarm profile 1 transcode
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 32
associate application SCCP
!
dial-peer voice 20 voip
description Incomming SIP CAY->MED
session protocol sipv2
session target ipv4:10.1.1.1
session transport udp
incoming called-number 2...
voice-class sip bind control source-interface BVI11
voice-class sip bind media source-interface BVI11
codec g711ulaw
ip qos dscp cs5 media
ip qos dscp cs4 signaling
!
dial-peer voice 21 voip
description Outgoing SIP MED->CAY
destination-pattern 8...
session protocol sipv2
session target ipv4:10.1.1.1
session transport udp
voice-class sip bind control source-interface BVI11
voice-class sip bind media source-interface BVI11
codec g711ulaw
ip qos dscp cs5 media
ip qos dscp cs4 signaling
!
dial-peer voice 10 voip
translation-profile outgoing Services
destination-pattern 9[49]11
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 11 voip
translation-profile outgoing Local10
destination-pattern 9786[2-9]......
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 12 voip
translation-profile outgoing Local7
shutdown
destination-pattern 9[2-9]......
session protocol sipv2
session target dns:bumblebee.vtnoc.net
incoming called-number .
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 13 voip
translation-profile outgoing LongDistance
destination-pattern 91[2-9]..[2-9]......
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 14 voip
translation-profile outgoing International
destination-pattern 9011T
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 15 voip
description Viatalk Voicemail
destination-pattern *123
session protocol sipv2
session target dns:bumblebee.vtnoc.net
codec g711ulaw
!
dial-peer voice 17 voip
description Unblock CallerID information
destination-pattern *82
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 18 voip
description Call Back The last incoming caller
destination-pattern *69
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
dial-peer voice 19 voip
description Broadcast Menu/Record Broadcast Messages
destination-pattern *400
session protocol sipv2
session target dns:bumblebee.vtnoc.net
dtmf-relay rtp-nte
codec g711ulaw
!
!
gateway
!
sip-ua
authentication username 178xxxxxxxx password 7 05xxxxxxxxxxx
no remote-party-id
retry invite 4
retry response 3
retry bye 2
retry cancel 2
retry register 5
timers register 250
mwi-server dns:bumblebee.vtnoc.net expires 300 port 5060 transport udp
registrar dns:bumblebee.vtnoc.net expires 300
sip-server dns:bumblebee.vtnoc.net
host-registrar
!
!
!
gatekeeper
shutdown
!
!
telephony-service
sdspfarm conference mute-on 111 mute-off 222
sdspfarm units 4
sdspfarm transcode sessions 10
sdspfarm tag 1 HWCONF
conference hardware
video
max-ephones 32
max-dn 128
ip source-address 10.227.35.4 port 2000
max-redirect 20
service phone videoCapability 1
service phone ehookenable 1
service dnis overlay
service dnis dir-lookup
load 7941 SCCP41.9-2-1S
load 7941GE SCCP41.9-2-1S
load 7942 SCCP42.9-2-1S
load 7961 SCCP41.9-2-1S
load 7961GE SCCP41.9-2-1S
load 7962 SCCP42.9-2-1S
time-zone 13
max-conferences 8 gain -6
web admin system name admin secret 5xxxxxxx
dn-webedit
transfer-system full-consult
transfer-pattern 9.T
transfer-pattern .T
secondary-dialtone 9
night-service day Sun 17:00 08:00
night-service day Mon 17:00 08:00
night-service day Tue 17:00 08:00
night-service day Wed 17:00 08:00
night-service day Thu 17:00 08:00
night-service day Fri 17:00 08:00
night-service day Sat 17:00 08:00
fac standard
create cnf-files version-stamp 7960 Feb 18 2016 14:38:10
!
!
ephone-dn 1 dual-line
number 2001
label 2001

!
!
ephone-dn 2 dual-line
number 2002
label 2002

!
!
ephone-dn 3 dual-line
number 2003
label 2003
!
!
ephone-dn 4 dual-line
number 2004
label 2004
!
!
ephone-dn 5 dual-line
number 2005
label 2005

!
!
ephone-dn 6 dual-line
number 2006
label 2006
!
!
ephone-dn 7
!
!
ephone-dn 9
number 2009 no-reg primary
label 2009
name DJ's Test phone
hold-alert 30 originator
!
!
ephone 1
device-security-mode none
mac-address 0023.331B.77E7
button 1:1
night-service bell
!
!
!
ephone 2
device-security-mode none
mac-address 0023.339B.102D
button 1:2
!
!
!
ephone 3
device-security-mode none
mac-address 0023.331B.C815
button 1:3
!
!
!
ephone 4
device-security-mode none
mac-address 0021.A02D.EFF5
button 1:4
!
!
!
ephone 5
device-security-mode none
mac-address 0023.331B.C9AF
button 1:5
!
!
!
ephone 6
device-security-mode none
mac-address 0023.EB53.94E1
button 1:6
!
!
!
ephone 7
device-security-mode none
mac-address 0050.56A5.EF0D
type CIPC
button 1:9
!
!
!
!
line con 0
line aux 0
line vty 0 4
timeout login response 300
privilege level 15
transport input telnet ssh
transport output telnet ssh
line vty 5 15
privilege level 15
transport input telnet
transport output telnet
!
scheduler allocate 20000 1000
ntp update-calendar
ntp server 129.6.15.30 prefer
ntp server 132.163.4.102
!
end

Labels:
0 Helpful
2 Replies 2

Djurre Woudstra
Level 1
Level 1

‎02-22-2016 08:13 AM

Debug


Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B6BA190) with key=[23214] to table
Feb 19 20:19:34.152: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:34.152: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:34.152: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/critical/32768/ccsip_ipip_media_forking_read_from_TDContainer: MF: Unable to read data from TD Container..
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/critical/32768/ccsip_ipip_media_forking_forked_leg_config: MF: TD container cannot be read/container is NULL. Setting of forked call leg failed..
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: EV_UNDEFINED
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_SET_MODE
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_default_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_MODE
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sip_md_snr:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sip_ed_snr:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sip_md:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sip_ed:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_h32x_in_set_mode:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_h323_in_set_mode:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sccp_in_set_mode:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_mode_sip_sccp_in_set_mode:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/8192/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sccp_early_dialog_container
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: CC_EV_IF_DIAG_DONE
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_SET_FLOW_MODE
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SET_FLOW_MODE
Feb 19 20:19:34.152: //28231/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_fa2ft_md_flow_mode_transition:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_fa2ft_flow_mode_transition:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/is_fa2ft_flow_mode_transition:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
Feb 19 20:19:34.152: //28231/000000000000/SIP/Info/info/4096/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_call_setup_request: Number Translation Set For Called-Number
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIUaddccCallIdToTable: Adding call id 6E47 to table
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/131072/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [13]
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/2049/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/2048/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 28231
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/32768/sipSPIGetCallConfig: Media Antitrombone disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/131072/sipSPICanSetFallbackFlag: Local Fallback is not active
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4/update_video_params_from_config: Checking Video Type Rate=-1 video_codec_allowed=1F
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=28230
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No defer BYE for last
call stats
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/36864/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/32768/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/32768/ccsip_ipip_media_forking_intra_frame_request_config: MF: FIR en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/11264/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIGetCallConfig: Initilise the DSCP policy
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/1/preprocessSetup:
This is a not a SIGO Call -, could be DM call
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_INIT_CALL_SETUP
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/4096/preprocessSetup: SIP-TDM or TCL/VXML app case
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/4096/sip_gw_pre_setup_update_stream_media_direction: peer_callID = 28230
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/4097/sip_gw_pre_setup_update_stream_media_direction: peer_channels/stream is NULL
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/1/sip_gw_pre_setup_add_sdp_container: DNS/ENUM resolution required; Deferred Creating SDP
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 0 flow mode flow-through
audio bw 0 bps video bw 0 bps fax bw 0 bps total bw 0 bps accounted bw 0 bps
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface BVI255 bw 0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 0
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/133120/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 13
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 0 bps
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/8192/sipSPIValidateGtd: Signal Forward disabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/8192/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/10240/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/3072/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/9216/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIAddPrivacyandIdentityInfo: ccb->local_host_name,ccb->src_addr_str is NULL
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/critical/2048/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Error/sipSPI_ipip_set_history_info_header:
ccb->src_addr_str is NULL
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS)
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_SENT_DNS)
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B6BA190 ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B6BA190 key=ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3 balance 1
Feb 19 20:19:34.152: //28231/EB59E1D3B413/SIP/Info/verbose/12288/sipSPIUsetBillingProfile: sipCallId for billing records = ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
Feb 19 20:19:34.152: //-1/xxxxxxxxxxxx/SIP/Info/notify/8192/sip_dns_type_srv_query: TYPE SRV query for _sip._udp.bumblebee.vtnoc.net and type:1
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: Server Name bumblebee.vtnoc.net
Priority 0 Weight 1 Port 5060
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: Server Name soundwave.vtnoc.net
Priority 1 Weight 1 Port 5060
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: Server Name ratchet.vtnoc.net
Priority 1 Weight 1 Port 5060
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: Server Name slingshot.vtnoc.net
Priority 1 Weight 1 Port 5060
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sip_dns_type_srv_query: Calculating Random Number : 26686 factor 65535 value 0
Feb 19 20:19:34.180: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: Selected Server is bumblebee.vtnoc.net
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/notify/8192/sip_dns_type_a_query: TYPE A query successful for bumblebee.vtnoc.net
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_query: ttl for A records = 177 seconds
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: IP Address of bumblebee.vtnoc.net is:

Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: 66.147.235.3

Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_srv_query: ttl for SRV records = 623seconds
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: IP Address No. 1, IP address 66.147.235.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIRscmsmAvail: Value returned by check is = 0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/2048/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/1024/sipSPI_ipip_set_history_info_header: No HI header recvd from container
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: peer_tag=13
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 12.1.2.3
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/1/sipSPIOutgoingCallSDP: Failure in creating outbound streams
SIP: (28231) Group (a= group line) attribute, level 65535 instance 1 not found.
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 19606 for stream 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIDoBearerCapToCodecMapping: Bearer capability to Codec Mapping: DISABLED

Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
SIP: (28231) Group (a= group line) attribute, level 65535 instance 1 not found.
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711ulaw bw 64000 index 0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/2/sipSPIBwCacCalcMaxAudioBandwidth: audio caps channel idx not found !!!!
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711ulaw bw 80000
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/8193/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_IDLE
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIProcessRtpSessions: No active streams.
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 5 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps accounted bw 0 bps
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/139264/sipSPIBwCacUpdateInterfaceBw: NOP (no interface change)
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (no interface change)
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 66.147.235.3,Port 5060, Transport 1, SentBy Port 5060
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:2F93A130
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/Session-Timer/sipSTSLSRReqSend: Session timer is not required
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 0 event
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_handle_sent_sdp:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_IDLE, event:E_SIP_INVITE_SDP_SENT
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_is_invite_offer_valid: TRUE
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_common_offer_sent_hdlr:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_network_event:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_IDLE, event:E_SIP_IWF_EV_SENT_SDP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/is_sent_sccp_do_video_inactive:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_sccp_do_video:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_sccp_do_video:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/sip_iwf_def_ed_sent_sdp_offer_hdlr:
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/sipSPIgetRegistrarHost: registrar host retrieved : bumblebee.vtnoc.net
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/4096/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not a Forked leg..
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/1024/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
SIP: (28231) Group (a= group line) attribute, level 65535 instance 1 not found.
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/34816/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendInvite: Associated container=0x2F93A130 to Invite
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 277
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x245391FC, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F5026C
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B6BA190 with connection=0x24D9EACC context list
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x245391FC
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x245391FC, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/512/sentInviteRequest: Sent Invite in state STATE_IDLE
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sentInviteRequest: Transaction active. Facilities will be queued.
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ADDING
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/6/sipSPIAddStream: set stream_callid from ccb->ccCallID:0x6E47, media_type:0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 28231) to the VOIP RTP library
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 12.1.2.3
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIAssignStreamCallId: Assigning streamid 28231 to stream 1. Old streamid = 28231
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPIUpdateRtcpSession: ccb->flags != LOOPBACK
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: Setting do_rtcp = FALSE 0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 12.1.2.3, lport = 19606, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE
src_callid = 28231, dest_callid = -1, stream type = voice+dtmf, stream direction = SENDRECV
media_ip_addr = - , vrf tableid = 0 media_addr_type = 1negotiated_bandwidth (kbps) = 0 srtp_services = 0 nat_flag = 0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/verbose/1/sipSPICreateRtpSession: sess: 220713D4 do_rtcp:0
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Media/sipSPICreateRtpSession: stun is disabled
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/critical/131072/sipSPIUpdateRtcpSession: Not able to Associate DSCP Profile with GCCB dscp_policy = 0x0, IS_SIPSPI_MODE_IN_SIP_SIP = 0 dscpPolicySeviceBlock = 0x0 , stream->qos_info = 0x0
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: Voice quality monitoring is not enabled for this RTP session due to sdp passthru enabled
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=28231
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/notify/2080/sipSPIUpdateRtcpSession: DTMF inb/oob disabled
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/State/sipSPIChangeStreamState: Stream (callid = 28231) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Info/info/2/sipSPIUpdateCallEntry:
Call 28231 set InfoType to SPEECH
Feb 19 20:19:34.182: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free:
Freeing NULL pointer!
Feb 19 20:19:34.182: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:18662966457@bumblebee.vtnoc.net:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK798C11E1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 3948536275-3598455269-3021200718-1106222154
User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6
All
Feb 19 20:19:34.332: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Feb 19 20:19:34.332: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2760FB28) counter, current msg->refCount = 1
Feb 19 20:19:34.332: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B6A0A10) with key=[23215] to table
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:bumblebee.vtnoc.net
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6A0A10 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 1
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B6C6D50) with key=[23216] to table
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:bumblebee.vtnoc.net
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6C6D50 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 2
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B6AD5D0) with key=[23217] to table
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:bumblebee.vtnoc.net
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6AD5D0 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to bumblebee.vtnoc.net:5060

Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B6A0A10 4DC50FFD-D67211E5-B373DD4E-41EF9C4A
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B6A0A10 key=4DC50FFD-D67211E5-B373DD4E-41EF9C4A balance 0
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:66.147.235.3:5060
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x27321740 with refCount = 1
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 7 event
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x2F939688 to Register
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:35.886: //28232/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Feb 19 20:19:35.886: //28232/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:35.886: //28232/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x27321740, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F4EA94
Feb 19 20:19:35.886: //28232/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //28232/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B6A0A10 with connection=0x24D9EACC context list
Feb 19 20:19:35.886: //28232/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x27321740
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x27321740, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:35.886: //28232/000000000000/SIP/State/sipSPIChangeState: 0x2B6A0A10 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //28232/000000000000/SIP/State/sipSPIChangeState: 0x2B6A0A10 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to bumblebee.vtnoc.net:5060

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B6C6D50 4DC50FFD-D67211E5-B377DD4E-41EF9C4A
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B6C6D50 key=4DC50FFD-D67211E5-B377DD4E-41EF9C4A balance 1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:66.147.235.3:5060
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2118DDB0 with refCount = 1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 7 event
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x2F938558 to Register
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x2118DDB0, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F4EA94
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B6C6D50 with connection=0x24D9EACC context list
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2118DDB0
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2118DDB0, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6C6D50 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6C6D50 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to bumblebee.vtnoc.net:5060

Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B6AD5D0 4DC50FFD-D67211E5-B379DD4E-41EF9C4A
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B6AD5D0 key=4DC50FFD-D67211E5-B379DD4E-41EF9C4A balance 0
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:66.147.235.3:5060
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x24CCC698 with refCount = 1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 7 event
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x2F938818 to Register
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x24CCC698, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F4EA94
Feb 19 20:19:35.886: //-1/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B6AD5D0 with connection=0x24D9EACC context list
Feb 19 20:19:35.886: //-1/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x24CCC698
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x24CCC698, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6AD5D0 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6AD5D0 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:bumblebee.vtnoc.net:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK798E110F
From: <sip:2001@bumblebee.vtnoc.net>;tag=B8F1AC9C-1898
To: <sip:2001@bumblebee.vtnoc.net>
Date: Fri, 19 Feb 2016 20:19:35 GMT
Call-ID: 4DC50FFD-D67211E5-B373DD4E-41EF9C4A
User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6
Max-Forwards: 70
Timestamp: 1455913175
CSeq: 40 REGISTER
Contact: <sip:2001@12.1.2.3:5060>
Expires: 300
Supported: path
Content-Length: 0

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x27321740
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:bumblebee.vtnoc.net:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK798F111F
From: <sip:2005@bumblebee.vtnoc.net>;tag=B8F1AC9C-67
To: <sip:2005@bumblebee.vtnoc.net>
Date: Fri, 19 Feb 2016 20:19:35 GMT
Call-ID: 4DC50FFD-D67211E5-B377DD4E-41EF9C4A
User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6
Max-Forwards: 70
Timestamp: 1455913175
CSeq: 40 REGISTER
Contact: <sip:2005@12.1.2.3:5060>
Expires: 300
Supported: path
Content-Length: 0

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2118DDB0
Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:bumblebee.vtnoc.net:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799072
From: <sip:2009@bumblebee.vtnoc.net>;tag=B8F1AC9C-F9C
To: <sip:2009@bumblebee.vtnoc.net>
Date: Fri, 19 Feb 2016 20:19:35 GMT
Call-ID: 4DC50FFD-D67211E5-B379DD4E-41EF9C4A
User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6
Max-Forwards: 70
Timestamp: 1455913175
CSeq: 40 REGISTER
Contact: <sip:2009@12.1.2.3:5060>
Expires: 300
Supported: path
Content-Length: 0

Feb 19 20:19:35.886: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x24CCC698
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B69A430) with key=[23218] to table
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:bumblebee.vtnoc.net
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Feb 19 20:19:35.890: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B69A430 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 4
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x2B6B3BB0) with key=[23219] to table
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:bumblebee.vtnoc.net
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : bumblebee.vtnoc.net target_port : 5060

Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Feb 19 20:19:35.890: //-1/000000000000/SIP/State/sipSPIChangeState: 0x2B6B3BB0 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 5
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to bumblebee.vtnoc.net:5060

Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B69A430 4DC50FFD-D67211E5-B374DD4E-41EF9C4A
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B69A430 key=4DC50FFD-D67211E5-B374DD4E-41EF9C4A balance 0
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:66.147.235.3:5060
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x232E2520 with refCount = 1
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 7 event
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x2F9399A0 to Register
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:35.890: //28235/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Feb 19 20:19:35.890: //28235/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:35.890: //28235/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x232E2520, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F4EA94
Feb 19 20:19:35.890: //28235/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.890: //28235/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x2B69A430 with connection=0x24D9EACC context list
Feb 19 20:19:35.890: //28235/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x232E2520
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x232E2520, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:35.890: //28235/000000000000/SIP/State/sipSPIChangeState: 0x2B69A430 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.890: //28235/000000000000/SIP/State/sipSPIChangeState: 0x2B69A430 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to bumblebee.vtnoc.net:5060

Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x2B6B3BB0 4DC50FFD-D67211E5-B378DD4E-41EF9C4A
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x2B6B3BB0 key=4DC50FFD-D67211E5-B378DD4E-41EF9C4A balance 1
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:66.147.235.3:5060
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 12.1.2.3 for SIP
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 12.1.2.3
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 12.1.2.3
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2118DDB0 with refCount = 1
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 7 event
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone EST to SIP default timezone = GMT
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:35.890: //-1/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x2F937ED0 to Register
Feb 19 20:19:35.890: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:35.890: //-1/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Feb 19 20:19:35.890: //-1/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE,uri="sip:bumblebee.vtnoc.net:5060",response="1faa69f467f50792d76a833078f10fa4",nonce="56c778f600003b0f017bc5bccb960caee1df4808c51e78e6",cnonce="82E89FBA",qop=auth,algorithm=md5,nc=00000001
Content-Length: 0

Feb 19 20:19:35.962: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x232E2520
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x232E2520 with refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x204E3B74 with refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x232E2520
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/info/1024/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK799710E5 from via branch list
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x204E3B74) counter, current msg->refCount = 2
Feb 19 20:19:36.022: //-1/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 12.1.2.3:5060;received=12.1.2.3;rport=62497;branch=z9hG4bK799710E5
From: <sip:2005@bumblebee.vtnoc.net>;tag=B8F1AC9C-67
To: <sip:2005@bumblebee.vtnoc.net>;tag=8324aa8d99b6bc7e21f3eb535a640969.1014
Call-ID: 4DC50FFD-D67211E5-B377DD4E-41EF9C4A
CSeq: 41 REGISTER
Contact: <sip:2005@12.1.2.3:5060>;expires=300;received="sip:12.1.2.3:62497"
Server: OpenSIPS (1.8.3-tls (x86_64/linux))
Content-Length: 0
Warning: 392 66.147.235.3:5060 "Noisy feedback tells: pid=31738 req_src_ip=12.1.2.3 req_src_port=62497 in_uri=sip:bumblebee.vtnoc.net:5060 out_uri=sip:bumblebee.vtnoc.net:5060 via_cnt==1"

Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/verbose/1024/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIDecrementOverloadCount: Count:Local 0 Global 6
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/notify/262144/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 200
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIGetRPCBFromRCB: Retreiving RCB [0x2B825288] from RPCB [0x0]
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/critical/262144/sipSPIRegPthruProcessResponse: Error NO RPCB

Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern 2005 for 240 seconds
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/notify/1024/ccsip_gw_register_process_response: No P-Assoicated-URI present in Register Response
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x204E3B74) counter, current msg->refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[23216] removed.
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2B6C6D50 key=4DC50FFD-D67211E5-B377DD4E-41EF9C4A
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, unregistering context=0x2B6C6D50
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x2B6C6D50 from the connection=0x24D9EACC context list
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Feb 19 20:19:36.022: //-1/000000000000/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Feb 19 20:19:36.022: //28233/000000000000/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x2B6C6D50
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[23216]
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x204E3B74
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x232E2520 with refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/info/1024/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK799521ED from via branch list
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x232E2520) counter, current msg->refCount = 2
Feb 19 20:19:36.022: //28232/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 12.1.2.3:5060;received=12.1.2.3;rport=62497;branch=z9hG4bK799521ED
From: <sip:2001@bumblebee.vtnoc.net>;tag=B8F1AC9C-1898
To: <sip:2001@bumblebee.vtnoc.net>;tag=8324aa8d99b6bc7e21f3eb535a640969.69a4
Call-ID: 4DC50FFD-D67211E5-B373DD4E-41EF9C4A
CSeq: 41 REGISTER
Contact: <sip:2001@12.1.2.3:5060>;expires=300;received="sip:12.1.2.3:62497"
Server: OpenSIPS (1.8.3-tls (x86_64/linux))
Content-Length: 0
Warning: 392 66.147.235.3:5060 "Noisy feedback tells: pid=31748 req_src_ip=12.1.2.3 req_src_port=62497 in_uri=sip:bumblebee.vtnoc.net:5060 out_uri=sip:bumblebee.vtnoc.net:5060 via_cnt==1"

Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/verbose/1024/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIDecrementOverloadCount: Count:Local 0 Global 5
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/notify/262144/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 200
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIGetRPCBFromRCB: Retreiving RCB [0x2B8235C0] from RPCB [0x0]
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/critical/262144/sipSPIRegPthruProcessResponse: Error NO RPCB

Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern 2001 for 240 seconds
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/notify/1024/ccsip_gw_register_process_response: No P-Assoicated-URI present in Register Response
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x232E2520) counter, current msg->refCount = 1
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[23215] removed.
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2B6A0A10 key=4DC50FFD-D67211E5-B373DD4E-41EF9C4A
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, unregistering context=0x2B6A0A10
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x2B6A0A10 from the connection=0x24D9EACC context list
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Feb 19 20:19:36.022: //28232/000000000000/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x2B6A0A10
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[23215]
Feb 19 20:19:36.022: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x232E2520
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/info/1024/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK79981D51 from via branch list
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x245391FC) counter, current msg->refCount = 2
Feb 19 20:19:36.026: //28235/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 12.1.2.3:5060;received=12.1.2.3;rport=62497;branch=z9hG4bK79981D51
From: <sip:2002@bumblebee.vtnoc.net>;tag=B8F1ACA0-30F
To: <sip:2002@bumblebee.vtnoc.net>;tag=8324aa8d99b6bc7e21f3eb535a640969.4ae7
Call-ID: 4DC50FFD-D67211E5-B374DD4E-41EF9C4A
CSeq: 41 REGISTER
Contact: <sip:2002@12.1.2.3:5060>;expires=300;received="sip:12.1.2.3:62497"
Server: OpenSIPS (1.8.3-tls (x86_64/linux))
Content-Length: 0
Warning: 392 66.147.235.3:5060 "Noisy feedback tells: pid=31758 req_src_ip=12.1.2.3 req_src_port=62497 in_uri=sip:bumblebee.vtnoc.net:5060 out_uri=sip:bumblebee.vtnoc.net:5060 via_cnt==1"

Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/verbose/1024/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIDecrementOverloadCount: Count:Local 0 Global 4
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/notify/262144/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 200
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIGetRPCBFromRCB: Retreiving RCB [0x276181E0] from RPCB [0x0]
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/critical/262144/sipSPIRegPthruProcessResponse: Error NO RPCB

Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern 2002 for 240 seconds
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/notify/1024/ccsip_gw_register_process_response: No P-Assoicated-URI present in Register Response
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x245391FC) counter, current msg->refCount = 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[23218] removed.
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x2B69A430 key=4DC50FFD-D67211E5-B374DD4E-41EF9C4A
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, unregistering context=0x2B69A430
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x2B69A430 from the connection=0x24D9EACC context list
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Feb 19 20:19:36.026: //28235/000000000000/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x2B69A430
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[23218]
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x24CCC698 with refCount = 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:36.026: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:36.028: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2FC5EE44
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2FC5EE44 with refCount = 1
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2118DDB0 with refCount = 1
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2FC5EE44
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2118DDB0) counter, current msg->refCount = 2
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: This is first RR in this dialog
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/critical/4096/sipSPICheckReliableProvStringtag: Unable to access supported header values
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sipSPIDoMediaNegotiation: Number of m-lines = 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sipSPIValidateConnectionAddress: Dest port = 19896
SIP: Attribute mid, level 1 instance 1 not found.
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 12.1.2.3
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec No Codec
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 96
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPICheckDynPayloadUse: Dynamic payload(101) could not be reserved as its in use by other codec
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/1/sipSPIUpdateDynamicPT: Requested payload-Type (101) is reserved by another application
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 98
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 101
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIAllocateFreeDynamicPT: Allocating free Dynamic Payload : 98 for Codec:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIDoPtimeNegotiation: No ptime present or multiple ptime attributes that can't be handled
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8/sipSPISetFaxFlags: FAX_PASSTHROUGH = 0, END_FAX_PASSTHROUGH = 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: m-line index 1
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPICheckDynPayloadUse: Dynamic payload(101) reserved for codec
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1/sipSPIUpdateDynamicPayloadunused: Unreserving dynamic payload type 98
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIReserveRtpNtePayload: Reserved the payload type 101 for RTP-NTE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/32/sipSPIDoDTMFRelayNegotiation: RTP-NTE DTMF relay option
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: Case of partial named event(NE) match in fmtp list of events.
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: V150 NSE payload = 0, SSE payload = 0, SPRT payload=0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/critical/8193/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=0, codec_bytes=160, codec=g711ulaw, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=4.55.23.6, dest_port=19896
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING)
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131073/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/131072/sipSPIDoStreamQoSNegotiation: Best effort
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_generic_event:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_CALL_BRIDGE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdCallWithSdpInfo:
Preferred Codec : g711ulaw, bytes :160
Preferred DTMF relay : rtp-nte
Preferred NTE payload : 101
Early Media : No
Delayed Media : No
Bridge Done : No
New Media : No
DSP DNLD Reqd : No

Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 12.1.2.3
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_handle_rcvd_sdp:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/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
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_is_incoming_invite_resp_answer_valid: TRUE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_common_answer_rcvd_hdlr:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_network_event:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_RCVD_SDP
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/sip_iwf_def_ed_rcvd_sdp_answer_hdlr:
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131074/sipSPIBwCacCalcNegoAudioBandwidth: max audio bw (including pak overhead) from negotiated stream : codec g711ulaw bw 80000 bps index 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted BW Option 1 flow mode flow-through
audio bw 80000 bps video bw 0 bps fax bw 0 bps total bw 80000 bps accounted bw 0 bps
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateInterfaceBw: bwcac updating interface BVI255 bw 80
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial answer) accounted bw adjusted to 80000 delta 80000
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_DONE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_ANSWER_COMPLETE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdCallWithSdpInfo:
Stream type : voice+dtmf
Media line : 1
State : STREAM_ADDING (2)
Stream address type : 1
Callid : 28231
Negotiated Codec : g711ulaw, bytes :160
Nego. Codec payload : 0 (tx), 0 (rx)
Negotiated DTMF relay : rtp-nte
Negotiated NTE payload : 101 (tx), 101 (rx)
Negotiated CN payload : 0
Media Srce Addr/Port : [12.1.2.3]:19606
Media Dest Addr/Port : [4.55.23.6]:19896

Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/critical/1024/sipSPIProcessHistoryInfoHeader: No HI headers recvd from app container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/133120/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 13
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/1/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 80000 bps
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/critical/12288/sipSPICreateNewRawMsg: No Data to form The Raw Message

Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/HandleSIP1xxSessionProgress: ccsip_api_call_cut_progress returned: SIP_SUCCESS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 2 event
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIGenerateAuthorizationResponse: HA1 is: 3c7356a2d9242d5bcca92572790352ff
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x245391FC, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x4F4F9F8
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x245391FC
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x245391FC, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/512/sentPrack: Sent Prack Request, starting PrackTimer
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/HandleSIP1xxSessionProgress: Transaction active. Facilities will be queued.
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2118DDB0) counter, current msg->refCount = 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_bridge: confID = 1104, srcCallID = 28231, dstCallID = 28230
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sipSPIUupdateCcCallIds: Old src/dest ccCallids: -1/-1, new src/dest ccCallids: 28231/28230
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sipSPIUupdateCcCallIds: Old streamcallid=28231, new streamcallid=28231
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_generic_event:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_CALL_BRIDGE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/256/ccsip_bridge: xcoder_attached = 0, xmitFunc = 0x4848172, ccb xmitFunc = 0x4848172
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/24576/ccsip_spi_check_call_locally_held: CCSIP: call is locally noton-hold
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_ADDING
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/6/sipSPIAddStream: set stream_callid from ccb->ccCallID:0x6E47, media_type:0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 28231) to the VOIP RTP library
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 12.1.2.3
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sipSPIAssignStreamCallId: Assigning streamid 28231 to stream 1. Old streamid = 28231
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8192/sipSPIUpdateRtcpSession: ccb->flags != LOOPBACK
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: setting do_rtcp= TRUE for non forked leg 1
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/is_call_held: Remote media IP addr: 4.55.23.6
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/is_call_held: Media IP Addr 4.55.23.6, RTCP Type 3
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info
laddr = 12.1.2.3, lport = 19606, raddr = 4.55.23.6, rport=19896, do_rtcp=TRUE
src_callid = 28231, dest_callid = 28230, stream type = voice+dtmf, stream direction = SENDRECV
media_ip_addr = 4.55.23.6, vrf tableid = 0 media_addr_type = 1negotiated_bandwidth (kbps) = 0 srtp_services = 0 nat_flag = 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtcpSession: RTP session already created - update
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:2FA7142C
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/critical/131072/sipSPIUpdateRtcpSession: Not able to Associate DSCP Profile with GCCB dscp_policy = 0x0, IS_SIPSPI_MODE_IN_SIP_SIP = 0 dscpPolicySeviceBlock = 0x0 , stream->qos_info = 0x0
Feb 19 20:19:40.335: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIUpdateRtcpSession: VQM: gccb=0x0, gccb->callId=0, ccb->ccCallID=28231
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/8193/sipSPICreateAndStartRtpTimer: Media Inactivity Timer is disabled.
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/2080/sipSPIUpdateRtcpSession: DTMF inb/oob iwf enabled 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4097/sipSPI_ipip_is_escalation: local channels = 0, peer channels = 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPIGetNewLocalMediaDirection:
New Remote Media Direction = SENDRECV
Present Local Media Direction = SENDRECV
New Local Media Direction = SENDRECV
retVal = 0

Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/sipSPIChangeStreamState: Stream (callid = 28231) State changed from (STREAM_ADDING) to (STREAM_ACTIVE)
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/notify/32/ccsip_bridge:
DTMF inb/oob iwf enabled 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_run_deferred_media_negotiate:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_is_media_negotiate_app_trigger_reinvite_deferred: retVal : 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: CC_EV_CALL_PING_DONE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_PEER_CAPS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_PEER_CAPS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_sccp_do_video:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/sip_iwf_def_peer_caps_ind_hdlr:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4097/ccsip_get_rtcp_session_parameters: CURRENT VALUES: stream_callid=28231, current_seq_num=0x1ABD
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4097/ccsip_get_rtcp_session_parameters: NEW VALUES: stream_callid=28231, current_seq_num=0xD93
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sip_iwf_def_process_caps_ind: Load DSP with negotiated codec: g711ulaw, Bytes=160
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sip_iwf_def_process_caps_ind: Set forking flag to 0x0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/32/sipSPISetDTMFRelayMode: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_NTE_AND_OOB with rx payload = 101, tx payload = 101
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/10241/sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=28230
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8193/sip_set_modem_caps: Disabling Modem Relay...
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8193/sip_set_modem_caps: Negotiation already Done. Set negotiated Modem caps and generate SDP Xcap list
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8193/sip_set_modem_caps: Modem Relay & Passthru both disabled
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/8193/sip_set_modem_caps: nse payload = 0, ptru mode = 0, ptru-codec=0, redundancy=0, xid=0, relay=0, sprt-retry=12, latecncy=200, compres-dir=3, dict=1024, strnlen=32
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetStreamInfo: 1 Active Streams
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetStreamInfo: Adding stream type (voice+dtmf) from media
line 1 codec g711ulaw
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetStreamInfo:
caps.stream_count=1,caps.stream[0].stream_type=0x3, caps.stream_list.xmitFunc=
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetStreamInfo: voip_rtp_xmit, caps.stream_list.context=
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Media/sipSPISetStreamInfo: 0x2FB73330 (gccb)
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/1/sip_iwf_def_process_caps_ind: Load DSP with codec : g711ulaw, Bytes=160, payload = 0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sip_iwf_def_process_caps_ind: ccsip_caps_ind: ccb->pld.flags_ipip = 0x2201
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/1/sip_iwf_def_process_caps_ind: Calling cc_api_caps_ack()
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 7
Feb 19 20:19:40.335: //28231/EB59E1D3B413/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
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_cc_return_code: Mapping SIP_IWF_SUCCESS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: CC_EV_CALL_FEATURE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_PEER_CAPS_ACK,
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_PEER_CAPS_ACK,
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/is_mode_sip_sccp:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/sip_iwf_def_peer_caps_ack_hdlr:
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/8193/sip_iwf_tdm_process_caps_ack: Set forking flag to 0x0
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Feb 19 20:19:40.335: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_iwf_map_cc_return_code: Mapping SIP_IWF_SUCCESS
Feb 19 20:19:40.337: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:40.337: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2760FB28) counter, current msg->refCount = 2
Feb 19 20:19:40.826: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:40.826: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:40.826: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2760FB28) counter, current msg->refCount = 1
Feb 19 20:19:40.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:40.836: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:40.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x245391FC) counter, current msg->refCount = 2
Feb 19 20:19:41.826: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:41.826: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:41.826: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x245391FC) counter, current msg->refCount = 1
Feb 19 20:19:41.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x245391FC, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x245391FC
Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x245391FC, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:41.836: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:41.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:43.506: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2760FB28) counter, current msg->refCount = 2
Feb 19 20:19:43.826: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:43.826: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:43.826: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2760FB28) counter, current msg->refCount = 1
Feb 19 20:19:43.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:43.836: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:43.836: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:45.879: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x245391FC) counter, current msg->refCount = 2
Feb 19 20:19:47.826: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:47.826: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:47.826: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x245391FC) counter, current msg->refCount = 1
Feb 19 20:19:47.826: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x245391FC, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x245391FC
Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x245391FC, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:47.838: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:47.838: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x245391FC, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x245391FC
Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x245391FC, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:51.839: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:51.839: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:52.083: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x2760FB28) counter, current msg->refCount = 2
Feb 19 20:19:55.616: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:19:55.616: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:19:55.616: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x2760FB28) counter, current msg->refCount = 1
Feb 19 20:19:55.616: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:55.840: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:55.840: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:19:57.835: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sipSPIGetRejectedRequestSyslog: Syslog Timer re-started for silent discard
Feb 19 20:19:57.997: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:19:59.841: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:19:59.841: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:20:03.843: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:20:03.843: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:20:04.295: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x2760FB28 with refCount = 1
Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x2760FB28, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x2760FB28
Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x2760FB28, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:20:07.844: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:20:07.844: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2760FB28
Feb 19 20:20:11.231: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=9
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x245391FC with refCount = 1
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x27321740 with refCount = 1
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x245391FC
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x27321740) counter, current msg->refCount = 2
Feb 19 20:20:11.615: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Record-Route: <sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Record-Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>
Contact: <sip:+18662966457@4.55.23.33:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE
Require: 100rel
RSeq: 22423
Content-Length: 231
Content-Disposition: session; handling=required
Content-Type: application/sdp

v=0
o=Sonus_UAC 16186 28782 IN IP4 4.55.23.33
s=SIP Media Capabilities
c=IN IP4 4.55.23.6
t=0 0
m=audio 19896 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20

Feb 19 20:20:11.615: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: Got new RR header
Feb 19 20:20:11.615: //28231/EB59E1D3B413/SIP/Info/notify/1024/HandleSIP1xxSessionProgress: Dropping 183 - Duplicate or Unexpected
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x27321740) counter, current msg->refCount = 1
Feb 19 20:20:11.615: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x27321740
Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x27321740 with refCount = 1
Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIAddRouteHeaders: status = TRUE Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendPrack: Associated container=0x2F937C68 to Prack
Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Transport/sipSPISendPrack: Sending PRACK to the transport layer
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x27321740, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x27321740
Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x27321740, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:20:11.845: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
PRACK sip:+18662966457@4.55.23.33:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK799C19D1
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:18662966457@bumblebee.vtnoc.net>;tag=gK04fa5845
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 103 PRACK
RAck: 22423 102 INVITE
Route: <sip:66.147.235.3:5060;lr;ftag=B8F1A5F4-1DF9;did=12.14cf1136>,<sip:198.8.62.14:5060;lr;ftag=B8F1A5F4-1DF9>
Allow-Events: telephone-event
Proxy-Authorization: Digest username="17866462052",realm="bumblebee.vtnoc.net",uri="sip:+18662966457@4.55.23.33:5060",response="a64cb0c01ee0b2356de4f2cb186dbbda",nonce="56c778f400003ade2d1a1a773ec81adab2a27b7968305c47",cnonce="EE93A286",qop=auth,algorithm=md5,nc=00000002
Max-Forwards: 70
Content-Length: 0

Feb 19 20:20:11.845: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x27321740
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x27321740 with refCount = 1
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x276089E0 with refCount = 1
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [66.147.235.3]:5060, local_address:[12.1.2.3]
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x27321740
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x2498648C
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x2498648C, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connid=4, transport=UDP
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/4608/sipHolderGetConnInstance: Return existing connection for port 5060 connId 4
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_get_method_from_request:
Unable to extract request line from message
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x276089E0) counter, current msg->refCount = 2
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 482 Loop Detected
Via: SIP/2.0/UDP 12.1.2.3:5060;rport=62497;received=12.1.2.3;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK048030a2
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
CSeq: 102 INVITE
Content-Length: 0

Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x27321740 with refCount = 1
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 1 event
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_handle_call_active:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/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
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_offer_ans_dialog_established_hdlr:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_handle_generic_event:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sccp_early_dialog_container, cur_state:S_SIP_IWF_SDP_DONE, event:E_SIP_IWF_EV_CALL_ACTIVE
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_sip:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_h323:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/is_mode_sip_h32x:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/is_mode_sip_sccp:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/ccsip_iwf_set_tdm_ip_call_active:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sccp_mid_dialog_container
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_MID_DIALOG_IDLE
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISendAck: Associated container=0x2F939630 to Ack
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: msg=0x27321740, addr=66.147.235.3, port=5060, sentBy_port=0, local_addr=12.1.2.3, is_req=1, transport=1, switch=0, callBack=0x0
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:66.147.235.3, rport:5060 with laddr:12.1.2.3

Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x2B6BA190 is already on connection=0x24D9EACC context_list
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x27321740
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x27321740, addr=66.147.235.3, port=5060, local_addr=12.1.2.3, connId=4 for UDP
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/critical/1024/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 482
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_set_release_source_for_peer: ownCallId[28231], src[4]
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/critical/4096/sipSPIInitiateDisconnect: Initiate call disconnect(127) for outgoing call
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x2118DDB0
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 29
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/critical/32768/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x276089E0) counter, current msg->refCount = 1
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 18
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/256/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/256/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES*****
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/256/sipSPIResetXcoder: Xcoder resource already cleaned up
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:2FA7142C
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:18662966457@bumblebee.vtnoc.net:5060 SIP/2.0
Via: SIP/2.0/UDP 12.1.2.3:5060;branch=z9hG4bK798DDA8
From: "DJ's Test phone" <sip:200@bumblebee.vtnoc.net>;tag=B8F1A5F4-1DF9
To: <sip:+18662966457@bumblebee.vtnoc.net>;tag=gK048030a2
Date: Fri, 19 Feb 2016 20:19:34 GMT
Call-ID: ECC8824B-D67C11E5-B418DD4E-41EF9C4A@12.1.2.3
Max-Forwards: 70
CSeq: 102 ACK
Allow-Events: telephone-event
Content-Length: 0

Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x27321740
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/8192/ccsip_call_statistics: Requesting stats for callid=28231
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 29
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/critical/32768/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/32768/ccsip_indicate_rt_packet_stats: Processing stats for callid=28231, proc_id=1
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/8192/Session-Timer/sipSTSLMain:
SE: 0;refresher:none peer refresher:none, flags:2000, posted event:E_STSL_INVALID_PEER_EVENT, reason:4
Configured SE:1800, Configured Min-SE:1800
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/133120/sipSPIBwCacReleaseDialPeerBw: bwcac dial-peer bw 80 Kbps released for tag 13 active bw 0 Kbps
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacReleaseInterfaceBw: bwcac releasing interface BVI255 bw 80 Kbps
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/131072/sipSPIBwCacReleaseAccountedBw: bwcac released accounted bw 80000 bps
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/act_disconnecting_disconnect: Disconnect now.. no defer BYE..
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/4096/sipSPISetCommonTimer: Started generic timer type 2 for 240000 millisecs
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIDeferCallClose: Not split dataplane, bail
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/8192/sipSPIWaitForStatsBforeCallClose: Not split dataplane, bail
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPICallCloseAfterFinalStat:
sipSPICallCloseAfterFinalStat:
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Info/info/12288/sipSPIIcpifUpdate: CallState: 3 Playout: 29560 DiscTime:310288234 ConnTime 0
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/Media/sipSPIHandleDestroyRtpSession: stream:2FA7142C
Feb 19 20:20:12.133: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x276089E0
Feb 19 20:20:12.133: //28231/EB59E1D3B413/SIP/State/sipSPIChangeState: 0x2B6BA190 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
Feb 19 20:20:12.135: //28231/EB59E1D3B413/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x2B6BA190
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 200
Called Number : 18662966457
Source IP Address (Sig ): 12.1.2.3
Destn SIP Req Addr:Port : 66.147.235.3:5060
Destn SIP Resp Addr:Port : 66.147.235.3:5060
Destination Name : bumblebee.vtnoc.net

Feb 19 20:20:12.135: //28231/EB59E1D3B413/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): 12.1.2.3
Source IP Port (Media): 19606
Destn IP Address (Media): 4.55.23.6
Destn IP Port (Media): 19896
Orig Destn IP Address:Port (Media): [ - ]:0

Feb 19 20:20:12.135: //28231/EB59E1D3B413/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 127
Disconnect Cause (SIP) : 482

Feb 19 20:20:12.135: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIUdeleteccCallIdFromTable: Removing call id 6E47
Feb 19 20:20:12.135: //28231/EB59E1D3B413/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Return and SIP_TIMER_REMOVE_TRANSACTION timer will free this ccb=0x2B6BA190
0 Helpful

anilkumar.cisco
Level 4
Level 4
In response to Djurre Woudstra

‎03-11-2020 03:34 AM

I am also getting the same error..

 

Any findings from you..

0 Helpful
Customers Also Viewed These Support Documents