Topic: IAX2 - Unable to create channel of type '' (cause 66 - Channel not implemented)

[jono]

I'm having a little bother with inbound calls from a small IAX2 provider. Below is what |I'm seeing at the CLI.

Outbound calling works fine, if that's of any use!

Code: [Select]

    -- Accepting UNAUTHENTICATED call from xx.xxx.xxx.xx:
       > requested format = alaw,
       > requested prefs = (alaw|gsm|g729|ulaw|g726|lpc10|slin|adpcm|speex|ilbc),
       > actual format = alaw,
       > host prefs = (),
       > priority = caller
    -- Executing [s@mainmenu:1] GotoIf("IAX2/IAX-3847", "0?s-ZapGroup2|1") in new stack
    -- Executing [s@mainmenu:2] AGI("IAX2/IAX-3847", "selintra|CheckState|") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
    -- AGI Script Executing Application: (Dial) Options: (/s|YES|t)
[Nov  9 20:38:39] WARNING[7585]: channel.c:3025 ast_request: No channel type registered for ''
[Nov  9 20:38:39] WARNING[7585]: app_dial.c:1183 dial_exec_full: Unable to create channel of type '' (cause 66 - Channel not implemented)
[Nov  9 20:38:39] WARNING[7585]: app_dial.c:1291 dial_exec_full: Invalid timeout specified: 'YES'
    -- AGI Script Executing Application: (Playback) Options: (silence/1)
    -- <IAX2/IAX-3847> Playing 'silence/1' (language 'en')
[Nov  9 20:38:39] NOTICE[7585]: chan_iax2.c:3420 iax2_read: I should never be called!
    -- AGI Script Executing Application: (Voicemail) Options: (s|u)
[Nov  9 20:38:40] WARNING[7585]: app_voicemail.c:2862 leave_voicemail: No entry in voicemail config file for 's'
    -- AGI Script selintra completed, returning 0
    -- Sent into invalid extension 's' in context 'extensions' on IAX2/IAX-3847
    -- Executing [i@extensions:1] PlayTones("IAX2/IAX-3847", "congestion") in new stack
    -- Executing [i@extensions:2] Hangup("IAX2/IAX-3847", "") in new stack
  == Spawn extension (extensions, i, 2) exited non-zero on 'IAX2/IAX-3847'
    -- Executing [h@extensions:1] Hangup("IAX2/IAX-3847", "") in new stack
  == Spawn extension (extensions, h, 1) exited non-zero on 'IAX2/IAX-3847'
    -- Hungup 'IAX2/IAX-3847'


[SARK devs]

Code: [Select]

Executing [s@mainmenu:1]
It's driving the "s" extension, which means it couldn't find a match for the DiD in sip.conf or extensions.conf.  This usually means that the DiD your carrier is sending you is not what you think it is.  Usually this is because the carrier is sending the call in against your username or accountname rather than your true DiD.

Try using IAX debug (iax2 set debug) from the asterisk console. 

Best

S
     

[jono]

Cheers.

Not sure I'm any the wiser, though

Code: [Select]

pbx*CLI> iax2 set debug
IAX2 Debugging Enabled
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: POKE
   Timestamp: 00005ms  SCall: 10236  DCall: 00000 [195.10.230.110:4569]
Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: PONG
   Timestamp: 00000ms  SCall: 00001  DCall: 10236 [195.10.230.110:4569]
Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK
   Timestamp: 00053ms  SCall: 10236  DCall: 00001 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
   Timestamp: 00018ms  SCall: 09447  DCall: 00000 [195.10.230.110:4569]
   VERSION         : 2
   CALLED NUMBER   : s
   CODEC_PREFS     : (alaw|gsm|g729|ulaw|g726|lpc10|slin|adpcm|speex|ilbc)
   CALLING NUMBER  : 0xxxxxxxxxx
   CALLING PRESNTN : 0
   CALLING TYPEOFN : 0
   CALLING TRANSIT : 0
   CALLING NAME    : 0xxxxxxxxxx
   LANGUAGE        : en
   FORMAT          : 8
   CAPABILITY      : 4078
   ADSICPE         : 2
   DATE TIME       : 2009-11-09  21:57:34

    -- Accepting UNAUTHENTICATED call from 195.10.230.110:
       > requested format = alaw,
       > requested prefs = (alaw|gsm|g729|ulaw|g726|lpc10|slin|adpcm|speex|ilbc),
       > actual format = alaw,
       > host prefs = (),
       > priority = caller
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACCEPT
   Timestamp: 00019ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
   FORMAT          : 8

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK
   Timestamp: 00019ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
    -- Executing [s@mainmenu:1] GotoIf("IAX2/IAX-365", "0?s-ZapGroup2|1") in new stack
    -- Executing [s@mainmenu:2] AGI("IAX2/IAX-365", "selintra|CheckState|") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
    -- AGI Script Executing Application: (Dial) Options: (/s|YES|t)
[Nov  9 21:57:36] WARNING[7862]: channel.c:3025 ast_request: No channel type registered for ''
[Nov  9 21:57:36] WARNING[7862]: app_dial.c:1183 dial_exec_full: Unable to create channel of type '' (cause 66 - Channel not implemented)
[Nov  9 21:57:36] WARNING[7862]: app_dial.c:1291 dial_exec_full: Invalid timeout specified: 'YES'
    -- AGI Script Executing Application: (Playback) Options: (silence/1)
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: ANSWER
   Timestamp: 00541ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
    -- <IAX2/IAX-365> Playing 'silence/1' (language 'en')
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 001 Type: VOICE   Subclass: 8
   Timestamp: 00560ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
[Nov  9 21:57:36] NOTICE[7862]: chan_iax2.c:3420 iax2_read: I should never be called!
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00541ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: CONTROL Subclass: (20?)
   Timestamp: 00589ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00589ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 003 Type: IAX     Subclass: ACK
   Timestamp: 00560ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: VOICE   Subclass: 8
   Timestamp: 00820ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: ACK
   Timestamp: 00820ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
    -- AGI Script Executing Application: (Voicemail) Options: (s|u)
[Nov  9 21:57:37] WARNING[7862]: app_voicemail.c:2862 leave_voicemail: No entry in voicemail config file for 's'
    -- AGI Script selintra completed, returning 0
    -- Sent into invalid extension 's' in context 'extensions' on IAX2/IAX-365
    -- Executing [i@extensions:1] PlayTones("IAX2/IAX-365", "congestion") in new stack
    -- Executing [i@extensions:2] Hangup("IAX2/IAX-365", "") in new stack
  == Spawn extension (extensions, i, 2) exited non-zero on 'IAX2/IAX-365'
    -- Executing [h@extensions:1] Hangup("IAX2/IAX-365", "") in new stack
  == Spawn extension (extensions, h, 1) exited non-zero on 'IAX2/IAX-365'
    -- Hungup 'IAX2/IAX-365'
Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: HANGUP
   Timestamp: 01562ms  SCall: 00365  DCall: 09447 [195.10.230.110:4569]
   CAUSE CODE      : 66

Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 004 Type: IAX     Subclass: ACK
   Timestamp: 01562ms  SCall: 09447  DCall: 00365 [195.10.230.110:4569]
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ
   Timestamp: 00010ms  SCall: 01285  DCall: 00000 [195.10.230.110:4569]
   USERNAME        : 106200
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00015ms  SCall: 04751  DCall: 01285 [195.10.230.110:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 180540742
   USERNAME        : 106200

Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ
   Timestamp: 00054ms  SCall: 01285  DCall: 04751 [195.10.230.110:4569]
   USERNAME        : 106200
   REFRESH         : 60
   MD5 RESULT      : 07bfb090fdb966b97301ea8ca7bcbf07

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK
   Timestamp: 00060ms  SCall: 04751  DCall: 01285 [195.10.230.110:4569]
   USERNAME        : 106200
   DATE TIME       : 2009-11-09  21:57:46
   REFRESH         : 60
   APPARENT ADDRES : IPV4 95.177.75.148:37401
   MESSAGE COUNT   : 0
   CALLING NUMBER  : 106200
   CALLING NAME    : Jono

Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00060ms  SCall: 01285  DCall: 04751 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: POKE
   Timestamp: 00007ms  SCall: 08934  DCall: 00000 [195.10.230.110:4569]
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: PONG
   Timestamp: 00007ms  SCall: 00104  DCall: 08934 [195.10.230.110:4569]
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK
   Timestamp: 00007ms  SCall: 08934  DCall: 00104 [195.10.230.110:4569]
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: POKE
   Timestamp: 00012ms  SCall: 15728  DCall: 00000 [195.10.230.110:4569]
Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: PONG
   Timestamp: 00000ms  SCall: 00001  DCall: 15728 [195.10.230.110:4569]
Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK
   Timestamp: 00062ms  SCall: 15728  DCall: 00001 [195.10.230.110:4569]
exit
Executing last minute cleanups
[root@pbx ~]#



[SARK devs]

they aren't sending in a DiD (the called number has "s" in it).  SARK won't work with that because we use the "s" extension for analogue circuits (much the same as FPBX).

You'll need to seek support from the carrier.

Kind Regards

S