[Asterisk-Dev] IAX2 problems

[John Todd]

Generic compile of Asterisk and zaptel about 40 minutes ago, complete 
checkout from CVS.  I have had problems with IAX and IAX2 in my 
internal configs to spur my upgrade of this morning.  Things still 
seem to be a little weird, and calls are not completing reliably. 
I'd be happy to provide access to my system for debugging.

SIP -> * -> IAX -> * -> PSTN   (the IAX -> * -> PSTN leg is provided by NuFone)

Here's the dial line: exten => s,3,Dial(IAX2/myaccount at nufone/${ARG1},100,T)

JT



*CLI>
*CLI> WARNING[6151]: File chan_sip.c, Line 400 (retrans_pkt): Maximum 
retries exceeded on call 2509f9b271711bed1b5d38c93b7c2a95 at 127.0.0.1 
for seqno 102 (Request)
DEBUG[6151]: File chan_sip.c, Line 3287 (check_user): Setting NAT on RTP to 0
DEBUG[6151]: File chan_sip.c, Line 512 (__sip_ack): Stopping 
retransmission on '949346451 at 204.91.156.6' of Response 1: Found
DEBUG[6151]: File chan_sip.c, Line 3287 (check_user): Setting NAT on RTP to 0
DEBUG[6151]: File chan_sip.c, Line 2854 (build_route): build_route: 
Contact hop: <sip:2203 at 204.91.156.6:5060;user=phone;transport=udp>
     -- Executing NoOp("SIP/2203-ab47", "") in new stack
     -- Executing Goto("SIP/2203-ab47", "intern-post|16153545500|1") 
in new stack
     -- Goto (intern-post,16153545500,1)
     -- Executing Macro("SIP/2203-ab47", 
"dialiconnect|16153545500|70") in new stack
     -- Executing SetCallerID("SIP/2203-ab47", "12158747249") in new stack
     -- Executing SetCIDName("SIP/2203-ab47", "John Todd") in new stack
     -- Executing Dial("SIP/2203-ab47", 
"IAX2/myaccount at nufone/16153545500|100|T") in new stack
DEBUG[15376]: File app_dial.c, Line 365 (dial_exec): SIMPLE DIAL (NO URL)
     -- Called myaccount at nufone/16153545500
     -- Call accepted by 65.127.126.42 (format 2)
     -- Format for call is 2
WARNING[9226]: File chan_iax2.c, Line 4314 (socket_read): Received 
mini frame before first full voice frame
  WARNING[9226]: File chan_iax2.c, Line 4314 (socket_read): Received 
mini frame before first full voice frame
  DEBUG[9226]: File chan_iax2.c, Line 3796 (socket_read): Ooh, voice 
format changed to 2
DEBUG[15376]: File rtp.c, Line 790 (ast_rtp_write): Ooh, format 
changed from 0 to 4
DEBUG[15376]: File chan_sip.c, Line 1288 (sip_rtp_read): Oooh, format 
changed to 8
DEBUG[15376]: File rtp.c, Line 790 (ast_rtp_write): Ooh, format 
changed from 4 to 8
NOTICE[15376]: File rtp.c, Line 190 (process_rfc3389): RFC3389 
support incomplete.  Turn off on client if possible
     -- IAX2[nufone]/1 is ringing
DEBUG[15376]: File channel.c, Line 1113 (ast_indicate): Driver for 
channel 'SIP/2203-ab47' does not support indication 3, emulating it
DEBUG[15376]: File channel.c, Line 1220 (ast_prod): Prodding channel 
'SIP/2203-ab47'
DEBUG[6151]: File chan_sip.c, Line 599 (create_addr): Setting NAT on RTP to -1
DEBUG[6151]: File chan_sip.c, Line 512 (__sip_ack): Stopping 
retransmission on '14d093102e78422a598705fc122cbd25 at 204.91.156.10' of 
Request 102: Found
NOTICE[15376]: File rtp.c, Line 190 (process_rfc3389): RFC3389 
support incomplete.  Turn off on client if possible
NOTICE[6151]: File chan_sip.c, Line 2521 (sip_reg_timeout): 
Registration for '12488 at 192.246.69.223' timed out, trying again
DEBUG[6151]: File chan_sip.c, Line 2588 (transmit_register): 
Scheduled a timeout # 24
DEBUG[6151]: File chan_sip.c, Line 806 (__sip_destroy): Detaching 
from SIP/2203-ab47
WARNING[15376]: File app_dial.c, Line 264 (wait_for_answer): Unable 
to forward frame
DEBUG[9226]: File channel.c, Line 368 (ast_queue_frame): Dropping 
voice to exceptionally long queue on IAX2[nufone]/1
DEBUG[9226]: File channel.c, Line 368 (ast_queue_frame): Dropping 
voice to exceptionally long queue on IAX2[nufone]/1

[insert a few hundred of these messages here]

DEBUG[9226]: File channel.c, Line 368 (ast_queue_frame): Dropping 
voice to exceptionally long queue on IAX2[nufone]/1
WARNING[6151]: File chan_sip.c, Line 2046 (__transmit_response): 
Unable to determine sequence number from ''
WARNING[15376]: File app_dial.c, Line 264 (wait_for_answer): Unable 
to forward frame
WARNING[15376]: File app_dial.c, Line 264 (wait_for_answer): Unable 
to forward frame

[insert a few hundred of these messages here; now I hit "ctrl-c" 
because it's spinning out of control]

WARNING[15376]: File app_dial.c, Line 264 (wait_for_answer): Unable 
to forward frame
WARNING[15376]: File app_dial.c, Line 264 (wait_for_answer): Unable 
to forward frame
Beginning asterisk shutdown....
Beginning asterisk shutdown....
DEBUG[15376]: File chan_iax2.c, Line 1678 (iax2_hangup): We're 
hanging up IAX2[nufone]/1 now...
     -- Hungup 'IAX2[nufone]/1'
   == Spawn extension (macro-dialiconnect, s, 3) exited non-zero on 
'SIP/2203-ab47' in macro 'dialiconnect'
   == Spawn extension (intern-post, s, 1) exited non-zero on 'SIP/2203-ab47'
     -- Executing Macro("SIP/2203-ab47", "record-cleanup") in new stack
Expression is '1'
     -- Executing GotoIf("SIP/2203-ab47", "1?5:2") in new stack
     -- Goto (macro-record-cleanup,s,5)
     -- Executing NoOp("SIP/2203-ab47", "") in new stack
DEBUG[15376]: File chan_sip.c, Line 943 (sip_hangup): Asked to hangup 
channel not connected
Asterisk cleanly ending (2).
[root at ms1 modules]#