=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2014.04.17 17:11:19 =~=~=~=~=~=~=~=~=~=~=~=
debug       no debug cc  voice ccapi in
CSI-voice#no debug voice ccapi inou
CSI-voice#no debug voice ccapi inout 
voip ccapi inout debugging is off
CSI-voice#debug ccsip
CSI-voice#debug ccsip al
CSI-voice#debug ccsip all 
This may severely impact system performance. Continue? [confirm]
All SIP Call tracing is enabled
CSI-voice#csim start 2501
csim: called number = 2501, loop count = 1 ping count = 0

csim err csimDisconnected recvd DISC cid(39) 
*Apr 17 10:10:08.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x22B6E918) with key=[39] to table
*Apr 17 10:10:08.033: //-1/000000000000/SIP/Info/ccsip_offer_ans_init: 
*Apr 17 10:10:08.033: //-1/000000000000/SIP/Info/ccsip_iwf_init:  
*Apr 17 10:10:08.033: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init: 
*Apr 17 10:10:08.033: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization:  Entry...
*Apr 17 10:10:08.033: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised..
*Apr 17 10:10:08.033: //39/000000000000/SIP/State/sipSPIChangeState: 0x22B6E918 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information
*Apr 17 10:10:08.033: //39/000000000000/SIP/Error/ccsip_ipip_media_forking_read_from_TDContainer: 
 MF: Unable to read data from TD Container..
*Apr 17 10:10:08.033: //39/000000000000/SIP/Error/ccsip_ipip_media_forking_forked_leg_config: 
 MF: TD container cannot be read/container is NULL. Setting of forked call leg failed..
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 183
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_process_event:  
*Apr 17 10:10:08.033: //39/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
*Apr 17 10:10:08.033: //39/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sip_md_snr: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Error/is_sip_snr_call: 
 CCSIP: invalid call entry
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sip_ed_snr: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Error/is_sip_snr_call: 
 CCSIP: invalid call entry
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sip_md: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sip_ed: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SP
csim: loop = 1, failed = 1  
csim: call attempted = 1, setup failed = 1, tone failed = 0

CSI-voice#I mode to SIP-TDM
*Apr 17 10:10:08.033: //39/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container
*Apr 17 10:10:08.033: //39/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_process_event:  
*Apr 17 10:10:08.033: //39/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_FLOW_MODE
*Apr 17 10:10:08.033: //39/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_fa2ft_flow_mode_transition: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/is_fa2ft_flow_mode_transition: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev: 
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200
*Apr 17 10:10:08.033: //39/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200
*Apr 17 10:10:08.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : 10.161.60.97 target_port : 5060

*Apr 17 10:10:08.033: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [37] in dial-peer [2]
*Apr 17 10:10:08.033: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 27 to table
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec    bytes: 0 
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPI_ipip_store_config_info: Setting mid_call_config_info = 0x0 for callid = 39
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Checking Video Type Rate=-1 video_codec_allowed=1F
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=-1
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Error/ccsip_ipip_media_forking_update_preferred_codec: 
 MF: Not a Forked SIP leg..
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Incoming: No defer BYE for last 
                              call stats
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Media forking disabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: en_p->encap_s.voIP.voipPeerCfgMediaClass = 0
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_forking_anchor_leg_config: MF: Dial-peer has no media class recorder.
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIMFChangeState: MF: Prev state = 0 & New state = -1
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_forking_anchor_leg_reset: MF: Anchor leg config reset done...
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/ccsipInitDSCPPolicyInfo: No DSCP Profile configured, No RPH 2 DSCP Mapping and DSCP policing
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIGetCallConfig: Initilise the DSCP policy
*Apr 17 10:10:08.033: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetSipProfilesTag: voice class SIP Profiles tag is set : 1
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host
*Apr 17 10:10:08.033: //39/49ABDC418074/SIP/Info/preprocessSetup: 
 This is a not a SIGO Call -, could be DM call 
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_iwf_process_event:  
*Apr 17 10:10:08.037: //39/49ABDC418074/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_INIT_CALL_SETUP
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/preprocessSetup: SIP-TDM or TCL/VXML app case
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Error/sipSPIOutgoingCallSDP: 
 Could not create source SDP for Outgoing Call
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Error/sipSPICreateOutboundSDP: 
 Error in creating an SDP for the outbound call - Check for supported codecs
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Error/preprocessSetup: 
 Error during outbound SDP creation
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(16) for outgoing call
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[39], src[6]
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQSIG: 
 No Inbound Container Created !!!
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetContentQ931: 
 No Inbound Container Created !!!
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/State/sipSPIChangeState: 0x22B6E918 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_spi_process_ccapi_event: 
 CCAPI Event Preprocessor Failure
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_call_statistics: Stats are not supported for IPIP call.
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 28
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg..
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 8
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/act_disconnecting_disconnect: Disconnect now.. no defer BYE..
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIDeferCallClose: Not split dataplane, bail
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIWaitForStatsBforeCallClose: Not split dataplane, bail
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPICallCloseAfterFinalStat: 
sipSPICallCloseAfterFinalStat:
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/State/sipSPIChangeState: 0x22B6E918 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Call/sipSPICallInfo: 
The Call Setup Information is:
Call Control Block (CCB) : 0x0x22B6E918
State of The Call        : STATE_DEAD
TCP Sockets Used         : YES
Calling Number           : 
Called Number            : 2501
Source IP Address (Sig  ): 0.0.0.0
Destn SIP Req Addr:Port  : :0
Destn SIP Resp Addr:Port : :0
Destination Name         : 

*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Call/sipSPICallInfo: 
Disconnect Cause (CC)    : 16
Disconnect Cause (SIP)   : 200

*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 27
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[39] removed.
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_offer_ans_delete: 
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/ccsip_iwf_delete:  
*Apr 17 10:10:08.037: //39/49ABDC418074/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x22B6E918
*Apr 17 10:10:08.037: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[39]
CSI-voice#