Asterisk - Ekiga (softphone) works, Snom M3 and SPA 922 fail

maxxware
Joined: Tue 02 of Aug, 2011

Re: Asterisk - Ekiga (softphone) works, Snom M3 and SPA 922 fail

Posted:Tue 02 of Aug, 2011 (16:43 UTC)
Reconfigured from scratch, working now with a basic configuration.
maxxware
Joined: Tue 02 of Aug, 2011

Asterisk - Ekiga (softphone) works, Snom M3 and SPA 922 fail

Posted:Tue 02 of Aug, 2011 (07:07 UTC)
Here's the situation:

1) Asterisk
2) SIP number for outside world
3) Snom M3 with 2 DECT handsets
4) SPA922
5) Laptop with Ekiga

- I cannot dial from the Snom handsets to the outside world or to the SPA 922.
- I cannot dial between the handsets of the Snom.
- I cannot dial from the SPA 922 to the Snom handsets or the outside world
- I can dial from Ekiga to the outside world, to the Snom handsets and to the SPA 922.
- I can dial from the Snom handsets to Ekiga
- I can dial from the SPA 922 to Ekiga
- I can dial from the outside world to my external SIP number and pickup the call on all of the above devices

Since I can successfully dialin to all phones and dialout ousing Ekiga my guess is that the Asterisk config is basically correct. Probably smething is the SIP phones (SPA 922 and Snom M3) is wrong. How can I get more detailed info out of S=Asterisk which tells me what's wrong in the Snom and SPA 922?

Below is the output I get:

+-------------------------------------------------------------------------------------------------
| SNOM (111) <-> SPA (131) (fails)

avalon*CLI> 
  == Using SIP RTP CoS mark 5
    -- Executing [111@sip-phones:1] Verbose("SIP/131-00000010", "1,*** Incoming Local Call! *** ") in new stack
 *** Incoming Local Call! *** 
    -- Executing [111@sip-phones:2] Dial("SIP/131-00000010", "SIP/111,25") in new stack
  == Using SIP RTP CoS mark 5
    -- Called 111
    -- SIP/111-00000011 is circuit-busy
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [111@sip-phones:3] VoiceMail("SIP/131-00000010", "111@default,u") in new stack
    -- <SIP/131-00000010> Playing 'vm-theperson.gsm' (language 'en')
    -- <SIP/131-00000010> Playing 'digits/1.gsm' (language 'en')
  == Spawn extension (sip-phones, 111, 3) exited non-zero on 'SIP/131-00000010'
avalon*CLI> 

+-------------------------------------------------------------------------------------------------
| SNOM (111) <-> Ekiga (191)

avalon*CLI> 
  == Using SIP RTP CoS mark 5
    -- Executing [111@sip-phones:1] Verbose("SIP/191-00000016", "1,*** Incoming Local Call! *** ") in new stack
 *** Incoming Local Call! *** 
    -- Executing [111@sip-phones:2] Dial("SIP/191-00000016", "SIP/111,25") in new stack
  == Using SIP RTP CoS mark 5
    -- Called 111
    -- SIP/111-00000017 is ringing
    -- SIP/111-00000017 answered SIP/191-00000016
    -- Packet2Packet bridging SIP/191-00000016 and SIP/111-00000017
  == Spawn extension (sip-phones, 111, 2) exited non-zero on 'SIP/191-00000016'
avalon*CLI>

Here is the SIP log on the Snom when dialing to the SPA 922:

+-------------------------------------------------------------------------------------------------
Sent to udp:192.168.128.1:5060 at 01/08/2011 22:12:47  (919 bytes)

INVITE sip:131@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKclrzmail8w94a4iq130n9dslylqy
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=cue0mjg
To: <sip:131@sip.maxxware.lan>
Call-ID: h8.adi23qrzs0mp4bcyy@sip.maxxware.lan
CSeq: 44075 INVITE
Contact: <sip:111@192.168.128.2;line=39290>
Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
Content-Disposition: session
Supported: replaces
User-Agent: snom-m3-SIP/02.02 (MAC=0004132A15A9; HW=1)
Content-Type: application/sdp
Content-Length: 323

v=0
o=111 617693933 617693933 IN IP4 192.168.128.2
s=-
c=IN IP4 192.168.128.2
t=0 0
a=sendrecv
m=audio 5014 RTP/AVP 0 8 97 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:5015

Received from udp:192.168.128.1:5060 at 01/08/2011 22:12:47  (535 bytes)

SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKclrzmail8w94a4iq130n9dslylqy;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=cue0mjg
To: <sip:131@sip.maxxware.lan>;tag=as6582c81b
Call-ID: h8.adi23qrzs0mp4bcyy@sip.maxxware.lan
CSeq: 44075 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="17905919"
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:12:47  (320 bytes)

ACK sip:131@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKclrzmail8w94a4iq130n9dslylqy
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=cue0mjg
To: <sip:131@sip.maxxware.lan>;tag=as6582c81b
Call-ID: h8.adi23qrzs0mp4bcyy@sip.maxxware.lan
CSeq: 44075 ACK
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:12:47  (1080 bytes)

INVITE sip:131@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bK1v16z1q7962dglv2yb5j1oz
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=cue0mjg
To: <sip:131@sip.maxxware.lan>
Call-ID: h8.adi23qrzs0mp4bcyy@sip.maxxware.lan
CSeq: 44076 INVITE
Contact: <sip:111@192.168.128.2;line=39290>
Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
Authorization: Digest username="111", realm="asterisk", nonce="17905919", uri="sip:131@sip.maxxware.lan", response="f3b36295e411ac776179b26744749c3b", algorithm=MD5
Content-Disposition: session
Supported: replaces
User-Agent: snom-m3-SIP/02.02 (MAC=0004132A15A9; HW=1)
Content-Type: application/sdp
Content-Length: 323

v=0
o=111 617693933 617693933 IN IP4 192.168.128.2
s=-
c=IN IP4 192.168.128.2
t=0 0
a=sendrecv
m=audio 5014 RTP/AVP 0 8 97 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:5015

Received from udp:192.168.128.1:5060 at 01/08/2011 22:12:47  (467 bytes)

SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bK1v16z1q7962dglv2yb5j1oz;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=cue0mjg
To: <sip:131@sip.maxxware.lan>
Call-ID: h8.adi23qrzs0mp4bcyy@sip.maxxware.lan
CSeq: 44076 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Contact: <sip:131@192.168.128.1>
Content-Length: 0


Received from udp:192.168.128.1:5060 at 01/08/2011 22:12:49  (556 bytes)

OPTIONS sip:111@192.168.128.2;line=39290 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.1:5060;branch=z9hG4bK7956cd63;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@192.168.128.1>;tag=as20a6b854
To: <sip:111@192.168.128.2;line=39290>
Contact: <sip:asterisk@192.168.128.1>
Call-ID: 433772872aac94c54e383b8a257c7a90@192.168.128.1
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 1.6.2.9-2+squeeze3
Date: Mon, 01 Aug 2011 20:12:49 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:12:49  (593 bytes)

SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.1:5060;branch=z9hG4bK7956cd63;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@192.168.128.1>;tag=as20a6b854
To: <sip:111@192.168.128.2;line=39290>
Call-ID: 433772872aac94c54e383b8a257c7a90@192.168.128.1
CSeq: 102 OPTIONS
Contact: <sip:asterisk@192.168.128.1>
Accept: application/sdp
Accept-Encoding: identity
Accept-Language: 
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Date: Mon, 01 Aug 2011 20:12:49 GMT
Supported: replaces, timer
User-Agent: Asterisk PBX 1.6.2.9-2+squeeze3
Content-Length: 0


Received from udp:192.168.128.1:5060 at 01/08/2011 22:12:49  (556 bytes)

OPTIONS sip:121@192.168.128.2;line=42627 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.1:5060;branch=z9hG4bK59c2a034;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@192.168.128.1>;tag=as3cc96daf
To: <sip:121@192.168.128.2;line=42627>
Contact: <sip:asterisk@192.168.128.1>
Call-ID: 1a79d03f64af486f5735569a0255f0e5@192.168.128.1
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 1.6.2.9-2+squeeze3
Date: Mon, 01 Aug 2011 20:12:50 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:12:49  (593 bytes)

SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.1:5060;branch=z9hG4bK59c2a034;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@192.168.128.1>;tag=as3cc96daf
To: <sip:121@192.168.128.2;line=42627>
Call-ID: 1a79d03f64af486f5735569a0255f0e5@192.168.128.1
CSeq: 102 OPTIONS
Contact: <sip:asterisk@192.168.128.1>
Accept: application/sdp
Accept-Encoding: identity
Accept-Language: 
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Date: Mon, 01 Aug 2011 20:12:50 GMT
Supported: replaces, timer
User-Agent: Asterisk PBX 1.6.2.9-2+squeeze3
Content-Length: 0

Here is the SIP log on the Snom when dialing to Ekiga:

+-------------------------------------------------------------------------------------------------
Sent to udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (904 bytes)

INVITE sip:191@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKcv1ka79s.yh
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43780 INVITE
Contact: <sip:111@192.168.128.2;line=39290>
Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
Content-Disposition: session
Supported: replaces
User-Agent: snom-m3-SIP/02.02 (MAC=0004132A15A9; HW=1)
Content-Type: application/sdp
Content-Length: 325

v=0
o=111 1069309515 1069309515 IN IP4 192.168.128.2
s=-
c=IN IP4 192.168.128.2
t=0 0
a=sendrecv
m=audio 5016 RTP/AVP 0 8 97 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:5017

Received from udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (518 bytes)

SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKcv1ka79s.yh;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as372e15d8
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43780 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7240dd5d"
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (303 bytes)

ACK sip:191@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKcv1ka79s.yh
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as372e15d8
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43780 ACK
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (1081 bytes)

INVITE sip:191@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 INVITE
Contact: <sip:111@192.168.128.2;line=39290>
Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
Authorization: Digest username="111", realm="asterisk", nonce="7240dd5d", uri="sip:191@sip.maxxware.lan", response="d3dd6394bb1f1ed98418e60fe0f4916e", algorithm=MD5
Content-Disposition: session
Supported: replaces
User-Agent: snom-m3-SIP/02.02 (MAC=0004132A15A9; HW=1)
Content-Type: application/sdp
Content-Length: 325

v=0
o=111 1069309515 1069309515 IN IP4 192.168.128.2
s=-
c=IN IP4 192.168.128.2
t=0 0
a=sendrecv
m=audio 5016 RTP/AVP 0 8 97 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:5017

Received from udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (466 bytes)

SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Contact: <sip:191@192.168.128.1>
Content-Length: 0


Received from udp:192.168.128.1:5060 at 01/08/2011 22:17:33  (482 bytes)

SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as7231b1d6
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Contact: <sip:191@192.168.128.1>
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:17:40  (616 bytes)

CANCEL sip:191@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 CANCEL
Contact: <sip:111@192.168.128.2;line=39290>
Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
Authorization: Digest username="111", realm="asterisk", nonce="7240dd5d", uri="sip:191@sip.maxxware.lan", response="d6e2b5e73f990133c912f0f00eb310b4", algorithm=MD5
Content-Length: 0


Received from udp:192.168.128.1:5060 at 01/08/2011 22:17:40  (459 bytes)

SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as7231b1d6
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 INVITE
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0



Sent to udp:192.168.128.1:5060 at 01/08/2011 22:17:40  (314 bytes)

ACK sip:191@sip.maxxware.lan SIP/2.0
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4
Max-Forwards: 70
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as7231b1d6
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 ACK
Content-Length: 0


Received from udp:192.168.128.1:5060 at 01/08/2011 22:17:40  (443 bytes)

SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.128.2;branch=z9hG4bKpxxq9.f.y08w5p6mwdvbl4;received=192.168.128.2
From: "SNOM-M3" <sip:111@sip.maxxware.lan>;tag=ys34.n9e15
To: <sip:191@sip.maxxware.lan>;tag=as7231b1d6
Call-ID: qpzndzba3prk82zgp@sip.maxxware.lan
CSeq: 43781 CANCEL
Server: Asterisk PBX 1.6.2.9-2+squeeze3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0