cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
Announcements

342
Views
5
Helpful
2
Replies
Flemming Ortvald
Contributor

Trying to fix NAT in with "voice class sip-profiles" but keeps getting "SIP/2.0 400 Bad Request - 'Invalid IP Address'" in response to incoming OPTIONS request.

Layout:

ITSP (134.119.180.134)<-->(89.202.162.90)FW(10.10.21.1)<--> CUBE(10.5.100.10)

I have implemented sip-profiles to change external ip address to internal inbound, and the reverse outbound.

sip-profiles:

no voice class sip-profiles 3521
voice class sip-profiles 3521
request ANY sip-header Via modify "89.202.162.90" "10.5.100.10"
request ANY sip-header Via modify "134.119.180.134" "10.10.21.1"
request ANY sip-header Remote-Party-ID modify "89.202.162.90" "10.5.100.10"
request ANY sip-header From modify "89.202.162.90" "10.5.100.10"
request ANY sip-header From modify "134.119.180.134" "10.10.21.1"
request ANY sip-header To modify "134.119.180.134" "10.10.21.1"
request ANY sip-header To modify "89.202.162.90" "10.5.100.10"
request ANY sip-header Call-ID modify "89.202.162.90" "10.5.100.10"
request ANY sip-header Call-ID modify "134.119.180.134" "10.10.21.1"
request ANY sip-header Contact modify "89.202.162.90" "10.5.100.10"
request ANY sip-header Contact modify "134.119.180.134" "10.10.21.1"
request ANY sdp-header Session-Owner modify "89.202.162.90" "10.5.100.10"
request ANY sdp-header Connection-Info modify "89.202.162.90" "10.5.100.10"
request ANY sdp-header Audio-Connection-Info modify "89.202.162.90" "10.5.100.10"
response ANY sip-header Via modify "89.202.162.90" "10.5.100.10"
response ANY sip-header Via modify "134.119.180.134" "10.10.21.1"
response ANY sip-header Remote-Party-ID modify "89.202.162.90" "10.5.100.10"
response ANY sip-header From modify "89.202.162.90" "10.5.100.10"
response ANY sip-header From modify "134.119.180.134" "10.10.21.1"
response ANY sip-header To modify "89.202.162.90" "10.5.100.10"
response ANY sip-header To modify "134.119.180.134" "10.10.21.1"
response ANY sip-header Call-ID modify "89.202.162.90" "10.5.100.10"
response ANY sip-header Call-ID modify "134.119.180.134" "10.10.21.1"
response ANY sip-header Contact modify "89.202.162.90" "10.5.100.10"
response ANY sip-header Contact modify "134.119.180.134" "10.10.21.1"
response ANY sdp-header Session-Owner modify "89.202.162.90" "10.5.100.10"
response ANY sdp-header Connection-Info modify "89.202.162.90" "10.5.100.10"
response ANY sdp-header Audio-Connection-Info modify "89.202.162.90" "10.5.100.10"
no voice class sip-profiles 3522
voice class sip-profiles 3522
request ANY sip-header Via modify "10.5.100.10" "89.202.162.90"
request ANY sip-header Via modify "10.10.21.1" "134.119.180.134"
request ANY sip-header Remote-Party-ID modify "10.5.100.10" "89.202.162.90"
request ANY sip-header From modify "10.5.100.10" "89.202.162.90"
request ANY sip-header From modify "10.10.21.1" "134.119.180.134"
request ANY sip-header To modify "10.10.21.1" "134.119.180.134"
request ANY sip-header To modify "10.5.100.10" "89.202.162.90"
request ANY sip-header Call-ID modify "10.5.100.10" "89.202.162.90"
request ANY sip-header Call-ID modify "10.10.21.1" "134.119.180.134"
request ANY sip-header Contact modify "10.5.100.10" "89.202.162.90"
request ANY sip-header Contact modify "10.10.21.1" "134.119.180.134"
request ANY sdp-header Session-Owner modify "10.5.100.10" "89.202.162.90"
request ANY sdp-header Connection-Info modify "10.5.100.10" "89.202.162.90"
request ANY sdp-header Audio-Connection-Info modify "10.5.100.10" "89.202.162.90"
response ANY sip-header Via modify "10.5.100.10" "89.202.162.90"
response ANY sip-header Via modify "10.10.21.1" "134.119.180.134"
response ANY sip-header Remote-Party-ID modify "10.5.100.10" "89.202.162.90"
response ANY sip-header From modify "10.5.100.10" "89.202.162.90"
response ANY sip-header From modify "10.10.21.1" "134.119.180.134"
response ANY sip-header To modify "10.10.21.1" "134.119.180.134"
response ANY sip-header To modify "10.5.100.10" "89.202.162.90"
response ANY sip-header Call-ID modify "10.5.100.10" "89.202.162.90"
response ANY sip-header Call-ID modify "10.10.21.1" "134.119.180.134"
response ANY sip-header Contact modify "10.5.100.10" "89.202.162.90"
response ANY sip-header Contact modify "10.10.21.1" "134.119.180.134"
response ANY sdp-header Session-Owner modify "10.5.100.10" "89.202.162.90"
response ANY sdp-header Connection-Info modify "10.5.100.10" "89.202.162.90"
response ANY sdp-header Audio-Connection-Info modify "10.5.100.10" "89.202.162.90"

Dial-peers:

!
dial-peer voice 351 voip
description *** CUBE to POR-GTT ***
session protocol sipv2
session transport udp
session server-group 351
destination e164-pattern-map 351
voice-class codec 1
voice-class sip early-offer forced
voice-class sip profiles 3521 inbound
voice-class sip profiles 3522
voice-class sip options-keepalive retry 3
voice-class sip bind control source-interface GigabitEthernet1
voice-class sip bind media source-interface GigabitEthernet1
dtmf-relay rtp-nte
no vad
!
dial-peer voice 3510 voip
description *** POR-GTT to CUBE ***
session protocol sipv2
session transport udp
incoming uri via 3510
voice-class codec 1
voice-class sip rel1xx require "100rel"
voice-class sip profiles 3522
voice-class sip profiles 3521 inbound
voice-class sip options-keepalive retry 3
voice-class sip bind control source-interface GigabitEthernet1
voice-class sip bind media source-interface GigabitEthernet1
dtmf-relay rtp-nte

no vad

 

debug ccsip all:

May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F82E666C710 with refCount = 1
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F827D2D7290 with refCount = 1
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.10.21.1]:64240, local_address:[ - ]
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F82E666C710
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0
May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:89.202.162.90 SIP/2.0
Via: SIP/2.0/UDP 134.119.180.134:5060;branch=z9hG4bK08aeb154;rport
Max-Forwards: 70
From: "Anonymous" <sip:Anonymous@134.119.180.134>;tag=as15f7cc77
To: <sip:89.202.162.90>
Contact: <sip:Anonymous@134.119.180.134:5060>
Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@134.119.180.134:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 16.10.0
Date: Wed, 05 May 2021 09:05:23 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIIpTrustSilentDiscard: sipSPIIpTrustSilentDiscard: called for method [101]
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIAddContextToTable: Added context(0x7F82DF2BD590) with key=[266167] to table
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/4096/ccsip_ipip_media_service_init:
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/4096/ccsip_tdmip_media_service_init:
May 5 09:05:28.010: //-1/000000000000/SIP/Info/verbose/36864/ccsip_ipip_media_forking_init: MF: Queue is initialised..
May 5 09:05:28.010: //-1/000000000000/SIP/Info/verbose/4097/ccsip_platform_init_ccb: Initialising rtp session queue
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/16384/sipSPICheckIpip: User portion null/non-e164.. DP match skipped
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 134.119.180.134
May 5 09:05:28.010: //-1/000000000000/SIP/Info/verbose/4096/sipSPIUpdateCallingInfoUsingRpidOrFrom: Updating Calling Info with FROM header data
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/2048/sipSPICheckAssertedIdConfig: Dialpeer match is not yet done
May 5 09:05:28.010: //-1/000000000000/SIP/Info/info/1024/ccsipInitPldCallingInfo: non-numeric calling number: Anonymous
May 5 09:05:28.010: //-1/000000000000/SIP/Info/verbose/1024/sipSPIGetViaHostInURLFormat: VIA URL:sip:134.119.180.134:5060, Host:134.119.180.134
May 5 09:05:28.010: //-1/000000000000/SIP/Info/verbose/67584/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : Anonymous
May 5 09:05:28.010: //-1/000000000000/SIP/Error/sipSPIGetPeerByCalledPartyId:
input arg error
May 5 09:05:28.010: //-1/000000000000/SIP/Info/critical/10240/sipSPIGetCallConfig: No match found for P-Called-Party-ID
May 5 09:05:28.010: //-1/000000000000/SIP/Error/sipSPIUpdateCallInfo:
input argument error
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/verbose/64/ccsip_inbound_profile_populate_callinfo_in_ccb: Dial-peer 3510 is used for inbound profiles config
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sipSPISetSipProfilesTag: voice class SIPProfiles tag is set : 3522
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sipSPISetSipProfilesTag: voice class SIP Profiles inbound tag is set : 3521
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Via: SIP/2.0/UDP 134.119.180.134:5060;branch=z9hG4bK08aeb154;rport
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: SIP profiles application couldnt modify the header : Via: SIP/2.0/UDP 134.119.180.134:5060;branch=z9hG4bK08aeb154;rport
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Via: SIP/2.0/UDP 134.119.180.134:5060;branch=z9hG4bK08aeb154;rport
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header after modification : Via: SIP/2.0/UDP 10.10.21.1:5060;branch=z9hG4bK08aeb154;rport
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: Unable to get Remote-Party-ID header from stack
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : From: "Anonymous" <sip:Anonymous@134.119.180.134>;tag=as15f7cc77
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: SIP profiles application couldnt modify the header : From: "Anonymous" <sip:Anonymous@134.119.180.134>;tag=as15f7cc77
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : From: "Anonymous" <sip:Anonymous@134.119.180.134>;tag=as15f7cc77
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header after modification : From: "Anonymous" <sip:Anonymous@10.10.21.1>;tag=as15f7cc77
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : To: <sip:89.202.162.90>
May 5 09:05:28.010: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: SIP profiles application couldnt modify the header : To: <sip:89.202.162.90>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : To: <sip:89.202.162.90>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header after modification : To: <sip:10.5.100.10>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@134.119.180.134:5060
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: SIP profiles application couldnt modify the header : Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@134.119.180.134:5060
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@134.119.180.134:5060
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header after modification : Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@10.10.21.1:5060
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Contact: <sip:Anonymous@134.119.180.134:5060>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/critical/64/sip_profiles_application_modify_remove_header: SIP profiles application couldnt modify the header : Contact: <sip:Anonymous@134.119.180.134:5060>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header before modification : Contact: <sip:Anonymous@134.119.180.134:5060>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/64/sip_profiles_application_modify_remove_header: Header after modification : Contact: <sip:Anonymous@10.10.21.1:5060>
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIIpTrustSilentDiscard: sipSPIIpTrustSilentDiscard: called for method [101]
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.10.21.1,Port 5060, Transport 1, SentBy Port 5060vrfid 0
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/verbose/512/ccsip_get_vrf_from_sip_bind: vrfid 0 for tag 0, sip_vrfid 0, voice_vrfid 0
May 5 09:05:28.011: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
May 5 09:05:28.011: //-1/000000000000/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: VRF id = 0
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: HSRP IPv4 vaddr 10.5.100.10 for SIP
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: bind_inuse: 0conn_reuse: 0
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/8192/resolve_sig_ip_address_to_bind: return addr 10.5.100.10
May 5 09:05:28.011: //-1/DEB1A921A346/SIP/State/sipSPIChangeState: 0x7F82DF2BD590 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.10.21.1,Port 64240, Transport 1, SentBy Port 5060vrfid 0
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone CEST to SIP default timezone = GMT
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.10.21.1,Port 64240, Transport 1, SentBy Port 5060vrfid 0
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7F827D2D7290) counter, current msg->refCount = 2
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Error/httpish_msg_free:
Freeing NULL pointer!
May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_validate_own_ip_addr:
ReqLine IP addr does not match with host IP addr
May 5 09:05:28.011: //-1/DEB1A921A346/SIP/State/sipSPIChangeState: 0x7F82DF2BD590 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)
May 5 09:05:28.011: //-1/DEB1A921A346/SIP/Info/info/1024/ccsipInitPldCallingInfo: non-numeric calling number: Anonymous
May 5 09:05:28.011: //-1/DEB1A921A346/SIP/Info/verbose/1024/sipSPIGetViaHostInURLFormat: VIA URL:sip:10.10.21.1:5060, Host:10.10.21.1
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/67584/sipSPIGetShrlPeer: Try match incoming dialpeer for Calling number: : Anonymous
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Error/sipSPIGetPeerByCalledPartyId:
input arg error
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/critical/10240/sipSPIGetCallConfig: No match found for P-Called-Party-ID
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Error/sipSPIUpdateCallInfo:
input argument error
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/2048/sipSPISetAudioForcedConfig: audio forced config is set to TRUE
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/1024/sipSPIGetCallConfig: Precondition tag absent in Require/Supported header
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/2048/sipSPIGetCallConfig: Media Antitrombone disabled
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/8192/sipSPISetMediaFlowMode: Storing the configured mode as FLOW-THROUGH
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/2304/sipSPISetMediaFlowMode: xcoder high-density disabled
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/8192/sipSPISetMediaFlowMode: Flow Mode set to FLOW_THROUGH
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/8704/sipSPIGetCallConfig: Incoming: No Defer BYE for last call stats
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: Non dial peer leg - using RTP Supported Codecs
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 18
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 0
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 8
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 9
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 4
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 2
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 15
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/10240/sipSPIGetCallConfig: RTP Preferred Codecs supported by GW 3
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/critical/32768/ccsip_ipip_media_forking_update_preferred_codec: MF: Not a Forked SIP leg..
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Error/ccsip_ipip_media_forking_anchor_leg_config:
MF: Dial-peer is absent..
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Error/ccsip_ipip_media_forking_intra_frame_request_config:
MF:video profile Dial-peer is absent..
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/1/ccsip_set_srtp_config: No Srtp configure for this leg.
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/12288/sipSPIGetModemInfoPerCall: peer_callID=0
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/8192/sipSPICheckFAAnatAssymetricOrDO2EO: Not a SIP-SIP call or not in FA mode
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/2049/populate_vcc_data: Non dial peer leg - using RTP Supported Codecs
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 18
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 0
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 8
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 9
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 4
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 2
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 15
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/info/1/populate_vcc_data: RTP Preferred Codecs supported by GW 3
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/notify/8192/sipSPISetOverlapConfiguration: Overlap signaling: FALSE: Endpt: SIP Trunk
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0
May 5 09:05:28.012: //-1/DEB1A921A346/SIP/Info/verbose/2048/sipSPI_ipip_GetCopyListCfg: Copy-list config:2 tag:0
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/notify/10240/sipSPI_ipip_build_consolidated_header_list: Both passthru and copylist are disabled
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7F827D178A40 with refCount = 1
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Transport/sipSPITransportSendMessage: msg=0x7F827D178A40, addr=10.10.21.1, port=64240, sentBy_port=5060, local_addr=, is_req=0, transport=1, switch=0, callBack=0x5620F90D6DA0
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x7F827D178A40 to default port=5060
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.10.21.1, rport:5060 with laddr:

May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: Registering gcb=0x7F82DF2BD590 with connection=0x7F82704043A8 context list
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x7F827D178A40
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7F827D178A40, addr=10.10.21.1, port=5060, local_addr=, connId=12 vrfid=0 sig_tos=0x68 for UDP
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/critical/512/sentErrorResponseFreeCCB: Sent Error Response, Free the CCB
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIDeleteContextFromTable: Context for key=[266167] removed.
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7F827D2D7290) counter, current msg->refCount = 1
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/verbose/4096/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Error/sipSPIFlushDeferredQueue:
Invalid deferredQueue
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F827D2D7290
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerUnregisterCtxtInConnection: gConnTab=0x7F82701E3970, addr=10.10.21.1, port=5060, local_addr=, unregistering context=0x7F82DF2BD590
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstancePurgeContextInConnection: Purging context gcb=0x7F82DF2BD590 from the connection=0x7F82704043A8 context list
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/verbose/1/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/info/8192/sipSPIStopOverlapInfoTimer: Stopping Overlap Info Timer
May 5 09:05:28.013: //-1/DEB1A921A346/SIP/Info/verbose/4096/sipSPIUfreeOneCCB: Freeing ccb 0x7F82DF2BD590
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIGetContextFromTable: NO context for key[266167]
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPITransportSendMessage: gcb has cleanedup, Done with send operations for this context
May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 400 Bad Request - 'Invalid IP Address'
Via: SIP/2.0/UDP 10.10.21.1:5060;branch=z9hG4bK08aeb154;rport
From: "Anonymous" <sip:Anonymous@10.10.21.1>;tag=as15f7cc77
To: <sip:10.5.100.10>;tag=BC448E06-1D60
Date: Wed, 05 May 2021 09:05:28 GMT
Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@10.10.21.1:5060
Server: Cisco-SIPGateway/IOS-17.3.2
CSeq: 102 OPTIONS
Content-Length: 0


May 5 09:05:28.013: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7F827D178A40

 

fortvald
2 REPLIES 2
TONY SMITH
VIP Rising star

I think it might be an idea to start from scratch.  Remove the sip profiles references from the dial peers, take a debug of the Options polling and see what it looks like.  Post up the debug and explain which addresses in which headers need to be changed.  Just "debug ccsip mess" will do.

Thinking a bit more, I would be surprised if you need to fix up any addresses on the incoming messages.  And I wouldn't expect you to have to translate the ITSP address, that should stay unchanged at all points.

In your actual example I suspect the error results from the fact that the incoming Options header has been changed to an address which would be unknown to the CUBE.

May 5 09:05:28.009: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
OPTIONS sip:89.202.162.90 SIP/2.0
Via: SIP/2.0/UDP 134.119.180.134:5060;branch=z9hG4bK08aeb154;rport
Max-Forwards: 70
From: "Anonymous" <sip:Anonymous@134.119.180.134>;tag=as15f7cc77
To: <sip:89.202.162.90>
Contact: <sip:Anonymous@134.119.180.134:5060>
Call-ID: 7ce407b83e95e0a1296de5e20a7eb0c7@134.119.180.134:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 16.10.0
Date: Wed, 05 May 2021 09:05:23 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

In fact you can see this in your original detail debug as well ...

May 5 09:05:28.011: //-1/xxxxxxxxxxxx/SIP/Error/sipSPI_validate_own_ip_addr:
ReqLine IP addr does not match with host IP addr
Create
Recognize Your Peers
Content for Community-Ad