[Aug 24 10:09:57] VERBOSE[8493] config.c:   == Parsing '/etc/asterisk/logger.conf': [Aug 24 10:09:57] DEBUG[8493] config.c: Parsing /etc/asterisk/logger.conf
[Aug 24 10:09:57] VERBOSE[8493] config.c:   == Found
[Aug 24 10:09:57] VERBOSE[8493] logger.c:  Asterisk Event Logger restarted
[Aug 24 10:09:57] VERBOSE[8493] logger.c:  Asterisk Queue Logger restarted
[Aug 24 10:10:44] DEBUG[8485] chan_dahdi.c: Monitor doohicky got event Ring/Answered on channel 63
[Aug 24 10:10:44] DEBUG[8485] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Aug 24 10:10:44] DEBUG[8485] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Aug 24 10:10:44] DEBUG[8485] devicestate.c: device 'DAHDI/63-1' state '2'
[Aug 24 10:10:44] VERBOSE[8633] chan_dahdi.c:     -- Starting simple switch on 'DAHDI/63-1'
[Aug 24 10:10:46] DTMF[8633] channel.c: DTMF begin '8' received on DAHDI/63-1
[Aug 24 10:10:46] DTMF[8633] channel.c: DTMF begin ignored '8' on DAHDI/63-1
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: DTMF digit: 8 on DAHDI/63-1
[Aug 24 10:10:47] DTMF[8633] channel.c: DTMF end '8' received on DAHDI/63-1, duration 0 ms
[Aug 24 10:10:47] DTMF[8633] channel.c: DTMF end accepted without begin '8' on DAHDI/63-1
[Aug 24 10:10:47] DTMF[8633] channel.c: DTMF end passthrough '8' on DAHDI/63-1
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: waitfordigit returned '8' (56), timeout = 0
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: No echo cancellation requested
[Aug 24 10:10:47] DEBUG[8633] pbx.c: Launching 'Dial'
[Aug 24 10:10:47] VERBOSE[8633] pbx.c:     -- Executing [8@from-internal:1] Dial("DAHDI/63-1", "DAHDI/65/099690773,,Ttr") in new stack
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Using channel 65
[Aug 24 10:10:47] DEBUG[8633] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Aug 24 10:10:47] DEBUG[8633] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Aug 24 10:10:47] DEBUG[8633] dsp.c: dsp busy pattern set to 0,0
[Aug 24 10:10:47] DEBUG[8633] devicestate.c: device 'DAHDI/65-1' state '2'
[Aug 24 10:10:47] DEBUG[8633] rtp.c: Channel 'DAHDI/65-1' has no RTP, not doing anything
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable DIALEDTIME.
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable ANSWEREDTIME.
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable DIALEDPEERNAME.
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable DIALEDPEERNUMBER.
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable DIALSTATUS.
[Aug 24 10:10:47] DEBUG[8633] channel.c: Not copying variable TRANSFERCAPABILITY.
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Dialing '099690773'
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Deferring dialing...
[Aug 24 10:10:47] VERBOSE[8633] app_dial.c:     -- Called 65/099690773
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Requested indication 3 on channel DAHDI/63-1
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 63
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: Changing state for DAHDI/63 - state 6 (Ringing)
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: device 'DAHDI/63' state '6'
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 65
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: Changing state for DAHDI/65 - state 2 (In use)
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: device 'DAHDI/65' state '2'
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 63
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: Changing state for DAHDI/63 - state 6 (Ringing)
[Aug 24 10:10:47] DEBUG[8480] devicestate.c: device 'DAHDI/63' state '6'
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Exception on 15, channel 65
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Got event Hook Transition Complete(12) on channel 65 (index 0)
[Aug 24 10:10:47] DEBUG[8633] chan_dahdi.c: Sent deferred digit string: T099690773w
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Exception on 15, channel 65
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Got event Dial Complete(9) on channel 65 (index 0)
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: No echo cancellation requested
[Aug 24 10:10:50] VERBOSE[8633] app_dial.c:     -- DAHDI/65-1 answered DAHDI/63-1
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Requested indication -1 on channel DAHDI/63-1
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Requested indication 3 on channel DAHDI/65-1
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Took DAHDI/63-1 off hook
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Requested indication -1 on channel DAHDI/63-1
[Aug 24 10:10:50] DEBUG[8633] features.c: bridge answer set, chan answer set
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:10:50] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 65
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: Changing state for DAHDI/65 - state 2 (In use)
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: device 'DAHDI/65' state '2'
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 63
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: Changing state for DAHDI/63 - state 2 (In use)
[Aug 24 10:10:50] DEBUG[8480] devicestate.c: device 'DAHDI/63' state '2'
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF begin '#' received on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF begin passthrough '#' on DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Got DTMF begin on channel (DAHDI/65-1)
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Bridge stops bridging channels DAHDI/63-1 and DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] dsp.c: DTMF Detected - Reset busydetector
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: DTMF digit: # on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end '#' received on DAHDI/65-1, duration 0 ms
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end accepted with begin '#' on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end passthrough '#' on DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Got DTMF end on channel (DAHDI/65-1)
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Bridge stops bridging channels DAHDI/63-1 and DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] features.c: Feature interpret: chan=DAHDI/63-1, peer=DAHDI/65-1, code=#, sense=2, features=2, dynamic=#
[Aug 24 10:11:00] DEBUG[8633] features.c: Set time limit to 9500
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF begin '1' received on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF begin passthrough '1' on DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Got DTMF begin on channel (DAHDI/65-1)
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Bridge stops bridging channels DAHDI/63-1 and DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] dsp.c: DTMF Detected - Reset busydetector
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: DTMF digit: 1 on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end '1' received on DAHDI/65-1, duration 0 ms
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end accepted with begin '1' on DAHDI/65-1
[Aug 24 10:11:00] DTMF[8633] channel.c: DTMF end passthrough '1' on DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Got DTMF end on channel (DAHDI/65-1)
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 20 on channel DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Bridge stops bridging channels DAHDI/63-1 and DAHDI/65-1
[Aug 24 10:11:00] DEBUG[8633] features.c: Feature interpret: chan=DAHDI/63-1, peer=DAHDI/65-1, code=#1, sense=2, features=2, dynamic=#
[Aug 24 10:11:00] DEBUG[8633] features.c: Feature detected: fname=Blind Transfer sname=blindxfer exten=#1
[Aug 24 10:11:00] DEBUG[8633] chan_dahdi.c: Requested indication 16 on channel DAHDI/63-1
[Aug 24 10:11:00] DEBUG[8633] channel.c: Set channel DAHDI/63-1 to write format slin
[Aug 24 10:11:00] VERBOSE[8633] res_musiconhold.c:     -- Started music on hold, class 'default', on channel 'DAHDI/63-1'
[Aug 24 10:11:00] VERBOSE[8633] file.c:     -- <DAHDI/65-1> Playing 'pbx-transfer.ulaw' (language 'es')
[Aug 24 10:11:00] DEBUG[8633] channel.c: Set channel DAHDI/65-1 to write format slin
[Aug 24 10:11:06] DEBUG[8633] channel.c: Set channel DAHDI/65-1 to write format ulaw
[Aug 24 10:11:06] VERBOSE[8633] features.c:     -- Unable to find extension '' in context 'from-pstn'
[Aug 24 10:11:06] WARNING[8633] file.c: File pbx-invalid does not exist in any format
[Aug 24 10:11:06] WARNING[8633] file.c: Unable to open pbx-invalid (format 0x4 (ulaw)): No such file or directory
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Requested indication 17 on channel DAHDI/63-1
[Aug 24 10:11:06] DEBUG[8633] channel.c: Set channel DAHDI/63-1 to write format ulaw
[Aug 24 10:11:06] VERBOSE[8633] res_musiconhold.c:     -- Stopped music on hold on DAHDI/63-1
[Aug 24 10:11:06] DEBUG[8633] cdr.c: Dropping CDR !
[Aug 24 10:11:06] DEBUG[8633] channel.c: Hanging up channel 'DAHDI/65-1'
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: dahdi_hangup(DAHDI/65-1)
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Hangup: channel: 65 index = 0, normal = 15, callwait = -1, thirdcall = -1
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/65-1
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Updated conferencing on 65, with 0 conference users
[Aug 24 10:11:06] VERBOSE[8633] chan_dahdi.c:     -- Hungup 'DAHDI/65-1'
[Aug 24 10:11:06] DEBUG[8633] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Aug 24 10:11:06] DEBUG[8633] pbx.c: Spawn extension (from-internal,8,1) exited non-zero on 'DAHDI/63-1'
[Aug 24 10:11:06] VERBOSE[8633] pbx.c:   == Spawn extension (from-internal, 8, 1) exited non-zero on 'DAHDI/63-1'
[Aug 24 10:11:06] DEBUG[8633] channel.c: Soft-Hanging up channel 'DAHDI/63-1'
[Aug 24 10:11:06] DEBUG[8633] channel.c: Hanging up channel 'DAHDI/63-1'
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: dahdi_hangup(DAHDI/63-1)
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Hangup: channel: 63 index = 0, normal = 13, callwait = -1, thirdcall = -1
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/63-1
[Aug 24 10:11:06] DEBUG[8633] chan_dahdi.c: Updated conferencing on 63, with 0 conference users
[Aug 24 10:11:06] VERBOSE[8633] chan_dahdi.c:     -- Hungup 'DAHDI/63-1'
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 65
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: Changing state for DAHDI/65 - state 0 (Unknown)
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: device 'DAHDI/65' state '0'
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: No provider found, checking channel drivers for DAHDI - 63
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: Changing state for DAHDI/63 - state 0 (Unknown)
[Aug 24 10:11:06] DEBUG[8480] devicestate.c: device 'DAHDI/63' state '0'
[Aug 24 10:11:06] DEBUG[8485] chan_dahdi.c: Monitor doohicky got event Hook Transition Complete on channel 63
[Aug 24 10:11:10] DEBUG[8485] chan_dahdi.c: Monitor doohicky got event On hook on channel 63