Greetings folks,
NV3140eSBC#10, call fails when routing from T01 to T02. Network side receives the call, and i see this error
22:16:32.907 TM.T02 497 Delivering | Skipping E.164 conversion due to voice international-prefix setting |
22:16:32.907 TM.T02 497 Looking up source address for destination 192.168.16.12
22:16:32.907 TM.T02 497 ERROR: local address invalid: 192.168.16.251 : 0
22:16:32.907 TM.T02 497 Delivering | ERROR! Failed to setup the IP header information. |
Config is pretty simple. I dont get why the local address would be invalid
The tree is obviously hiding the forest, i must be missing something obsvious
Thanks for your help!
-Vince
22:16:32.895 TM.T01 01 SipTM_Idle | rcvd SIP call-leg request: INVITE | |
22:16:32.896 TM.T01 01 SipTM_Idle | call-leg -> Offering | |
22:16:32.896 TM.T01 01 SipTM_Idle | State change | >> SipTM_Idle->SipTM_Trying |
22:16:32.897 TM.T01 01 SipTM_Trying | SDP offer is not loopback request | |
22:16:32.897 TM.T01 01 SipTM_Trying | Using P-Asserted-Identity for Caller-ID name and number. | |
22:16:32.897 TM.T01 01 SipTM_Trying | Caller ID is private due to Privacy level(id) | |
22:16:32.897 TM.T01 01 SipTM_Trying | Caller ID Name = "Anonymous" | |
22:16:32.898 TM.T01 01 SipTM_Trying | Caller ID Number = "1234567890" | |
22:16:32.898 TM.T01 01 SipTM_Trying | Caller ID is private | |
22:16:32.898 TM.T01 01 SipTM_Trying | info: unable to set redirect number(s) from INVITE | |
22:16:32.898 TM.T01 01 SipTM_Trying | sent: TA->InboundCall |
22:16:32.898 TM.T01 01 Looking up source address for destination 10.10.10.14
22:16:32.899 TM.T01 01 call-leg (0x0x52663d88) -> src: 10.10.7.3 : 5060 dst: 10.10.10.14 : 5060
22:16:32.899 TM.T01 01 SipTM_Trying | sent: 100 Trying |
22:16:32.900 TA.T01 01 TAIdle | rcvd: inboundCall from TM |
22:16:32.900 TA.T01 01 State change | >> TAIdle->TAInboundCall (TAS_Calling) |
22:16:32.900 TA.T01 01 Failed - DID translation: no match for 0987654321, using 0987654321
22:16:32.901 TA.T01 01 TAIdle | sent: call to SB | |
22:16:32.901 TM.T01 01 SipTM_Trying | tachg -> TAInboundCall | |
22:16:32.901 TM.T01 01 SipTM_Trying | State change | >> SipTM_Trying->SipTM_Pending |
22:16:32.901 SB.CALL 4 Idle | Called the call routine with0 |
22:16:32 SB.TGMgr For dialed number 0987654321, against template NXX-NXX-XXXX, on TrunkGroup PROVIDER, the score is 1000
22:16:32.901 SB.CCM isMappable:
22:16:32.901 SB.CCM : Call Struct 0x0x52cb9a10 : Call-ID = 4
22:16:32.902 SB.CCM : Org Acct = T01 | Dst Acct = T02 |
22:16:32.902 SB.CCM : Org Port ID = SipTrunk 0/0 Dst Port ID = unknown 0/0
22:16:32.902 SB.CCM : SDP Transaction = CallID: 4
22:16:32.902 SB.CCM : SDP Offer = 0x52d2b510, (10.10.10.29:33700)
22:16:32.902 SB.CCM isMappable: Call Connection Type is RTP_TO_RTP
22:16:32.902 SB.CCM handleRtpToRtp: Modifying SDP Offer
22:16:32.903 SB.CCM translateOffer: offer codec list: PCMU
22:16:32.903 SB.CCM translateOffer: revised offer codec list: PCMU
22:16:32.903 SB.CCM translateOffer: codec list after answerer: PCMU
22:16:32.904 SB.CCM translateOffer: DTMF signaling: answerer has no restrictions configured, passing offer(NTE 101) through
22:16:32.904 SB.CCM translateOffer: success
22:16:32.904 MEDIA.MANAGER Allocating media port.
22:16:32.904 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 4
22:16:32.905 MEDIA.MANAGER Call ID map : Added new entry : call ID 4 : session -809589578INIP410.10.10.29 : version 809589578 : index 6
22:16:32.905 MEDIA.MANAGER New media entry : type(0), callID(4), sessionID(-809589578INIP410.10.10.29), original IP(10.10.10.29) ports(33700-33701), substitute IP(::) ports(10006-10007), connection(0x0x52d2ce10), sdpOverride(0), me(0x0x52cc1210).
22:16:32.905 SB.CALL 4 Idle | Call sent from T01 to T02 (0987654321) | |
22:16:32.905 SB.CALL 4 State change | >> Idle->Delivering | |
22:16:32.905 TA.T01 01 TAInboundCall | CallResp event accepted | |
22:16:32.906 TA.T01 01 State change | >> TAInboundCall->TAConnectWaitIn (TAS_Calling) | |
22:16:32.906 TA.T02 497 State change | >> TAIdle->TAOutGoing (TAS_Delivering) | |
22:16:32.906 TM.T02 497 SipTM_Idle | State change | >> SipTM_Idle->Delivering |
22:16:32.906 TM.T02 497 Delivering | Applying E.164 settings to called party number (4502524079) | |
22:16:32.907 TM.T02 497 Delivering | Skipping E.164 conversion due to voice international-prefix setting |
22:16:32.907 TM.T02 497 Looking up source address for destination 192.168.16.12
22:16:32.907 TM.T02 497 ERROR: local address invalid: 192.168.16.251 : 0
22:16:32.907 TM.T02 497 Delivering | ERROR! Failed to setup the IP header information. | |
22:16:32.908 TM.T02 497 Delivering | Sip_CreateCallLegNextServer with default validator | |
22:16:32.908 TM.T02 497 Delivering | State change | >> Delivering->SipTM_Closing |
22:16:32.908 TM.T02 497 SipTM_Closing | sent: TA->Clear | |
22:16:32.908 TM.T02 497 SipTM_Closing | call-leg -> Terminated | |
22:16:32.908 TM.T02 497 SipTM_Closing | given call-leg != current call-leg, dropping call-leg state change: Terminated | |
22:16:32.908 SB.CALL 4 Delivering | Called the deliverResponse routine from Delivering | |
22:16:32.909 SB.CALL 4 Delivering | DeliverResponse(accept) sent from T02 to T01 | |
22:16:32.909 TA.T02 497 TAOutGoing | rcvd: clear from TM | |
22:16:32.909 TA.T02 497 State change | >> TAOutGoing->TATrunkClearing (TAS_Clearing) | |
22:16:32.909 TM.T02 497 SipTM_Closing | tachg -> TATrunkClearing | |
22:16:32.909 TM.T02 497 SipTM_Closing | State change | >> SipTM_Closing->SipTM_Terminated |
22:16:32.909 TM.T02 497 SipTM_Terminated | sent: TA->AppearanceOff | |
22:16:32.909 TM.T02 497 SipTM_Terminated | State change | >> SipTM_Terminated->SipTM_Idle |
22:16:32.910 TA.T01 01 TAConnectWaitIn | deliverResponse event accepted | |
22:16:32.910 TA.T01 01 TAConnectWaitIn | ERROR! deliverResponse ignored | |
22:16:32.910 SB.CALL 4 Delivering | Called the clearCall routine | |
22:16:32.910 SB.CALL 4 Delivering | SIP Proxy rejected call to 0987654321for survivability - no matching Proxy user | |
22:16:32.910 SB.CALL 4 Delivering | No available resources on call from T01 to T02 (last attempt) | |
22:16:32.910 SB.CALL 4 State change | >> Delivering->Clearing | |
22:16:32.910 TA.T02 497 TATrunkClearing | rcvd: appearance off from TM | |
22:16:32.911 TA.T02 497 State change | >> TATrunkClearing->TAClearingComplete (TAS_Clearing) | |
22:16:32.911 TA.T02 497 TATrunkClearing | Processing an appearance OFF | |
22:16:32.911 TA.T01 01 TAConnectWaitIn | ClearCall event accepted | |
22:16:32.911 TA.T01 01 State change | >> TAConnectWaitIn->TAClearingComplete (TAS_Clearing) | |
22:16:32.912 TM.T01 01 SipTM_Pending | tachg -> TAClearingComplete | |
22:16:32.912 TM.T01 01 SipTM_Pending | State change | >> SipTM_Pending->SipTM_CallFail |
22:16:32.913 TM.T01 01 SipTM_CallFail | call-leg -> Disconnected | |
22:16:32.913 TM.T01 01 SipTM_CallFail | CallLegStateChanged to Disconnected - TM change to closing state. | |
22:16:32.913 TM.T01 01 SipTM_CallFail | State change | >> SipTM_CallFail->SipTM_Closing |
22:16:32.913 TM.T01 01 SipTM_Closing | sent: TA->Clear | |
22:16:32.913 TM.T01 01 SipTM_CallFail | sent: 503 | |
22:16:32.914 TM.T01 01 SipTM_Closing | State change | >> SipTM_Closing->SipTM_Terminated |
22:16:32.914 TM.T01 01 SipTM_Terminated | sent: TA->AppearanceOff | |
22:16:32.914 TM.T01 01 SipTM_Terminated | State change | >> SipTM_Terminated->SipTM_Idle |
22:16:32.915 SB.CALL 4 Clearing | Called the clearResponse routine | |
22:16:32.915 SB.CALL 4 State change | >> Clearing->CallIdlePending |
22:16:32.915 SB.CCM release:
22:16:32.915 SB.CCM : Call Struct 0x0x52cb9a10 : Call-ID = 4
22:16:32.915 SB.CCM : Org Acct = T01 | Dst Acct = T02 |
22:16:32.915 SB.CCM : Org Port ID = SipTrunk 0/0 Dst Port ID = SipTrunk 0/0.996
22:16:32.915 SB.CCM : SDP Transaction = CallID: 4
22:16:32.916 SB.CCM : SDP Offer = 0x52d2b510, (10.10.10.29:33700)
22:16:32.916 SB.CCM release: Call Connection Type is RTP_TO_RTP
22:16:32.916 SB.CALL 4 CallIdlePending | ClearResponse sent from T01 to T02 |
22:16:32.916 TA.T01 01 TAClearingComplete rcvd: clear from TM
22:16:32.916 TA.T01 01 TAClearingComplete rcvd: appearance off from TM
22:16:32.916 TA.T01 01 TAClearingComplete Clear Local Variables
22:16:32.916 TA.T01 01 State change | >> TAClearingComplete->TAIdle (TAS_Idle) |
22:16:32.916 TM.T01 01 SipTM_Idle | tachg -> TAIdle |
22:16:32.917 TA.T02 497 TAClearingComplete clearResponse event accepted
22:16:32.917 TA.T02 497 TAClearingComplete Clear Local Variables
22:16:32.917 TA.T02 497 State change | >> TAClearingComplete->TAIdle (TAS_Idle) |
22:16:32.918 TM.T02 497 SipTM_Idle | tachg -> TAIdle |
22:16:32 SB.CallStructObserver 4 Created
Change this:
voice grouped-trunk PROVIDER
trunk T01
trunk T02
accept <pretty much everything>
To this:
voice grouped-trunk PROVIDER
trunk T01
accept <pretty much everything>
voice grouped-trunk CUSTOMER
trunk T02
accept <patterns of customer DIDs>
Is the configuration you attached complete? I see that the customer side has private RFC1918 addresses but the provider IP is redacted. If you're doing NAT, you'll need to enable IP firewall with appropriate rules. If the connection to the provider is NATted upstream, then there may be a problem with the SIP fixup there. You might also try removing the grammar from host local on trunk T02.