[asterisk-users] Line drops

Giannis Margaritis giannis.margaritis at cyberstream.gr
Fri Feb 2 04:09:12 MST 2007


Hello to all,
I post again (last time subject: Line drops strange problem(got event On 
hook) because i have caught in debug a situation where i get a call and 
the line drops and i get a call from the same caller and the line works 
well and the call normally closes by both parties. The only differences 
i find are underlined.
If someone can understand the reason why the line drops from the debug 
messages or has any thoughts  ....

Any help would be highly appreciated .

p.s. The whole situation is that my line drops (once or twice a week). 
If you want more details i can post them

Thank you in advance

*line drops situation
---------------------------------------------------------------------------------------------------------------------------------------------------------------
*

Jan 31 15:20:40 VERBOSE[25962] logger.c:     -- SIP/51-0986fab0 is ringing
Jan 31 15:20:40 DEBUG[25962] chan_zap.c: Requested indication 3 on 
channel Zap/7-1
Jan 31 15:20:40 DEBUG[25962] chan_zap.c: Exception on 19, channel 7
Jan 31 15:20:40 DEBUG[25962] chan_zap.c: Got event Ring Begin(18) on 
channel 7 (index 0)
Jan 31 15:20:42 DEBUG[25962] chan_zap.c: Exception on 19, channel 7
Jan 31 15:20:42 DEBUG[25962] chan_zap.c: Got event Ring/Answered(2) on 
channel 7 (index 0)
Jan 31 15:20:42 DEBUG[25962] chan_zap.c: Setting IDLE polarity due to 
ring. Old polarity was 0
Jan 31 15:20:45 DEBUG[25962] chan_zap.c: Exception on 19, channel 7
Jan 31 15:20:45 DEBUG[25962] chan_zap.c: Got event Ring Begin(18) on 
channel 7 (index 0)
Jan 31 15:20:46 DEBUG[25962] chan_zap.c: Exception on 19, channel 7
Jan 31 15:20:46 DEBUG[25962] chan_zap.c: Got event Ring/Answered(2) on 
channel 7 (index 0)
Jan 31 15:20:46 DEBUG[25962] chan_zap.c: Setting IDLE polarity due to 
ring. Old polarity was 0
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Setting NAT on RTP to 524288
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'62930aba41127773 at 10.0.0.63' of Response 52547: Match Found
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Setting NAT on RTP to 524288
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Checking SIP call limits for 
device 53
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: build_route: Contact hop: 
<sip:53 at 10.0.0.63>
Jan 31 15:20:47 DEBUG[2434] channel.c: Avoiding initial deadlock for 
'SIP/53-b7a05818'
Jan 31 15:20:47 DEBUG[2442] channel.c: Planning to masquerade channel 
SIP/53-b7a05818 into the structure of SIP/51-0986fab0
Jan 31 15:20:47 DEBUG[2442] channel.c: Done planning to masquerade 
channel SIP/53-b7a05818 into the structure of SIP/51-0986fab0
Jan 31 15:20:47 DEBUG[25962] channel.c: Got clone lock for masquerade on 
'SIP/53-b7a05818' at 0xb7a0adf4
Jan 31 15:20:47 DEBUG[25962] chan_sip.c: update_call_counter(51) - 
decrement call limit counter
Jan 31 15:20:47 DEBUG[25962] chan_sip.c: Acked pending invite 102
Jan 31 15:20:47 DEBUG[25962] chan_sip.c: Stopping retransmission on 
'340302fd7e29e92250436f617fcfdd03 at 10.0.0.60' of Request 102: Match Found
Jan 31 15:20:47 DEBUG[25962] channel.c: Putting channel SIP/53-b7a05818 
in 64/64 formats
Jan 31 15:20:47 DEBUG[25962] channel.c: Released clone lock on 
'SIP/51-0986fab0<ZOMBIE>'
Jan 31 15:20:47 DEBUG[25962] channel.c: Done Masquerading 
SIP/53-b7a05818 (0)
Jan 31 15:20:47 VERBOSE[25962] logger.c:     -- SIP/53-b7a05818 answered 
Zap/7-1
Jan 31 15:20:47 DEBUG[25962] chan_zap.c: Requested indication -1 on 
channel Zap/7-1
Jan 31 15:20:47 DEBUG[25962] chan_zap.c: Took Zap/7-1 off hook
Jan 31 15:20:47 DEBUG[25962] chan_zap.c: Enabled echo cancellation on 
channel 7
Jan 31 15:20:47 DEBUG[25962] chan_zap.c: Engaged echo training on channel 7
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'340302fd7e29e92250436f617fcfdd03 at 10.0.0.60' of Request 102: Match Found
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'340302fd7e29e92250436f617fcfdd03 at 10.0.0.60' of Request 102: Match Not Found
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'62930aba41127773 at 10.0.0.63' of Response 52548: Match Found
_Jan 31 15:22:40 DEBUG[25962] chan_zap.c: Exception on 19, channel 7
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: Got event On hook(1) on channel 
7 (index 0)
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: disabled echo cancellation on 
channel 7
Jan 31 15:22:40 DEBUG[25962] channel.c: Didn't get a frame from channel: 
Zap/7-1
Jan 31 15:22:40 DEBUG[25962] channel.c: Bridge stops bridging channels 
Zap/7-1 and SIP/53-b7a05818
Jan 31 15:22:40 DEBUG[25962] chan_sip.c: update_call_counter(53) - 
decrement call limit counter
Jan 31 15:22:40 DEBUG[25962] app_dial.c: Exiting with DIALSTATUS=ANSWER._
Jan 31 15:22:40 VERBOSE[25962] logger.c:   == Spawn extension 
(ringoffice, s, 1) exited non-zero on 'Zap/7-1'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '00381113237515'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '00381113237515'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 's'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'ringoffice'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'Zap/7-1'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'SIP/53-b7a05818'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'Dial'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'SIP/51'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '2007-01-31 15:20:40'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '2007-01-31 15:20:47'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '2007-01-31 15:22:40'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '120'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '113'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'ANSWERED'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is 'DOCUMENTATION'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '(null)'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '1170249639.201'
Jan 31 15:22:40 DEBUG[25962] pbx.c: Function result is '(null)'
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: Hangup: channel: 7 index = 0, 
normal = 19, callwait = -1, thirdcall = -1
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: disabled echo cancellation on 
channel 7
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: Set option TDD MODE, value: 
OFF(0) on Zap/7-1
Jan 31 15:22:40 DEBUG[25962] chan_zap.c: Updated conferencing on 7, with 
0 conference users
Jan 31 15:22:40 VERBOSE[25962] logger.c:     -- Hungup 'Zap/7-1'

------------------------------------------------------------------------------------------------------------------------------------------------------

*line works well situation*

Jan 31 15:22:57 VERBOSE[25981] logger.c:     -- SIP/51-0986fab0 is ringing
Jan 31 15:22:57 DEBUG[25981] chan_zap.c: Requested indication 3 on 
channel Zap/7-1
Jan 31 15:22:57 DEBUG[25981] chan_zap.c: Exception on 19, channel 7
Jan 31 15:22:57 DEBUG[25981] chan_zap.c: Got event Ring Begin(18) on 
channel 7 (index 0)
Jan 31 15:22:58 DEBUG[25981] chan_zap.c: Exception on 19, channel 7
Jan 31 15:22:58 DEBUG[25981] chan_zap.c: Got event Ring/Answered(2) on 
channel 7 (index 0)
Jan 31 15:22:58 DEBUG[25981] chan_zap.c: Setting IDLE polarity due to 
ring. Old polarity was 0
Jan 31 15:23:02 DEBUG[25981] chan_zap.c: Exception on 19, channel 7
Jan 31 15:23:02 DEBUG[25981] chan_zap.c: Got event Ring Begin(18) on 
channel 7 (index 0)
Jan 31 15:23:03 DEBUG[25981] chan_zap.c: Exception on 19, channel 7
Jan 31 15:23:03 DEBUG[25981] chan_zap.c: Got event Ring/Answered(2) on 
channel 7 (index 0)
Jan 31 15:23:03 DEBUG[25981] chan_zap.c: Setting IDLE polarity due to 
ring. Old polarity was 0
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Setting NAT on RTP to 524288
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'b8c82789bad2f990 at 10.0.0.63' of Response 36455: Match Found
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Setting NAT on RTP to 524288
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Checking SIP call limits for 
device 53
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: build_route: Contact hop: 
<sip:53 at 10.0.0.63>
Jan 31 15:23:04 DEBUG[2434] channel.c: Avoiding initial deadlock for 
'SIP/53-b7a05818'
Jan 31 15:23:04 DEBUG[2442] channel.c: Planning to masquerade channel 
SIP/53-b7a05818 into the structure of SIP/51-0986fab0
Jan 31 15:23:04 DEBUG[2442] channel.c: Done planning to masquerade 
channel SIP/53-b7a05818 into the structure of SIP/51-0986fab0
Jan 31 15:23:04 DEBUG[25981] channel.c: Got clone lock for masquerade on 
'SIP/53-b7a05818' at 0xb7a0adf4
Jan 31 15:23:04 DEBUG[25981] chan_sip.c: update_call_counter(51) - 
decrement call limit counter
Jan 31 15:23:04 DEBUG[25981] chan_sip.c: Acked pending invite 102
Jan 31 15:23:04 DEBUG[25981] chan_sip.c: Stopping retransmission on 
'59d2eb1a3399e8d65320b154377f4a90 at 10.0.0.60' of Request 102: Match Found
Jan 31 15:23:04 DEBUG[25981] channel.c: Putting channel SIP/53-b7a05818 
in 64/64 formats
Jan 31 15:23:04 DEBUG[25981] channel.c: Released clone lock on 
'SIP/51-0986fab0<ZOMBIE>'
Jan 31 15:23:04 DEBUG[25981] channel.c: Done Masquerading 
SIP/53-b7a05818 (0)
Jan 31 15:23:04 VERBOSE[25981] logger.c:     -- SIP/53-b7a05818 answered 
Zap/7-1
Jan 31 15:23:04 DEBUG[25981] chan_zap.c: Requested indication -1 on 
channel Zap/7-1
Jan 31 15:23:04 DEBUG[25981] chan_zap.c: Took Zap/7-1 off hook
Jan 31 15:23:04 DEBUG[25981] chan_zap.c: Enabled echo cancellation on 
channel 7
Jan 31 15:23:04 DEBUG[25981] chan_zap.c: Engaged echo training on channel 7
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'59d2eb1a3399e8d65320b154377f4a90 at 10.0.0.60' of Request 102: Match Found
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'59d2eb1a3399e8d65320b154377f4a90 at 10.0.0.60' of Request 102: Match Not Found
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on 
'b8c82789bad2f990 at 10.0.0.63' of Response 36456: Match Found
_Jan 31 15:23:24 DEBUG[25981] channel.c: Didn't get a frame from 
channel: SIP/53-b7a05818
Jan 31 15:23:24 DEBUG[25981] channel.c: Bridge stops bridging channels 
Zap/7-1 and SIP/53-b7a05818
Jan 31 15:23:24 DEBUG[25981] chan_sip.c: update_call_counter(53) - 
decrement call limit counter_
Jan 31 15:23:24 DEBUG[25981] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jan 31 15:23:24 VERBOSE[25981] logger.c:   == Spawn extension 
(ringoffice, s, 1) exited non-zero on 'Zap/7-1'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '00381113237515'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '00381113237515'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 's'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'ringoffice'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'Zap/7-1'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'SIP/53-b7a05818'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'Dial'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'SIP/51'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '2007-01-31 15:22:57'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '2007-01-31 15:23:04'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '2007-01-31 15:23:24'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '27'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '20'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'ANSWERED'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is 'DOCUMENTATION'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '(null)'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '1170249775.204'
Jan 31 15:23:24 DEBUG[25981] pbx.c: Function result is '(null)'
Jan 31 15:23:24 DEBUG[25981] chan_zap.c: Hangup: channel: 7 index = 0, 
normal = 19, callwait = -1, thirdcall = -1
Jan 31 15:23:24 DEBUG[25981] chan_zap.c: disabled echo cancellation on 
channel 7
Jan 31 15:23:24 DEBUG[25981] chan_zap.c: Set option TDD MODE, value: 
OFF(0) on Zap/7-1
Jan 31 15:23:24 DEBUG[25981] chan_zap.c: Updated conferencing on 7, with 
0 conference users
Jan 31 15:23:24 VERBOSE[25981] logger.c:     -- Hungup 'Zap/7-1'
-------------------------------------------------------------------------------------------------------------------------------------------------------------------

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20070202/4af48663/attachment.htm


More information about the asterisk-users mailing list