[Asterisk-Users] Re: wctdm, issue w/outbound calls (additional notes)

[Sean Hull]

> I've been running Asterisk with a TDM400P for about 6months, no problems. 2 
> in/outgoing analog lines, one analog phone.  Recently I was messing with the 
> XTEN client, got to finagling with things, and not knowing what was wrong I

Two more interesting things I've found.  (1) When I call the analog 
internal extension, it does not ring, however when I pickup I can talk 
between the IP phone and analog phone (yes I've checked the ringer 
switch).  (2) Below is a log with errors when I call the main incoming 
line at which point I SHOULD get the welcome message, but it just keeps 
ringing and never picks up.

Thx again for any thoughts.

Sean

--

*CLI> Sep 13 22:30:02 DEBUG[13253]: chan_zap.c:6294 do_monitor: Message 
status for 0 changed from -1 to 0 on 4
     -- Starting simple switch on 'Zap/1-1'
     -- Executing Answer("Zap/1-1", "") in new stack
Sep 13 22:30:12 DEBUG[13253]: chan_zap.c:2503 zt_answer: Took Zap/1-1 off 
hook
Sep 13 22:30:12 DEBUG[13253]: chan_zap.c:1371 zt_enable_ec: Enabled echo 
cancellation on channel 1
Sep 13 22:30:12 DEBUG[13253]: chan_zap.c:1390 zt_train_ec: No echo 
training requested
     -- Executing DigitTimeout("Zap/1-1", "10") in new stack
Sep 13 22:30:12 WARNING[13253]: pbx.c:5577 pbx_builtin_dtimeout: 
DigitTimeout is deprecated, please use Set(TIMEOUT(digit)=timeout) 
instead.
     -- Set Digit Timeout to 10
     -- Executing ResponseTimeout("Zap/1-1", "20") in new stack
Sep 13 22:30:12 WARNING[13253]: pbx.c:5557 pbx_builtin_rtimeout: 
ResponseTimeout is deprecated, please use Set(TIMEOUT(response)=timeout) 
instead.
     -- Set Response Timeout to 20
     -- Executing BackGround("Zap/1-1", "iheavy-welcome") in new stack
Sep 13 22:30:12 DEBUG[13253]: channel.c:1388 ast_settimeout: Scheduling 
timer at 160 sample intervals
     -- Playing 'iheavy-welcome' (language 'en')
Sep 13 22:30:17 DEBUG[13253]: chan_zap.c:4136 __zt_exception: Exception on 
17, channel 1
Sep 13 22:30:17 DEBUG[13253]: chan_zap.c:3346 zt_handle_event: Got event 
Ring/Answered(2) on channel 1 (index 0)
Sep 13 22:30:17 DEBUG[13253]: chan_zap.c:3695 zt_handle_event: Setting 
IDLE polarity due to ring. Old polarity was 0
Sep 13 22:30:17 WARNING[13253]: chan_zap.c:3733 zt_handle_event: 
Ring/Off-hook in strange state 6 on channel 1
Sep 13 22:30:23 DEBUG[13253]: chan_zap.c:4136 __zt_exception: Exception on 
17, channel 1
Sep 13 22:30:23 DEBUG[13253]: chan_zap.c:3346 zt_handle_event: Got event 
Ring/Answered(2) on channel 1 (index 0)
Sep 13 22:30:23 DEBUG[13253]: chan_zap.c:3695 zt_handle_event: Setting 
IDLE polarity due to ring. Old polarity was 0
Sep 13 22:30:23 WARNING[13253]: chan_zap.c:3733 zt_handle_event: 
Ring/Off-hook in strange state 6 on channel 1
Sep 13 22:30:29 DEBUG[13253]: chan_zap.c:4136 __zt_exception: Exception on 
17, channel 1
Sep 13 22:30:35 DEBUG[13253]: chan_zap.c:3346 zt_handle_event: Got event 
Ring/Answered(2) on channel 1 (index 0)
Sep 13 22:30:35 DEBUG[13253]: chan_zap.c:3695 zt_handle_event: Setting 
IDLE polarity due to ring. Old polarity was 0
Sep 13 22:30:35 WARNING[13253]: chan_zap.c:3733 zt_handle_event: 
Ring/Off-hook in strange state 6 on channel 1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:4136 __zt_exception: Exception on 
17, channel 1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:3346 zt_handle_event: Got event 
Ring/Answered(2) on channel 1 (index 0)
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:3695 zt_handle_event: Setting 
IDLE polarity due to ring. Old polarity was 0
Sep 13 22:30:39 WARNING[13253]: chan_zap.c:3733 zt_handle_event: 
Ring/Off-hook in strange state 6 on channel 1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:4136 __zt_exception: Exception on 
17, channel 1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:3346 zt_handle_event: Got event 
On hook(1) on channel 1 (index 0)
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:1403 zt_disable_ec: disabled echo 
cancellation on channel 1
Sep 13 22:30:39 DEBUG[13253]: channel.c:1388 ast_settimeout: Scheduling 
timer at 0 sample intervals
   == Spawn extension (incoming, s, 4) exited non-zero on 'Zap/1-1'
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:2186 zt_hangup: Hangup: channel: 
1 index = 0, normal = 17, callwait = -1, thirdcall = -1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:1403 zt_disable_ec: disabled echo 
cancellation on channel 1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:2595 zt_setoption: Set option TDD 
MODE, value: OFF(0) on Zap/1-1
Sep 13 22:30:39 DEBUG[13253]: chan_zap.c:1340 update_conf: Updated 
conferencing on 1, with 0 conference users
     -- Hungup 'Zap/1-1'
Sep 13 22:30:58 DEBUG[13253]: chan_sip.c:1274 __sip_ack: Stopping 
retransmission on '7c9c969576264bdb5354e77713ccad26 at 192.168.0.17' of 
Request 102: Match Found
Sep 13 22:31:58 DEBUG[13253]: chan_sip.c:1274 __sip_ack: Stopping 
retransmission on '4c14724b08280c217dcda9be5f6286d7 at 192.168.0.17' of 
Request 102: Match Found
Sep 13 22:32:01 NOTICE[13253]: chan_sip.c:9835 handle_request_register: 
Registration from 'Phone3 <sip:Phone3 at 192.168.0.17>' failed for 
'192.168.0.18' - Wrong password
Sep 13 22:32:16 DEBUG[13253]: chan_sip.c:1197 __sip_autodestruct: Auto 
destroying call '29586FF28477499FAD7843D93E5E9360 at 192.168.0.17'
Sep 13 22:32:21 NOTICE[13253]: chan_sip.c:9835 handle_request_register: 
Registration from 'Phone3 <sip:Phone3 at 192.168.0.17>' failed for 
'192.168.0.18' - Wrong password
Sep 13 22:32:36 DEBUG[13253]: chan_sip.c:1197 __sip_autodestruct: Auto 
destroying call '29586FF28477499FAD7843D93E5E9360 at 192.168.0.17'
Sep 13 22:32:58 DEBUG[13253]: chan_sip.c:1274 __sip_ack: Stopping 
retransmission on '78696c4f54714b202f67c5163b595b1a at 192.168.0.17' of 
Request 102: Match Found
Sep 13 22:33:02 NOTICE[13253]: chan_sip.c:9835 handle_request_register: 
Registration from 'Phone3 <sip:Phone3 at 192.168.0.17>' failed for 
'192.168.0.18' - Wrong password
Sep 13 22:33:17 DEBUG[13253]: chan_sip.c:1197 __sip_autodestruct: Auto 
destroying call '29586FF28477499FAD7843D93E5E9360 at 192.168.0.17'