SIP and Gamma issue

[DQuaN]

Hi all,

Long time reader of Tek-Tips, first time poster (I think!). I've always found this place useful but I haven't been able to find an answer to my current issue.

I've configured a few SIP trunks through Gamma before, never had an issue. This setup is a little different.

In the past, I've used a random STUN server as Gamma frustratingly doesn't have one but this has worked fine.

On the current setup I am working on, Gamma have supplied the internet line and it is on a private circuit. This means the system can contact the Gamma media gateways but cannot access anything else on the internet. So no STUN is possible. I spoke to our Avaya supplier and they said this isn't an issue, we just have to make sure the correct ports are open on the firewall. I have done this, and the system is now receiving phone calls. However, it can't make any.

Calls appear in system status but them dissapear in a couple of seconds. I have noticed that there is no Registration on the line but I assume this has something to do with there being no STUN. I'm hoping this isn't an issue as the system can receive calls I think possibly the URI or outbound configuration needs a little tweaking. When dialing on a phone, the message "Rejected" comes on the screen.

I can provide trace or config if needed.

Thanks in advance.

Dunc

 

[Gunnaro]

Do that, put up a trace and the .cfg (remove pwds, mobile numbers and other info you don't want to share)

Kind regards

Gunnar
______________________________________
Mille viae ducunt homines per saecula Romam

 

[DQuaN]

Here's the trace.

If you still want the config it will take a little more time to clear the info. I can answer any questions about the settings though.


********** Warning: Logging to Screen Started **********
19:19:47 11294450mS CMExtnRxP: v=9119
CMSetup
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[07816312514] Type=Default (100) Reason=CMDRdirect
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
19:19:47 11294450mS CMCallEvt: 0.1009.0 -1 BaseEP: NEW CMEndpoint f4ef1128 TOTAL NOW=1 CALL_LIST=0
19:19:47 11294450mS CMCallEvt: 0.1009.0 -1 Linda Blackmore.-1: NEW CMExtnEndpoint f4ef1128, Name=Linda Blackmore, Extn=9119, Phys Extn=9119
19:19:47 11294451mS CMCallEvt: CREATE CALL:3 (f4ef0bf8)
19:19:47 11294451mS CMCallEvt: 0.1010.0 -1 BaseEP: NEW CMEndpoint f4eef1b0 TOTAL NOW=2 CALL_LIST=0
19:19:47 11294452mS CMExtnEvt: Linda Blackmore: CMExtnHandler::SetCurrent( id: 0->1009 )
19:19:47 11294454mS CMExtnRx: v=9119, p1=0
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
Called[07816312514] Type=Default (100) Reason=CMDRdirect Calling[9119] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Linda Blackmore
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Linda Blackmore
19:19:47 11294454mS CMExtnCopyProcessMsg: v=4
CMSetup
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
Called[07816312514] Type=Default (100) Reason=CMDRdirect Calling[9119] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=20 chan=4
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Linda Blackmore
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Linda Blackmore
19:19:47 11294454mS CMCallEvt: 0.1009.0 3 Linda Blackmore.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
19:19:47 11294454mS CMExtnEvt: v=4 State, new=Dialling old=Idle,0,0,Linda Blackmore
19:19:47 11294454mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: Setting Hard Timer 3000
19:19:47 11294455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: LOOKUP CALL ROUTE: type=100 called_party=07816312514 sub= calling=9119 dir=out complete=0 ses=0
19:19:47 11294455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: ADD TARGET (N): number=07816312514 type=100 depth=1 nobar=1 setorig=1 ses=0
19:19:47 11294455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: Setting Hard Timer on Setup 3000
19:19:47 11294455mS CMCallEvt: 0.1009.0 3 Linda Blackmore.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
19:19:47 11294456mS CMExtnCopyProcessCallMsg: v=4
CMSetupAck
Line: type=NoLine 0 Call: lid=0 id=1009 in=0
19:19:47 11294456mS CMExtnTxP: v=9119
CMSetupAck
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
BChan: slot=20 chan=4
Timed: 15/02/15 19:19
19:19:47 11294456mS CMExtnTx: v=9119, p1=0
CMSetupAck
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
BChan: slot=20 chan=4
Timed: 15/02/15 19:19
19:19:47 11294458mS CD: CALL: 0.1009.0 BState=Idle Cut=0 Music=0.0 Aend="Linda Blackmore(9119)" (20.4) Bend="" [] (0.0) CalledNum=07816312514 () CallingNum=9119 (Linda Blackmore) Internal=1 Time=8 AState=Dialling
19:19:47 11294681mS RES: Sun 15/2/2015 19:19:47 FreeMem=55150688 54802464(4) CachedMem=348224 CMMsg=3(3) Buff=5200 1351 999 7460 3 Links=6929 BTree=14632 CPU=07.08% CPUStats=7/13/5950/17814/18249/0/1 MCR=0 MCW=0
19:19:47 11294681mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=46 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=58 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:19:47 11294681mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
19:19:50 11297455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: TimerExpired cause=CMTCDelayedProcessing
19:19:50 11297455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: LOOKUP CALL ROUTE: type=100 called_party=07816312514 sub= calling=9119 dir=out complete=1 ses=0
19:19:50 11297455mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: ADD TARGET (N): number=07816312514 type=100 depth=1 nobar=1 setorig=1 ses=0
19:19:50 11297456mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: SYS SC: 07816312514 3 7816312514 sc=type=Dial code=0N;, num=0N"@10.128.0.57" callinfop->sending_complete=1 secondary_dialtone=
19:19:50 11297456mS CMARS: FindActiveARSByGroupID GroupID=19 - Not Found
19:19:50 11297456mS CMLRQ: FindActiveLRQByGroupID GroupID=19 - Not Found
19:19:50 11297456mS CMMap: a=6.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f56850f8) resource busy 1, total 32
19:19:50 11297457mS CMCallEvt: 0.1011.0 -1 BaseEP: NEW CMEndpoint f4ee6d60 TOTAL NOW=3 CALL_LIST=1
19:19:50 11297458mS Stun: Info: Line 17: Not using STUN for media in this case.
19:19:50 11297460mS CMTARGET: DIAL LINE: 3 GROUP = 19 SUCCESS = f4ee6d60
19:19:50 11297460mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: INITIAL TARGETING SUCCEEDED
19:19:50 11297460mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: GetNoAnswerTimer:15
19:19:50 11297460mS CMCallEvt: 0.1009.0 3 Linda Blackmore.0: StateChange: END=A CMCSDialling->CMCSDialled
19:19:50 11297460mS CMExtnEvt: v=4 State, new=Proceeding old=Dialling,0,0,Linda Blackmore
19:19:50 11297460mS CMExtnCopyProcessCallMsg: v=4
CMProceeding
Line: type=NoLine 0 Call: lid=0 id=1009 in=0
19:19:50 11297461mS CMExtnTxP: v=9119
CMProceeding
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
Timed: 15/02/15 19:19
19:19:50 11297461mS CMExtnTx: v=9119, p1=0
CMProceeding
Line: type=DigitalExtn 2 Call: lid=0 id=1009 in=0
Timed: 15/02/15 19:19
19:19:50 11297461mS CMCallEvt: 0.1010.0 3 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
19:19:50 11297462mS CMCallEvt: 17.1011.0 3 SIPTrunk Endpoint: StateChange: END=T CMCSIdle->CMCSOffering
19:19:50 11297464mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1011 in=0
Called[07816312514@10.128.0.57] Type=Default (100) Reason=CMDRdirect SndComp Calling[9119] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 14627
user 'Linda Blackmore' presentation Allowed
IE CMIEFastStartInfoData (6) 14 item(s)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Linda Blackmore
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Linda Blackmore
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Linda Blackmore
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Linda Blackmore
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=9119
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceStdPhone type=77 number=4 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1009 ipaddr=192.168.1.5 apps=0 loc=0 em_loc=1 features2=0x0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
Display [Linda Blackmore>07816312514]
Timed: 15/02/15 19:19
Locale: eng
19:19:50 11297466mS SIP Tx: UDP 10.10.10.2:5060 -> 10.128.0.57:5060
INVITE sip:07816312514@10.128.0.57 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.2:5060;rport;branch=z9hG4bK97d224b1a89c35540d34db97522d39f9
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=6eb5351f4d5e6896
To: <sip:07816312514@10.128.0.57>
Call-ID: c7a787d73028dd021a0ab9c1003cc67c
CSeq: 1139742617 INVITE
Contact: <sip:anonymous@10.10.10.2:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Content-Type: application/sdp
Supported: timer
User-Agent: IP Office 9.0.5.0 build 972
Privacy: id
P-Preferred-Identity: "Unavailable" <sip:02037612920@10.128.0.57:5060>
Content-Length: 295

v=0
o=UserA 3285838050 4112769889 IN IP4 10.10.10.2
s=Session SDP
c=IN IP4 10.10.10.2
t=0 0
m=audio 49152 RTP/AVP 8 0 18 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
19:19:50 11297467mS CD: CALL: 0.1009.0 BState=Idle Cut=1 Music=0.0 Aend="Linda Blackmore(9119)" (20.4) Bend="Line 17" [Line 17] (0.0) CalledNum=07816312514 () CallingNum=9119 (Linda Blackmore) Internal=0 Time=3017 AState=Dialled
19:19:50 11297474mS SIP Rx: UDP 10.128.0.57:5060 -> 10.10.10.2:5060
SIP/2.0 403 Forbidden-Source Endpoint Lookup Failed
Via: SIP/2.0/UDP 10.10.10.2:5060;rport=5060;received=10.0.8.130;branch=z9hG4bK97d224b1a89c35540d34db97522d39f9
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=6eb5351f4d5e6896
Call-ID: c7a787d73028dd021a0ab9c1003cc67c
CSeq: 1139742617 INVITE
To: <sip:07816312514@10.128.0.57>;tag=3633016805-916348
Content-Length: 0

19:19:50 11297477mS SIP Tx: UDP 10.10.10.2:5060 -> 10.128.0.57:5060
ACK sip:07816312514@10.128.0.57 SIP/2.0
Via: SIP/2.0/UDP 10.10.10.2:5060;rport;branch=z9hG4bK97d224b1a89c35540d34db97522d39f9
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=6eb5351f4d5e6896
To: <sip:07816312514@10.128.0.57>;tag=3633016805-916348
Call-ID: c7a787d73028dd021a0ab9c1003cc67c
CSeq: 1139742617 ACK
Privacy: id
P-Preferred-Identity: "Unavailable" <sip:02037612920@10.128.0.57:5060>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
User-Agent: IP Office 9.0.5.0 build 972
Content-Length: 0

19:19:50 11297478mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[9119] Type=Internal Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07816312514
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1011 ipaddr=192.168.1.5 apps=0 loc=999 em_loc=999 features2=0x0
Cause=21, Call rejected
19:19:50 11297479mS CMCallEvt: 0.1010.0 3 TargetingEP: RequestEnd 17.1011.0 3 SIPTrunk Endpoint
19:19:50 11297479mS CMTARGET: 0.1009.0 3 Linda Blackmore.0: CancelTimer CMTCNoAnswerTimeout
19:19:50 11297479mS CMCallEvt: 0.1010.0 -1 BaseEP: DELETE CMEndpoint f4eef1b0 TOTAL NOW=2 CALL_LIST=1
19:19:50 11297479mS CMCallEvt: 17.1011.0 3 SIPTrunk Endpoint: StateChange: END=B CMCSOffering->CMCSCompleted
19:19:50 11297481mS CMLOGGING: CALL:2015/02/1519:19,00:00:00,000,9119,O,07816312514@10.128.0.57,07816312514,LindaBlackmore,,,0,,""n/a,0
19:19:50 11297482mS CD: CALL: 0.1009.0 BState=Disconnecting Cut=1 Music=0.0 Aend="Linda Blackmore(9119)" (20.4) Bend="Line 17" [Line 17] (0.0) CalledNum=07816312514@10.128.0.57 () CallingNum=9119 (Linda Blackmore) Internal=0 Time=3031 AState=Dialled
19:19:50 11297482mS CD: CALL: 0.1009.0 Deleted
19:19:50 11297482mS CMExtnEvt: Linda Blackmore: CALL LOST (CMCauseCallRejected)
19:19:50 11297483mS CMExtnEvt: Linda Blackmore: Extn(9119) Calling Party Number(9119) Type(CMNTypeInternal)
19:19:50 11297483mS CMCallEvt: 0.1009.0 -1 Linda Blackmore.0: StateChange: END=X CMCSDialled->CMCSCompletedTone
19:19:50 11297483mS CMExtnEvt: v=4 State, new=CMESCompleted old=Proceeding,0,0,Linda Blackmore
19:19:50 11297483mS CMExtnCopyCallLostMsg: v=4
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=1009 in=0
Cause=17, User busy
19:19:50 11297484mS CMCallEvt: 17.1011.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
19:19:50 11297484mS CMCallEvt: END CALL:3 (f4ef0bf8)
19:19:50 11297486mS CMCallEvt: 17.1011.0 -1 BaseEP: DELETE CMEndpoint f4ee6d60 TOTAL NOW=1 CALL_LIST=0
19:19:50 11297487mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail(f5667588) resource busy 2, total 45
19:19:50 11297487mS CMMap: a=21.11 b=1.65535 T
19:19:50 11297488mS CMMap: a=21.11 b=20.4 M22
19:19:50 11297488mS CMMap: a=6.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f56850f8) resource busy 0, total 32
19:19:55 11302690mS RES: Sun 15/2/2015 19:19:55 FreeMem=55163424 54802464(4) CachedMem=360960 CMMsg=3(3) Buff=5200 1343 1000 7460 3 Links=6919 BTree=14633 CPU=06.49% CPUStats=1/3/5950/17600/18249/0/1 MCR=0 MCW=0
19:19:55 11302691mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=46 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:19:55 11302691mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
19:20:06 11313132mS CMExtnRxP: v=9119
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
19:20:06 11313132mS CMCallEvt: 0.1009.0 -1 Linda Blackmore.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
19:20:06 11313132mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=CMESCompleted,0,0,Linda Blackmore
19:20:06 11313133mS CMExtnEvt: Linda Blackmore: CALL LOST (CMCauseForceClear)
19:20:06 11313133mS CMExtnEvt: Linda Blackmore: Extn(9119) Calling Party Number(9119) Type(CMNTypeInternal)
19:20:06 11313133mS CMExtnCopyCallLostMsg: v=4
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=1009 in=0
Cause=130, Force Clear(IPO)
19:20:06 11313133mS CMExtnTxP: v=9119
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Cause=130, Force Clear(IPO)
19:20:06 11313133mS CMExtnEvt: Linda Blackmore: CMExtnHandler::SetCurrent( id: 1009->0 )
19:20:06 11313134mS CMCallEvt: 0.1009.0 -1 Linda Blackmore.-1: StateChange: END=X CMCSCompleted->CMCSDelete
19:20:06 11313134mS CMTARGET: 0.1009.0 -1 BaseEP: ~CMTargetHandler f4eeaca0 ep f4ef1128
19:20:06 11313134mS CMCallEvt: 0.1009.0 -1 BaseEP: DELETE CMEndpoint f4ef1128 TOTAL NOW=0 CALL_LIST=0
19:20:06 11313135mS CMMap: a=21.11 b=20.4 M02
19:20:06 11313135mS CMMap: a=21.11 b=0.0 T0
19:20:06 11313136mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown(f5667588) resource busy 1, total 45
19:20:06 11313191mS RES: Sun 15/2/2015 19:20:06 FreeMem=55177760 54802464(4) CachedMem=375296 CMMsg=3(3) Buff=5200 1351 999 7460 3 Links=6932 BTree=14633 CPU=06.40% CPUStats=1/3/5950/17630/18249/0/0 MCR=0 MCW=0
19:20:06 11313191mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=46 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=59 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:20:06 11313191mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
19:20:08 11315134mS CMExtnEvt: Linda Blackmore: Recover Timer reason=CMTRWrapUp
19:20:08 11315134mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Linda Blackmore
19:20:08 11315135mS CMExtnTxP: v=9119
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Linda Blackmore Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Linda Blackmore Msgs=0]
Timed: 15/02/15 19:20
19:20:08 11315135mS CMExtnTx: v=9119, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Linda Blackmore Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Linda Blackmore Msgs=0]
Timed: 15/02/15 19:20

********** Warning: Logging to Screen Stopped **********

 

[DQuaN]

Sorry, couldn't see an option to edit the post.

This looks like it might be the issue "403 Forbidden-Source Endpoint Lookup Failed".

Gamma authenticate not by username and password but by the source IP of the transmission. They provided a range of IPs for the equipment. The firewall the system sits behind has the IP that Gamma are authenticating and the phone system has a random LAN IP. I'm wondering if the system needs the WAN IP that Gamma authenticate and the firewall another in the range.

 

[DQuaN]

This is the IP settings Gamma sent us...

Allocated subnet: x.0.8.128/29
Use default gateway: x.0.8.129
SIP endpoint: x.0.8.130

So should the IP office have .130 and the firewall .129?

If so, how are the incoming calls working?

 

[DQuaN]

Nevermind, it's sorted!

The instructions I was given for running without STUN was to open the ports and on the SIP trunk to configure network topology info to none.

As the network topology info contains the WAN IP, I assume it uses that info in the packets it sends.

I manually entered the WAN IP and the ports in network topology on LAN2 and set the SIP line to use LAN2 network topology info.

All working