Problem SNOM 300 and 3CX 11 SP4

Status
Not open for further replies.

mvla

Joined
Nov 17, 2010
Messages
2
Reaction score
0
I have 12 snom 300 phones and they all have the same problem. Inbound calling goes fine, but outbound gives the message "forbidden" on the display of the snom 300's. The firewall check in 3cx is all good (green text), so I don't see what the problem is. Below you find an verbose log from 3cx from an outbound call:
20-mrt-2013 07:36:02.476 Call(C:21) is terminated
20-mrt-2013 07:36:02.475 [CM503020]: Call(C:21): Normal call termination. Call originator: Extn:105. Reason: Forbidden
20-mrt-2013 07:36:02.475 [CM503016]: Call(C:21): Attempt to reach <sip:[email protected]:5060> from Extn:105 has failed. Reason: Forbidden
20-mrt-2013 07:36:02.475 L:21.2[Line:10000>>0610949453]: Terminating targets, reason: SIP ;cause=603 ;text="Declined"
20-mrt-2013 07:36:02.475 Leg L:21.2[Line:10000>>0610949453] is terminated: Cause: 603 Declined/INVITE from 82.150.141.97:5060
20-mrt-2013 07:36:02.475 L:21.2[Line:10000>>0610949453] got Terminated Recv 603/INVITE from 82.150.141.97:5060 tid=e740ee3141339f49 Call-ID=MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.:
SIP/2.0 603 Declined
Via: SIP/2.0/UDP 192.168.1.2:5060;branch=z9hG4bK-d8754z-e740ee3141339f49-1---d8754z-;rport=37956;received=109.109.96.210
To: <sip:[email protected]:5060>;tag=f65cabccd3eab8b13db945193832bb26.681b
From: "31182749021"<sip:[email protected]:5060>;tag=5802bd37
Call-ID: MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.
CSeq: 1 INVITE
Server: VoipNow
Content-Length: 0
20-mrt-2013 07:36:02.475 [Flow] Current call diversion path:[]
20-mrt-2013 07:36:02.475 L:21.1[Extn] failed to reach Line:10000>>0610949453, reason Forbidden
20-mrt-2013 07:36:02.475 RerouteReq
20-mrt-2013 07:36:02.475 Terminated from <sip:[email protected]:5060>;tag=f65cabccd3eab8b13db945193832bb26.681b to "31182749021"<sip:[email protected]:5060>;tag=5802bd37; reason: Error
20-mrt-2013 07:36:02.475 ~Target=VoIPline:0610949453@(Ln.10000@Belcentrale)
20-mrt-2013 07:36:02.475 ~Route=Dev:sip:1717*[email protected]:5060
20-mrt-2013 07:36:02.475 Call to T:Line:10000>>0610949453@[Dev:sip:1717*[email protected]:5060] from L:21.1[Extn] failed, cause: Cause: 603 Declined/INVITE from 82.150.141.97:5060
20-mrt-2013 07:36:02.475 L:21.2[Line:10000>>0610949453] got Failure: Failure Recv 603/INVITE from 82.150.141.97:5060 tid=e740ee3141339f49 Call-ID=MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.:
SIP/2.0 603 Declined
Via: SIP/2.0/UDP 192.168.1.2:5060;branch=z9hG4bK-d8754z-e740ee3141339f49-1---d8754z-;rport=37956;received=109.109.96.210
To: <sip:[email protected]:5060>;tag=f65cabccd3eab8b13db945193832bb26.681b
From: "31182749021"<sip:[email protected]:5060>;tag=5802bd37
Call-ID: MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.
CSeq: 1 INVITE
Server: VoipNow
Content-Length: 0
20-mrt-2013 07:36:02.475 [CM503003]: Call(C:21): Call to <sip:[email protected]:5060> has failed; Cause: 603 Declined/INVITE from 82.150.141.97:5060
20-mrt-2013 07:36:02.475 Session 10536 has failed in leg L:21.2[Line:10000>>0610949453] ; Cause: 603 Declined/INVITE from 82.150.141.97:5060
20-mrt-2013 07:36:02.475 Failure from <sip:[email protected]:5060>;tag=f65cabccd3eab8b13db945193832bb26.681b to "31182749021"<sip:[email protected]:5060>;tag=5802bd37
20-mrt-2013 07:36:02.421 [CM503025]: Call(C:21): Calling T:Line:10000>>0610949453@[Dev:sip:1717*[email protected]:5060] for L:21.1[Extn]
20-mrt-2013 07:36:02.421 Route to L:21.2[Line:10000>>0610949453] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=9164e3492a24c36d Call-ID=MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-9164e3492a24c36d-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:1717*[email protected]:5060>
To: <sip:[email protected]:5060>
From: "31182749021"<sip:[email protected]:5060>;tag=5802bd37
Call-ID: MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 305
Remote-Party-ID: "31182749021"<sip:[email protected]:5060>;party=calling

v=0
o=3cxPS 335410102272 230921601025 IN IP4 109.109.96.209
s=3cxPS Audio call
c=IN IP4 109.109.96.209
t=0 0
m=audio 9040 RTP/AVP 0 8 3 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=sendrecv
20-mrt-2013 07:36:02.420 Dev(1):[sip:1717*[email protected]:5060 / 1717*001]: PBX contact is public IP: <sip:1717*[email protected]:5060>
20-mrt-2013 07:36:02.411 Outbound URI is used: sip:82.150.141.97;lr;ftag=as5c979bb7;did=2c1.fbbbe092
20-mrt-2013 07:36:02.411 SLA is globally disabled
20-mrt-2013 07:36:02.411 Added leg L:21.2[Line:10000>>0610949453]
20-mrt-2013 07:36:02.370 [Flow] Call(C:21): making call from L:21.1[Extn] to T:Line:10000>>0610949453@[Dev:sip:1717*[email protected]:5060]
20-mrt-2013 07:36:02.370 [CM503027]: Call(C:21): From: Extn:105 ("SK 105" <sip:[email protected]:5060>) to T:Line:10000>>0610949453@[Dev:sip:1717*[email protected]:5060]
20-mrt-2013 07:36:02.370 [CM503004]: Call(C:21): Route 1: from L:21.1[Extn] to T:Line:10000>>0610949453@[Dev:sip:1717*[email protected]:5060]
20-mrt-2013 07:36:02.370 Line limit check: Current # of calls for line Lc:10000(@Belcentrale[<sip:1717*[email protected]:5060>]) is 0; limit is 2
20-mrt-2013 07:36:02.370 Call(C:21): Call from Extn:105 to 0610949453 matches outbound rule 'Rule for Pronto'
20-mrt-2013 07:36:02.370 [Flow] Call(C:21): has built target endpoint: Out#:>>Rule{Rule for Pronto}>>0610949453 for call from L:21.1[Extn]
20-mrt-2013 07:36:02.370 [Flow] Target endpoint for 0610949453 is Out#:>>Rule{Rule for Pronto}>>0610949453
20-mrt-2013 07:36:02.369 Caller '105' is found in the list of allowed extensions: 100-100; 101-101; 105-105
20-mrt-2013 07:36:02.369 Selected prefix: 0
20-mrt-2013 07:36:02.369 Looking for outbound rule: dialed = [0610949453], processed: [0610949453]; from-ext:
20-mrt-2013 07:36:02.369 [Flow] Building target endpoint to 0610949453 from "SK 105" <sip:[email protected]:5060>
20-mrt-2013 07:36:02.369 [CM503010]: Call(C:21): Making route(s) from Extn:105 to <sip:[email protected]:5060>
20-mrt-2013 07:36:02.369 Remote SDP is set for leg L:21.1[Extn]
20-mrt-2013 07:36:02.369 OnOffer from "SK 105"<sip:[email protected]:5060>;tag=qp9yajnuv2
20-mrt-2013 07:36:02.369 [CM505001]: Endpoint Extn:105: Device info: Device Identified: [Man: Snom;Mod: 3xx series;Rev: General] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [snom300/8.4.32] PBX contact: [sip:[email protected]:5060]
20-mrt-2013 07:36:02.369 Inbound DID: ''; Phonebook Name: ''
20-mrt-2013 07:36:02.368 [CM500002]: Call(C:21): Info on incoming INVITE from Extn:105:
Invite-IN Recv Req INVITE from 192.168.1.34:2048 tid=-59ma9unfgkwa Call-ID=3c26f46ba464-xm6vvqx407dm:
INVITE sip:[email protected]:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:2048;branch=z9hG4bK-59ma9unfgkwa;rport=2048
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=8ou9etak>;reg-id=1
To: <sip:[email protected]:5060;user=phone>
From: "SK 105"<sip:[email protected]:5060>;tag=qp9yajnuv2
Call-ID: 3c26f46ba464-xm6vvqx407dm
CSeq: 2 INVITE
Session-Expires: 3600;refresher=uas
Min-SE: 90
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Content-Type: application/sdp
Proxy-Authorization: Digest username="105",realm="3CXPhoneSystem",nonce="414d535c0759e9d205:90601d2e309fef0a5f90fab9986e1c06",uri="sip:[email protected]:5060;user=phone",response="c85722fe7923de3edbfa5bd37d3eb418",algorithm=MD5
Supported: timer, 100rel, replaces, from-change
User-Agent: snom300/8.4.32
Allow-Events: talk, hold, refer, call-info
Content-Length: 389
X-Serialnumber: 0004133DB193
P-Key-Flags: keys="3"

v=0
o=root 844680647 844680647 IN IP4 192.168.1.34
s=call
c=IN IP4 192.168.1.34
t=0 0
m=audio 59070 RTP/AVP 0 8 9 99 3 18 4 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:3 GSM/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-16
a=ptime:20
a=sendrecv
20-mrt-2013 07:36:02.368 [CM503001]: Call(C:21): Incoming call from Extn:105 to <sip:[email protected]:5060>
20-mrt-2013 07:36:02.362 Outbound URI is used: sip:[email protected]:2048;line=8ou9etak
20-mrt-2013 07:36:02.362 IncomingCall: C:21 from <sip:[email protected]:5060> to <sip:[email protected]:5060>
20-mrt-2013 07:36:02.362 Added leg L:C:21.1[No endpoint yet]
20-mrt-2013 07:36:02.361 UasSession 10533 started
20-mrt-2013 07:36:02.361 Call from "SK 105"<sip:[email protected]:5060>;tag=qp9yajnuv2 to <sip:[email protected]:5060;user=phone>;tag=12375815
 
@tijnie

The clue is in this log message:
20-mrt-2013 07:36:02.475 L:21.2[Line:10000>>0610949453] got Failure: Failure Recv 603/INVITE from 82.150.141.97:5060 tid=e740ee3141339f49 Call-ID=MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.:
SIP/2.0 603 Declined
Via: SIP/2.0/UDP 192.168.1.2:5060;branch=z9hG4bK-d8754z-e740ee3141339f49-1---d8754z-;rport=37956;received=109.109.96.210
To: <sip:[email protected]:5060>;tag=f65cabccd3eab8b13db945193832bb26.681b
From: "31182749021"<sip:[email protected]:5060>;tag=5802bd37
Call-ID: MjlkNTExYTU5ZjQ2NGRlMDk2ODEyOWVjMmQ5YjQ2YjM.
CSeq: 1 INVITE
Server: VoipNow
Content-Length: 0

It seems that the VoIP Provider is rejecting the call with a "603 Declined". You should ask the VoIP Provider's technical support to make a trace of failed call, and tell you which part of the SIP INVITE message that they are receiving is not accepted by their platform.
 
Status
Not open for further replies.