[Oct 19 09:17:32] VERBOSE[419] sig_pri.c:     -- Accepting overlap call from '' to '<unspecified>' on channel 0/15, span 2
[Oct 19 09:17:32] VERBOSE[436] sig_pri.c:     -- Starting simple switch on 'DAHDI/i2/-7'
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Enabled echo cancellation on channel 46
[Oct 19 09:17:38] DEBUG[398] devicestate.c: Changing state for DAHDI/i2/ - state 2 (In use)
[Oct 19 09:17:38] DEBUG[398] devicestate.c: device 'DAHDI/i2/' state '2'
[Oct 19 09:17:38] DEBUG[436] pbx.c: Function result is 'DAHDI/G1'
[Oct 19 09:17:38] DEBUG[436] pbx.c: Launching 'Dial'
[Oct 19 09:17:38] VERBOSE[436] pbx.c:     -- Executing [18xx@in-from-pbx-ext:1] Dial("DAHDI/i2/-7", "DAHDI/G1/18xx") in new stack
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Using channel 15
[Oct 19 09:17:38] DEBUG[436] sig_pri.c: sig_pri_request 15
[Oct 19 09:17:38] DEBUG[436] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Oct 19 09:17:38] DEBUG[436] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Oct 19 09:17:38] DEBUG[398] devicestate.c: Changing state for DAHDI/i1/18xx - state 2 (In use)
[Oct 19 09:17:38] DEBUG[398] devicestate.c: device 'DAHDI/i1/18xx' state '2'
[Oct 19 09:17:38] DEBUG[436] rtp_engine.c: Can't find native functions for channel 'DAHDI/i1/18xx-3'
[Oct 19 09:17:38] DEBUG[436] rtp_engine.c: Seeded SDP of 'DAHDI/i1/18xx-3' with that of 'DAHDI/i2/-7'
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable DIALEDTIME.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable ANSWEREDTIME.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable DIALEDPEERNAME.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable DIALEDPEERNUMBER.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable DIALSTATUS.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable PRIREDIRECTREASON.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable CALLEDTON.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable ANI2.
[Oct 19 09:17:38] DEBUG[436] channel.c: Not copying variable TRANSFERCAPABILITY.
[Oct 19 09:17:38] DEBUG[436] sig_pri.c: CALLER NAME:  NUM:
[Oct 19 09:17:38] VERBOSE[436] sig_pri.c:     -- Requested transfer capability: 0x00 - SPEECH
[Oct 19 09:17:38] DEBUG[398] devicestate.c: Changing state for DAHDI/i1/18xx - state 2 (In use)
[Oct 19 09:17:38] DEBUG[398] devicestate.c: device 'DAHDI/i1/18xx' state '2'
[Oct 19 09:17:38] VERBOSE[436] app_dial.c:     -- Called DAHDI/G1/18xx
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Dropping frame since I'm still dialing on DAHDI/i1/18xx-3...
[Oct 19 09:17:38] VERBOSE[436] app_dial.c:     -- DAHDI/i1/18xx-3 is making progress passing it to DAHDI/i2/-7
[Oct 19 09:17:38] DEBUG[436] chan_dahdi.c: Requested indication 14 on channel DAHDI/i2/-7
[Oct 19 09:17:38] DEBUG[436] sig_pri.c: Received AST_CONTROL_PROGRESS on DAHDI/i2/-7
[Oct 19 09:17:43] DEBUG[418] sig_pri.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/15 span 1
[Oct 19 09:17:43] VERBOSE[436] app_dial.c:     -- DAHDI/i1/18xx-3 is proceeding passing it to DAHDI/i2/-7
[Oct 19 09:17:43] DEBUG[436] chan_dahdi.c: Requested indication 15 on channel DAHDI/i2/-7
[Oct 19 09:17:43] DEBUG[436] sig_pri.c: Received AST_CONTROL_PROCEEDING on DAHDI/i2/-7
[Oct 19 09:17:45] VERBOSE[418] sig_pri.c:     -- Span 1: Channel 0/15 got hangup request, cause 34
[Oct 19 09:17:45] VERBOSE[436] app_dial.c:     -- DAHDI/i1/18xx-3 is circuit-busy
[Oct 19 09:17:45] DEBUG[436] channel.c: Hanging up channel 'DAHDI/i1/18xx-3'
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: dahdi_hangup(DAHDI/i1/18xx-3)
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i1/18xx-3
[Oct 19 09:17:45] DEBUG[436] sig_pri.c: sig_pri_hangup 15
[Oct 19 09:17:45] DEBUG[436] sig_pri.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/i1/18xx-3
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Updated conferencing on 15, with 0 conference users
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/i1/18xx-3
[Oct 19 09:17:45] VERBOSE[436] chan_dahdi.c:     -- Hungup 'DAHDI/i1/18xx-3'
[Oct 19 09:17:45] DEBUG[398] devicestate.c: Changing state for DAHDI/i1/18xx - state 0 (Unknown)
[Oct 19 09:17:45] DEBUG[398] devicestate.c: device 'DAHDI/i1/18xx' state '0'
[Oct 19 09:17:45] VERBOSE[436] app_dial.c:   == Everyone is busy/congested at this time (1:0/1/0)
[Oct 19 09:17:45] DEBUG[436] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
[Oct 19 09:17:45] DEBUG[436] pbx.c: Launching 'Hangup'
[Oct 19 09:17:45] VERBOSE[436] pbx.c:     -- Executing [18xx@in-from-pbx-ext:2] Hangup("DAHDI/i2/-7", "") in new stack
[Oct 19 09:17:45] DEBUG[436] pbx.c: Spawn extension (in-from-pbx-ext,18xx,2) exited non-zero on 'DAHDI/i2/-7'
[Oct 19 09:17:45] VERBOSE[436] pbx.c:   == Spawn extension (in-from-pbx-ext, 18xx, 2) exited non-zero on 'DAHDI/i2/-7'
[Oct 19 09:17:45] DEBUG[436] channel.c: Soft-Hanging up channel 'DAHDI/i2/-7'
[Oct 19 09:17:45] DEBUG[436] channel.c: Hanging up channel 'DAHDI/i2/-7'
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: dahdi_hangup(DAHDI/i2/-7)
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i2/-7
[Oct 19 09:17:45] DEBUG[436] sig_pri.c: sig_pri_hangup 46
[Oct 19 09:17:45] DEBUG[436] sig_pri.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Disabled echo cancellation on channel 46
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/i2/-7
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Updated conferencing on 46, with 0 conference users
[Oct 19 09:17:45] DEBUG[436] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/i2/-7
[Oct 19 09:17:45] VERBOSE[436] chan_dahdi.c:     -- Hungup 'DAHDI/i2/-7'
[Oct 19 09:17:45] DEBUG[398] devicestate.c: Changing state for DAHDI/i2/ - state 0 (Unknown)
[Oct 19 09:17:45] DEBUG[398] devicestate.c: device 'DAHDI/i2/' state '0'
[Oct 19 09:17:49] DEBUG[419] sig_pri.c: Span 2: SETUP requested unavailable channel 0/15.  Attempting to renegotiate.