[Asterisk-Users] Problem with simple incoming calls

Tue Jun 6 18:14:50 MST 2006

Hi all,

I must admit that I am stuck.  I have a TDM400P card with two FXS and
two FXO modules which I had set up and configured so that it was
working beautifully.  The only problem was that occasionally it would
get itself into a state where outgoing calls would simply be met with
a very loud static.  A reboot would fix this issue and everything
would work fine for a while.

Recently however, incoming calls have also stopped working at all.
Asterisk would seemingly pick up the line only to immediately hangup.
Then the internal extension will ring twice and also hang up.

In trying to trace down these issues I have gone back to a bare bones
configuration.

zapata.conf:

[channels]
usecallerid=no
hidecallerid=no
callwaiting=no
threewaycalling=no
transfer=no
echocancel=no
echotraining=no
immediate=no
usedistinctiveringdetection=no

; define channels
context=internal
signalling=fxo_ks
channel => 1

; define channels
context=incoming
signalling=fxs_ks
channel => 3

-------------------------------
extensions.conf:
[incoming]
;exten => s, 1, Answer()
;exten => s, 2, Playback(hello-world)
exten => s,1, Dial(Zap/1)

Neither of these configurations will currently work properly.  The
commented out Playback setup answers, sais it is playing back, but you
don't hear anything.  The Dial(zap/1) will ring the extension but as
soon as you pick it up, asterisk hangs up.

The debug messages from the latter example are as follows:
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4763 zt_write: Dropping frame
since I'm still dialing on Zap/1-1...
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4763 zt_write: Dropping frame
since I'm still dialing on Zap/1-1...
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4763 zt_write: Dropping frame
since I'm still dialing on Zap/1-1...
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4763 zt_write: Dropping frame
since I'm still dialing on Zap/1-1...
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4351 __zt_exception: Exception
on 20, channel 1
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:3539 zt_handle_event: Got
event Ring/Answered(2) on channel 1 (index 0)
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1408 zt_enable_ec: No echo
cancellation requested
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1424 zt_train_ec: No echo
training requested
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:3824 zt_handle_event: channel 1 answered
Jun  6 21:08:42 DEBUG[2121]: channel.c:777 channel_find_locked:
Avoiding initial deadlock for 'Zap/1-1'
    -- Zap/1-1 answered Zap/3-1
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:4817 zt_indicate: Requested
indication -1 on channel Zap/3-1
Jun  6 21:08:42 DEBUG[2134]: channel.c:2348 set_format: Set channel
Zap/3-1 to read format slin
Jun  6 21:08:42 DEBUG[2134]: channel.c:2348 set_format: Set channel
Zap/1-1 to write format slin
Jun  6 21:08:42 DEBUG[2134]: channel.c:2348 set_format: Set channel
Zap/1-1 to read format slin
Jun  6 21:08:42 DEBUG[2134]: channel.c:2348 set_format: Set channel
Zap/3-1 to write format slin
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:2677 zt_answer: Took Zap/3-1 off hook
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1408 zt_enable_ec: No echo
cancellation requested
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1424 zt_train_ec: No echo
training requested
    -- Attempting native bridge of Zap/3-1 and Zap/1-1
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:3111 zt_bridge: master: 3,
slave: 1, nothingok: 0
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:3126 zt_bridge: Stopping tones
on 3/0 talking to 1/0
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:3138 zt_bridge: Stopping tones
on 1/0 talking to 3/0
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:2954 zt_link: Making 1 slave
to master 3 at 0
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1228 conf_add: Added 20 to
conference 9/3
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1228 conf_add: Added 21 to
conference 9/1
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1374 update_conf: Updated
conferencing on 3, with 0 conference users
Jun  6 21:08:42 DEBUG[2134]: chan_zap.c:1374 update_conf: Updated
conferencing on 1, with 0 conference users
Jun  6 21:08:42 DEBUG[2121]: devicestate.c:187 do_state_change:
Changing state for Zap/1 - state 2 (In use)
Jun  6 21:08:42 DEBUG[2138]: app_queue.c:471 changethread: Device
'Zap/1' changed to state '2' (In use)
Jun  6 21:08:42 DEBUG[2121]: devicestate.c:187 do_state_change:
Changing state for Zap/3 - state 2 (In use)
Jun  6 21:08:42 DEBUG[2139]: app_queue.c:471 changethread: Device
'Zap/3' changed to state '2' (In use)
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:4351 __zt_exception: Exception
on 20, channel 1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:3539 zt_handle_event: Got
event On hook(1) on channel 1 (index 0)
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1408 zt_enable_ec: No echo
cancellation requested
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1408 zt_enable_ec: No echo
cancellation requested
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2898 zt_unlink: Unlinking slave 1 from 3
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1260 conf_del: Removed 20 from
conference 9/3
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1260 conf_del: Removed 21 from
conference 9/1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1374 update_conf: Updated
conferencing on 3, with 0 conference users
Jun  6 21:08:46 DEBUG[2134]: channel.c:3478 ast_channel_bridge:
Returning from native bridge, channels: Zap/3-1, Zap/1-1
Jun  6 21:08:46 DEBUG[2134]: channel.c:1323 ast_hangup: Hanging up
channel 'Zap/1-1'
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2312 zt_hangup: zt_hangup(Zap/1-1)
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2345 zt_hangup: Hangup:
channel: 1 index = 0, normal = 20, callwait = -1, thirdcall = -1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2785 zt_setoption: Set option
TDD MODE, value: OFF(0) on Zap/1-1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1374 update_conf: Updated
conferencing on 1, with 0 conference users
    -- Hungup 'Zap/1-1'
Jun  6 21:08:46 DEBUG[2121]: devicestate.c:187 do_state_change:
Changing state for Zap/1 - state 0 (Unknown)
Jun  6 21:08:46 DEBUG[2134]: app_dial.c:1605 dial_exec_full: Exiting
with DIALSTATUS=ANSWER.
Jun  6 21:08:46 DEBUG[2134]: pbx.c:2313 __ast_pbx_run: Spawn extension
(incoming,s,1) exited non-zero on 'Zap/3-1'
Jun  6 21:08:46 DEBUG[2134]: cdr.c:992 ast_cdr_detach: Dropping CDR !
Jun  6 21:08:46 DEBUG[2134]: channel.c:1323 ast_hangup: Hanging up
channel 'Zap/3-1'
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2312 zt_hangup: zt_hangup(Zap/3-1)
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2345 zt_hangup: Hangup:
channel: 3 index = 0, normal = 21, callwait = -1, thirdcall = -1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:2785 zt_setoption: Set option
TDD MODE, value: OFF(0) on Zap/3-1
Jun  6 21:08:46 DEBUG[2134]: chan_zap.c:1374 update_conf: Updated
conferencing on 3, with 0 conference users
    -- Hungup 'Zap/3-1'
Jun  6 21:08:46 DEBUG[2140]: app_queue.c:471 changethread: Device
'Zap/1' changed to state '0' (Unknown)
Jun  6 21:08:46 DEBUG[2121]: devicestate.c:187 do_state_change:
Changing state for Zap/3 - state 0 (Unknown)
Jun  6 21:08:46 DEBUG[2141]: app_queue.c:471 changethread: Device
'Zap/3' changed to state '0' (Unknown)
Jun  6 21:08:47 DEBUG[2133]: chan_zap.c:6638 do_monitor: Monitor
doohicky got event Hook Transition Complete on channel 1

I am hoping against hope that it isn't a hardware issue.  If anyone
has any insights it would be very much appreciated.

Thanks,
Darin