[May 19 06:21:06] DEBUG[2354] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[May 19 06:21:06] DEBUG[2354] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[May 19 06:21:06] DEBUG[2367] pbx.c: Launching 'Macro'
[May 19 06:21:06] DEBUG[2367] pbx.c: Launching 'Macro'
[May 19 06:21:06] DEBUG[2367] pbx.c: Launching 'Wait'
[May 19 06:21:06] DEBUG[2354] chan_dahdi.c: Enabled echo cancellation on channel 1
[May 19 06:21:06] DEBUG[2329] devicestate.c: Processing device state change for 'DAHDI/1-1'
[May 19 06:21:06] DEBUG[2329] devicestate.c: Adding per-server state of 'In use' for 'DAHDI/1-1'
[May 19 06:21:06] DEBUG[2329] devicestate.c: Aggregate devstate result is 2
[May 19 06:21:06] DEBUG[2329] devicestate.c: Aggregate state for device 'DAHDI/1-1' has changed to 'In use'
[May 19 06:21:06] DEBUG[2354] chan_dahdi.c: Echo cancellation already on
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Wait
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Set'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Set
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Macro'
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'NoOp'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: NoOp
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Goto'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Goto
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'NoOp'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: NoOp
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Macro
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Set'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Set
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Set'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Set
[May 19 06:21:08] DEBUG[2367] pbx.c: Expression result is '0'
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'GotoIf'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: GotoIf
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'NoOp'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: NoOp
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Macro
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Answer'
[May 19 06:21:08] DEBUG[2330] channel.c: Avoiding initial deadlock for channel '0x7fe7ac002a50'
[May 19 06:21:08] DEBUG[2330] channel.c: Avoiding initial deadlock for channel '0x7fe7ac002a50'
[May 19 06:21:08] DEBUG[2367] chan_dahdi.c: Requested indication -1 on channel DAHDI/1-1
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Answer
[May 19 06:21:08] DEBUG[2367] pbx.c: Expression result is '1'
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'GotoIf'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: GotoIf
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'Goto'
[May 19 06:21:08] DEBUG[2367] app_macro.c: Executed application: Goto
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'AGI'
[May 19 06:21:08] DEBUG[2329] devicestate.c: Processing device state change for 'DAHDI/1'
[May 19 06:21:08] DEBUG[2329] devicestate.c: Adding per-server state of 'In use' for 'DAHDI/1'
[May 19 06:21:08] DEBUG[2329] devicestate.c: Aggregate devstate result is 2
[May 19 06:21:08] DEBUG[2329] devicestate.c: Aggregate state for device 'DAHDI/1' has changed to 'In use'
[May 19 06:21:08] VERBOSE[2367] res_agi.c: open-iglou-main-menu.agi: Using status = open
[May 19 06:21:08] ERROR[2367] utils.c: write() returned error: Broken pipe
[May 19 06:21:08] DEBUG[2367] pbx.c: Expression result is '0'
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'GotoIf'
[May 19 06:21:08] DEBUG[2367] pbx.c: Launching 'BackGround'
[May 19 06:21:08] DEBUG[2367] channel.c: Set channel DAHDI/1-1 to write format gsm
[May 19 06:21:08] DEBUG[2367] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: DTMF digit: 1 on DAHDI/1-1
[May 19 06:21:16] DEBUG[2367] channel.c: Set channel DAHDI/1-1 to write format ulaw
[May 19 06:21:16] DEBUG[2367] pbx.c: Oooh, got something to jump out with ('1')!
[May 19 06:21:16] WARNING[2367] chan_dahdi.c: Cannot handle frames in 2 format
[May 19 06:21:16] WARNING[2367] file.c: Failed to write frame
[May 19 06:21:16] DEBUG[2367] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[May 19 06:21:16] DEBUG[2367] channel.c: Soft-Hanging up channel 'DAHDI/1-1'
[May 19 06:21:16] DEBUG[2367] channel.c: Hanging up channel 'DAHDI/1-1'
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: dahdi_hangup(DAHDI/1-1)
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Hangup: channel: 1 index = 0, normal = 12, callwait = -1, thirdcall = -1
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Disabled echo cancellation on channel 1
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/1-1
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Updated conferencing on 1, with 0 conference users
[May 19 06:21:16] DEBUG[2367] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1
[May 19 06:21:16] DEBUG[2367] cdr_pgsql.c: inserting a CDR record.
[May 19 06:21:16] DEBUG[2329] devicestate.c: Processing device state change for 'DAHDI/1'
[May 19 06:21:16] DEBUG[2329] devicestate.c: Adding per-server state of 'Not in use' for 'DAHDI/1'
[May 19 06:21:16] DEBUG[2329] devicestate.c: Aggregate devstate result is 1
[May 19 06:21:16] DEBUG[2329] devicestate.c: Aggregate state for device 'DAHDI/1' has changed to 'Not in use'