Another mystery for the list, hopefully someone has ideas on a fix... :)
I've got an Asterisk 1.8.12.0 system connected to a CAS T1 (ESF/B8ZS,
fractional 1-8). Outbound dialing works correctly, but while the call is in
progress, there is no 'ringing' heard by the end user. So, on a SIP
phone connected to this system, I dial a number, that call goes out DAHDI via
the CAS T1, and the remote side is actually ringing (my cell phone for example),
but the SIP phone remains silent. If I answer my cell phone, full 2-way audio is
present.
The telco has already enabled ringback on the circuit but that has not had any
effect on the operation. Any thoughts on how to proceed? Here are the pertinent
parts of the debug log showing the events on the circuit when dialing:
[Jul 27 11:31:20] VERBOSE[14199] app_dial.c: -- Called DAHDI/g1/XXXYYYY
[Jul 27 11:31:20] DEBUG[14149] devicestate.c: No provider found, checking
channel drivers for DAHDI - 1
[Jul 27 11:31:20] DEBUG[14149] devicestate.c: Changing state for DAHDI/1 - state
2 (In use)
[Jul 27 11:31:20] DEBUG[14149] devicestate.c: device 'DAHDI/1' state
'2'
[Jul 27 11:31:20] DEBUG[14190] app_queue.c: Device 'DAHDI/1' changed to
state '2' (In use) but we don't care because they're not a
member of any queue.
[Jul 27 11:31:20] DEBUG[14190] app_queue.c: Device 'DAHDI/1' changed to
state '2' (In use) but we don't care because they're not a
member of any queue.
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: analog_exception 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: Exception on 19, channel 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: __analog_handle_event 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: Got event ANALOG_EVENT_WINKFLASH(3)
on channel 1 (index 0)
[Jul 27 11:31:21] DEBUG[14199] chan_dahdi.c: Channel 1: Sending
'T355885' to DAHDI_DIAL.
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: Sent deferred digit string on
channel 1: TXXXYYY
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: analog_exception 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: Exception on 19, channel 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: __analog_handle_event 1
[Jul 27 11:31:21] DEBUG[14199] sig_analog.c: Got event
ANALOG_EVENT_HOOKCOMPLETE(9) on channel 1 (index 0)
[Jul 27 11:31:22] DEBUG[14199] sig_analog.c: analog_exception 1
[Jul 27 11:31:22] DEBUG[14199] sig_analog.c: Exception on 19, channel 1
[Jul 27 11:31:22] DEBUG[14199] sig_analog.c: __analog_handle_event 1
[Jul 27 11:31:22] DEBUG[14199] sig_analog.c: Got event
ANALOG_EVENT_DIALCOMPLETE(6) on channel 1 (index 0)
[Jul 27 11:31:22] DEBUG[14199] chan_dahdi.c: Enabled echo cancellation on
channel 1
[Jul 27 11:31:22] DEBUG[14199] chan_dahdi.c: Engaged echo training on channel 1
[Jul 27 11:31:22] DEBUG[14199] chan_dahdi.c: Channel 1: Sending 'wwwYw'
to DAHDI_DIAL.
[Jul 27 11:31:24] DEBUG[14199] sig_analog.c: analog_exception 1
[Jul 27 11:31:24] DEBUG[14199] sig_analog.c: Exception on 19, channel 1
[Jul 27 11:31:24] DEBUG[14199] sig_analog.c: __analog_handle_event 1
[Jul 27 11:31:24] DEBUG[14199] sig_analog.c: Got event
ANALOG_EVENT_DIALCOMPLETE(6) on channel 1 (index 0)
[Jul 27 11:31:24] DEBUG[14199] chan_dahdi.c: Echo cancellation already on
[Jul 27 11:31:24] DEBUG[14149] devicestate.c: No provider found, checking
channel drivers for DAHDI - 1
[Jul 27 11:31:24] DEBUG[14149] devicestate.c: Changing state for DAHDI/1 - state
6 (Ringing)
[Jul 27 11:31:24] DEBUG[14149] devicestate.c: device 'DAHDI/1' state
'6'
The odd part is, you can see above the dialed number was XXXYYYY, but the actual
sequence on the trunk as performed was to dial XXXYYY, then some
'waits', then the last digit Y. Is this normal?
--Tim