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@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@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@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@10.0.0.60' of Request 102: Match Found
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on
'340302fd7e29e92250436f617fcfdd03@10.0.0.60' of Request 102: Match Not
Found
Jan 31 15:20:47 DEBUG[2442] chan_sip.c: Stopping retransmission on
'62930aba41127773@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@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@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@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@10.0.0.60' of Request 102: Match Found
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on
'59d2eb1a3399e8d65320b154377f4a90@10.0.0.60' of Request 102: Match Not
Found
Jan 31 15:23:04 DEBUG[2442] chan_sip.c: Stopping retransmission on
'b8c82789bad2f990@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
