Bueno, hice un debug.
Aca esta mi chandadhi
[channels]
language=es
callprogress=yes
busydetect=yes
busycount=3
busypattern=200,200
context=from-pstn
callwaiting=yes
usecallingpres=yes
threewaycalling=yes
usercallerid=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=yes
answeronpolarityswitch=yes
hanguponpolarityswitch=yes
txgain=5.0
rxgain=5.0
include => dahdi-channels.conf
Aca esta el debug:
[Sep 6 09:58:34] DEBUG[432] chan_dahdi.c: Monitor doohicky got event Polarity Reversal on channel 2
[Sep 6 09:58:34] DEBUG[432] sig_analog.c: channel (2) - signaling (5) - event (ANALOG_EVENT_POLARITY)
[Sep 6 09:58:34] DEBUG[432] chan_dahdi.c: Monitor doohicky got event Ring Begin on channel 2
[Sep 6 09:58:34] DEBUG[432] sig_analog.c: channel (2) - signaling (5) - event (ANALOG_EVENT_RINGBEGIN)
[Sep 6 09:58:35] DEBUG[432] chan_dahdi.c: Monitor doohicky got event Ring/Answered on channel 2
[Sep 6 09:58:35] DEBUG[432] sig_analog.c: channel (2) - signaling (5) - event (ANALOG_EVENT_RINGOFFHOOK)
[Sep 6 09:58:35] DEBUG[432] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Sep 6 09:58:35] DEBUG[432] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Sep 6 09:58:35] DEBUG[434] sig_analog.c: __analog_ss_thread 2
[Sep 6 09:58:35] VERBOSE[434] sig_analog.c: -- Starting simple switch on 'DAHDI/2-1'
[Sep 6 09:58:35] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 2
[Sep 6 09:58:35] DEBUG[389] devicestate.c: Changing state for DAHDI/2 - state 2 (In use)
[Sep 6 09:58:35] DEBUG[389] devicestate.c: device 'DAHDI/2' state '2'
[Sep 6 09:58:35] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - 29021381
[Sep 6 09:58:35] DEBUG[390] chan_sip.c: Checking device state for peer 29021381
[Sep 6 09:58:35] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - 29021381
[Sep 6 09:58:35] DEBUG[390] chan_iax2.c: Checking device state for device 29021381
[Sep 6 09:58:35] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 29021381? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:58:35] DEBUG[390] app_queue.c: Extension '29021381@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Sep 6 09:58:35] DEBUG[426] app_queue.c: Device 'DAHDI/2' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Sep 6 09:58:39] ERROR[434] callerid.c: No start bit found in fsk data.
[Sep 6 09:58:39] WARNING[434] chan_dahdi.c: Failed to decode CallerID
[Sep 6 09:58:40] DEBUG[434] pbx.c: Launching 'Answer'
[Sep 6 09:58:40] VERBOSE[434] pbx.c: -- Executing [s@from-pstn:1] Answer("DAHDI/2-1", "") in new stack
[Sep 6 09:58:40] DEBUG[434] sig_analog.c: analog_answer 2
[Sep 6 09:58:40] DEBUG[434] sig_analog.c: Took DAHDI/2-1 off hook
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: Enabled echo cancellation on channel 2
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: No echo training requested
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: Requested indication -1 on channel DAHDI/2-1
[Sep 6 09:58:40] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 2
[Sep 6 09:58:40] DEBUG[389] devicestate.c: Changing state for DAHDI/2 - state 2 (In use)
[Sep 6 09:58:40] DEBUG[389] devicestate.c: device 'DAHDI/2' state '2'
[Sep 6 09:58:40] DEBUG[426] app_queue.c: Device 'DAHDI/2' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - 29021381
[Sep 6 09:58:40] DEBUG[390] chan_sip.c: Checking device state for peer 29021381
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - 29021381
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: Checking device state for device 29021381
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 29021381? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:58:40] DEBUG[434] pbx.c: Launching 'Dial'
[Sep 6 09:58:40] VERBOSE[434] pbx.c: -- Executing [s@from-pstn:2] Dial("DAHDI/2-1", "DAHDI/7,22,m") in new stack
[Sep 6 09:58:40] DEBUG[434] sig_analog.c: analog_available 7
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: Using channel 7
[Sep 6 09:58:40] DEBUG[434] sig_analog.c: analog_request 7
[Sep 6 09:58:40] DEBUG[434] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Sep 6 09:58:40] DEBUG[434] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Sep 6 09:58:40] DEBUG[434] rtp_engine.c: Can't find native functions for channel 'DAHDI/7-1'
[Sep 6 09:58:40] DEBUG[434] rtp_engine.c: Seeded SDP of 'DAHDI/7-1' with that of 'DAHDI/2-1'
[Sep 6 09:58:40] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 7
[Sep 6 09:58:40] DEBUG[434] channel.c: Not copying variable DIALEDTIME.
[Sep 6 09:58:40] DEBUG[434] channel.c: Not copying variable ANSWEREDTIME.
[Sep 6 09:58:40] DEBUG[434] channel.c: Not copying variable DIALEDPEERNAME.
[Sep 6 09:58:40] DEBUG[434] channel.c: Not copying variable DIALEDPEERNUMBER.
[Sep 6 09:58:40] DEBUG[434] channel.c: Not copying variable DIALSTATUS.
[Sep 6 09:58:40] DEBUG[434] sig_analog.c: CALLING CID_NAME: CID_NUM::
[Sep 6 09:58:40] DEBUG[389] devicestate.c: Changing state for DAHDI/7 - state 6 (Ringing)
[Sep 6 09:58:40] DEBUG[389] devicestate.c: device 'DAHDI/7' state '6'
[Sep 6 09:58:40] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 7
[Sep 6 09:58:40] VERBOSE[434] app_dial.c: -- Called DAHDI/7
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - telLocutorio
[Sep 6 09:58:40] DEBUG[390] chan_sip.c: Checking device state for peer telLocutorio
[Sep 6 09:58:40] DEBUG[389] devicestate.c: Changing state for DAHDI/7 - state 6 (Ringing)
[Sep 6 09:58:40] VERBOSE[434] res_musiconhold.c: -- Started music on hold, class 'default', on DAHDI/2-1
[Sep 6 09:58:40] DEBUG[389] devicestate.c: device 'DAHDI/7' state '6'
[Sep 6 09:58:40] DEBUG[426] app_queue.c: Device 'DAHDI/7' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Sep 6 09:58:40] DEBUG[426] app_queue.c: Device 'DAHDI/7' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - telLocutorio
[Sep 6 09:58:40] DEBUG[434] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: Checking device state for device telLocutorio
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of telLocutorio? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: Requested indication 14 on channel DAHDI/2-1
[Sep 6 09:58:40] DEBUG[390] app_queue.c: Extension 'telLocutorio@default' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Sep 6 09:58:40] DEBUG[434] chan_dahdi.c: Received AST_CONTROL_PROGRESS on DAHDI/2-1
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - telLocutorio
[Sep 6 09:58:40] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:58:40] DEBUG[390] chan_sip.c: Checking device state for peer telLocutorio
[Sep 6 09:58:40] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - telLocutorio
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: Checking device state for device telLocutorio
[Sep 6 09:58:40] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of telLocutorio? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:58:40] DEBUG[434] channel.c: Set channel DAHDI/2-1 to write format slin
[Sep 6 09:58:40] DEBUG[434] res_musiconhold.c: DAHDI/2-1 Opened file 0 '/var/lib/asterisk/moh/macroform-the_simplicity'
[Sep 6 09:58:42] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:42] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:42] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:42] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:58:42] DEBUG[434] sig_analog.c: Sending callerid. CID_NAME: '' CID_NUM: ''
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: Starting cid spill
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 0 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 160 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 320 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 480 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 640 of 8867, res = -1
[Sep 6 09:58:42] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 640 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 640 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 800 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 800 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 960 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 960 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1120 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1120 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1280 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1280 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1440 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1440 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1600 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1600 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1760 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1760 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1920 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 1920 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2080 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2080 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2240 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2240 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2400 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2400 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2560 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2560 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2720 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2720 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2880 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 2880 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3040 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3040 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3200 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3200 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3360 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3360 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3520 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3520 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3680 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3680 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3840 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 3840 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4000 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4000 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4160 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4160 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4320 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4320 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4480 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4480 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4640 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4640 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4800 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4800 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4960 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 4960 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5120 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5120 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5280 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5280 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5440 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5440 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5600 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5600 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5760 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5760 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5920 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 5920 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6080 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6080 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6240 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6240 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6400 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6400 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6560 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6560 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6720 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6720 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6880 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 6880 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7040 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7040 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7200 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7200 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7360 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7360 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7520 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7520 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7680 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7680 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7840 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 7840 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 8000 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 8000 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 8160 of 8867, res = -1
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 8160 of 8867, res = 160
[Sep 6 09:58:42] DEBUG[434] chan_dahdi.c: writing callerid at pos 8320 of 8867, res = -1
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8320 of 8867, res = 160
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8480 of 8867, res = -1
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8480 of 8867, res = 160
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8640 of 8867, res = -1
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8640 of 8867, res = 160
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8800 of 8867, res = -1
[Sep 6 09:58:43] DEBUG[434] chan_dahdi.c: writing callerid at pos 8800 of 8867, res = 67
[Sep 6 09:58:45] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:45] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:45] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:45] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 7 (index 0)
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:58:46] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: analog_exception 2
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Exception on 14, channel 2
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: __analog_handle_event 2
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_POLARITY(11) on channel 2 (index 0)
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Ignore Hangup on polarity switch, channel 2
[Sep 6 09:58:46] DEBUG[434] sig_analog.c: Polarity Reversal event occured - DEBUG 2: channel 2, state 6, pol= 0, aonp= 0, honp= 0, pdelay= 600, tv= 1346936326925
[Sep 6 09:58:49] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:49] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:49] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:49] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 7 (index 0)
[Sep 6 09:58:51] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:51] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:51] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:51] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:58:51] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:58:52] DEBUG[398] chan_sip.c: Auto destroying SIP dialog '
4a3d5d5902bba423...@192.168.33.104'
[Sep 6 09:58:52] DEBUG[398] chan_sip.c: Destroying SIP dialog
4a3d5d5902bba423...@192.168.33.104[Sep 6 09:58:54] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:54] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:54] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:54] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 7 (index 0)
[Sep 6 09:58:55] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:55] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:55] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:55] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:58:55] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:58:56] DEBUG[409] chan_iax2.c: Allocate call number
[Sep 6 09:58:56] DEBUG[409] chan_iax2.c: ip callno count incremented to 2 for 68.169.49.253
[Sep 6 09:58:56] DEBUG[409] chan_iax2.c: Registration created on call 3320
[Sep 6 09:58:56] DEBUG[413] chan_iax2.c: schedule decrement of callno used for 68.169.49.253 in 60 seconds
[Sep 6 09:58:58] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:58:58] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:58:58] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:58:58] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 7 (index 0)
[Sep 6 09:59:00] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:59:00] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:59:00] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:59:00] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:59:00] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:59:03] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:59:03] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:59:03] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:59:03] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 7 (index 0)
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: analog_exception 7
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Exception on 16, channel 7
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: __analog_handle_event 7
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 7 (index 0)
[Sep 6 09:59:04] VERBOSE[434] app_dial.c: -- DAHDI/7-1 is ringing
[Sep 6 09:59:04] VERBOSE[434] app_dial.c: -- Nobody picked up in 22000 ms
[Sep 6 09:59:04] VERBOSE[434] res_musiconhold.c: -- Stopped music on hold on DAHDI/2-1
[Sep 6 09:59:04] DEBUG[434] channel.c: Set channel DAHDI/2-1 to write format ulaw
[Sep 6 09:59:04] DEBUG[434] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Sep 6 09:59:04] DEBUG[434] channel.c: Hanging up channel 'DAHDI/7-1'
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: dahdi_hangup(DAHDI/7-1)
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: analog_hangup 7
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Hangup: channel: 7 index = 0, normal = 1, callwait = 0, thirdcall = 0
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/7-1
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/7-1
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Updated conferencing on 7, with 0 conference users
[Sep 6 09:59:04] VERBOSE[434] sig_analog.c: -- Hanging up on 'DAHDI/7-1'
[Sep 6 09:59:04] VERBOSE[434] chan_dahdi.c: -- Hungup 'DAHDI/7-1'
[Sep 6 09:59:04] DEBUG[434] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
[Sep 6 09:59:04] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 7
[Sep 6 09:59:04] VERBOSE[434] pbx.c: -- Auto fallthrough, channel 'DAHDI/2-1' status is 'NOANSWER'
[Sep 6 09:59:04] DEBUG[434] channel.c: Soft-Hanging up channel 'DAHDI/2-1'
[Sep 6 09:59:04] DEBUG[389] devicestate.c: Changing state for DAHDI/7 - state 0 (Unknown)
[Sep 6 09:59:04] DEBUG[389] devicestate.c: device 'DAHDI/7' state '0'
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - telLocutorio
[Sep 6 09:59:04] DEBUG[390] chan_sip.c: Checking device state for peer telLocutorio
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - telLocutorio
[Sep 6 09:59:04] DEBUG[426] app_queue.c: Device 'DAHDI/7' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Sep 6 09:59:04] DEBUG[434] channel.c: Hanging up channel 'DAHDI/2-1'
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: dahdi_hangup(DAHDI/2-1)
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: analog_hangup 2
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Hangup: channel: 2 index = 0, normal = 1, callwait = 0, thirdcall = 0
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: Disabled echo cancellation on channel 2
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/2-1
[Sep 6 09:59:04] DEBUG[434] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/2-1
[Sep 6 09:59:04] DEBUG[434] sig_analog.c: Updated conferencing on 2, with 0 conference users
[Sep 6 09:59:04] VERBOSE[434] sig_analog.c: -- Hanging up on 'DAHDI/2-1'
[Sep 6 09:59:04] VERBOSE[434] chan_dahdi.c: -- Hungup 'DAHDI/2-1'
[Sep 6 09:59:04] DEBUG[434] cdr_mysql.c: Inserting a CDR record.
[Sep 6 09:59:04] DEBUG[434] cdr_mysql.c: SQL command as follows: INSERT INTO cdr (`calldate`,`dst`,`dcontext`,`channel`,`dstchannel`,`lastapp`,`lastdata`,`duration`,`billsec`,`disposition`,`amaflags`,`uniqueid`) VALUES ('2012-09-06 09:58:35','s','from-pstn','DAHDI/2-1','DAHDI/7-1','Dial','DAHDI/7,22,m','29','24','NO ANSWER','3','1346936315.2')
[Sep 6 09:59:04] DEBUG[390] chan_iax2.c: Checking device state for device telLocutorio
[Sep 6 09:59:04] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of telLocutorio? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for DAHDI - 7
[Sep 6 09:59:04] DEBUG[390] app_queue.c: Extension 'telLocutorio@default' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[Sep 6 09:59:04] DEBUG[389] devicestate.c: No provider found, checking channel drivers for DAHDI - 2
[Sep 6 09:59:04] DEBUG[389] devicestate.c: Changing state for DAHDI/2 - state 0 (Unknown)
[Sep 6 09:59:04] DEBUG[389] devicestate.c: device 'DAHDI/2' state '0'
[Sep 6 09:59:04] DEBUG[426] app_queue.c: Device 'DAHDI/2' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for SIP - 29021381
[Sep 6 09:59:04] DEBUG[390] chan_sip.c: Checking device state for peer 29021381
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for IAX2 - 29021381
[Sep 6 09:59:04] DEBUG[390] chan_iax2.c: Checking device state for device 29021381
[Sep 6 09:59:04] DEBUG[390] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 29021381? addr=0, defaddr=0 maxms=0, lastms=0
[Sep 6 09:59:04] DEBUG[390] devicestate.c: No provider found, checking channel drivers for DAHDI - 2
[Sep 6 09:59:04] DEBUG[390] app_queue.c: Extension '29021381@default' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[Sep 6 09:59:06] DEBUG[403] chan_iax2.c: ip callno count decremented to 1 for 68.169.49.253
Si uso busydetect, funciona, pero cuando quiere, hay dias que anda y dias que no.
Con reversion de polaridad nunca funca.
Gracias!