Есть zap канал, творится с ним странная ситуация,

===> zapata.conf
context = incoming-pstn
callerid = "ATC" <540002>
channel => 1-3

====> extensions.conf
[incoming-pstn]
exten => s,1,Dial(SIP/101|20,Ttr)
exten => s,2,Goto(incoming-menu,s,1)


те если никто не отвечает в течении 20 сек то идет голосовое меню, если я звоню с обычного телефона то все нормально, также у нас есть IAX линия которая падает в тотже контекст и с ней тоже все нормально, но как только я звоню с сотового телефона на наш номер происходит странное

-- Starting simple switch on 'Zap/3-1'
-- Executing [s@incoming-pstn:1] Dial("Zap/3-1", "SIP/101|20|Ttr") in new stack
-- Called 101
-- SIP/101-081fd060 is ringing
== Spawn extension (incoming-pstn, s, 1) exited non-zero on 'Zap/3-1'
-- Hungup 'Zap/3-1'
-- Starting simple switch on 'Zap/3-1'
-- Executing [s@incoming-pstn:1] Dial("Zap/3-1", "SIP/101|20|Ttr") in new stack
-- Called 101
-- SIP/101-0822af40 is ringing
== Spawn extension (incoming-pstn, s, 1) exited non-zero on 'Zap/3-1'
-- Hungup 'Zap/3-1'

те не происходит переключение на голосовое меню, на номере 101 идет звонок, потом он исчезает, потом начинает звонить снова и так происходит до бесконечности.

что происходит, куда копать?

в дебаге нашел только


[Nov 16 09:27:08] DEBUG[5321] chan_zap.c: Monitor doohicky got event Ring Begin on channel 3
[Nov 16 09:27:10] DEBUG[5321] chan_zap.c: Monitor doohicky got event Ring/Answered on channel 3
[Nov 16 09:27:10] DEBUG[5321] dsp.c: dsp busy pattern set to 0,0
[Nov 16 09:27:10] DEBUG[5321] devicestate.c: Notification of state change to be queued on device/channel Zap/3-1
[Nov 16 09:27:10] DEBUG[5318] devicestate.c: No provider found, checking channel drivers for Zap - 3
[Nov 16 09:27:10] DEBUG[5318] devicestate.c: Changing state for Zap/3 - state 2 (In use)
[Nov 16 09:27:10] DEBUG[5341] pbx.c: Launching 'Dial'
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Setting NAT on RTP to Off
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Setting NAT on VRTP to Off
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: *** Our native formats are 0x4 (ulaw)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: *** Joint capabilities are 0x0 (nothing)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: *** Our capabilities are 0x8010f (g723|gsm|ulaw|alaw|g729|h263)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: This channel will not be able to handle video.
[Nov 16 09:27:10] DEBUG[5341] rtp.c: Channel 'Zap/3-1' has no RTP, not doing anything
[Nov 16 09:27:10] DEBUG[5341] channel.c: Not copying variable STACK-incoming-pstn-s-1.
[Nov 16 09:27:10] DEBUG[5341] channel.c: Not copying variable TRANSFERCAPABILITY.
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Outgoing Call for 101
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Updating call counter for outgoing call
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Our T38 capability (0), joint T38 capability (0)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: ** Our capability: 0x8010f (g723|gsm|ulaw|alaw|g729|h263) Video flag: False
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: ** Our prefcodec: 0x4 (ulaw)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: This call needs video offers!
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: ** No compatible video codecs... Disabling video.
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: ** Capability now set to : 0x10f (g723|gsm|ulaw|alaw|g729)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: -- Done with adding codecs to SDP
[Nov 16 09:27:10] DEBUG[5341] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=30)
[Nov 16 09:27:10] DEBUG[5341] chan_sip.c: Done building SDP. Settling with this capability: 0x10f (g723|gsm|ulaw|alaw|g729)
[Nov 16 09:27:10] DEBUG[5341] chan_zap.c: Requested indication 3 on channel Zap/3-1
[Nov 16 09:27:10] DEBUG[5342] app_queue.c: Device 'Zap/3' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Nov 16 09:27:10] DEBUG[5338] chan_sip.c: SIP response 180 to standard invite
[Nov 16 09:27:10] DEBUG[5338] devicestate.c: Notification of state change to be queued on device/channel SIP/101-081fd080
[Nov 16 09:27:10] DEBUG[5318] devicestate.c: No provider found, checking channel drivers for SIP - 101
[Nov 16 09:27:10] DEBUG[5318] chan_sip.c: Checking device state for peer 101
[Nov 16 09:27:10] DEBUG[5318] devicestate.c: Changing state for SIP/101 - state 1 (Not in use)
[Nov 16 09:27:10] DEBUG[5343] app_queue.c: Device 'SIP/101' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Nov 16 09:27:11] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:11] DEBUG[5341] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0)
[Nov 16 09:27:13] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:13] DEBUG[5341] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0)
[Nov 16 09:27:13] DEBUG[5341] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
[Nov 16 09:27:13] DEBUG[5341] chan_zap.c: Ring detected
[Nov 16 09:27:15] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:15] DEBUG[5341] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0)
[Nov 16 09:27:16] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:16] DEBUG[5341] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0)
[Nov 16 09:27:16] DEBUG[5341] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
[Nov 16 09:27:16] DEBUG[5341] chan_zap.c: Ring detected
[Nov 16 09:27:18] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:18] DEBUG[5341] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0)
[Nov 16 09:27:19] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:19] DEBUG[5341] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0)
[Nov 16 09:27:19] DEBUG[5341] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
[Nov 16 09:27:19] DEBUG[5341] chan_zap.c: Ring detected
[Nov 16 09:27:21] DEBUG[5341] chan_zap.c: Exception on 13, channel 3
[Nov 16 09:27:21] DEBUG[5341] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0)
[Nov 16 09:27:22] DEBUG[5341] dsp.c: ast_dsp_busydetect detected busy, avgtone: 1405, avgsilence 1735
[Nov 16 09:27:22] DEBUG[5341] dsp.c: Requesting Hangup because the busy tone was detected on channel Zap/3-1
[Nov 16 09:27:22] DEBUG[5341] rtp.c: Channel 'Zap/3-1' has no RTP, not doing anything
[Nov 16 09:27:22] DEBUG[5341] channel.c: Hanging up channel 'SIP/101-081fd080'
[Nov 16 09:27:22] DEBUG[5341] chan_sip.c: Hangup call SIP/101-081fd080, SIP callid 5be2625a181c3a076e7448c14355613f@192.168.8.2)
[Nov 16 09:27:22] DEBUG[5341] chan_sip.c: Updating call counter for outgoing call
[Nov 16 09:27:22] DEBUG[5341] chan_sip.c: Hanging up channel in state Ringing (not UP)
[Nov 16 09:27:22] DEBUG[5341] app_dial.c: Exiting with DIALSTATUS=CANCEL.
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Spawn extension (incoming-pstn,s,1) exited non-zero on 'Zap/3-1'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '"ATC" <543000>'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '543000'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 's'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'incoming-pstn'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'Zap/3-1'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'SIP/101-081fd080'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'Dial'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'SIP/101|20|Ttr'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '2006-11-16 09:27:10'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is ''
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '2006-11-16 09:27:22'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '12'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '0'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'NO ANSWER'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is 'DOCUMENTATION'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is ''
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is '1163647630.0'
[Nov 16 09:27:22] DEBUG[5341] pbx.c: Function result is ''
[Nov 16 09:27:22] DEBUG[5341] channel.c: Hanging up channel 'Zap/3-1'
[Nov 16 09:27:22] DEBUG[5341] chan_zap.c: zt_hangup(Zap/3-1)
[Nov 16 09:27:22] DEBUG[5341] chan_zap.c: Hangup: channel: 3 index = 0, normal = 13, callwait = -1, thirdcall = -1
[Nov 16 09:27:22] DEBUG[5341] chan_zap.c: disabled echo cancellation on channel 3
[Nov 16 09:27:22] DEBUG[5341] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1
[Nov 16 09:27:22] DEBUG[5341] chan_zap.c: Updated conferencing on 3, with 0 conference users
[Nov 16 09:27:22] DEBUG[5341] devicestate.c: Notification of state change to be queued on device/channel Zap/3-1
[Nov 16 09:27:22] DEBUG[5318] devicestate.c: No provider found, checking channel drivers for SIP - 101
[Nov 16 09:27:22] DEBUG[5318] chan_sip.c: Checking device state for peer 101
[Nov 16 09:27:22] DEBUG[5318] devicestate.c: Changing state for SIP/101 - state 1 (Not in use)
[Nov 16 09:27:22] DEBUG[5318] devicestate.c: No provider found, checking channel drivers for Zap - 3
[Nov 16 09:27:22] DEBUG[5318] devicestate.c: Changing state for Zap/3 - state 0 (Unknown)
[Nov 16 09:27:22] DEBUG[5344] app_queue.c: Device 'SIP/101' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Nov 16 09:27:22] DEBUG[5345] app_queue.c: Device 'Zap/3' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Nov 16 09:27:22] DEBUG[5338] chan_sip.c: Updating call counter for outgoing call
[Nov 16 09:27:26] DEBUG[5321] chan_zap.c: Monitor doohicky got event Ring/Answered on channel 3
[Nov 16 09:27:26] DEBUG[5321] dsp.c: dsp busy pattern set to 0,0
[Nov 16 09:27:26] DEBUG[5321] devicestate.c: Notification of state change to be queued on device/channel Zap/3-1
[Nov 16 09:27:26] DEBUG[5318] devicestate.c: No provider found, checking channel drivers for Zap - 3
[Nov 16 09:27:26] DEBUG[5318] devicestate.c: Changing state for Zap/3 - state 2 (In use)
[Nov 16 09:27:26] DEBUG[5346] pbx.c: Launching 'Dial'

и опять начался дозвон на 101