Eric C. Snowdeal III
2004-Nov-18 20:55 UTC
[Asterisk-Users] [perhpas OT] asterisk holding rtp ports open with natted spa-3000
i'm running asterisk ( Asterisk CVS-v1-0-11/15/04-21:24:51 ) on a public IP address and am accessing it with natted x-lite and spa-3000 clients. the xten client runs perfectly and i can also make and receive calls with the spa-3000 with no issues, so both appear to be configured correctly; however with the spa-3000, asterisk keeps opening rtp ports until there are none left which leads to Bad Things [1]. since the xten client doesn't exibit this behavior, and both clients can make and receive calls, i'm probably way off topic for this group, but i thought i'd lob this out and see if anyone had any helpful suggestions or had seen anything similar. the only reason i feel i have a valid reason to post this to an asterisk group is that the spa-3000 is configured to have a maximum of 4 rtp ports open, so it seems wierd that asterisk would be opening/keep open a large number of ports [ e.g. 2, which shows just a small sample of open rtp ports] to my relatively untrained eye, the sip debug log looks good [3], except that there is a lot more message traffic as compared to when only the xlite client is connected; i was able to moderate the behavior slightly by altering the "register expires" parameter based on a suggestion from a voxilla forum thread [4], but the problem still persists. the only other circumstantial evidence that i have is that asterisk keeps telling me that the Line 1 peer becomes unavailable and available over and over again [5], which seems to be associated with the run-up on open RTP ports. again - apologies if this is off topic, but i'm pretty much at wits end with this. [1] Nov 13 08:12:08 WARNING[11730]: rtp.c:868 ast_rtp_new_with_bindaddr: No RTP ports remaining [2] udp 0 0 my.public.ip.address:10000 0.0.0.0:* udp 0 0 0.0.0.0:10001 0.0.0.0:* udp 0 0 my.public.ip.address:10002 0.0.0.0:* udp 0 0 0.0.0.0:10003 0.0.0.0:* udp 0 0 my.public.ip.address:10004 0.0.0.0:* udp 0 0 0.0.0.0:10005 0.0.0.0:* udp 0 0 0.0.0.0:1813 0.0.0.0:* udp 0 0 0.0.0.0:1814 0.0.0.0:* udp 0 0 my.public.ip.address:10008 0.0.0.0:* udp 0 0 0.0.0.0:10009 0.0.0.0:* udp 0 0 my.public.ip.address:10010 0.0.0.0:* udp 0 0 0.0.0.0:10011 0.0.0.0:* udp 0 0 my.public.ip.address:10014 0.0.0.0:* udp 0 0 0.0.0.0:10015 0.0.0.0:* udp 0 0 my.public.ip.address:10016 0.0.0.0:* udp 0 0 0.0.0.0:10017 0.0.0.0:* udp 0 0 my.public.ip.address:10030 0.0.0.0:* udp 0 0 0.0.0.0:10031 0.0.0.0:* udp 0 0 my.public.ip.address:10036 0.0.0.0:* udp 0 0 0.0.0.0:10037 0.0.0.0:* udp 0 0 my.public.ip.address:10040 0.0.0.0:* udp 0 0 0.0.0.0:10041 0.0.0.0:* udp 0 0 my.public.ip.address:10042 0.0.0.0:* udp 0 0 0.0.0.0:10043 0.0.0.0:* udp 0 0 my.public.ip.address:10046 0.0.0.0:* udp 0 0 0.0.0.0:10047 0.0.0.0:* udp 0 0 my.public.ip.address:10050 0.0.0.0:* udp 0 0 0.0.0.0:10051 0.0.0.0:* udp 0 0 my.public.ip.address:10054 0.0.0.0:* udp 0 0 0.0.0.0:10055 0.0.0.0:* udp 0 0 my.public.ip.address:10064 0.0.0.0:* udp 0 0 0.0.0.0:10065 0.0.0.0:* udp 0 0 my.public.ip.address:10066 0.0.0.0:* udp 0 0 0.0.0.0:10067 0.0.0.0:* udp 0 0 my.public.ip.address:10070 0.0.0.0:* udp 0 0 0.0.0.0:10071 0.0.0.0:* udp 0 0 my.public.ip.address:10072 0.0.0.0:* udp 0 0 0.0.0.0:10073 0.0.0.0:* udp 0 0 my.public.ip.address:10078 0.0.0.0:* udp 0 0 0.0.0.0:10079 0.0.0.0:* [3] Transmitting (NAT): SIP/2.0 200 OK Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-3c9c7b77;received=my.wan.ip.address;rport=5060 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=as4cef267a Call-ID: be68e66b-5b7a07f7@my.spa3000.lan.ip CSeq: 5600 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Expires: 10 Contact: <sip:2000@my.wan.ip.address:5060>;expires=10 Date: Fri, 19 Nov 2004 03:16:43 GMT Content-Length: 0 to my.wan.ip.address:5060 Scheduling destruction of call 'be68e66b-5b7a07f7@my.spa3000.lan.ip' in 15000 ms mercury*CLI> Sip read: REGISTER sip:my.asterisk.fqdm SIP/2.0 Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-46a3be23 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm> Call-ID: be68e66b-5b7a07f7@my.spa3000.lan.ip CSeq: 5601 REGISTER Max-Forwards: 70 Authorization: Digest username="2000",realm="asterisk",nonce="175e9928",uri="sip:2000@my.asterisk.fqdm",algorithm=MD5,response="81f28467735f8c8676486d56335a2d05" Contact: Eric C. Snowdeal III <sip:2000@my.wan.ip.address:5060>;expires=10 User-Agent: Sipura/SPA3000-2.0.11(GWg) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura 13 headers, 0 lines Using latest request as basis request Sending to my.wan.ip.address : 5060 (NAT) Transmitting (NAT): SIP/2.0 100 Trying Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-46a3be23;received=my.wan.ip.address;rport=5060 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=as4cef267a Call-ID: be68e66b-5b7a07f7@my.spa3000.lan.ip CSeq: 5601 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Expires: 10 Contact: <sip:2000@70.84.27.194>;expires=10 Content-Length: 0 to my.wan.ip.address:5060 Transmitting (NAT): SIP/2.0 200 OK Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-46a3be23;received=my.wan.ip.address;rport=5060 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=as4cef267a Call-ID: be68e66b-5b7a07f7@my.spa3000.lan.ip CSeq: 5601 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Expires: 10 Contact: <sip:2000@my.wan.ip.address:5060>;expires=10 Date: Fri, 19 Nov 2004 03:16:52 GMT Content-Length: 0 to my.wan.ip.address:5060 Scheduling destruction of call 'be68e66b-5b7a07f7@my.spa3000.lan.ip' in 15000 ms mercury*CLI> Sip read: NOTIFY sip:my.asterisk.fqdm SIP/2.0 Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-a0bf7d74 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: <sip:my.asterisk.fqdm> Call-ID: 9a0a73db-ea105947@my.spa3000.lan.ip CSeq: 2999 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Sipura/SPA3000-2.0.11(GWg) Content-Length: 0 10 headers, 0 lines Transmitting (no NAT): SIP/2.0 200 OK Via: SIP/2.0/UDP my.wan.ip.address:5060;branch=z9hG4bK-a0bf7d74 From: Eric C. Snowdeal III <sip:2000@my.asterisk.fqdm>;tag=2fc5ef1bb4791707o0 To: <sip:my.asterisk.fqdm>;tag=as61650e74 Call-ID: 9a0a73db-ea105947@my.spa3000.lan.ip CSeq: 2999 NOTIFY User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: Content-Length: 0 to my.wan.ip.address:5060 Destroying call '9a0a73db-ea105947@my.spa3000.lan.ip' [4] http://voxilla.com/forum-viewtopic-t-1290.html [5] Nov 18 19:15:49 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:16:14 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:16:39 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:17:19 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:18:13 NOTICE[18956]: chan_sip.c:6606 handle_response: Peer '2000' is now REACHABLE! Nov 18 19:19:15 NOTICE[18956]: chan_sip.c:6612 handle_response: Peer '2000' is now TOO LAGGED! Nov 18 19:19:49 NOTICE[18956]: chan_sip.c:6606 handle_response: Peer '2000' is now REACHABLE! Nov 18 19:21:51 NOTICE[18956]: chan_sip.c:6612 handle_response: Peer '2000' is now TOO LAGGED! Nov 18 19:22:16 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:22:52 NOTICE[18956]: chan_sip.c:7911 sip_poke_noanswer: Peer '2000' is now UNREACHABLE! Nov 18 19:23:02 NOTICE[18956]: chan_sip.c:6606 handle_response: Peer '2000' is now REACHABLE!