[Home]

Summary:ASTERISK-06842: Unreachable IAX peer causes silence on calls using other IAX channel
Reporter:Phil Marsden (philmarsden)Labels:
Date Opened:2006-04-25 05:50:00Date Closed:2006-05-12 11:20:46
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Core/General
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:Asterisk v1.2.6 installed and configured with 2 IAX accounts:
- Gradwell (used for all incoming and outgoing calls)
- VoipTalk (used only rarely for DID incoming calls)

Everything has been working fine for many weeks - Asterisk really is an amazing piece of Software!!

This morning, when receiving calls via Gradwell IAX, the lines would ofen go silent (could not hear other party although they coudl hear us) for around 10s then would be fine again.

On looking at the logs there were quite a few "NOTICE[5855] chan_iax2.c: Peer 'V-3094245' is now UNREACHABLE! Time: 15" logs that co-incided almost exactly with the times when the lines went quiet

The V-3094245 peer is, however, the VoipTalk IAX provider and NOT the one being used for the call.

Extract from the log file when an example of the problem occured is shown in the additional information.

Problem could be reprodcued easily with almost every call exhibiting the behaviour and when VoipTalk peer was removed from configuration, the problem went away.

This is a live production system but could be used for limited testing out of hours if necessary although I suspect that if VOIPTalk fix their problem it might be more tricky

This is the first bug I have raised using this system so if you need any more information or I've missed anything vital please accept my apologies and let me know what to do

Many thanks,
Phil.



****** ADDITIONAL INFORMATION ******

Extract from

Apr 25 09:53:51 VERBOSE[4892] logger.c:   == Manager 'admin' logged on from 127.0.0.1
Apr 25 09:53:51 VERBOSE[4886] logger.c:     --  dialparties.agi: Correct AMPMGRUSER and AMPMGRPASS
Apr 25 09:53:51 DEBUG[4892] manager.c: Manager received command 'ExtensionState'
Apr 25 09:53:51 DEBUG[4892] manager.c: Manager received command 'Logoff'
Apr 25 09:53:51 VERBOSE[4892] logger.c:   == Manager 'admin' logged off from 127.0.0.1
Apr 25 09:53:51 VERBOSE[4886] logger.c:   dialparties.agi: Extension 212 is available...skipping checks
Apr 25 09:53:51 VERBOSE[4886] logger.c:     --  dialparties.agi: DbSet CALLTRACE/212 to 07738127238
Apr 25 09:53:51 VERBOSE[4886] logger.c:     -- AGI Script dialparties.agi completed, returning 0
Apr 25 09:53:51 VERBOSE[4886] logger.c:     -- Executing Dial("IAX2/213.166.5.129:4569-3", "SIP/212|25|tr") in new stack
Apr 25 09:53:51 DEBUG[4886] chan_sip.c: Setting NAT on RTP to 0
Apr 25 09:53:51 DEBUG[4886] chan_sip.c: Outgoing Call for 212
Apr 25 09:53:51 VERBOSE[4886] logger.c:     -- Called 212
Apr 25 09:53:51 DEBUG[4468] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '397db74436c5fbb74c32c1305ea2e648@10.0.10.20' Request 102: Found
Apr 25 09:53:51 DEBUG[4468] chan_sip.c: Stopping retransmission on '39b7d59a3e8ef3ba15c31efb38edb4c7@10.0.10.20' of Request 102: Match Found
Apr 25 09:53:52 DEBUG[4468] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '397db74436c5fbb74c32c1305ea2e648@10.0.10.20' Request 102: Found
Apr 25 09:53:52 DEBUG[4459] channel.c: Avoiding initial deadlock for 'SIP/212-9593'
Apr 25 09:53:52 VERBOSE[4886] logger.c:     -- SIP/212-9593 is ringing
Apr 25 09:53:55 DEBUG[4468] chan_sip.c: Auto destroying call '140eb081-63d36b59-80549674-d03847e9@10.0.14.200'
Apr 25 09:53:56 DEBUG[4468] chan_sip.c: Acked pending invite 102
Apr 25 09:53:56 DEBUG[4468] chan_sip.c: Stopping retransmission on '397db74436c5fbb74c32c1305ea2e648@10.0.10.20' of Request 102: Match Found
Apr 25 09:53:56 DEBUG[4468] chan_sip.c: build_route: Contact hop: <sip:212@10.0.14.212:5060>
Apr 25 09:53:56 DEBUG[4459] channel.c: Avoiding initial deadlock for 'SIP/212-9593'
Apr 25 09:53:56 VERBOSE[4886] logger.c:     -- SIP/212-9593 answered IAX2/213.166.5.129:4569-3
Apr 25 09:53:57 DEBUG[4517] manager.c: Manager received command 'Command'
Apr 25 09:53:57 DEBUG[4517] manager.c: Manager received command 'Command'
Apr 25 09:54:07 NOTICE[4474] chan_iax2.c: Peer 'V-3094245' is now UNREACHABLE! Time: 16
Apr 25 09:54:17 NOTICE[4474] chan_iax2.c: Peer 'V-3094245' is now REACHABLE! Time: 16
Apr 25 09:54:17 DEBUG[4474] chan_iax2.c: Peer lastms 16, historicms 16, maxms 2000
Apr 25 09:54:23 DEBUG[4474] chan_iax2.c: Received VNAK: resending outstanding frames
Apr 25 09:54:23 DEBUG[4474] chan_iax2.c: Received VNAK: resending outstanding frames
Apr 25 09:54:35 DEBUG[4468] chan_sip.c: Stopping retransmission on '160a85666216a6c415b65fa74f14d4fa@10.0.10.20' of Request 102: Match Found
Apr 25 09:54:42 DEBUG[4468] chan_sip.c: Auto destroying call '642147c5-fd8eb193-b17ebb40-88fdd78b@10.0.14.212'
~
Comments:By: Serge Vecher (serge-v) 2006-05-04 12:59:58

Phil: please try the latest 1.2 branch code. Also, please post your entries from iax.conf for these peers.
By: Serge Vecher (serge-v) 2006-05-12 11:20:46

if the problem still exists in the latest 1.2 branch code (rev > 26000), please reopen the issue with updated debugging information and iax.conf entries.

thanks