*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>     -- Accepting call from '' to '402' on channel 0/7, span 1
[Jun 27 20:43:36] DEBUG[7650]: chan_zap.c:1436 zt_enable_ec: No echo cancellation requested
[Jun 27 20:43:36] DEBUG[7700]: pbx.c:1815 pbx_extension_helper: Launching 'Dial'
    -- Executing [402@from-ido:1] Dial("Zap/7-1", "SIP/402|10") in new stack
[Jun 27 20:43:36] DEBUG[7700]: chan_sip.c:15482 sip_request_call: Asked to create a SIP channel with formats: 0x8 (alaw)
[Jun 27 20:43:36] DEBUG[7700]: chan_sip.c:4357 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Jun 27 20:43:36] DEBUG[7700]: chan_sip.c:2606 do_setnat: Setting NAT on RTP to On
[Jun 27 20:43:36] DEBUG[7700]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Zap/7-1' has no RTP, not doing anything
[Jun 27 20:43:36] DEBUG[7700]: channel.c:3413 ast_channel_inherit_variables: Not copying variable STACK-from-ido-402-1.
[Jun 27 20:43:36] DEBUG[7700]: channel.c:3413 ast_channel_inherit_variables: Not copying variable CALLEDTON.
[Jun 27 20:43:36] DEBUG[7700]: channel.c:3413 ast_channel_inherit_variables: Not copying variable ANI2.
[Jun 27 20:43:36] DEBUG[7700]: channel.c:3413 ast_channel_inherit_variables: Not copying variable TRANSFERCAPABILITY.
[Jun 27 20:43:36] DEBUG[7700]: chan_sip.c:2861 sip_call: Outgoing Call for 402
    -- Called 402
[Jun 27 20:43:37] DEBUG[7652]: chan_sip.c:2162 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' Request 102: Found
[Jun 27 20:43:38] DEBUG[7652]: chan_sip.c:2162 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' Request 102: Found
[Jun 27 20:43:38] DEBUG[7652]: chan_sip.c:2162 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' Request 102: Found
    -- SIP/402-091ded78 is ringing
[Jun 27 20:43:38] DEBUG[7700]: rtp.c:1484 ast_rtp_early_bridge: Channel 'Zap/7-1' has no RTP, not doing anything
[Jun 27 20:43:38] DEBUG[7700]: chan_zap.c:4993 zt_indicate: Requested indication 3 on channel Zap/7-1
[Jun 27 20:43:38] DEBUG[7645]: channel.c:1058 channel_find_locked: Avoiding initial deadlock for channel '0x91e54d8'
    -- Channel 0/7, span 1 got hangup request, cause 16
    -- Nobody picked up in 10000 ms
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:4993 zt_indicate: Requested indication -1 on channel Zap/7-1
[Jun 27 20:43:47] DEBUG[7700]: rtp.c:1484 ast_rtp_early_bridge: Channel 'Zap/7-1' has no RTP, not doing anything
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 's'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'from-ido-sip'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'SIP/402-091ded78'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 20:43:36'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 20:43:47'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '11'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '0'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '1182962616.7'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: channel.c:1727 ast_hangup: Hanging up channel 'SIP/402-091ded78'
[Jun 27 20:43:47] DEBUG[7700]: chan_sip.c:3346 sip_hangup: Hangup call SIP/402-091ded78, SIP callid 2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14)
[Jun 27 20:43:47] DEBUG[7700]: chan_sip.c:2102 __sip_ack: Acked pending invite 102
[Jun 27 20:43:47] DEBUG[7700]: chan_sip.c:2120 __sip_ack: Stopping retransmission on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' of Request 102: Match Not Found
[Jun 27 20:43:47] DEBUG[7700]: app_dial.c:1691 dial_exec_full: Exiting with DIALSTATUS=NOANSWER.
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1815 pbx_extension_helper: Launching 'Hangup'
    -- Executing [402@from-ido:2] Hangup("Zap/7-1", "") in new stack
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:2413 __ast_pbx_run: Spawn extension (from-ido,402,2) exited non-zero on 'Zap/7-1'
  == Spawn extension (from-ido, 402, 2) exited non-zero on 'Zap/7-1'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1815 pbx_extension_helper: Launching 'Hangup'
    -- Executing [h@from-ido:1] Hangup("Zap/7-1", "") in new stack
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:2534 __ast_pbx_run: Spawn extension (from-ido,h,1) exited non-zero on 'Zap/7-1'
  == Spawn extension (from-ido, h, 1) exited non-zero on 'Zap/7-1'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '402'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'from-ido'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'Zap/7-1'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'SIP/402-091ded78'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'Hangup'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 20:43:36'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '2007-06-27 20:43:47'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '11'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '0'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is '1182962616.6'
[Jun 27 20:43:47] DEBUG[7700]: pbx.c:1668 pbx_substitute_variables_helper_full: Function result is ''
[Jun 27 20:43:47] DEBUG[7700]: channel.c:1727 ast_hangup: Hanging up channel 'Zap/7-1'
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2411 zt_hangup: zt_hangup(Zap/7-1)
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2956 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/7-1
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2445 zt_hangup: Hangup: channel: 7 index = 0, normal = 24, callwait = -1, thirdcall = -1
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2599 zt_hangup: Not yet hungup...  Calling hangup once with icause, and clearing call
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2873 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/7-1
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:1402 update_conf: Updated conferencing on 7, with 0 conference users
[Jun 27 20:43:47] DEBUG[7700]: chan_zap.c:2952 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/7-1
    -- Hungup 'Zap/7-1'
[Jun 27 20:43:47] DEBUG[7652]: chan_sip.c:2120 __sip_ack: Stopping retransmission on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' of Request 102: Match Not Found
[Jun 27 20:43:47] DEBUG[7652]: chan_sip.c:2120 __sip_ack: Stopping retransmission on '2ea0cf9c1f14f3625e0b89dc4a5f5f24@87.229.134.14' of Request 102: Match Found