[asterisk-dev] BRI support in chan_zap of asterisk 1.6?

Matthew Fredrickson creslin at digium.com
Wed Mar 5 17:18:33 CST 2008 

Jean-Denis Girard wrote:
> Matthew Fredrickson a écrit :
>> Please post your problem with "pri intensive debug span x" output.  Thanks!
> 
> pri intensive debug span 1
> Enabled EXTENSIVE debugging on span 1
> *CLI>
> *CLI>
> *CLI> Sending TEI management message 1, TEI=127
> 
>  > [ fe ff 03 0f d8 bd 01 ff ]
> 
>  > Unnumbered frame:
>  > SAPI: 63  C/R: 1 EA: 0
>  >  TEI: 127        EA: 1
>  >   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
>  > 5 bytes of data
> 
> < [ fe ff 03 0f 00 00 04 ff ]
> 
> < Unnumbered frame:
> < SAPI: 63  C/R: 1 EA: 0
> <  TEI: 127        EA: 1
> <   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
> < 5 bytes of data
> Handling message for SAPI/TEI=63/127
> Received MDL message
> 
> < [ fe ff 03 0f 00 00 04 ff ]
> 
> < Unnumbered frame:
> < SAPI: 63  C/R: 1 EA: 0
> <  TEI: 127        EA: 1
> <   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
> < 5 bytes of data
> Handling message for SAPI/TEI=63/127
> Received MDL message
>      -- Accepting AUTHENTICATED call from 192.168.0.10:
>         > requested format = alaw,
>         > requested prefs = (alaw),
>         > actual format = alaw,
>         > host prefs = (alaw),
>         > priority = mine
> [Mar  5 12:45:24] DEBUG[20028]: pbx.c:2680 pbx_extension_helper: 
> Launching 'NoOp'
>      -- Executing [483527 at sortant:1] NoOp("IAX2/tiare2ast-1", "Appel 
> 483527 => RNIS en provenance d asterisk.sysnux.pf") in new stack
> [Mar  5 12:45:24] DEBUG[20028]: pbx.c:2680 pbx_extension_helper: 
> Launching 'Dial'
>      -- Executing [483527 at sortant:2] Dial("IAX2/tiare2ast-1", 
> "misdn/1/483527") in new stack
> [Mar  5 12:45:24] WARNING[20028]: channel.c:3375 ast_request: No channel 
> type registered for 'misdn'
> [Mar  5 12:45:24] WARNING[20028]: app_dial.c:1456 dial_exec_full: Unable 
> to create channel of type 'misdn' (cause 66 - Channel not implemented)
>    == Everyone is busy/congested at this time (1:0/0/1)
> [Mar  5 12:45:24] DEBUG[20028]: app_dial.c:1946 dial_exec_full: Exiting 
> with DIALSTATUS=CHANUNAVAIL.
> [Mar  5 12:45:24] DEBUG[20028]: pbx.c:2680 pbx_extension_helper: 
> Launching 'Dial'
>      -- Executing [483527 at sortant:3] Dial("IAX2/tiare2ast-1", 
> "Zap/g1/483527") in new stack
> [Mar  5 12:45:24] DEBUG[20028]: chan_zap.c:8892 zt_request: Using channel 1
> [Mar  5 12:45:24] DEBUG[20028]: chan_zap.c:8892 zt_request: Using channel 2
> [Mar  5 12:45:24] WARNING[20028]: app_dial.c:1456 dial_exec_full: Unable 
> to create channel of type 'Zap' (cause 34 - Circuit/channel congestion)
>    == Everyone is busy/congested at this time (1:0/1/0)
> [Mar  5 12:45:24] DEBUG[20028]: app_dial.c:1946 dial_exec_full: Exiting 
> with DIALSTATUS=CONGESTION.
> [Mar  5 12:45:24] DEBUG[20028]: pbx.c:2680 pbx_extension_helper: 
> Launching 'Congestion'
>      -- Executing [483527 at sortant:4] Congestion("IAX2/tiare2ast-1", "") 
> in new stack
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:441 
> __ast_devstate_changed_literal: Notification of state change to be 
> queued on device/channel IAX2/tiare2ast-1
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:441 
> __ast_devstate_changed_literal: Notification of state change to be 
> queued on device/channel IAX2/tiare2ast
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11445 iax2_devicestate: 
> Checking device state for device tiare2ast-1
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:430 do_state_change: 
> Changing state for IAX2/tiare2ast-1 - state 4 (Invalid)
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11445 iax2_devicestate: 
> Checking device state for device tiare2ast
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11453 iax2_devicestate: 
> iax2_devicestate: Found peer. What's device state of tiare2ast? 
> addr=167815360, defaddr=0 maxms=2000, lastms=1
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:430 do_state_change: 
> Changing state for IAX2/tiare2ast - state 2 (In use)
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:8875 socket_process: 
> Received VNAK: resending outstanding frames
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:8389 socket_process: 
> Immediately destroying 1, having received hangup
> [Mar  5 12:45:24] DEBUG[20028]: pbx.c:3323 __ast_pbx_run: Spawn 
> extension (sortant,483527,4) exited non-zero on 'IAX2/tiare2ast-1'
>    == Spawn extension (sortant, 483527, 4) exited non-zero on 
> 'IAX2/tiare2ast-1'
> [Mar  5 12:45:24] DEBUG[20028]: channel.c:1512 ast_softhangup_nolock: 
> Soft-Hanging up channel 'IAX2/tiare2ast-1'
> [Mar  5 12:45:24] DEBUG[20028]: channel.c:1611 ast_hangup: Hanging up 
> channel 'IAX2/tiare2ast-1'
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:3542 iax2_hangup: We're 
> hanging up IAX2/tiare2ast-1 now...
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:3557 iax2_hangup: Really 
> destroying IAX2/tiare2ast-1 now...
>      -- Hungup 'IAX2/tiare2ast-1'
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:441 
> __ast_devstate_changed_literal: Notification of state change to be 
> queued on device/channel IAX2/tiare2ast-1
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11445 iax2_devicestate: 
> Checking device state for device tiare2ast-1
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:430 do_state_change: 
> Changing state for IAX2/tiare2ast-1 - state 4 (Invalid)
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:441 
> __ast_devstate_changed_literal: Notification of state change to be 
> queued on device/channel IAX2/tiare2ast
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11445 iax2_devicestate: 
> Checking device state for device tiare2ast
> [Mar  5 12:45:24] DEBUG[20028]: chan_iax2.c:11453 iax2_devicestate: 
> iax2_devicestate: Found peer. What's device state of tiare2ast? 
> addr=167815360, defaddr=0 maxms=2000, lastms=1
> [Mar  5 12:45:24] DEBUG[20028]: devicestate.c:430 do_state_change: 
> Changing state for IAX2/tiare2ast - state 1 (Not in use)
> Sending TEI management message 1, TEI=127
> 
>  > [ fe ff 03 0f e4 1c 01 ff ]
> 
>  > Unnumbered frame:
>  > SAPI: 63  C/R: 1 EA: 0
>  >  TEI: 127        EA: 1
>  >   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
>  > 5 bytes of data
> 
> < [ fe ff 03 0f 00 00 04 ff ]
> 
> < Unnumbered frame:
> < SAPI: 63  C/R: 1 EA: 0
> <  TEI: 127        EA: 1
> <   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
> < 5 bytes of data
> Handling message for SAPI/TEI=63/127
> Received MDL message
> 
> < [ fe ff 03 0f 00 00 04 ff ]
> 
> < Unnumbered frame:
> < SAPI: 63  C/R: 1 EA: 0
> <  TEI: 127        EA: 1
> <   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
> < 5 bytes of data
> Handling message for SAPI/TEI=63/127
> Received MDL message
> Sending TEI management message 1, TEI=127
> 
>  > [ fe ff 03 0f e2 18 01 ff ]
> 
>  > Unnumbered frame:
>  > SAPI: 63  C/R: 1 EA: 0
>  >  TEI: 127        EA: 1
>  >   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
>  > 5 bytes of data
> 
> < [ fe ff 03 0f 00 00 04 ff ]
> 
> < Unnumbered frame:
> < SAPI: 63  C/R: 1 EA: 0
> <  TEI: 127        EA: 1
> <   M3: 0   P/F: 0 M2: 0 11: 3  [ UI (unnumbered information) ]
> < 5 bytes of data
> Handling message for SAPI/TEI=63/127
> Received MDL message
> 
> < [ fe ff 03 0f 00 00 04 ff ]

We are sending an identity request to the other end it looks like, but 
the other end is not assigning us a TEI.  Instead, its repeatedly 
sending us an identity check request message to us for some reason...

-- 
Matthew Fredrickson
Software/Firmware Engineer
Digium, Inc.

More information about the asterisk-dev mailing list