<HTML><HEAD></HEAD>
<BODY dir=ltr>
<DIV dir=ltr>
<DIV style="FONT-SIZE: 12pt; FONT-FAMILY: 'Calibri'; COLOR: #000000">
<DIV 
style='FONT-SIZE: small; TEXT-DECORATION: none; FONT-FAMILY: "Calibri"; FONT-WEIGHT: normal; COLOR: #000000; FONT-STYLE: normal; DISPLAY: inline'>
<DIV dir=ltr>
<DIV style="FONT-SIZE: 12pt; FONT-FAMILY: 'Calibri'; COLOR: #000000">
<DIV>Hi All,</DIV>
<DIV> </DIV>
<DIV>First of all happy new year to everyone!</DIV>
<DIV> </DIV>
<DIV>I am developing an FXO driver to use with Asterisk.</DIV>
<DIV>My driver is working fine and I can receive and originate calls and I have 
both ways audio.</DIV>
<DIV> </DIV>
<DIV>I have issue with the callerid however.  I have set in can_dahdi.conf 
the default bell type starting after the first ring.</DIV>
<DIV>For testing I use FXS port which seems to send the calerid properly as it 
is displayed on an analog phone.</DIV>
<DIV>Occasionally I get the callerid (on the incoming calls for the FXO channel 
) working but it is once per 20 calls.</DIV>
<DIV>It seems enabling Asterisk debug in the CLI makes the callerid detection a 
bit better as I see it working more often with CLI debug enabled.</DIV>
<DIV>This makes me think that I have timing or noise issue. </DIV>
<DIV>On the FXO RING/TIP terminals I do see (with oscilloscope) the callerID FSK 
signals. It I swith about 5Vpp and yes I see some noise.</DIV>
<DIV>Not sure how sensitive the demodulator is. </DIV>
<DIV> </DIV>
<DIV>Before going into more details I want to ask the mailing list. Probably I 
have some simple signaling issue like getting the FXO off hook to early or too 
late.</DIV>
<DIV> </DIV>
<DIV>I have my FXO context like</DIV>
<DIV>[fxo]</DIV>
<DIV>exten => 
s,1,Answer                                                                   
</DIV>
<DIV>exten => s,2, 
Noop(${CALLERID(num)})                                                         
</DIV>
<DIV>exten => 
s,3,Background(/demo-congrats)                                      
</DIV>
<DIV>exten => s,4,Hangup   </DIV>
<DIV> </DIV>
<DIV>Bellow I include the log for reference. </DIV>
<DIV>Any thoughts or advices how to narrow the problem are welcome!</DIV>
<DIV> </DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:56] DEBUG[1672]: chan_dahdi.c:12229 
do_monitor: Monitor doohicky got event Ring Begin on channel 2</DIV>
<DIV>[Jan  2 19:35:56] DEBUG[1672]: sig_analog.c:3697 
analog_handle_init_event: channel (2) - signaling (5) - event 
(ANALOG_EVENT_RINGBEGIN)</DIV>
<DIV>[Jan  2 19:35:56] DEBUG[1672]: chan_dahdi.c:12229 do_monitor: Monitor 
doohicky got event Ring Begin on channel 2</DIV>
<DIV>[Jan  2 19:35:56] DEBUG[1672]: sig_analog.c:3697 
analog_handle_init_event: channel (2) - signaling (5) - event 
(ANALOG_EVENT_RINGBEGIN)</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:58] DEBUG[1672]: chan_dahdi.c:12229 
do_monitor: Monitor doohicky got event Ring/Answered on channel 2</DIV>
<DIV>[Jan  2 19:35:58] DEBUG[1672]: chan_dahdi.c:12229 do_monitor: Monitor 
doohicky got event Ring/Answered on channel 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:58] DEBUG[1672]: sig_analog.c:3697 
analog_handle_init_event: channel (2) - signaling (5) - event 
(ANALOG_EVENT_RINGOFFHOOK)</DIV>
<DIV>[Jan  2 19:35:58] DEBUG[1672]: sig_analog.c:3697 
analog_handle_init_event: channel (2) - signaling (5) - event 
(ANALOG_EVENT_RINGOFFHOOK)</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:58] DEBUG[1672][C-00000007]: dsp.c:482 
ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, 
hits_required=21</DIV>
<DIV>[Jan  2 19:35:58] DEBUG[1672][C-00000007]: dsp.c:482 
ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, 
hits_required=21</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:58] DEBUG[1672][C-00000007]: dsp.c:482 
ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, 
hits_required=116</DIV>
<DIV>[Jan  2 19:35:58] DEBUG[1672][C-00000007]: dsp.c:482 
ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, 
hits_required=116</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:35:58] DEBUG[1696]: sig_analog.c:1764 
__analog_ss_thread: __analog_ss_thread 2</DIV>
<DIV>[Jan  2 19:35:58] DEBUG[1696]: sig_analog.c:1764 __analog_ss_thread: 
__analog_ss_thread 2</DIV>
<DIV>    -- Starting simple switch on 'DAHDI/2-1'</DIV>
<DIV>d-ffffff*CLI>     -- Starting simple switch on 
'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'Answer'</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'Answer'</DIV>
<DIV>d-ffffff*CLI>     -- Executing [s@penev_fxo:1] 
Answer("DAHDI/2-1", "") in new stack</DIV>
<DIV>    -- Executing [s@penev_fxo:1] Answer("DAHDI/2-1", "") in 
new stack</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
sig_analog.c:1497 analog_answer: analog_answer 2</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:1497 
analog_answer: analog_answer 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
sig_analog.c:1528 analog_answer: Took DAHDI/2-1 off hook</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:1528 
analog_answer: Took DAHDI/2-1 off hook</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
chan_dahdi.c:5061 dahdi_enable_ec: No echo cancellation requested</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: chan_dahdi.c:5061 
dahdi_enable_ec: No echo cancellation requested</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
chan_dahdi.c:5077 dahdi_train_ec: No echo training requested</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: chan_dahdi.c:5077 
dahdi_train_ec: No echo training requested</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
chan_dahdi.c:9656 dahdi_indicate: Requested indication -1 on channel 
DAHDI/2-1</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: chan_dahdi.c:9656 
dahdi_indicate: Requested indication -1 on channel DAHDI/2-1</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'NoOp'</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'NoOp'</DIV>
<DIV>    -- Executing [s@penev_fxo:2] NoOp("DAHDI/2-1", "") in 
new stack</DIV>
<DIV>d-ffffff*CLI>     -- Executing [s@penev_fxo:2] 
NoOp("DAHDI/2-1", "") in new stack</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'BackGround'</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: pbx.c:4883 
pbx_extension_helper: Launching 'BackGround'</DIV>
<DIV>    -- Executing [s@penev_fxo:3] BackGround("DAHDI/2-1", 
"/demo-congrats") in new stack</DIV>
<DIV>d-ffffff*CLI>     -- Executing [s@penev_fxo:3] 
BackGround("DAHDI/2-1", "/demo-congrats") in new stack</DIV>
<DIV>    -- <DAHDI/2-1> Playing '/demo-congrats.ulaw' 
(language 'en')</DIV>
<DIV>d-ffffff*CLI>     -- <DAHDI/2-1> Playing 
'/demo-congrats.ulaw' (language 'en')</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:3572 
analog_exception: analog_exception 2</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:3572 
analog_exception: analog_exception 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
sig_analog.c:3666 analog_exception: Exception on 7, channel 2</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:3666 
analog_exception: Exception on 7, channel 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
sig_analog.c:2679 __analog_handle_event: __analog_handle_event 2</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:2679 
__analog_handle_event: __analog_handle_event 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] DEBUG[1696][C-00000007]: 
sig_analog.c:2706 __analog_handle_event: Got event ANALOG_EVENT_RINGOFFHOOK(2) 
on channel 2 (index 0)</DIV>
<DIV>[Jan  2 19:36:02] DEBUG[1696][C-00000007]: sig_analog.c:2706 
__analog_handle_event: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 2 (index 
0)</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:02] WARNING[1696][C-00000007]: 
sig_analog.c:3103 __analog_handle_event: Ring/Off-hook in strange state 6 on 
channel 2</DIV>
<DIV>[Jan  2 19:36:02] WARNING[1696][C-00000007]: sig_analog.c:3103 
__analog_handle_event: Ring/Off-hook in strange state 6 on channel 2</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:28] DEBUG[1696][C-00000007]: 
sig_analog.c:3572 analog_exception: analog_exception 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:3666 
analog_exception: Exception on 7, channel 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:2679 
__analog_handle_event: __analog_handle_event 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:2706 
__analog_handle_event: Got event ANALOG_EVENT_ONHOOK(1) on channel 2 (index 
0)</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: pbx.c:6572 __ast_pbx_run: 
Spawn extension (penev_fxo,s,3) exited non-zero on 'DAHDI/2-1'</DIV>
<DIV>  == Spawn extension (penev_fxo, s, 3) exited non-zero on 
'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: channel.c:2661 
ast_softhangup_nolock: Soft-Hanging up channel 'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: channel.c:2840 ast_hangup: 
Hanging up channel 'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: chan_dahdi.c:6325 
dahdi_hangup: dahdi_hangup(DAHDI/2-1)</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:1280 
analog_hangup: analog_hangup 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:1303 
analog_hangup: Hangup: channel: 2 index = 0, normal = 1, callwait = 0, thirdcall 
= 0</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:3572 
analog_exception: analog_exception 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:3666 
analog_exception: Exception on 7, channel 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:2679 
__analog_handle_event: __analog_handle_event 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:2706 
__analog_handle_event: Got event ANALOG_EVENT_ONHOOK(1) on channel 2 (index 
0)</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: pbx.c:6572 __ast_pbx_run: 
Spawn extension (penev_fxo,s,3) exited non-zero on 'DAHDI/2-1'</DIV>
<DIV>  == Spawn extension (penev_fxo, s, 3) exited non-zero on 
'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: channel.c:2661 
ast_softhangup_nolock: Soft-Hanging up channel 'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: channel.c:2840 ast_hangup: 
Hanging up channel 'DAHDI/2-1'</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: chan_dahdi.c:6325 
dahdi_hangup: dahdi_hangup(DAHDI/2-1)</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:1280 
analog_hangup: analog_hangup 2</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:1303 
analog_hangup: Hangup: channel: 2 index = 0, normal = 1, callwait = 0, thirdcall 
= 0</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:28] DEBUG[1696][C-00000007]: 
chan_dahdi.c:6923 dahdi_setoption: Set option TONE VERIFY, mode: OFF(0) on 
DAHDI/2-1</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: chan_dahdi.c:6923 
dahdi_setoption: Set option TONE VERIFY, mode: OFF(0) on DAHDI/2-1</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:28] DEBUG[1696][C-00000007]: 
chan_dahdi.c:6933 dahdi_setoption: Set option TDD MODE, value: OFF(0) on 
DAHDI/2-1</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: chan_dahdi.c:6933 
dahdi_setoption: Set option TDD MODE, value: OFF(0) on DAHDI/2-1</DIV>
<DIV>d-ffffff*CLI> [Jan  2 19:36:28] DEBUG[1696][C-00000007]: 
sig_analog.c:785 analog_update_conf: Updated conferencing on 2, with 0 
conference users</DIV>
<DIV>[Jan  2 19:36:28] DEBUG[1696][C-00000007]: sig_analog.c:785 
analog_update_conf: Updated conferencing on 2, with 0 conference users</DIV>
<DIV>d-ffffff*CLI>     -- Hanging up on 'DAHDI/2-1'</DIV>
<DIV>    -- Hanging up on 'DAHDI/2-1'</DIV>
<DIV>d-ffffff*CLI>     -- Hungup 'DAHDI/2-1'</DIV>
<DIV> </DIV>
<DIV>Best Regards</DIV>
<DIV>Dimitar</DIV></DIV></DIV></DIV></DIV></DIV></BODY></HTML>