Solved: CISCO CME SIP-UA ITSP Registration issues

Kevin100 · ‎07-25-2021

Our client use 2911 router cme sip with some sip phones and sccp wireless phones .This router itself is also internet router, its not sending sip registration requests, and not registering to itsp.Some times phones trying to register to itsp,2911 is using the latest ios c2900-universalk9-mz.SPA.157-3.M8.bin. Internally all phones works. with terminal monitor and debug ccsip ,no response from router

!
voice service voip
ip address trusted list
ipv4 192.168.1.0 255.255.255.0
ipv4 62.150.254.82
ipv4 62.150.105.203
ipv4 62.150.254.81
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
sip
bind control source-interface GigabitEthernet0/0
bind media source-interface GigabitEthernet0/0
registrar server expires max 3600 min 360
options-ping 90
early-offer forced
midcall-signaling passthru
privacy-policy passthru
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g711alaw
codec preference 3 g729r8
codec preference 4 ilbc
!

!
sip-ua
credentials username xxxx password xxxxxx realm xxxxxx
authentication username xxxxx password xxxxxx
retry invite 8
retry response 8
retry bye 8
retry register 8
registrar 1 ipv4:62.150.105.203:5060 expires 3600
sip-server ipv4:62.150.105.203:5060
connection-reuse
host-registrar
!

!
dial-peer voice 100 voip
translation-profile incoming did

session protocol sipv2
session target ipv4:62.150.105.203:5060
incoming called-number 22247...
voice-class codec 1
voice-class sip dtmf-relay force rtp-nte
voice-class sip bind control source-interface GigabitEthernet0/1
voice-class sip bind media source-interface GigabitEthernet0/1
dtmf-relay sip-notify sip-kpml sip-info h245-alphanumeric h245-signal rtp-nte cisco-rtp
no vad
authentication username xxxxx password xxxxxxx realm xxxx
!
dial-peer voice 101 voip
translation-profile outgoing SIP_1
destination-pattern 9[1-9].......
session protocol sipv2
session target ipv4:62.150.105.203:5060
voice-class codec 1
voice-class sip bind control source-interface GigabitEthernet0/1
voice-class sip bind media source-interface GigabitEthernet0/1
dtmf-relay sip-notify sip-kpml sip-info h245-alphanumeric h245-signal rtp-nte cisco-rtp
no vad
authentication username xxxx password xxxxx realm xxxx
!

Roger Kallberg · ‎07-26-2021

Looks like you have a problem with the registration as it times out with a 408 Request Timeout. Check your configuration and verify with your ITSP that you use the proper credentials and that you source the communication from the appropriate IP on your end and that you have the correct reference for the ITSP registration endpoint.

View solution in original post

Roger Kallberg · ‎07-25-2021

Try with configuring the ITSP registration and authentication on a tenant configuration instead. In my experience this works much better. Check these documents for details.

https://www.cisco.com/c/en/us/support/docs/voice/ip-telephony-voice-over-ip-voip/211306-In-Depth-Explanation-of-Cisco-IOS-and-IO.html
https://www.cisco.com/c/en/us/td/docs/ios-xml/ios/voice/cube/configuration/cube-book/voi-cube-multi-tenants.html

Kevin100 · ‎07-26-2021

Dear Roger Thanks for reply and info

Now incoming ok, outgoing not work. Attached debug ccsip and calls in out

There is no PVDM for this cme and sip ua t provider is configured.

Edge_Router#
Jul 25 12:08:48.844: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x4271B970) with key=[902] to table
Jul 25 12:08:48.844: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Jul 25 12:08:48.844: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Jul 25 12:08:48.844: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Jul 25 12:08:48.844: //893/000000000000/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Jul 25 12:08:48.844: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/notify/131072/ccsip_call_setup_request: vrfID[0]
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/critical/32768/ccsip_ipip_media_forking_read_from_TDContainer: MF: Unable to read data from TD Container..
Jul 25 12:08:48.844: //893/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..
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: EV_UNDEFINED
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_SET_MODE
Jul 25 12:08:48.844: //893/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
Jul 25 12:08:48.844: //893/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sip_md_snr:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sip_ed_snr:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sip_md:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sip_ed:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_h32x_in_set_mode:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_h323_in_set_mode:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sccp_in_set_mode:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_int_type_frm_set_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_mode_sip_sccp_in_set_mode:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/8192/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM
Jul 25 12:08:48.844: //893/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_sccp_early_dialog_container
Jul 25 12:08:48.844: //893/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: CC_EV_IF_DIAG_DONE
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/verbose/4096/ccsip_iwf_map_ccapi_event_to_iwf_event: IWF Event: E_SIP_IWF_EV_SET_FLOW_MODE
Jul 25 12:08:48.844: //893/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
Jul 25 12:08:48.844: //893/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_fa2ft_md_flow_mode_transition:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_fa2ft_flow_mode_transition:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/is_fa2ft_flow_mode_transition:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_get_flow_mode_frm_set_flow_mode_ev:
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_iwf_handle_peer_event: Return value: SIP_IWF_SUCCESS
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/4096/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/critical/32768/ccsip_call_setup_request: MF or SIP TDM call flow
Jul 25 12:08:48.844: //893/000000000000/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Generate uuid
Jul 25 12:08:48.848: //893/000000000000/SIP/Info/info/8192/sipSPIGenerateSessionUUID: Initial Invite SIP-TDM /MF case
Jul 25 12:08:48.848: //893/000000000000/SIP/Info/info/8192/sipSPIGenerateSessionUUID: generated uuid - b5016d1f92885b43a49c8f3393568632
Jul 25 12:08:48.848: //893/000000000000/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - b5016d1f92885b43a49c8f3393568632remoteUUID - 00000000000000000000000000000000
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/5120/ccsip_call_setup_request: Session target or outbound proxy configured
Jul 25 12:08:48.848: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : alwatan.siptrk.kw target_port : 5060

Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUaddccCallIdToTable: Adding call id 37D to table
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/131072/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [101]
Jul 25 12:08:48.848: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
Jul 25 12:08:48.848: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/2049/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0
Jul 25 12:08:48.848: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/131072/sipSPIGetCallConfig: peer_tag = 101, tenant_tag = 0, VRFId = 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/2048/sipSPISetAudioForcedConfig: audio forced config is set to TRUE
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/32768/sipSPIGetCallConfig: Media Antitrombone disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/131072/sipSPICanSetFallbackFlag: Local Fallback is not active
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetCallConfig: VRF id = 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/4/update_video_params_from_config: Checking Video Type Rate=-1 video_codec_allowed=1F
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: STUN Usage is not enabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=892
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No defer BYE for last
call stats
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/1/ccsip_set_srtp_config: No Srtp configure for this leg.
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetCallConfig: Media forking disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/34816/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/36864/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/32768/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/32768/ccsip_ipip_media_forking_intra_frame_request_config: MF: FIR en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/32768/ccsip_ipip_media_forking_get_forked_leg_config: MF: This leg is not forked call leg.
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/11264/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIGetCallConfig: Initilise the DSCP policy
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/2048/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 893
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/1/preprocessSetup:
This is a not a SIGO Call -, could be DM call
Jul 25 12:08:48.848: //893/E67B64D082CE/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
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/4096/preprocessSetup: SIP-TDM or TCL/VXML app case
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/4096/sip_gw_pre_setup_update_stream_media_direction: peer_callID = 892
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/4097/sip_gw_pre_setup_update_stream_media_direction: peer_channels/stream is NULL
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/1/sip_gw_pre_setup_add_sdp_container: DNS/ENUM resolution required; Deferred Creating SDP
Jul 25 12:08:48.848: //893/E67B64D082CE/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
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/131072/sipSPIBwCacUpdateInterfaceBw: bwcac acquiring interface GigabitEthernet0/1 bw 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (initial offer) accounted bw set to 0
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/133120/sipSPIBwCacIsDialPeerBwAvailable: bwcac NOP dial-peer bw available tag 101
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/notify/1/sipSPIBwCacIsInterfaceBwAvailable: bwcac interface bw threshold not configured
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/131072/sipSPIBwCacVerifyBwThreshold: bwcac verify bw threshold, bw available allow call total bw 0 bps
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/8192/sipSPIValidateGtd: Signal Forward disabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/8192/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/10240/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/3072/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/info/9216/sipSPIAddCiscoGcid: Gcid value not set - not adding header.
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIAddPrivacyandIdentityInfo: ccb->local_host_name,ccb->src_addr_str is NULL
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Info/critical/2048/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/Error/sipSPI_ipip_set_history_info_header:
ccb->src_addr_str is NULL
Jul 25 12:08:48.848: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE
Jul 25 12:08:48.848: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS)
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_SENT_DNS)
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 62.150.254.82
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x4271B970 E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x4271B970 key=E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82 balance 1
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/12288/sipSPIUsetBillingProfile: sipCallId for billing records = E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: DNS query for alwatan.siptrk.kw and type:1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/notify/8192/sip_dns_type_a_query: TYPE A query successful for alwatan.siptrk.kw
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_query: ttl for A records = 0 seconds
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: IP Address of alwatan.siptrk.kw is:

Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: 62.150.105.203

Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_NONE)
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: IP Address No. 1, IP address 62.150.105.203
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 62.150.254.82
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/131072/sipSPIRscmsmAvail: Value returned by check is = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/critical/2048/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/critical/1024/sipSPI_ipip_set_history_info_header: No HI header recvd from container
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/2048/sipSPIAddPrivacyandIdentityInfo: asserted-id is configured as Not Set header
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: peer_tag=101
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_media_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 62.150.254.82
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/critical/1/sipSPIOutgoingCallSDP: Failure in creating outbound streams
SIP: (893) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 101, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 62.150.254.82 for SIP
Jul 25 12:08:48.852: //893/E67B64D082CE/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 62.150.254.82
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:48.852: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 62.150.254.82
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 16584 for stream 1
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/1/sipSPIDoBearerCapToCodecMapping: Bearer capability to Codec Mapping: DISABLED

Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Media/sipSPIAddSDPMediaPayload: Preferred method of dtmf relay is: 6, with payload: 101
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: calculating max bw from preffered codecs (local offer)
SIP: (893) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: max bw (excluding pak overhead) from preffered codecs: codec g711alaw bw 64000 index 0
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/critical/2/sipSPIBwCacCalcMaxAudioBandwidth: audio caps channel idx not found !!!!
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/131074/sipSPIBwCacCalcMaxAudioBandwidth: max bw (including pak overhead) from preffered codecs: codec g711alaw bw 80000
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/8193/sip_generate_sdp_xcaps_list: Negotiation not done v150_capable 0
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/8193/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/1/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Media/sipSPIProcessRtpSessions: Processing stream state = STREAM_IDLE
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Media/sipSPIProcessRtpSessions: No active streams.
Jul 25 12:08:48.856: //893/E67B64D082CE/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
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/139264/sipSPIBwCacUpdateInterfaceBw: NOP (no interface change)
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/131072/sipSPIBwCacUpdateAccountedBw: bwcac update accounted bw (no interface change)
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 62.150.105.203,Port 5060, Transport 1, SentBy Port 5060vrfid 0
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetTDSessionID: Get session-ID local uuid - b5016d1f92885b43a49c8f3393568632 remote uuid - 00000000000000000000000000000000
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - b5016d1f92885b43a49c8f3393568632 remoteUUID - 00000000000000000000000000000000
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header b5016d1f92885b43a49c8f3393568632;remote=00000000000000000000000000000000
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:3FFD4E5C
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/8192/Session-Timer/sipSTSLSRReqSend: Session timer is not required
Jul 25 12:08:48.856: //893/E67B64D082CE/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
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 0 event
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_handle_sent_sdp:
Jul 25 12:08:48.856: //893/E67B64D082CE/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
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_is_invite_offer_valid: TRUE
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_common_offer_sent_hdlr:
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_iwf_handle_network_event:
Jul 25 12:08:48.856: //893/E67B64D082CE/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
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/is_sent_sccp_do_video_inactive:
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/is_mode_sip_sccp_do_video:
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/is_mode_sip_sccp_do_video:
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/sip_iwf_def_ed_sent_sdp_offer_hdlr:
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/critical/2048/sipSPIgetRegistrarHost: multiple registrar mode, host name is 62.150.254.82
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/critical/4096/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not a Forked leg..
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/critical/1024/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
SIP: (893) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/34816/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/verbose/6144/addAllowHeaderKpml: KPML Subscriptions allowed.
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISendInvite: Associated container=0x3FFD4E5C to Invite
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 274
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: msg=0x22BFD104, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=62.150.254.82, is_req=1, transport=1, switch=0, callBack=0x35A27868
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22BFD104, addr=62.150.105.203, port=5060, local_addr=62.150.254.82, connId=2 vrfid=0 for UDP
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/Info/info/512/sentInviteRequest: Sent Invite in state STATE_IDLE
Jul 25 12:08:48.856: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sentInviteRequest: Transaction active. Facilities will be queued.
Jul 25 12:08:48.856: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)Session-ID: b5016d1f92885b43a49c8f3393568632;remote=00000000000000000000000000000000
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 274

v=0
o=CiscoSystemsSIP-GW-UserAgent 9872 9137 IN IP4 62.150.254.82
s=SIP Call
c=IN IP4 62.150.254.82
t=0 0
m=audio 16584 RTP/AVP 8 101 19
c=IN IP4 62.150.254.82
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:19 CN/8000
a=ptime:20

Jul 25 12:08:48.860: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.560: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.560: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x4313CCC8 with refCount = 1
Jul 25 12:08:49.560: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[62.150.254.82]
Jul 25 12:08:49.560: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.560: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.560: //893/E67B64D082CE/SIP/Info/critical/4096/ccsip_ipip_media_forking_get_forked_recording_data: MF: Not a Forked leg..
Jul 25 12:08:49.560: //893/E67B64D082CE/SIP/Info/critical/1024/sipSPICreateRecParticipantHeaders: X-Cisco-Recording-Participant header not added.
Jul 25 12:08:49.560: //893/E67B64D082CE/SIP/Event/sipSPICreateRpid: Received Octet3A=0x00 -> Setting ;screen=no ;privacy=off
SIP: (893) Group (a= group line) attribute, level 65535 instance 1 not found.
Jul 25 12:08:49.560: //893/E67B64D082CE/SIP/Info/info/34816/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISendInvite: Associated container=0x3FFD4E5C to Invite
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 274
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: msg=0x22BFD104, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=62.150.254.82, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22BFD104, addr=62.150.105.203, port=5060, local_addr=62.150.254.82, connId=2 vrfid=0 for UDP
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x4313CCC8) counter, current msg->refCount = 2
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 62.150.254.82:5060;received=62.150.254.82;rport=5060;branch=z9hG4bK35F1092
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
CSeq: 101 INVITE
Content-Length: 0

Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Jul 25 12:08:49.564: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x4313CCC8) counter, current msg->refCount = 1
Jul 25 12:08:49.564: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x4313CCC8
Jul 25 12:08:49.576: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:65139333@alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 62.150.254.82:5060;branch=z9hG4bK35F1092
Remote-Party-ID: <sip:22247145@62.150.254.82>;party=calling;screen=no;privacy=off
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:49 gmt
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 3866846416-3967226347-2194583996-3849914783
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1627214929
Contact: <sip:22247145@62.150.254.82:5060>
Call-Info: <sip:62.150.254.82:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"
Expires: 180
Allow-Events: kpml, telephone-event
Session-ID: b5016d1f92885b43a49c8f3393568632;remote=00000000000000000000000000000000
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 274

v=0
o=CiscoSystemsSIP-GW-UserAgent 9872 9137 IN IP4 62.150.254.82
s=SIP Call
c=IN IP4 62.150.254.82
t=0 0
m=audio 16584 RTP/AVP 8 101 19
c=IN IP4 62.150.254.82
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:19 CN/8000
a=ptime:20

Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x4313CCC8 with refCount = 1
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[62.150.254.82]
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.576: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3FF989BC with refCount = 1
Jul 25 12:08:49.580: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[62.150.254.82]
Jul 25 12:08:49.580: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x4313CCC8) counter, current msg->refCount = 2
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 62.150.254.82:5060;received=62.150.254.82;rport=5060;branch=z9hG4bK35F1092
Max-Forwards: 69
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>;tag=1c200378067
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
CSeq: 101 INVITE
Supported: timer
Allow: INVITE,ACK,CANCEL,BYE,INFO,REGISTER,NOTIFY
Remote-Party-ID: <sip:22247145@62.150.254.82>;screen=no;party=calling;privacy=off
Proxy-Authenticate: Digest realm="alwatan.siptrk.kw",nonce="290996f590980c9d65d007755c49dd5b",opaque="550f1be8830bb4e1a969db9823579143",algorithm=MD5,qop="auth"
Expires: 180
User-Agent: TELES.C5/6.2.0.62
Allow-Events: talk
Content-Length: 0

Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This rpid could/could not be best one..
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPISetInfoFromRpid: calling host comes from rpid 62.150.254.82
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received current remote name: , current remote number: 22247145
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received ;screen=no ;privacy=off -> Setting Octet3A 0x80, extended_privacy 0x00
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIChangeDeferAckState: Change state from 0 to new state 0, called from :sipSPIPresendProcessing
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetTDSessionID: Get session-ID local uuid - b5016d1f92885b43a49c8f3393568632 remote uuid - 00000000000000000000000000000000
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - b5016d1f92885b43a49c8f3393568632 remoteUUID - 00000000000000000000000000000000
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 00000000000000000000000000000000;remote=b5016d1f92885b43a49c8f3393568632
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 1 event
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_handle_call_active:
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_ignore_hdlr: S_SIP_EARLY_DIALOG_OFFER_SENT, E_SIP_DIALOG_ESTD
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:next_state:CNFSM_NO_STATE_CHANGE
Jul 25 12:08:49.588: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISendAck: Associated container=0x3FFD322C to Ack
Jul 25 12:08:49.588: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: msg=0x22BFD104, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=62.150.254.82, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22BFD104, addr=62.150.105.203, port=5060, local_addr=62.150.254.82, connId=2 vrfid=0 for UDP
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/critical/1024/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 407
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Error/sipSPIHandleAuthChallenge:
Error getting credentials
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Error/act_recdproc_new_message_response:
Error handling AuthenticationChallenge
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/critical/4096/ccsip_set_cc_cause_for_spi_err: Categorized cause:47, category:181
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/critical/4096/sipSPIInitiateDisconnect: Initiate call disconnect(47) for outgoing call
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/4096/ccsip_set_release_source_for_peer: ownCallId[893], src[6]
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQSIG: No QSIG Body found in inbound container
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQ931: No RawMsg Body found in inbound container
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 34
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/critical/32768/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x4313CCC8) counter, current msg->refCount = 1
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x3FF989BC) counter, current msg->refCount = 2
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 62.150.254.82:5060;received=62.150.254.82;rport=5060;branch=z9hG4bK35F1092
Max-Forwards: 69
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>;tag=1c200378067
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
CSeq: 101 INVITE
Supported: timer
Allow: INVITE,ACK,CANCEL,BYE,INFO,REGISTER,NOTIFY
Remote-Party-ID: <sip:22247145@62.150.254.82>;screen=no;party=calling;privacy=off
Proxy-Authenticate: Digest realm="alwatan.siptrk.kw",nonce="290996f590980c9d65d007755c49dd5b",opaque="550f1be8830bb4e1a969db9823579143",algorithm=MD5,qop="auth"
Expires: 180
User-Agent: TELES.C5/6.2.0.62
Allow-Events: talk
Content-Length: 0

Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: Delete the previous remote party id..
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/32768/ccsip_ipip_media_forking_copy_prev_best_rpid: MF: This rpid could/could not be best one..
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPISetInfoFromRpid: calling host comes from rpid 62.150.254.82
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received current remote name: , current remote number: 22247145
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISetInfoFromRpid: Received ;screen=no ;privacy=off -> Setting Octet3A 0x80, extended_privacy 0x00
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/sact_disconnecting_new_message_response: [sact_disconnecting_new_message_response] Received Response Class [4] Method Code [102]

Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3ED128C4 with refCount = 1
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIChangeDeferAckState: Change state from 0 to new state 0, called from :sipSPIPresendProcessing
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetTDSessionID: Get session-ID local uuid - b5016d1f92885b43a49c8f3393568632 remote uuid - 00000000000000000000000000000000
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - b5016d1f92885b43a49c8f3393568632 remoteUUID - 00000000000000000000000000000000
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 00000000000000000000000000000000;remote=b5016d1f92885b43a49c8f3393568632
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 1 event
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_handle_call_active:
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_ignore_hdlr: S_SIP_EARLY_DIALOG_OFFER_SENT, E_SIP_DIALOG_ESTD
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:next_state:CNFSM_NO_STATE_CHANGE
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISendAck: Associated container=0x3FFD834C to Ack
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: msg=0x3ED128C4, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=62.150.254.82, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:49.592: //893/E67B64D082CE/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3ED128C4, addr=62.150.105.203, port=5060, local_addr=62.150.254.82, connId=2 vrfid=0 for UDP
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x3FF989BC) counter, current msg->refCount = 1
Jul 25 12:08:49.592: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3FF989BC
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/8192/ccsip_call_statistics: Requesting stats for callid=893
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/critical/8192/ccsip_call_statistics: Stats request failed for callid=893, dstCallID=-1, rc=-7
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 34
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/critical/4096/ccsip_ipip_mf_create_xml_metadata_for_disconnect: MF: Not a Forked leg..
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/critical/32768/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
Jul 25 12:08:49.596: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
Jul 25 12:08:49.596: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SPI_EVENT
Jul 25 12:08:49.596: //893/E67B64D082CE/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
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/info/4096/act_disconnecting_disconnect: Disconnect now.. no defer BYE..
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/info/4096/sipSPISetCommonTimer: Started generic timer type 2 for 240000 millisecs
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIDeferCallClose: Not split dataplane, bail
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIWaitForStatsBforeCallClose: Not split dataplane, bail
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPICallCloseAfterFinalStat:
sipSPICallCloseAfterFinalStat:
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/info/12288/sipSPIIcpifUpdate: CallState: 2 Playout: 0 DiscTime:2161724 ConnTime 0
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Media/sipSPIHandleDestroyRtpSession: stream:C361D35C
Jul 25 12:08:49.596: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x4313CCC8
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/State/sipSPIChangeState: 0x4271B970 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x0x4271B970
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number : 22247145
Called Number : 65139333
Source IP Address (Sig 62.150.254.82
Destn SIP Req Addr:Port : 62.150.105.203:5060
Destn SIP Resp Addr:Port : 62.150.105.203:5060
Destination Name : alwatan.siptrk.kw

Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream : 1
Negotiated Codec : No Codec
Negotiated Codec Bytes : 0
Nego. Codec payload : 255 (tx), 255 (rx)
Negotiated Dtmf-relay : 0
Dtmf-relay Payload : 0 (tx), 0 (rx)
Source IP Address (Media): 62.150.254.82
Source IP Port (Media): 16584
Destn IP Address (Media): -
Destn IP Port (Media): 0
Orig Destn IP Address:Port (Media): [ - ]:0

Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 47
Disconnect Cause (SIP) : 407

Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUdeleteccCallIdFromTable: Removing call id 37D
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Return and SIP_TIMER_REMOVE_TRANSACTION timer will free this ccb=0x4271B970
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:65139333@alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 62.150.254.82:5060;branch=z9hG4bK35F1092
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>;tag=1c200378067
Date: Sun, 25 Jul 2021 12:08:49 gmt
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: kpml, telephone-event
Session-ID: 00000000000000000000000000000000;remote=b5016d1f92885b43a49c8f3393568632
Content-Length: 0

Jul 25 12:08:49.596: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22BFD104
Jul 25 12:08:49.596: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:65139333@alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 62.150.254.82:5060;branch=z9hG4bK35F1092
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>;tag=1c200378067
Date: Sun, 25 Jul 2021 12:08:49 gmt
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: kpml, telephone-event
Session-ID: 00000000000000000000000000000000;remote=b5016d1f92885b43a49c8f3393568632
Content-Length: 0

Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3ED128C4
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3ED128C4 with refCount = 1
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22BFD104 with refCount = 1
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[62.150.254.82]
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3ED128C4
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x22BFD104) counter, current msg->refCount = 2
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 62.150.254.82:5060;received=62.150.254.82;rport=5060;branch=z9hG4bK35F1092
Max-Forwards: 69
From: <sip:22247145@62.150.254.82>;tag=149D774-30D
To: <sip:65139333@alwatan.siptrk.kw>;tag=1c200378067
Call-ID: E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
CSeq: 101 INVITE
Supported: timer
Allow: INVITE,ACK,CANCEL,BYE,INFO,REGISTER,NOTIFY
Remote-Party-ID: <sip:22247145@62.150.254.82>;screen=no;party=calling;privacy=off
Proxy-Authenticate: Digest realm="alwatan.siptrk.kw",nonce="290996f590980c9d65d007755c49dd5b",opaque="550f1be8830bb4e1a969db9823579143",algorithm=MD5,qop="auth"
Expires: 180
User-Agent: TELES.C5/6.2.0.62
Allow-Events: talk
Content-Length: 0

Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/verbose/1024/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX
Jul 25 12:08:49.600: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3ED128C4 with refCount = 1
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIChangeDeferAckState: Change state from 0 to new state 0, called from :sipSPIPresendProcessing
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIGetTDSessionID: Get session-ID local uuid - b5016d1f92885b43a49c8f3393568632 remote uuid - 00000000000000000000000000000000
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - b5016d1f92885b43a49c8f3393568632 remoteUUID - 00000000000000000000000000000000
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 00000000000000000000000000000000;remote=b5016d1f92885b43a49c8f3393568632
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 1 event
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/Info/info/4096/ccsip_offer_ans_handle_call_active:
Jul 25 12:08:49.600: //893/E67B64D082CE/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_EARLY_DIALOG_OFFER_SENT, event:E_SIP_DIALOG_ESTD
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x426EE598) with key=[903] to table
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:alwatan.siptrk.kw:5060
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : alwatan.siptrk.kw target_port : 5060

Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/262144/ccsip_spi_outgoing_register: ccb's vrfid is set to 0
Jul 25 12:08:56.024: //-1/000000000000/SIP/State/sipSPIChangeState: 0x426EE598 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 1
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Jul 25 12:08:56.024: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to alwatan.siptrk.kw:5060

Jul 25 12:08:56.024: //894/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x426EE598 34759BA6-EC5811EB-8060B1BC-E579099F
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x426EE598 key=34759BA6-EC5811EB-8060B1BC-E579099F balance 0
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE
Jul 25 12:08:56.024: //894/000000000000/SIP/State/sipSPIChangeState: 0x426EE598 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS)
Jul 25 12:08:56.024: //894/000000000000/SIP/State/sipSPIChangeState: 0x426EE598 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS)
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: DNS query for alwatan.siptrk.kw and type:1
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/notify/8192/sip_dns_type_a_query: TYPE A query successful for alwatan.siptrk.kw
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_query: ttl for A records = 0 seconds
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: IP Address of alwatan.siptrk.kw is:

Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/sip_dns_type_a_aaaa_query: 62.150.105.203

Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPICacheHostToCCB: IP Address No. 1, IP address 62.150.105.203
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 192.168.1.253 for SIP
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 192.168.1.253 for SIP
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.1.253
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 192.168.1.253
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/notify/262144/ccsip_api_register_target_dns_resolved: ttl = 0
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_spi_register_get_rcb: Getting New RCB [0x42C89818]
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/notify/262144/ccsip_register_set_dns_resolved_address: CCSIP_REGISTER:: registrar 0 DNS resolved addr set to 62.150.105.203:5060
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern for 3600 seconds
Jul 25 12:08:56.024: //894/000000000000/SIP/State/sipSPIChangeState: 0x426EE598 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x4313CCC8 with refCount = 1
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 8 event
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.024: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:08:56.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:56.024: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:56.028: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:56.028: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x4313CCC8, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x35A2DC44
Jul 25 12:08:56.028: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.028: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.028: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:56.028: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:56.028: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4313CCC8, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:56.028: //894/000000000000/SIP/State/sipSPIChangeState: 0x426EE598 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Jul 25 12:08:56.028: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free:
Freeing NULL pointer!
Jul 25 12:08:56.028: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:56 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214936
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:56.028: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x4313CCC8
Jul 25 12:08:56.776: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x4313CCC8 with refCount = 1
Jul 25 12:08:56.776: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.776: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:08:56.776: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:56.776: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:56.776: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:56.776: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x4313CCC8, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:56.776: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.776: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:56.776: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:56.776: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:56.776: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4313CCC8, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:56.776: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:56 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214936
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:56.780: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x4313CCC8
Jul 25 12:08:57.644: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x4313CCC8 with refCount = 1
Jul 25 12:08:57.644: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.644: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:08:57.644: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:57.644: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:57.644: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:57.644: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x4313CCC8, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:57.644: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.644: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.644: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:57.644: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:57.644: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x4313CCC8, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:57.644: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:57 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214937
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:57.644: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x4313CCC8
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x4270EAA0) with key=[904] to table
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/notify/262144/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:alwatan.siptrk.kw:5060
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/262144/ccsipRegisterSetTargetInfo: Parsing The Registrar Address
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/5120/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : alwatan.siptrk.kw target_port : 5060

Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/5120/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/critical/8192/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/262144/ccsip_spi_outgoing_register: ccb's vrfid is set to 0
Jul 25 12:08:57.648: //-1/000000000000/SIP/State/sipSPIChangeState: 0x4270EAA0 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/6144/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIIncrementOverloadCount: Local 1 Global 2
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40
Jul 25 12:08:57.648: //-1/000000000000/SIP/Info/verbose/262144/act_idle_outgoing_register: In act_idle_outgoing_register

Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Send REGISTER to alwatan.siptrk.kw:5060

Jul 25 12:08:57.648: //895/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToUACTable: ****Adding to UAC table.0x4270EAA0 34759BA6-EC5811EB-805FB1BC-E579099F
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/verbose/4096/sipSPIUaddCcbToTable: Added to table. ccb=0x4270EAA0 key=34759BA6-EC5811EB-805FB1BC-E579099F balance 1
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/262144/act_idle_outgoing_register: Locally Resolved IP:62.150.105.203:5060
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: sip-ua conn_reuse configured
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/ccsip_get_ifaddress: ip_address IPv4 192.168.1.253 for SIP
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 192.168.1.253 for SIP
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: signaling bind address : 192.168.1.253
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 1conn_reuse: 1
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 192.168.1.253
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3FF989BC with refCount = 1
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 8 event
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:57.648: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:57.648: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:57.648: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3FF989BC, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x35A2DC44
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.648: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:57.648: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3FF989BC, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:57.648: //895/000000000000/SIP/State/sipSPIChangeState: 0x4270EAA0 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Jul 25 12:08:57.648: //895/000000000000/SIP/State/sipSPIChangeState: 0x4270EAA0 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE)
Jul 25 12:08:57.648: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:57 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214937
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:57.648: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3FF989BC
Jul 25 12:08:58.300: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3FF989BC with refCount = 1
Jul 25 12:08:58.300: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:58.300: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:08:58.300: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:58.300: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:58.300: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:58.300: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3FF989BC, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:58.300: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:58.300: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:58.300: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:58.300: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:58.300: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3FF989BC, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:58.300: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:58 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214938
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:58.300: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3FF989BC
Jul 25 12:08:59.272: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x3FF989BC with refCount = 1
Jul 25 12:08:59.276: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:59.276: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:08:59.276: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:08:59.276: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:08:59.276: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:08:59.276: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x3FF989BC, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:08:59.276: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:59.276: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:08:59.276: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:08:59.276: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:08:59.276: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x3FF989BC, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:08:59.276: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:08:59 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214939
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:08:59.276: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x3FF989BC
Jul 25 12:09:00.304: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:00.304: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:00.304: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:09:00.304: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:00.304: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:00.304: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:00.308: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:00.308: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:00.308: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:00.308: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:00.308: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:00.308: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:00.308: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:00 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214940
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:00.308: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:01.272: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:01.272: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:01.272: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:09:01.272: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:01.272: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:01.272: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:01.276: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:01.276: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:01.276: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:01.276: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:01.276: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:01.276: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:01.276: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:01 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214941
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:01.276: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:03.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:03.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:03.528: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:09:03.528: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:03.528: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:03.528: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:03.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:03.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:03.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:03.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:03.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:03.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:03.528: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:03 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214943
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:03.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:05.768: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:05.768: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:05.768: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:09:05.768: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:05.768: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:05.768: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:05.768: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:05.768: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:05.768: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:05.768: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:05.768: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:05.768: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:05.768: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:05 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214945
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:05.768: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:07.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:07.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:07.528: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:09:07.528: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:07.528: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:07.528: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:07.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:07.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:07.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:07.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:07.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:07.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:07.528: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:07 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214947
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:07.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:09.148: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:09.148: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:09.148: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:09:09.148: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:09.148: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:09.148: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:09.148: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:09.148: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:09.148: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:09.148: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:09.148: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:09.148: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:09.148: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:09 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214949
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:09.148: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:11.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:11.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:11.528: //894/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD3F94 to Register
Jul 25 12:09:11.528: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:11.528: //894/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:11.528: //894/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:11.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x22D04828, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:11.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:11.528: //894/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:11.528: //894/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:11.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:11.528: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x22D04828, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:11.528: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:11 gmt
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214951
CSeq: 71 REGISTER
Contact: <sip:201@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:11.528: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0xC3552930 with refCount = 1
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[192.168.1.253]
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:09:12.032: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/info/1024/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK3602523 from via branch list
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0xC3552930) counter, current msg->refCount = 2
Jul 25 12:09:12.036: //894/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 408 Request Timeout
Via: SIP/2.0/UDP 192.168.1.253:5060;received=192.168.1.253;rport=1416;branch=z9hG4bK3602523
From: <sip:201@alwatan.siptrk.kw>;tag=149F374-162
To: <sip:201@alwatan.siptrk.kw>;tag=1c375263307
Call-ID: 34759BA6-EC5811EB-8060B1BC-E579099F
CSeq: 71 REGISTER
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
Content-Length: 0

Jul 25 12:09:12.036: //894/000000000000/SIP/Info/verbose/1024/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIDecrementOverloadCount: Count:Local 0 Global 1
Jul 25 12:09:12.036: //894/000000000000/SIP/Error/ccsip_api_register_result_ind:
Message Code Class 4xx Method Code 100 received for REGISTER
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/ccsipGetCCBFromRCB: Retrived CCB pointer [0x0]
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_spi_register_free_rcb: Freeing rcb [0x42C89818]
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/notify/262144/ccsip_register_reset_dns_cache: CCSIP_REGISTER:: registrar 0 DNS resolved addr reset
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0xC3552930) counter, current msg->refCount = 1
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/notify/262144/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 408
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIGetRPCBFromRCB: Retreiving RCB [0x3F5718BC] from RPCB [0x0]
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/critical/262144/sipSPIRegPthruProcessResponse: Error NO RPCB

Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern 201 for 180 seconds
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[903] removed.
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x426EE598 key=34759BA6-EC5811EB-8060B1BC-E579099F
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Jul 25 12:09:12.036: //894/000000000000/SIP/Error/sipSPIFlushDeferredQueue:
Invalid deferredQueue
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0xC24A69D0, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, unregistering context=0x426EE598
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/critical/512/sipConnectionManagerUnregisterCtxtInConnection: Could not find conn holder for addr=62.150.105.203
Jul 25 12:09:12.036: //894/000000000000/SIP/Transport/sipSPITransportContextCleanup: Could not purge context gcb=0x426EE598 from the connection; gcb might be locked
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Jul 25 12:09:12.036: //894/000000000000/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x426EE598
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[903]
Jul 25 12:09:12.036: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0xC3552930
Jul 25 12:09:13.480: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0xC3552930 with refCount = 1
Jul 25 12:09:13.480: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:13.480: //895/000000000000/SIP/Info/info/4096/sipSPISendRegister: Associated container=0x3FFD36FC to Register
Jul 25 12:09:13.480: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0
Jul 25 12:09:13.480: //895/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer
Jul 25 12:09:13.480: //895/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Dial peer configuration, Switch Transport is FALSE
Jul 25 12:09:13.480: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0xC3552930, addr=62.150.105.203, port=5060, sentBy_port=0, local_addr=192.168.1.253, is_req=1, transport=1, switch=0, callBack=0x0
Jul 25 12:09:13.480: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:13.480: //895/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
Jul 25 12:09:13.480: //895/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
Jul 25 12:09:13.480: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 3
Jul 25 12:09:13.480: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0xC3552930, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, connId=3 vrfid=0 for UDP
Jul 25 12:09:13.484: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
REGISTER sip:alwatan.siptrk.kw:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.253:5060;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>
Date: Sun, 25 Jul 2021 12:09:13 gmt
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
User-Agent: Cisco-SIPGateway/IOS-15.7.3.M8
Max-Forwards: 70
Timestamp: 1627214953
CSeq: 71 REGISTER
Contact: <sip:200@192.168.1.253:5060>
Expires: 3600
Supported: path
Authorization: Digest username="1389784",realm="alwatan.siptrk.kw",uri="sip:62.150.105.203:5060",response="",nonce=""
Content-Length: 0

Jul 25 12:09:13.484: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0xC3552930
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0xC3552930 with refCount = 1
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x22D04828 with refCount = 1
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [62.150.105.203]:5060, local_address:[192.168.1.253]
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0xC3552930
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/info/1024/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK3611F3E from via branch list
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x22D04828) counter, current msg->refCount = 2
Jul 25 12:09:14.448: //895/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 408 Request Timeout
Via: SIP/2.0/UDP 192.168.1.253:5060;received=192.168.1.253;rport=1416;branch=z9hG4bK3611F3E
From: <sip:200@alwatan.siptrk.kw>;tag=149F9CC-B7F
To: <sip:200@alwatan.siptrk.kw>;tag=1c1881481418
Call-ID: 34759BA6-EC5811EB-805FB1BC-E579099F
CSeq: 71 REGISTER
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
Content-Length: 0

Jul 25 12:09:14.448: //895/000000000000/SIP/Info/verbose/1024/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/262144/sipSPIDecrementOverloadCount: Count:Local 0 Global 0
Jul 25 12:09:14.448: //895/000000000000/SIP/Error/ccsip_api_register_result_ind:
Message Code Class 4xx Method Code 100 received for REGISTER
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/notify/262144/ccsip_register_reset_dns_cache: CCSIP_REGISTER:: registrar 0 DNS resolved addr reset
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x22D04828) counter, current msg->refCount = 1
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/notify/262144/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 408
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/verbose/262144/sipSPIGetRPCBFromRCB: Retreiving RCB [0x22497EB0] from RPCB [0x0]
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/critical/262144/sipSPIRegPthruProcessResponse: Error NO RPCB

Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/2048/ccsipRegisterStartRCBTimer: Starting timer for pattern 200 for 180 seconds
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[904] removed.
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x4270EAA0 key=34759BA6-EC5811EB-805FB1BC-E579099F
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Jul 25 12:09:14.448: //895/000000000000/SIP/Error/sipSPIFlushDeferredQueue:
Invalid deferredQueue
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0xC24A69D0, addr=62.150.105.203, port=5060, local_addr=192.168.1.253, unregistering context=0x4270EAA0
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/critical/512/sipConnectionManagerUnregisterCtxtInConnection: Could not find conn holder for addr=62.150.105.203
Jul 25 12:09:14.448: //895/000000000000/SIP/Transport/sipSPITransportContextCleanup: Could not purge context gcb=0x4270EAA0 from the connection; gcb might be locked
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Jul 25 12:09:14.448: //895/000000000000/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x4270EAA0
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[904]
Jul 25 12:09:14.448: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x22D04828
Jul 25 12:09:21.596: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[902] removed.
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/info/4096/sipSPIStopCommonTimer: Stopping Generic Timer 2
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x4271B970 key=E72A9011-EC7711EB-82D3B1BC-E579099F@62.150.254.82
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Error/sipSPIFlushDeferredQueue:
Invalid deferredQueue
Jul 25 12:09:21.596: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0xC24A69D0, addr=62.150.105.203, port=5060, local_addr=62.150.254.82, unregistering context=0x4271B970
Jul 25 12:09:21.596: //-1/xxxxxxxxxxxx/SIP/Info/critical/512/sipConnectionManagerUnregisterCtxtInConnection: Could not find conn holder for addr=62.150.105.203
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Transport/sipSPITransportContextCleanup: Could not purge context gcb=0x4271B970 from the connection; gcb might be locked
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
Jul 25 12:09:21.596: //893/E67B64D082CE/SIP/Info/verbose/4096/sipSPIUfreeOneCC

Roger Kallberg · ‎07-26-2021

Looks like you have a problem with the registration as it times out with a 408 Request Timeout. Check your configuration and verify with your ITSP that you use the proper credentials and that you source the communication from the appropriate IP on your end and that you have the correct reference for the ITSP registration endpoint.