[asterisk-users] Still dropped calls :(

mccoy silva mccoy.silva at gmail.com
Sun Feb 10 07:55:23 CST 2008 

Hello All!

I have a problem with my calls, that drops after 20 - 30 seconds. I got a
piece of PAP2-NA log and Asterisk log and there's an error 603 - call
declived, as showed.
Thanks for any help.

McCoy

*********** PAP2-NA LOG ***********

Feb  9 09:00:56 192.168.4.205
Feb  9 09:01:11 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:11 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:11 192.168.4.205 NOTIFY sip:192.168.3.14 SIP/2.0^M Via:
SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-96e6cfdd^M From: 1221 <
sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0^M To: <sip:192.168.3.14>^M
Call-ID: 41e4f931-3594a397 at 192.168.4.205^M CSeq: 14 NOTIFY^M Max-Forwards:
70^M Event: keep-alive^M User-Agent: Linksys/PAP2-3.1.22(LS)^M
Content-Length: 0^M ^M
Feb  9 09:01:11 192.168.4.205
Feb  9 09:01:11 192.168.4.205
Feb  9 09:01:11 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:11 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:11 192.168.4.205 SIP/2.0 489 Bad event^M Via: SIP/2.0/UDP
192.168.4.205:5060;branch=z9hG4bK-96e6cfdd;received=192.168.4.205^M From:
1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0^M To: <sip:
192.168.3.14>;tag=as3b8e69d7^M Call-ID: 41e4f931-3594a397 at 192.168.4.205^M
CSeq: 14 NOTIFY^M User-Agent: MY PBX^M Allow: INVITE, ACK, CANCEL, OPTIONS,
BYE, REFER, SUBSCRIBE, NOTIFY^M Supported: replaces^M Content-Length: 0^M ^M

Feb  9 09:01:11 192.168.4.205
Feb  9 09:01:11 192.168.4.205
Feb  9 09:01:25 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:25 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:25 192.168.4.205 SIP/2.0 603 Declined^M Via: SIP/2.0/UDP
192.168.4.205:5060;branch=z9hG4bK-4f7097bb;received=192.168.4.205^M From:
1221 <sip:dep2_1221 at 192.168.3.14>;tag=57812b6c98e269ebo0^M To: <
sip:1261 at 192.168.3.14>;tag=as5d8b3a8f^M Call-ID:
ebcc5640-ca1b1d27 at 192.168.4.205^M CSeq: 102 INVITE^M User-Agent: MY PBX^M
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY^M
Supported: replaces^M Contact: <sip:1261 at 192.168.3.14>^M Content-Length: 0^M
^M
Feb  9 09:01:25 192.168.4.205
Feb  9 09:01:26 192.168.4.205
Feb  9 09:01:26 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 ACK sip:1261 at 192.168.3.14 SIP/2.0^M Via:
SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-4f7097bb^M From: 1221 <
sip:dep2_1221 at 192.168.3.14>;tag=57812b6c98e269ebo0^M To: <
sip:1261 at 192.168.3.14>;tag=as5d8b3a8f^M Call-ID:
ebcc5640-ca1b1d27 at 192.168.4.205^M CSeq: 102 ACK^M Max-Forwards: 70^M
Proxy-Authorization: Digest
username="dep2_1221",realm="asterisk",nonce="34fd0aa2",uri="
sip:1261 at 192.168.3.14",algorithm=MD5,response="4516bd0844903ba2942056181d6b2846"^M
Contact: 1221 <sip:dep2_1221 at 192.168.4.205:5060>^M User-Agent: Linksys/PAP2-
3.1.22(LS)^M Content-Length: 0^M ^M
Feb  9 09:01:26 192.168.4.205
Feb  9 09:01:26 192.168.4.205
Feb  9 09:01:26 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 [0:5060]->192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 NOTIFY sip:192.168.3.14 SIP/2.0^M Via:
SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-64d734e5^M From: 1221 <
sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0^M To: <sip:192.168.3.14>^M
Call-ID: 41e4f931-3594a397 at 192.168.4.205^M CSeq: 15 NOTIFY^M Max-Forwards:
70^M Event: keep-alive^M User-Agent: Linksys/PAP2-3.1.22(LS)^M
Content-Length: 0^M ^M
Feb  9 09:01:26 192.168.4.205
Feb  9 09:01:26 192.168.4.205
Feb  9 09:01:26 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 [0:5060]<<192.168.3.14:5060
Feb  9 09:01:26 192.168.4.205 SIP/2.0 489 Bad event^M Via: SIP/2.0/UDP
192.168.4.205:5060;branch=z9hG4bK-64d734e5;received=192.168.4.205^M From:
1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0^M To: <sip:
192.168.3.14>;tag=as074e30f7^M Call-ID: 41e4f931-3594a397 at 192.168.4.205^M
CSeq: 15 NOTIFY^M User-Agent: MY PBX^M Allow: INVITE, ACK, CANCEL, OPTIONS,
BYE, REFER, SUBSCRIBE, NOTIFY^M Supported: replaces^M Content-Length: 0^M ^M

Feb  9 09:01:26 192.168.4.205


*********** ASTERISK LOG ***********


[Feb  9 09:00:53] VERBOSE[2279] logger.c:     -- Executing [1261 at pstn2:1]
Dial("SIP/dep2_1221-082035f8", "ZAP/4/1261|30|tT") in new stack
[Feb  9 09:00:53] DEBUG[2279] chan_zap.c: Dialing '1261'
[Feb  9 09:00:53] DEBUG[2279] chan_zap.c: Deferring dialing...
[Feb  9 09:00:53] VERBOSE[2279] logger.c:     -- Called 4/1261
[Feb  9 09:00:55] DEBUG[2279] chan_zap.c: No echo training requested
[Feb  9 09:00:56] VERBOSE[2204] logger.c:
<--- SIP read from 192.168.4.205:5060 --->
NOTIFY sip:192.168.3.14 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-efcdd2e0
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0
To: <sip:192.168.3.14>
Call-ID: 41e4f931-3594a397 at 192.168.4.205
CSeq: 13 NOTIFY
Max-Forwards: 70
Event: keep-alive
User-Agent: Linksys/PAP2-3.1.22(LS)
Content-Length: 0


<------------->
[Feb  9 09:00:56] VERBOSE[2204] logger.c: --- (10 headers 0 lines) ---
[Feb  9 09:00:56] VERBOSE[2204] logger.c:
<--- Transmitting (no NAT) to 192.168.4.205:5060 --->
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-efcdd2e0;received=
192.168.4.205
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0
To: <sip:192.168.3.14>;tag=as40c4c3f8
Call-ID: 41e4f931-3594a397 at 192.168.4.205
CSeq: 13 NOTIFY
User-Agent: MY PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>
[Feb  9 09:00:56] VERBOSE[2279] logger.c: Audio is at 192.168.3.14 port
11678
[Feb  9 09:00:56] VERBOSE[2279] logger.c: Adding codec 0x100 (g729) to SDP
[Feb  9 09:00:56] VERBOSE[2279] logger.c: Adding codec 0x4 (ulaw) to SDP
[Feb  9 09:00:56] VERBOSE[2279] logger.c: Adding codec 0x8 (alaw) to SDP
[Feb  9 09:00:56] VERBOSE[2279] logger.c: Adding non-codec 0x1
(telephone-event) to SDP
[Feb  9 09:00:56] VERBOSE[2279] logger.c:
<--- Transmitting (no NAT) to 192.168.4.205:5060 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-4f7097bb;received=
192.168.4.205
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=57812b6c98e269ebo0
To: <sip:1261 at 192.168.3.14>;tag=as5d8b3a8f
Call-ID: ebcc5640-ca1b1d27 at 192.168.4.205
CSeq: 102 INVITE
User-Agent: MY PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1261 at 192.168.3.14>
Content-Type: application/sdp
Content-Length: 309

v=0
o=root 2153 2153 IN IP4 192.168.3.14
s=session
c=IN IP4 192.168.3.14
t=0 0
m=audio 11678 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

<------------>
[Feb  9 09:01:11] VERBOSE[2204] logger.c:
<--- SIP read from 192.168.4.205:5060 --->
NOTIFY sip:192.168.3.14 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-96e6cfdd
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0
To: <sip:192.168.3.14>
Call-ID: 41e4f931-3594a397 at 192.168.4.205
CSeq: 14 NOTIFY
Max-Forwards: 70
Event: keep-alive
User-Agent: Linksys/PAP2-3.1.22(LS)
Content-Length: 0


<------------->
[Feb  9 09:01:11] VERBOSE[2204] logger.c: --- (10 headers 0 lines) ---
[Feb  9 09:01:11] VERBOSE[2204] logger.c:
<--- Transmitting (no NAT) to 192.168.4.205:5060 --->
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-96e6cfdd;received=
192.168.4.205
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=f1611009c41fba9fo0
To: <sip:192.168.3.14>;tag=as3b8e69d7
Call-ID: 41e4f931-3594a397 at 192.168.4.205
CSeq: 14 NOTIFY
User-Agent: MY PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>
[Feb  9 09:01:25] VERBOSE[2279] logger.c:     -- Nobody picked up in 30000
ms
[Feb  9 09:01:25] VERBOSE[2279] logger.c:     -- Hungup 'Zap/4-1'
[Feb  9 09:01:25] VERBOSE[2279] logger.c:   == Auto fallthrough, channel
'SIP/dep2_1221-082035f8' status is 'NOANSWER'
[Feb  9 09:01:25] VERBOSE[2279] logger.c: Scheduling destruction of SIP
dialog 'ebcc5640-ca1b1d27 at 192.168.4.205' in 32000 ms (Method: INVITE)
[Feb  9 09:01:25] VERBOSE[2279] logger.c:
<--- Reliably Transmitting (no NAT) to 192.168.4.205:5060 --->
SIP/2.0 603 Declined
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-4f7097bb;received=
192.168.4.205
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=57812b6c98e269ebo0
To: <sip:1261 at 192.168.3.14>;tag=as5d8b3a8f
Call-ID: ebcc5640-ca1b1d27 at 192.168.4.205
CSeq: 102 INVITE
User-Agent: MY PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1261 at 192.168.3.14>
Content-Length: 0


<------------>
[Feb  9 09:01:26] VERBOSE[2204] logger.c:
<--- SIP read from 192.168.4.205:5060 --->
ACK sip:1261 at 192.168.3.14 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.205:5060;branch=z9hG4bK-4f7097bb
From: 1221 <sip:dep2_1221 at 192.168.3.14>;tag=57812b6c98e269ebo0
To: <sip:1261 at 192.168.3.14>;tag=as5d8b3a8f
Call-ID: ebcc5640-ca1b1d27 at 192.168.4.205
CSeq: 102 ACK
Max-Forwards: 70
Proxy-Authorization: Digest
username="dep2_1221",realm="asterisk",nonce="34fd0aa2",uri="
sip:1261 at 192.168.3.14",algorithm=MD5,response="4516bd0844903ba2942056181d6b2846"

Contact: 1221 <sip:dep2_1221 at 192.168.4.205:5060>
User-Agent: Linksys/PAP2-3.1.22(LS)
Content-Length: 0
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20080210/9ddc9a8b/attachment.htm

More information about the asterisk-users mailing list