043632: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x24CE428) with key=[392] to table 043633: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsip_offer_ans_init: 043634: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsip_iwf_init: 043635: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsip_ipip_media_service_init: 043636: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/sipSPI_ipip_vcc_Initialization: Entry... 043637: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsip_ipip_media_forking_init: MF: Queue is initialised.. 043638: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetSipProfilesTag: voice class SIP Profiles tag is set : 3 043639: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsipRegisterSetTargetInfo: p2p mode with Registrar Server = dns:vodafone.qa:5066 043640: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsipRegisterSetTargetInfo: Parsing The Registrar Address 043641: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: target_host : vodafone.qa target_port : 5066 043642: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetOutboundHostAndDestHostPrivate: CCSIP: outbound_host : vodafone.qa outbound_port : 5066 043643: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/sipSPIOutboundProxyReuse: Do not reuse Outbound Proxy IP adress and Port 043644: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 043645: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/ccsip_spi_registrar_add_expires_header: Inside ccsip_spi_registrar_add_expires_header for Expires 043646: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_OUTBOUND_REGISTER 043647: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIIncrementOverloadCount: Local 1 Global 1 043648: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 40 043649: Sep 19 15:12:59.615 AST: //-1/000000000000/SIP/Info/act_idle_outgoing_register: In act_idle_outgoing_register 043650: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/act_idle_outgoing_register: Send REGISTER to vodafone.qa:5066 043651: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/sipSPIUaddCcbToUACTable: ****Adding to UAC table. 043652: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x24CE428 key=9D464073-3F1411E4-8012BD61-4E562DA1 043653: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Event/sipSPIEventInfo: Queued event from SIP SPI : SIPSPI_EV_DNS_RESOLVE 043654: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_SENT_DNS) 043655: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (STATE_IDLE, SUBSTATE_SENT_DNS) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS) 043656: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: DNS query for vodafone.qa and type:1 043657: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_query: TYPE A query successful for vodafone.qa 043658: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: IP Address of vodafone.qa is: 043659: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_dns_type_a_aaaa_query: 172.16.6.4 043660: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 43 043661: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: sipSPICacheHostToCCB dnsResponse.num_hosts = 1 043662: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICacheHostToCCB: IP Address No. 1, IP address 172.16.6.4 043663: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/resolve_ip_address_to_bind: ip_get_ifaddress IPv4 10.72.0.130 for SIP 043664: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/resolve_sig_ip_address_to_bind: signaling bind address : 10.72.0.130 043665: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_spi_register_get_rcb: Getting New RCB [0x256A528] 043666: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_register_set_dns_resolved_address: CCSIP_REGISTER:: registrar 0 DNS resolved addr set to 172.16.6.4:5066 043667: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartRCBTimer: Starting timer for pattern for 3600 seconds 043668: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_SENT_DNS) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) 043669: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 7 event 043670: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB 043671: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB 043672: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT 043673: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x417A0F50 to Register 043674: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer 043675: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is TRUE 043676: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x14191000, addr=172.16.6.4, port=5066, sentBy_port=0, local_addr=10.72.0.130, is_req=1, transport=1, switch=1, callBack=0x890E5E4 043677: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI before modification : REGISTER sip:vodafone.qa:5066 SIP/2.0 043678: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI after modification : REGISTER sip:vodafone.qa SIP/2.0 043679: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_get_complete_header: No holder for this header 043680: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Unable to get header from stack 043681: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 043682: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 1 043683: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportGetInterfaceMtuSize: MTU size for remote address 172.16.6.4 is 1500 043684: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportVerifyMsgForMTUThreshold: Interface MTU Size 1500, Msg Size 469 043685: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.6.4, rport:5066 with laddr:10.72.0.130 043686: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x24CE428 with connection=0x1415A1B8 context list 043687: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x14191000 043688: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x14191000, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connId=2 for UDP 043689: Sep 19 15:12:59.615 AST: //376/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) 043690: Sep 19 15:12:59.615 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: REGISTER sip:vodafone.qa SIP/2.0 Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1D91593 From: ;tag=4040B4C-591 To: Date: Fri, 19 Sep 2014 12:12:59 GMT Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5 Max-Forwards: 70 Timestamp: 1411128779 CSeq: 113 REGISTER Contact: Expires: 3600 Supported: path Content-Length: 0 043691: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.16.6.4]:5066, local_address:[10.72.0.130] 043692: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 043693: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 043694: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 043695: Sep 19 15:12:59.635 AST: //376/000000000000/SIP/Info/sipSPIMatchRespToReqTran: removing unneeded z9hG4bK1D91593 from via branch list 043696: Sep 19 15:12:59.635 AST: //376/000000000000/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1D91593 From: ;tag=4040B4C-591 To: ;tag=SDtp8r999-529fa17d-1411128983249799 Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 Timestamp: 1411128779 CSeq: 113 REGISTER Date: Fri, 19 Sep 2014 12:16:23 GMT Server: Alcatel-Lucent-HPSS/3.0.3 WWW-Authenticate: Digest realm="vodafone.qa", nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808", opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_", algorithm=MD5, qop="auth" Content-Length: 0 043697: Sep 19 15:12:59.635 AST: //376/000000000000/SIP/Info/sipSPICheckResponseExt: non-INVITE response with no RSEQ - do not disable IS_REL1XX 043698: Sep 19 15:12:59.635 AST: //376/000000000000/SIP/Info/sipSPIGenerateAuthorizationResponse: HA1 is: d8e4e6e9e10cb630dead2847582a8d6b 043699: Sep 19 15:12:59.635 AST: //376/000000000000/SIP/Info/sipSPIPresendProcessing: Presend Processing called for 7 event 043700: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB 043701: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIRetrieveOutgoingPassThruData: Retrieving Data from RCB 043702: Sep 19 15:12:59.635 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT 043703: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x417A17E8 to Register 043704: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer 043705: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is TRUE 043706: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x1485A314, addr=172.16.6.4, port=5066, sentBy_port=0, local_addr=10.72.0.130, is_req=1, transport=1, switch=1, callBack=0x890E5E4 043707: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI before modification : REGISTER sip:vodafone.qa:5066 SIP/2.0 043708: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI after modification : REGISTER sip:vodafone.qa SIP/2.0 043709: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header before modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa:5066",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043710: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header after modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043711: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 043712: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 1 043713: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportGetInterfaceMtuSize: MTU size for remote address 172.16.6.4 is 1500 043714: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportVerifyMsgForMTUThreshold: Interface MTU Size 1500, Msg Size 807 043715: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.6.4, rport:5066 with laddr:10.72.0.130 043716: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x24CE428 is already on connection=0x1415A1B8 context_list 043717: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x1485A314 043718: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x1485A314, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connId=2 for UDP 043719: Sep 19 15:12:59.639 AST: //376/000000000000/SIP/State/sipSPIChangeState: 0x24CE428 : State change from (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) to (SIP_STATE_OUTGOING_REGISTER, SUBSTATE_NONE) 043720: Sep 19 15:12:59.639 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: REGISTER sip:vodafone.qa SIP/2.0 Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1DAA22 From: ;tag=4040B4C-591 To: Date: Fri, 19 Sep 2014 12:12:59 GMT Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5 Max-Forwards: 70 Timestamp: 1411128779 CSeq: 114 REGISTER Contact: Expires: 3600 Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 Content-Length: 0 043721: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT 043722: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x417A17E8 to Register 043723: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer 043724: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is TRUE 043725: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x418F3EAC, addr=172.16.6.4, port=5066, sentBy_port=0, local_addr=10.72.0.130, is_req=1, transport=1, switch=1, callBack=0x0 043726: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI before modification : REGISTER sip:vodafone.qa:5066 SIP/2.0 043727: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI after modification : REGISTER sip:vodafone.qa SIP/2.0 043728: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header before modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa:5066",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043729: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header after modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043730: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 043731: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 1 043732: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportGetInterfaceMtuSize: MTU size for remote address 172.16.6.4 is 1500 043733: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportVerifyMsgForMTUThreshold: Interface MTU Size 1500, Msg Size 807 043734: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.6.4, rport:5066 with laddr:10.72.0.130 043735: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x24CE428 is already on connection=0x1415A1B8 context_list 043736: Sep 19 15:12:59.739 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x418F3EAC 043737: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x418F3EAC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connId=2 for UDP 043738: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: REGISTER sip:vodafone.qa SIP/2.0 Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1DAA22 From: ;tag=4040B4C-591 To: Date: Fri, 19 Sep 2014 12:12:59 GMT Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5 Max-Forwards: 70 Timestamp: 1411128779 CSeq: 114 REGISTER Contact: Expires: 3600 Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 Content-Length: 0 043739: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.16.6.4]:5066, local_address:[10.72.0.130] 043740: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 043741: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x14158ADC 043742: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x14158ADC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connid=2, transport=UDP 043743: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 5066 connId 2 043744: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 043745: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Error/sippmh_parse_via_ext: input_via=0 sipVia=2383B50 043746: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIMatchRespToReqTran: Error in parsing Via header 043747: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 403 Forbidden From: ;tag=4040B4C-591 To: ;tag=SDtp8r999-529fa17d-1411128983261680 Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 Timestamp: 1411128779 CSeq: 114 REGISTER Date: Fri, 19 Sep 2014 12:16:23 GMT Server: Alcatel-Lucent-HPSS/3.0.3 Content-Length: 0 043748: Sep 19 15:12:59.739 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPILocateInviteDialogCCB: Could not find matching transaction for this response, Dropping it 043749: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT 043750: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x417A17E8 to Register 043751: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer 043752: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is TRUE 043753: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x418F3EAC, addr=172.16.6.4, port=5066, sentBy_port=0, local_addr=10.72.0.130, is_req=1, transport=1, switch=1, callBack=0x0 043754: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI before modification : REGISTER sip:vodafone.qa:5066 SIP/2.0 043755: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI after modification : REGISTER sip:vodafone.qa SIP/2.0 043756: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header before modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa:5066",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043757: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header after modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043758: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 043759: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 1 043760: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportGetInterfaceMtuSize: MTU size for remote address 172.16.6.4 is 1500 043761: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportVerifyMsgForMTUThreshold: Interface MTU Size 1500, Msg Size 807 043762: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.6.4, rport:5066 with laddr:10.72.0.130 043763: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x24CE428 is already on connection=0x1415A1B8 context_list 043764: Sep 19 15:12:59.939 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x418F3EAC 043765: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x418F3EAC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connId=2 for UDP 043766: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: REGISTER sip:vodafone.qa SIP/2.0 Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1DAA22 From: ;tag=4040B4C-591 To: Date: Fri, 19 Sep 2014 12:12:59 GMT Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5 Max-Forwards: 70 Timestamp: 1411128779 CSeq: 114 REGISTER Contact: Expires: 3600 Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 Content-Length: 0 043767: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.16.6.4]:5066, local_address:[10.72.0.130] 043768: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 043769: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x14158ADC 043770: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x14158ADC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connid=2, transport=UDP 043771: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 5066 connId 2 043772: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 043773: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Error/sippmh_parse_via_ext: input_via=0 sipVia=2383B50 043774: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIMatchRespToReqTran: Error in parsing Via header 043775: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 403 Forbidden From: ;tag=4040B4C-591 To: ;tag=SDtp8r999-529fa17d-1411128983261680 Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 Timestamp: 1411128779 CSeq: 114 REGISTER Date: Fri, 19 Sep 2014 12:16:23 GMT Server: Alcatel-Lucent-HPSS/3.0.3 Content-Length: 0 043776: Sep 19 15:12:59.939 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPILocateInviteDialogCCB: Could not find matching transaction for this response, Dropping it 043777: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Converting TimeZone AST to SIP default timezone = GMT 043778: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Info/sipSPISendRegister: Associated container=0x417A17E8 to Register 043779: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipSPISendRegister: Sending REGISTER to the transport layer 043780: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipSPIGetSwitchTransportFlag: Return the Global configuration, Switch Transport is TRUE 043781: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x418F3EAC, addr=172.16.6.4, port=5066, sentBy_port=0, local_addr=10.72.0.130, is_req=1, transport=1, switch=1, callBack=0x0 043782: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI before modification : REGISTER sip:vodafone.qa:5066 SIP/2.0 043783: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_req_uri: Req URI after modification : REGISTER sip:vodafone.qa SIP/2.0 043784: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header before modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa:5066",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043785: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sip_profiles_application_modify_remove_header: Header after modification : Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 043786: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 043787: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: switch transport is 1 043788: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportGetInterfaceMtuSize: MTU size for remote address 172.16.6.4 is 1500 043789: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportVerifyMsgForMTUThreshold: Interface MTU Size 1500, Msg Size 807 043790: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:172.16.6.4, rport:5066 with laddr:10.72.0.130 043791: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x24CE428 is already on connection=0x1415A1B8 context_list 043792: Sep 19 15:13:00.339 AST: //376/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x418F3EAC 043793: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x418F3EAC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connId=2 for UDP 043794: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: REGISTER sip:vodafone.qa SIP/2.0 Via: SIP/2.0/UDP 10.72.0.130:5060;branch=z9hG4bK1DAA22 From: ;tag=4040B4C-591 To: Date: Fri, 19 Sep 2014 12:13:00 GMT Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 User-Agent: Cisco-SIPGateway/IOS-15.2.4.M5 Max-Forwards: 70 Timestamp: 1411128780 CSeq: 114 REGISTER Contact: Expires: 3600 Authorization: Digest username="+97440002600@ims.vodafone.qa",realm="vodafone.qa",uri="sip:vodafone.qa",response="ac8694ec2ef669615257ed06fca3a88a",nonce="b7c9036dbf3054aea9541c1e9740e9703dc8f84c1808",opaque="ALU:QbkRBthOEgEQAkgVEwwHRAIBHgkdHwQCQ1lFRkdWCAk1MnItJjV3eWcjJj9jOCA0MDQ8OjB4NDc0dEBTCQEFAQc_",cnonce="3496886E",qop=auth,algorithm=MD5,nc=00000001 Content-Length: 0 043795: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [172.16.6.4]:5066, local_address:[10.72.0.130] 043796: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 043797: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x14158ADC 043798: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x14158ADC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, connid=2, transport=UDP 043799: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipHolderGetConnInstance: Return existing connection for port 5066 connId 2 043800: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog 043801: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Error/sippmh_parse_via_ext: input_via=0 sipVia=2383B50 043802: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIMatchRespToReqTran: Error in parsing Via header 043803: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 403 Forbidden From: ;tag=4040B4C-591 To: ;tag=SDtp8r999-529fa17d-1411128983261680 Call-ID: 9D464073-3F1411E4-8012BD61-4E562DA1 Timestamp: 1411128779 CSeq: 114 REGISTER Date: Fri, 19 Sep 2014 12:16:23 GMT Server: Alcatel-Lucent-HPSS/3.0.3 Content-Length: 0 043804: Sep 19 15:13:00.339 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPILocateInviteDialogCCB: Could not find matching transaction for this response, Dropping it 043805: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Error/act_sent_register_wait_100: act_sent_register_wait_100: Out of retries 043806: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDecrementOverloadCount: Count:Local 0 Global 0 043807: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Error/ccsip_api_register_result_ind: Message Code Class 4xx Method Code 100 received for REGISTER 043808: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsipGetCCBFromRCB: Retrived CCB pointer [0x0] 043809: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_spi_register_free_rcb: Freeing rcb [0x256A528] 043810: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_register_reset_dns_cache: CCSIP_REGISTER:: registrar 0 DNS resolved addr reset 043811: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Error/ccsip_api_register_result_ind: SIP Registration Retries Exhausted 043812: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPIRegPthruProcessResponse: Processing response w/ resp code == 408 043813: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetRPCBFromRCB: Retreiving RCB [0x255BE38] from RPCB [0x0] 043814: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Error/sipSPIRegPthruProcessResponse: Error NO RPCB 043815: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/ccsipRegisterStartRCBTimer: Starting timer for pattern +97440002600 for 180 seconds 043816: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[392] removed. 043817: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPIUdeleteCcbFromUACTable: ****Deleting from UAC table. 043818: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x24CE428 key=9D464073-3F1411E4-8012BD61-4E562DA1 043819: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd 043820: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x14158ADC, addr=172.16.6.4, port=5066, local_addr=10.72.0.130, unregistering context=0x24CE428 043821: Sep 19 15:13:01.139 AST: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x24CE428 from the connection=0x1415A1B8 context list 043822: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed 043823: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/ccsip_offer_ans_delete: 043824: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/ccsip_iwf_delete: 043825: Sep 19 15:13:01.139 AST: //376/000000000000/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 0x24CE428