[SOLVED] Call accepted, but dialplan context never executed

Been trying to resolve this for a while. Haven’t been able to find anyone that has had this problem. Am using version 1.4.10 compiled from source. We are initiating a call from Asterisk through a voip provider. The voip gateway accepts the call, but the recipient phone never rings and the dialplan context is never executed.

It could be misconfiguration with our provider account or a problem on their end, but I wanted to rule out any problems from our Asterisk server first.

I’ve included the IAX debug output from a sample call:

Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
   Timestamp: 00018ms  SCall: 00001  DCall: 00000 [XXX.XXX.XXX.XXX:4569]
   VERSION         : 2
   CALLED NUMBER   : 15715551234
   CODEC_PREFS     : (gsm)
   CALLING NUMBER  : 15715559876
   CALLING PRESNTN : 0
   CALLING TYPEOFN : 0
   CALLING TRANSIT : 0
   CALLING NAME    :
   LANGUAGE        : en
   USERNAME        : user
   FORMAT          : 2
   CAPABILITY      : 57346
   ADSICPE         : 2
   DATE TIME       : 2009-06-08  11:43:06

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: AUTHREQ
   Timestamp: 00019ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 112157819
   USERNAME        : user

Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: AUTHREP
   Timestamp: 00099ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
   MD5 RESULT      : 46d886354706af203fa37638e54b1fc1

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACCEPT
   Timestamp: 00093ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
   FORMAT          : 2

    -- Call accepted by XXX.XXX.XXX.XXX (format gsm)
    -- Format for call is gsm
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00093ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: PROGRES
   Timestamp: 00096ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: ACK
   Timestamp: 00096ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: LAGRQ
   Timestamp: 10019ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: LAGRP
   Timestamp: 10019ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 004 Type: IAX     Subclass: ACK
   Timestamp: 10019ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: IAX     Subclass: LAGRQ
   Timestamp: 10021ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: LAGRP
   Timestamp: 10021ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: ACK
   Timestamp: 10021ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 005 Type: IAX     Subclass: LAGRQ
   Timestamp: 20021ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX     Subclass: LAGRP
   Timestamp: 20021ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 006 Type: IAX     Subclass: ACK
   Timestamp: 20021ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX     Subclass: LAGRQ
   Timestamp: 20023ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 007 Type: IAX     Subclass: LAGRP
   Timestamp: 20023ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 006 Type: IAX     Subclass: ACK
   Timestamp: 20023ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 007 Type: IAX     Subclass: PING
   Timestamp: 21019ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 007 Type: IAX     Subclass: PONG
   Timestamp: 21019ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 008 Type: IAX     Subclass: ACK
   Timestamp: 21019ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 007 Type: IAX     Subclass: PING
   Timestamp: 21021ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 009 Type: IAX     Subclass: PONG
   Timestamp: 21021ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 008 Type: IAX     Subclass: ACK
   Timestamp: 21021ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]
    -- Hungup 'IAX2/XXX.XXX.XXX.XXX:4569-1'
Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 009 Type: IAX     Subclass: HANGUP
   Timestamp: 30019ms  SCall: 00001  DCall: 10000 [XXX.XXX.XXX.XXX:4569]
   CAUSE CODE      : 0

Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 009 Type: IAX     Subclass: ACK
   Timestamp: 30019ms  SCall: 10000  DCall: 00001 [XXX.XXX.XXX.XXX:4569]

Apparently a leading 1 in the callerid field was causing problems at our provider’s end (Teliax). removing the 1 from the callerid field solved that and calls go through now. A new issue has cropped up in that dtmf signals are not being sent back to Asterisk. This appears to be another provider related issue.

So consider this thread closed.