I'd really like to use shtoom/doug to program my shiny new supercool
voip app, but I'm just trying to get the basic shecho.py to work and
I can't. I've gotten the latest sources from SVN, twisted 2.1, and
managed to get it to sign in to gizmoproject/sipphone. But when I
call, it connects but I simply do not get any echo back. This is on
both a cable modem on OS X and on a red hat enterprise 4 w/a fresh
install of python on MIT's network (so bandwidth is not the issue).
No firewalls, just direct connection to net. Below is the full log
(sorry new to project and don't know what is relevant versus not),
any help is most appreciated. I really want this to work so I can
hack away! I'm not sure but these particular lines seem suspicious:
2005-11-20T13:52:01.508448 [-] (Port 50268 Closed)
2005-11-20T13:52:01.512674 [-] Stopping protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x60c2b0>
2005-11-20T13:52:01.524135 [-] <shtoom.rtp.protocol.RTPProtocol
instance at 0x6267d8>.handle_media_sample() should only be called
only when it is in sending mode.
Thanks,
Aaron
c-66-30-115-182:~/Desktop/voip/shtoom/scripts aaron$ python2.4 shecho.py
logging to stdout
2005-11-20T13:51:05.275155 [-] Log opened.
2005-11-20T13:51:05.441471 [-] <class 'shtoom.sip.SipProtocol'>
starting on 5060
2005-11-20T13:51:05.441737 [-] Starting protocol
<shtoom.sip.SipProtocol object at 0x4e9450>
2005-11-20T13:51:05.442461 [app] sip listener installed on 5060
2005-11-20T13:51:05.443107 [-] app is <__main__.EchoApplication
instance at 0x54d28>
2005-11-20T13:51:05.443508 [nat] NAT preference says to use both (both)
2005-11-20T13:51:05.443974 [-] NAT pref sez both
2005-11-20T13:51:05.496858 [-] <class 'shtoom.upnp.UPnPProtocol'>
starting on 1900
2005-11-20T13:51:05.497088 [-] Starting protocol
<shtoom.upnp.UPnPProtocol object at 0x5d5570>
2005-11-20T13:51:05.503511 [-] <class 'shtoom.stun._DetectSTUNProt'>
starting on 50265
2005-11-20T13:51:05.503871 [-] Starting protocol
<shtoom.stun._DetectSTUNProt object at 0x602e50>
2005-11-20T13:51:05.504526 [sip] no outstanding registrations,
registering
2005-11-20T13:51:05.505822 [sip] using outboundProxyURI of
sip:proxy01.sipphone.com:5060
2005-11-20T13:51:05.524199 [-]
twisted.internet.protocol.DatagramProtocol starting on 50266
2005-11-20T13:51:05.524596 [-] Starting protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x618c60>
2005-11-20T13:51:05.663862 [doug] register sent
REGISTER sip:proxy01.sipphone.com:5060 SIP/2.0
CSeq: 4423 REGISTER
To: sip:[EMAIL PROTECTED]:5060
From: sip:[EMAIL PROTECTED]:5060
Expires: 900
Call-ID: [EMAIL PROTECTED]
User-Agent: Shtoom/0.3alpha0
Contact: <sip:[EMAIL PROTECTED]:5060>
Content-Length: 0
Via: SIP/2.0/UDP XXXXXXXXXX:
5060;branch=z9hG4bK3cd44f9a1fa0837d13ab762a98892ea0;rport
2005-11-20T13:51:05.665855 [-] (Port 50266 Closed)
2005-11-20T13:51:05.666630 [-] Stopping protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x618c60>
2005-11-20T13:51:05.668830 [-]
twisted.internet.protocol.DatagramProtocol starting on 50267
2005-11-20T13:51:05.669598 [-] Starting protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x624b98>
2005-11-20T13:51:05.692321 [-] (Port 50267 Closed)
2005-11-20T13:51:05.692615 [-] Stopping protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x624b98>
2005-11-20T13:51:05.751330 [doug] Got a SIP packet from
198.65.166.131:5060
2005-11-20T13:51:05.751570 [doug] SIP PACKET
SIP/2.0 401 Unauthorized
CSeq: 4423 REGISTER
To: sip:[EMAIL PROTECTED]:
5060;tag=21a483426c2cd5d9b85bffe6bba40a2e.45d0
From: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
Via: SIP/2.0/UDP XXXXXXXXXXXX:
5060;branch=z9hG4bK3cd44f9a1fa0837d13ab762a98892ea0;rport=5060
WWW-Authenticate: Digest realm="proxy01.sipphone.com",
nonce="4380c6c55ebb0334d53c30e24fa5c85c58c28bdd"
Content-Length: 0
2005-11-20T13:51:05.752728 [doug] sip Protocol got SIP response 401:
Unauthorized
2005-11-20T13:51:05.752895 [doug] STATUS: Trying SIP registration
password...
2005-11-20T13:51:05.753871 [shtoom.sip.SipProtocol (UDP)] auth
XXXXXXXXX:proxy01.sipphone.com:PASSWORDREMOVED XXXXXXXXXXX XXXXXXXXXXXX
2005-11-20T13:51:05.756010 [doug] register sent
REGISTER sip:proxy01.sipphone.com:5060 SIP/2.0
CSeq: 4424 REGISTER
To: sip:[EMAIL PROTECTED]:5060
From: sip:[EMAIL PROTECTED]:5060
Expires: 900
Call-ID: [EMAIL PROTECTED]
Authorization: Digest username="XXXXXXXXX",
realm="proxy01.sipphone.com", nonce="XXXXXXX",
uri="sip:proxy01.sipphone.com:5060", response="XXXXXXXXX"
User-Agent: Shtoom/0.3alpha0
Contact: <sip:[EMAIL PROTECTED]:5060>
Content-Length: 0
Via: SIP/2.0/UDP XXXXXXXXX:
5060;branch=z9hG4bK10dafd56a74716901891dc0363168ca8;rport
2005-11-20T13:51:05.774447 [stun] got STUN response to None from
('64.69.76.23', 3478)
2005-11-20T13:51:05.774818 [stun] STUN: unhandled AV (Unknown type
-7fe0), val "'\\x00\\x01\\t\\x9f\\x8f\\xd8\\xed\\xbe'"
2005-11-20T13:51:05.775100 [stun] STUN: unhandled AV (Unknown type
-7fde), val "'Vovida.org 0.96\\x00'"
2005-11-20T13:51:05.844961 [doug] Got a SIP packet from
198.65.166.131:5060
2005-11-20T13:51:05.845192 [doug] SIP PACKET
SIP/2.0 200 OK
CSeq: 4424 REGISTER
To: sip:[EMAIL PROTECTED]:
5060;tag=21a483426c2cd5d9b85bffe6bba40a2e.7002
From: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
Via: SIP/2.0/UDP XXXXXXXX:
5060;branch=z9hG4bK10dafd56a74716901891dc0363168ca8;rport=5060
Contact: <sip:[EMAIL PROTECTED]:5060>;q=0.00;expires=900
Content-Length: 0
2005-11-20T13:51:05.846309 [doug] sip Protocol got SIP response 200: OK
2005-11-20T13:51:05.846579 [doug] STATUS: 200: OK
2005-11-20T13:51:05.846888 [doug] STATUS: Registration: OK
2005-11-20T13:51:05.847268 [shtoom.sip.SipProtocol (UDP)] 'registered'
2005-11-20T13:51:05.852163 [stun] got STUN response to
'8e47886bcb0e45c6e91d710c3bda4ce' from ('64.69.76.24', 3479)
2005-11-20T13:51:05.852623 [stun] STUN: unhandled AV (Unknown type
-7fe0), val "'\\x00\\x01J\\x1e\\xccY\\xfb\\xdd'"
2005-11-20T13:51:05.852924 [stun] STUN: unhandled AV (Unknown type
-7fde), val "'Vovida.org 0.96\\x00'"
2005-11-20T13:51:05.853585 [-] (Port 50265 Closed)
2005-11-20T13:51:05.853821 [-] Stopping protocol
<shtoom.stun._DetectSTUNProt object at 0x602e50>
2005-11-20T13:51:11.502792 [UPnP] discovery timed out
2005-11-20T13:51:11.503249 [UPnP] no UPnP found!
2005-11-20T13:51:11.503833 [nat] detectNAT got [(True, None), (True,
<NatType None>)]
2005-11-20T13:51:11.504026 [nat] using STUN mapper
2005-11-20T13:51:11.595870 [stun] got STUN response to None from
('64.69.76.23', 3478)
2005-11-20T13:51:11.596475 [stun] STUN: unhandled AV (Unknown type
-7fe0), val "'\\x00\\x01\\x9f6\\xce\\xec#\\x88'"
2005-11-20T13:51:11.596845 [stun] STUN: unhandled AV (Unknown type
-7fde), val "'Vovida.org 0.96\\x00'"
2005-11-20T13:51:11.636302 [sip] late stun packet, ignoring
NOW I PLACE PHONE CALL
2005-11-20T13:52:01.334150 [doug] Got a SIP packet from
198.65.166.131:5060
2005-11-20T13:52:01.334435 [doug] SIP PACKET
INVITE sip:[EMAIL PROTECTED]:5060 SIP/2.0
Record-Route: <sip:[EMAIL PROTECTED];ftag=SD8032501-
as50d9938a;lr=on>
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bK1998.d3cdc6e4.1
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740.1sr
From: "XXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=SD
8032501-as50d9938a
To: <sip:[EMAIL PROTECTED]>
Contact:
<sip:[EMAIL PROTECTED]:5060;pointone=SIPVLOINSIDE-70u68eq6pb31f;tr
ansport=udp>
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Date: Sun, 20 Nov 2005 20:17:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Content-Type: application/sdp
Content-Length: 241
Max-Forwards: 69
RemoteIP: 38.114.14.210
P-hint: usrloc routed
v=0
o=root 8679 8679 IN IP4 38.114.14.210
s=session
c=IN IP4 38.114.14.210
t=0 0
m=audio 7650 RTP/AVP 0 97 101
a=rtpmap:0 PCMU/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
2005-11-20T13:52:01.336992 [doug] got SIP request INVITE:
sip:[EMAIL PROTECTED]:5060
2005-11-20T13:52:01.337741 [doug] got SIP request
INVITE sip:[EMAIL PROTECTED]:5060 SIP/2.0
Record-Route: <sip:[EMAIL PROTECTED];ftag=SD8032501-
as50d9938a;lr=on>
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bK1998.d3cdc6e4.1
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740.1sr
From: "XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a
To: <sip:[EMAIL PROTECTED]>
Contact:
<sip:[EMAIL PROTECTED]:5060;pointone=SIPVLOINSIDE-70u68eq6pb31f;
transport=udp>
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Date: Sun, 20 Nov 2005 20:17:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Content-Type: application/sdp
Content-Length: 241
Max-Forwards: 69
Remoteip: 38.114.14.210
P-Hint: usrloc routed
v=0
o=root 8679 8679 IN IP4 38.114.14.210
s=session
c=IN IP4 38.114.14.210
t=0 0
m=audio 7650 RTP/AVP 0 97 101
a=rtpmap:0 PCMU/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
2005-11-20T13:52:01.340763 [sip] using outboundProxyURI of
sip:proxy01.sipphone.com:5060
2005-11-20T13:52:01.346056 [-]
twisted.internet.protocol.DatagramProtocol starting on 50268
2005-11-20T13:52:01.346326 [-] Starting protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x60c2b0>
2005-11-20T13:52:01.349076 [doug] Response sent to ('198.65.166.131',
5060)
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bK1998.d3cdc6e4.1
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740.1sr
To: <sip:[EMAIL PROTECTED]>
From: "XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a
Date: Sun, 20 Nov 2005 18:52:01 GMT
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
Server: Shtoom/0.3alpha0
CSeq: 102 INVITE
Allow-Events: telephone-event
Content-Length: 0
2005-11-20T13:52:01.453535 [doug] acceptCall dialog is <Dialog
"XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a->sip:[EMAIL PROTECTED];tag=03750009,
SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010>
2005-11-20T13:52:01.495582 [-] shtoom.rtp.protocol.RTPProtocol
starting on 13880
2005-11-20T13:52:01.496026 [-] Starting protocol
<shtoom.rtp.protocol.RTPProtocol instance at 0x6267d8>
2005-11-20T13:52:01.497168 [-] shtoom.rtp.rtcp.RTCPProtocol starting
on 13881
2005-11-20T13:52:01.497504 [-] Starting protocol
<shtoom.rtp.rtcp.RTCPProtocol instance at 0x6ca828>
2005-11-20T13:52:01.497814 [doug] creating voiceapp <class
'__main__.EchoApp'> with start args {}
2005-11-20T13:52:01.498702 [-] voiceapp.__start__
2005-11-20T13:52:01.501648 [doug] new voiceapp <__main__.EchoApp
object at 0x6ce7b0>
2005-11-20T13:52:01.502685 [-] voiceapp.__start__ to user XXXXXXXXXXX
2005-11-20T13:52:01.503007 [doug] <Leg at 6ce7d0 connected to None>
answering this call
2005-11-20T13:52:01.503469 [sip] acceptIncoming setting cookie to
'CallCookie1'
2005-11-20T13:52:01.506579 [doug] Response sent to ('198.65.166.131',
5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bK1998.d3cdc6e4.1
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740.1sr
To: <sip:[EMAIL PROTECTED]>
From: "XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a
Date: Sun, 20 Nov 2005 18:52:01 GMT
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
Server: Shtoom/0.3alpha0
CSeq: 102 INVITE
Allow-Events: telephone-event
Contact: sip:[EMAIL PROTECTED]:5060
Content-Length: 173
Content-Type: application/sdp
v=0
o=root 72150 72150 IN IP4 XXXXXXXXXXX
s=shtoom
c=IN IP4 XXXXXXXXXXX
t=0 0
m=audio 13880 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
2005-11-20T13:52:01.508448 [-] (Port 50268 Closed)
2005-11-20T13:52:01.512674 [-] Stopping protocol
<twisted.internet.protocol.DatagramProtocol instance at 0x60c2b0>
2005-11-20T13:52:01.524135 [-] <shtoom.rtp.protocol.RTPProtocol
instance at 0x6267d8>.handle_media_sample() should only be called
only when it is in sending mode.
2005-11-20T13:52:01.625421 [doug] Got a SIP packet from
38.114.14.210:5060
2005-11-20T13:52:01.625655 [doug] SIP PACKET
ACK sip:[EMAIL PROTECTED]:5060 SIP/2.0
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740c.c00000j1.1sr
From: "XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a
To: <sip:[EMAIL PROTECTED]>
Contact:
<sip:[EMAIL PROTECTED]:5060;pointone=SIPVLOINSIDE-70u68eq6pb31f;
transport=udp>
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
CSeq: 102 ACK
User-Agent: Asterisk PBX
Content-Length: 0
Max-Forwards: 70
2005-11-20T13:52:01.627045 [doug] got SIP request ACK:
sip:[EMAIL PROTECTED]:5060
2005-11-20T13:52:01.627807 [doug] got SIP request
ACK sip:[EMAIL PROTECTED]:5060 SIP/2.0
Via: SIP/2.0/UDP
38.114.14.210:5060;branch=z9hG4bK3201ca207030rdg21740c.c00000j1.1sr
From: "XXXXXXXXXXX"
<sip:[EMAIL PROTECTED];pointone=SIPVLOINSIDE-70u68eq6pb31f>;tag=
SD8032501-as50d9938a
To: <sip:[EMAIL PROTECTED]>
Contact:
<sip:[EMAIL PROTECTED]:5060;pointone=SIPVLOINSIDE-70u68eq6pb31f;
transport=udp>
Call-ID: SD8032501-b21d11aaf2f54cbbad8fa8cda6ee346d-06a3010
CSeq: 102 ACK
User-Agent: Asterisk PBX
Content-Length: 0
Max-Forwards: 70
2005-11-20T13:52:01.633306 [rtp] sending CN(0) to seed firewall to
38.114.14.210:7650
2005-11-20T13:52:01.633943 [doug] call CallCookie1 connected
_______________________________________________
Shtoom mailing list
[email protected]
http://mail.python.org/mailman/listinfo/shtoom
