000138: Jan 2 14:48:20.823: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=616, Called Number=, Voice-Interface=0x888BC2FC, Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH 000139: Jan 2 14:48:20.823: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=616 000140: Jan 2 14:48:20.823: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 000141: Jan 2 14:48:23.239: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 3_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000142: Jan 2 14:48:23.239: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 3_PSTN_CalledNumber 000143: Jan 2 14:48:23.239: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000144: Jan 2 14:48:23.239: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=3, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000145: Jan 2 14:48:23.239: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000146: Jan 2 14:48:23.699: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000147: Jan 2 14:48:23.699: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33_PSTN_CalledNumber 000148: Jan 2 14:48:23.699: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000149: Jan 2 14:48:23.699: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000150: Jan 2 14:48:23.699: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000151: Jan 2 14:48:24.159: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33x_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000152: Jan 2 14:48:24.159: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33x_PSTN_CalledNumber 000153: Jan 2 14:48:24.159: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000154: Jan 2 14:48:24.159: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=335, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000155: Jan 2 14:48:24.159: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000156: Jan 2 14:48:24.619: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000157: Jan 2 14:48:24.619: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xx_PSTN_CalledNumber 000158: Jan 2 14:48:24.619: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000159: Jan 2 14:48:24.619: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=3358, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000160: Jan 2 14:48:24.619: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000161: Jan 2 14:48:25.079: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xxx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000162: Jan 2 14:48:25.079: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xxx_PSTN_CalledNumber 000163: Jan 2 14:48:25.079: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000164: Jan 2 14:48:25.079: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33583, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000165: Jan 2 14:48:25.079: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000166: Jan 2 14:48:25.539: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xxxx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000167: Jan 2 14:48:25.539: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xxxx_PSTN_CalledNumber 000168: Jan 2 14:48:25.539: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000169: Jan 2 14:48:25.539: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=335836, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000170: Jan 2 14:48:25.539: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000171: Jan 2 14:48:26.000: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xxxxx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000172: Jan 2 14:48:26.000: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xxxxx_PSTN_CalledNumber 000173: Jan 2 14:48:26.000: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000174: Jan 2 14:48:26.000: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=3358361, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000175: Jan 2 14:48:26.000: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000176: Jan 2 14:48:26.460: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xxxxxx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000177: Jan 2 14:48:26.460: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xxxxxx_PSTN_CalledNumber 000178: Jan 2 14:48:26.460: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000179: Jan 2 14:48:26.460: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33583613, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000180: Jan 2 14:48:26.460: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000181: Jan 2 14:48:26.920: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, 33xxxxxxx_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000182: Jan 2 14:48:26.920: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; 33xxxxxxx_PSTN_CalledNumber 000183: Jan 2 14:48:26.920: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000184: Jan 2 14:48:26.920: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=335836137, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000185: Jan 2 14:48:26.920: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000186: Jan 2 14:48:27.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000187: Jan 2 14:48:27.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000188: Jan 2 14:48:27.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Partial Matches(1) after DP_MATCH_DEST 000189: Jan 2 14:48:27.400: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=1, saf_dndb_lookup=0, dp_result=1 000190: Jan 2 14:48:27.400: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=MORE_DIGITS_NEEDED(1) 000191: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, Called Number=3358361370T, Peer Info Type=DIALPEER_INFO_SPEECH 000192: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=3358361370T 000193: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000194: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=3358361370T, saf_enabled=1, saf_dndb_lookup=0, dp_result=0 000195: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000196: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000197: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000198: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000199: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=0, saf_dndb_lookup=1, dp_result=0 000200: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000201: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000202: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000203: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000204: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=0, saf_dndb_lookup=1, dp_result=0 000205: Jan 2 14:48:37.400: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000206: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=33xxxxxxx0_PSTN_CalledNumber, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000207: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000208: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000209: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=0, saf_dndb_lookup=1, dp_result=0 000210: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000211: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH 000212: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt 000213: Jan 2 14:48:37.400: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 000214: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=33xxxxxxx0_PSTN_CalledNumber, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH 000215: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=11 000216: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 000217: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000218: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000219: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000220: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=0, saf_dndb_lookup=1, dp_result=0 000221: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000222: Jan 2 14:48:37.404: //-1/A6F044E28017/DPM/dpMatchPeersCore: Calling Number=, Called Number=33xxxxxxx0_PSTN_CalledNumber, Peer Info Type=DIALPEER_INFO_SPEECH 000223: Jan 2 14:48:37.404: //-1/A6F044E28017/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=33xxxxxxx0_PSTN_CalledNumber 000224: Jan 2 14:48:37.404: //-1/A6F044E28017/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST 000225: Jan 2 14:48:37.404: //-1/A6F044E28017/DPM/dpMatchSafModulePlugin: dialstring=33xxxxxxx0_PSTN_CalledNumber, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 000226: Jan 2 14:48:37.404: //-1/A6F044E28017/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=11 000227: Jan 2 14:48:37.404: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x88D1F968) with key=[11] to table 000228: Jan 2 14:48:37.404: //-1/000000000000/SIP/Info/ccsip_offer_ans_init: 000229: Jan 2 14:48:37.404: //-1/000000000000/SIP/Info/ccsip_iwf_init: 000230: Jan 2 14:48:37.404: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init: 000231: Jan 2 14:48:37.404: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry... 000232: Jan 2 14:48:37.408: //20/000000000000/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 000233: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_call_setup_request: Set Protocol information 000234: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: 000235: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 1, Event Id: 176 000236: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_process_event: 000237: Jan 2 14:48:37.408: //20/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 000238: Jan 2 14:48:37.408: //20/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000239: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000240: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_sip_md: 000241: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000242: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_sip_ed: 000243: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000244: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_h32x_in_set_mode: 000245: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000246: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_h323_in_set_mode: 000247: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000248: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode: 000249: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_int_type_frm_set_mode_ev: 000250: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_mode_sip_sccp_in_set_mode: 000251: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/sip_iwf_def_set_mode_hdlr: Setting SPI mode to SIP-TDM 000252: Jan 2 14:48:37.408: //20/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_default_early_dialog_container 000253: Jan 2 14:48:37.408: //20/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 000254: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS 000255: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: 000256: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_map_ccapi_event_to_iwf_event: Event Category: 3, Event Id: 5 000257: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_process_event: 000258: Jan 2 14:48:37.408: //20/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 000259: Jan 2 14:48:37.408: //20/000000000000/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000260: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_fa2ft_md_flow_mode_transition: 000261: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_fa2ft_flow_mode_transition: 000262: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev: 000263: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/is_fa2ft_flow_mode_transition: 000264: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_get_flow_mode_frm_set_flow_mode_ev: 000265: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2 000266: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_iwf_handle_peer_event: Return value : SIP_IWF_SUCCESS 000267: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_call_setup_request: Before processing SETUP REQccb->pld.flags_ipip = 200 000268: Jan 2 14:48:37.408: //20/000000000000/SIP/Info/ccsip_call_setup_request: After processing SETUP REQccb->pld.flags_ipip = 200 000269: Jan 2 14:48:37.408: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : voip.fastwebnet.it target_port : 5060 000270: Jan 2 14:48:37.408: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_SETUP 000271: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/ccsip_call_setup_request: Incrementing call counter to [1] in dial-peer [11] 000272: Jan 2 14:48:37.408: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 2 000273: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPIUaddccCallIdToTable: Adding call id 14 to table 000274: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPIGetCallConfig: preferred_codec set[0] type :No Codec bytes: 0 000275: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPIGetCallConfig: Media forking disabled 000276: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPIGetCallConfig: Media Antitrombone disabled 000277: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPICanSetFallbackFlag: Local Fallback is not active 000278: Jan 2 14:48:37.408: //20/A6F044E28017/SIP/Info/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH 000279: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPISetMediaFlowMode: xcoder high-density disabled 000280: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH 000281: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIGetCallConfig: Using Voice Class Codec, tag = 1 and offer-all is = FALSE 000282: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Media/sipSPICopyStunConfigFromPeerToCCB: Firewall traversal is not enabled 000283: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIGetModemInfoPerCall: peer_callID=19 000284: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIGetCallConfig: Media forking disabled 000285: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk 000286: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0 000287: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0 000288: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled 000289: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIValidateAndCopyOutboundHost: CCSIP: copy target_host to outbound_host 000290: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/preprocessSetup: This is a not a SIGO Call -, could be DM call 000291: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/ccsip_iwf_process_event: 000292: Jan 2 14:48:37.412: //20/A6F044E28017/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 000293: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 000294: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/ccsip_iwf_process_event: IWF - cnfsm ret 2 000295: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sip_gw_pre_setup_add_sdp_container: DNS/ENUM resolution required; Deferred Creating SDP 000296: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIValidateGtd: Signal Forward disabled 000297: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIValidateTunnelData: RawMsg/QSIG Tunneling Not Enabled 000298: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIAddMLPPServicesInfo: No MLP Info available on incoming leg 000299: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIPreprocessUriFormat: Url cfg for 1: 2,phone-ctxt=FALSE 000300: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIAddCiscoGcid: Gcid value not set - not adding header. 000301: Jan 2 14:48:37.412: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddPrivacyandIdentityInfo: ccb->local_host_name,ccb->src_addr_str is NULL 000302: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled 000303: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Error/sipSPI_ipip_set_history_info_header: ccb->src_addr_str is NULL 000304: Jan 2 14:48:37.412: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE 000305: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS) 000306: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_SENT_DNS) 000307: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.X.Y.88 000308: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table. 000309: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x88D1F968 key=B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 000310: Jan 2 14:48:37.412: //20/A6F044E28017/SIP/Info/sipSPIUsetBillingProfile: sipCallId for billing records = B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 000311: Jan 2 14:48:37.412: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_srv_query: TYPE SRV query for _sip._udp.voip.fastwebnet.it and type:1 000312: Jan 2 14:48:37.420: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: DNS query for voip.fastwebnet.it and type:1 000313: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: TYPE A query successful for voip.fastwebnet.it 000314: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: IP Address of voip.fastwebnet.it is: 000315: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 85.18.217.100 000316: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43 000317: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (STATE_IDLE, SUBSTATE_NONE) 000318: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1 000319: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: IP Address No. 1, IP address 85.18.217.100 000320: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.X.Y.88 000321: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.X.Y.88 000322: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPIRscmsmAvail: Value returned by check is = 0 000323: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPICompareHistoryInfoWithMatchedDialpeer: call-route history-info CLI not enabled 000324: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPI_ipip_set_history_info_header: No HI header recvd from container 000325: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPIAddPrivacyandIdentityInfo: Removing "id" value from Privacy 000326: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1 000327: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.X.Y.88 000328: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 192.X.Y.88 000329: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Media/sipSPIReserveRtpPort: reserved port 18202 for stream 1 000330: Jan 2 14:48:37.424: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetMediaDirectionForStream: Setting Media direction SENDRECV for stream 1 000331: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed 000332: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Info/sipSPIOutgoingCallSDP: Creating recv-only stream for outbound call 000333: Jan 2 14:48:37.424: //20/A6F044E28017/SIP/Media/sipSPIProcessRtpSessions: No active streams. 000334: Jan 2 14:48:37.428: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 85.18.217.100,Port 5060, Transport 1, SentBy Port 5060 000335: Jan 2 14:48:37.428: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone CET to SIP default timezone = GMT 000336: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ 000337: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:0, container:8A2EAE40 000338: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/Session-Timer/sipSTSLSRReqSend: Session timer is not required 000339: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/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 000340: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_handle_sent_sdp: 000341: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_process_event: 000342: Jan 2 14:48:37.428: //20/A6F044E28017/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 000343: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_is_invite_offer_valid: - 1 000344: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_common_offer_sent_hdlr: 000345: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_iwf_handle_network_event: 000346: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/ccsip_iwf_process_event: 000347: Jan 2 14:48:37.428: //20/A6F044E28017/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_SENT_SDP 000348: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/sip_iwf_def_ed_sent_sdp_offer_hdlr: 000349: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:S_SIP_IWF_SDP_SENT_AWAIT_SDP 000350: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/State/ccsip_cnfsm_debugs: OA:next_state:S_SIP_EARLY_DIALOG_OFFER_SENT 000351: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/sipSPIgetRegistrarHost: multiple registrar mode, host name is 192.X.Y.88 SIP: (20) Group (a= group line) attribute, level 65535 instance 1 not found. 000352: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/sipSPIGetCallExtensionSupported: anat enabled, src_sdp dont have anat 000353: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/sipSPISendInvite: Associated container=0x8A2EAE40 to Invite 000354: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Transport/sipSPISendInvite: Sending Invite to the transport layer 000355: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE 000356: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Transport/sipSPITransportSendMessage: msg=0x8A30A01C, addr=85.18.217.100, port=5060, sentBy_port=0, local_addr=192.X.Y.88, is_req=1, transport=1, switch=0, callBack=0x814A82CC 000357: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 000358: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 000359: Jan 2 14:48:37.428: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2 000360: Jan 2 14:48:37.428: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x8A30A01C, addr=85.18.217.100, port=5060, local_addr=192.X.Y.88, connId=2 for UDP 000361: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Info/sentInviteRequest: Sent Invite in state STATE_IDLE 000362: Jan 2 14:48:37.428: //-1/xxxxxxxxxxxx/SIP/Info/sentInviteRequest: Transaction active. Facilities will be queued. 000363: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE) 000364: Jan 2 14:48:37.428: //20/A6F044E28017/SIP/Media/sipSPIAddStream: Adding stream 1 of type voice+dtmf (callid 20) to the VOIP RTP library 000365: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Info/sipSPIValidateStreamAddrType: stream:1, Mode : 1 000366: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Info/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 000367: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 192.X.Y.88 000368: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Media/sipSPIUpdateRtcpSession: sipSPIUpdateRtcpSession for m-line 1 000369: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Media/sipSPIUpdateRtcpSession: rtcp_session info laddr = 192.X.Y.88, lport = 18202, raddr = 0.0.0.0, rport=0, do_rtcp=FALSE src_callid = 20, dest_callid = -1, stream type = voice+dtmf, stream direction = RECVONLY media_ip_addr = - , vrf tableid = 0 media_addr_type = 1 000370: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Media/sipSPIUpdateRtcpSession: No rtp session, creating a new one 000371: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Info/sipSPICreateRtpSession: sess: 8862CF30 do_rtcp:0 000372: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Media/sipSPICreateRtpSession: stun is disabled 000373: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/State/sipSPIChangeStreamState: Stream (callid = 20) State changed from (STREAM_ADDING) to (STREAM_ACTIVE) 000374: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Info/sipSPIUpdateCallEntry: Call 20 set InfoType to SPEECH 000375: Jan 2 14:48:37.432: //20/A6F044E28017/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:33xxxxxxx0_PSTN_CalledNumber@voip.fastwebnet.it:5060 SIP/2.0 Via: SIP/2.0/UDP 192.X.Y.88:5060;branch=z9hG4bK613F1 From: "fxs1" ;tag=9AA870-19C6 To: Date: Mon, 02 Jan 2006 14:48:37 GMT Call-ID: B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 2800764130-2060784090-2149050152-0201961812 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1136213317 Contact: Expires: 900 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 250 v=0 o=CiscoSystemsSIP-GW-UserAgent 3591 2323 IN IP4 192.X.Y.88 s=SIP Call c=IN IP4 192.X.Y.88 t=0 0 m=audio 18202 RTP/AVP 8 18 19 c=IN IP4 192.X.Y.88 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:19 CN/8000 000376: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [85.18.217.100]:5060, local_address:[192.X.Y.88] 000377: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [85.18.217.100]:5060, local_address:[192.X.Y.88] 000378: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 000379: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 000380: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 000381: Jan 2 14:48:37.448: //20/A6F044E28017/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.X.Y.88:5060;branch=z9hG4bK613F1 From: "fxs1" ;tag=9AA870-19C6 To: Call-ID: B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 CSeq: 101 INVITE Content-Length: 0 000382: Jan 2 14:48:37.448: //20/A6F044E28017/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX 000383: Jan 2 14:48:37.448: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) 000384: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 000385: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 000386: Jan 2 14:48:37.448: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 000387: Jan 2 14:48:37.448: //20/A6F044E28017/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 192.X.Y.88:5060;branch=z9hG4bK613F1 From: "fxs1" ;tag=9AA870-19C6 To: ;tag=aprqngfrt-e2tjc200000a6 Call-ID: B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 CSeq: 101 INVITE Content-Length: 0 000388: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/sipSPICheckResponseExt: INVITE response with no RSEQ - disable IS_REL1XX 000389: Jan 2 14:48:37.452: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentGTD: No GTD found in inbound container 000390: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_handle_call_active: 000391: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_process_event: 000392: Jan 2 14:48:37.452: //20/A6F044E28017/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 000393: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_process_event: O/A - cnfsm ret 2 000394: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/sipSPISendAck: Associated container=0x8A2EA4A0 to Ack 000395: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Transport/sipSPISendAck: Sending ACK to the transport layer 000396: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is FALSE 000397: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Transport/sipSPITransportSendMessage: msg=0x8860C6DC, addr=85.18.217.100, port=5060, sentBy_port=0, local_addr=192.X.Y.88, is_req=1, transport=1, switch=0, callBack=0x0 000398: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 000399: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0 000400: Jan 2 14:48:37.452: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportLogicSendMsg: connection-reuse configured, listen conn-id : 2 000401: Jan 2 14:48:37.452: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x8860C6DC, addr=85.18.217.100, port=5060, local_addr=192.X.Y.88, connId=2 for UDP 000402: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/act_recdproc_new_message_response: Received a 4/5/6xx message with StatusCode: 403 000403: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[20], src[4] 000404: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/sipSPIInitiateDisconnect: Initiate call disconnect(57) for outgoing call 000405: Jan 2 14:48:37.452: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQSIG: No QSIG Body found in inbound container 000406: Jan 2 14:48:37.452: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContentQ931: No RawMsg Body found in inbound container 000407: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26 000408: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/sipSPI_ipip_antiTrombone: Entered Antitrombone service 000409: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/sipSPI_ipip_antiTrombone: Antitrombone service not configured 000410: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/State/sipSPIChangeState: 0x88D1F968 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_DISCONNECTING, SUBSTATE_NONE) 000411: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_call_statistics: Requesting stats for callid=20 000412: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_call_statistics: Stats request failed for callid=20, dstCallID=-1, rc=-7 000413: Jan 2 14:48:37.452: //20/A6F044E28017/SIP/Info/ccsip_ipip_media_service_get_event_data: Event id = 26 000426: Jan 2 14:48:37.456: //20/A6F044E28017/SIP/Info/sipSPIUdeleteccCallIdFromTable: Removing call id 14 000427: Jan 2 14:48:37.460: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:33xxxxxxx0_PSTN_CalledNumber@voip.fastwebnet.it:5060 SIP/2.0 Via: SIP/2.0/UDP 192.X.Y.88:5060;branch=z9hG4bK613F1 From: "fxs1" ;tag=9AA870-19C6 To: ;tag=aprqngfrt-e2tjc200000a6 Date: Mon, 02 Jan 2006 14:48:37 GMT Call-ID: B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 000428: Jan 2 14:49:09.457: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[11] removed. 000429: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. 000430: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x88D1F968 key=B0D37AA1-7AD511DA-801CE728-C09B154@192.X.Y.88 000431: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd 000432: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed 000433: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/ccsip_offer_ans_delete: 000434: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/ccsip_iwf_delete: 000435: Jan 2 14:49:09.457: //20/A6F044E28017/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 88D1F968 UC540# UC540#