*CLI>
*CLI> [Jun 27 18:24:08] DEBUG[24378]: chan_sip.c:2041 __sip_autodestruct: Auto destroying SIP dialog '2349515074-1939230419@192.168.3.178'
-- Accepting call from '' to '401' on channel 0/11, span 1
[Jun 27 18:24:10] DEBUG[24376]: chan_zap.c:1436 zt_enable_ec: No echo cancellation requested
[Jun 27 18:24:10] DEBUG[26025]: pbx.c:1815 pbx_extension_helper: Launching 'Dial'
-- Executing [401@from-ido:1] Dial("Zap/11-1", "SIP/401|30") in new stack
[Jun 27 18:24:10] DEBUG[26025]: chan_sip.c:15482 sip_request_call: Asked to create a SIP channel with formats: 0x8 (alaw)
[Jun 27 18:24:10] DEBUG[26025]: chan_sip.c:4357 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Jun 27 18:24:10] DEBUG[26025]: chan_sip.c:2606 do_setnat: Setting NAT on RTP to On
[Jun 27 18:24:10] DEBUG[26025]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Zap/11-1' has no RTP, not doing anything
[Jun 27 18:24:10] DEBUG[26025]: channel.c:3413 ast_channel_inherit_variables: Not copying variable STACK-from-ido-401-1.
[Jun 27 18:24:10] DEBUG[26025]: channel.c:3413 ast_channel_inherit_variables: Not copying variable CALLEDTON.
[Jun 27 18:24:10] DEBUG[26025]: channel.c:3413 ast_channel_inherit_variables: Not copying variable ANI2.
[Jun 27 18:24:10] DEBUG[26025]: channel.c:3413 ast_channel_inherit_variables: Not copying variable TRANSFERCAPABILITY.
[Jun 27 18:24:10] DEBUG[26025]: chan_sip.c:2861 sip_call: Outgoing Call for 401
-- Called 401
[Jun 27 18:24:10] DEBUG[24378]: chan_sip.c:2162 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '20775c683a2918ae24b05ed1080a622d@87.229.134.14' Request 102: Found
[Jun 27 18:24:10] DEBUG[24378]: chan_sip.c:2162 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '20775c683a2918ae24b05ed1080a622d@87.229.134.14' Request 102: Found
-- SIP/401-08af6c98 is ringing
[Jun 27 18:24:10] DEBUG[26025]: rtp.c:1484 ast_rtp_early_bridge: Channel 'Zap/11-1' has no RTP, not doing anything
[Jun 27 18:24:10] DEBUG[26025]: chan_zap.c:4993 zt_indicate: Requested indication 3 on channel Zap/11-1
-- Channel 0/11, span 1 got hangup request, cause 16
[Jun 27 18:24:33] DEBUG[24378]: chan_sip.c:4357 sip_alloc: Allocating new SIP dialog for 2349515074-1939230419@192.168.3.178 - REGISTER (No RTP)
-- Nobody picked up in 30000 ms
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:4993 zt_indicate: Requested indication -1 on channel Zap/11-1
[Jun 27 18:24:42] DEBUG[26025]: rtp.c:1484 ast_rtp_early_bridge: Channel 'Zap/11-1' has no RTP, not doing anything
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 's'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'from-ido-sip'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'SIP/401-08af6c98'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 18:24:10'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 18:24:42'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '32'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '0'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '1182954250.15'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: channel.c:1727 ast_hangup: Hanging up channel 'SIP/401-08af6c98'
[Jun 27 18:24:42] DEBUG[26025]: chan_sip.c:3346 sip_hangup: Hangup call SIP/401-08af6c98, SIP callid 20775c683a2918ae24b05ed1080a622d@87.229.134.14)
[Jun 27 18:24:42] DEBUG[26025]: chan_sip.c:2102 __sip_ack: Acked pending invite 102
[Jun 27 18:24:42] DEBUG[26025]: chan_sip.c:2120 __sip_ack: Stopping retransmission on '20775c683a2918ae24b05ed1080a622d@87.229.134.14' of Request 102: Match Not Found
[Jun 27 18:24:42] DEBUG[26025]: app_dial.c:1691 dial_exec_full: Exiting with DIALSTATUS=NOANSWER.
== Auto fallthrough, channel 'Zap/11-1' status is 'NOANSWER'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '401'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'from-ido'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'Zap/11-1'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'SIP/401-08af6c98'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'Dial'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'SIP/401|30'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 18:24:10'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 18:24:42'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '32'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '0'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '1182954250.14'
[Jun 27 18:24:42] DEBUG[26025]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 18:24:42] DEBUG[26025]: channel.c:1727 ast_hangup: Hanging up channel 'Zap/11-1'
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2411 zt_hangup: zt_hangup(Zap/11-1)
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2956 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/11-1
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2445 zt_hangup: Hangup: channel: 11 index = 0, normal = 28, callwait = -1, thirdcall = -1
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2599 zt_hangup: Not yet hungup... Calling hangup once with icause, and clearing call
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2873 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/11-1
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:1402 update_conf: Updated conferencing on 11, with 0 conference users
[Jun 27 18:24:42] DEBUG[26025]: chan_zap.c:2952 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/11-1