<html>
<head>
<meta http-equiv="content-type" content="text/html; charset=ISO-8859-1">
</head>
<body bgcolor="#FFFFFF" text="#000000">
Upgrading an ancient customer installation... was running 1.4.23.1
(Trixbox) with Zaptel 1.4.12.9 and a Sangoma A102D, which has been
running fine for 5+ years. Customer getting anxious about hardware
failure, so we built a new box and installed 1.8.24.0, Dahdi
2.7.0.1, and a new Sangoma A104D. The single active span is an RBS
T1 B8ZS/ESF/E&M Wink.<br>
<br>
I tried to move one span over one night which was working fine on
the old box. Once plugged in there were no alarms, Sangoma
wanpipemon utility showed "connected". I tried calling in on a DID
number, and in the 'full' log, with debug and verbose set to 100:<br>
<small><small><tt><br>
</tt><tt>[Dec 5 00:51:37] VERBOSE[5283] sig_analog.c: --
Starting simple switch on 'DAHDI/9-1'</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=1.17E+04, Et=1.45E+06, s/n= 0.01</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=2.76E+03, Et=1.10E+06, s/n= 0.00</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=2.06E+04, Et=1.39E+06, s/n= 0.01</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=4.68E+03, Et=1.40E+06, s/n= 0.00</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=0.00E+00, Et=0.00E+00, s/n= -nan</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=0.00E+00, Et=0.00E+00, s/n= -nan</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=0.00E+00, Et=0.00E+00, s/n= -nan</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=0.00E+00, Et=0.00E+00, s/n= -nan</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=0.00E+00, Et=0.00E+00, s/n= -nan</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=1.30E+10, Et=2.11E+12, s/n= 0.01</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c:
analog_exception 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Exception
on 15, channel 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c:
__analog_handle_event 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Got event
UNKNOWN/OTHER(131127) on channel 9 (index 0)</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: DTMF Down
'7'</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Begin DTMF
digit: 0x37 '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] chan_dahdi.c: Begin DTMF
digit: 0x37 '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF begin '7'
received on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF begin
ignored '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=2.02E+10, Et=4.01E+12, s/n= 0.01</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=1.88E+10, Et=3.89E+12, s/n= 0.00</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=4.78E+10, Et=1.17E+12, s/n= 0.04</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] dsp.c: tone 1100,
Ew=5.10E+03, Et=6.26E+06, s/n= 0.00</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c:
analog_exception 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Exception
on 15, channel 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c:
__analog_handle_event 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Got event
UNKNOWN/OTHER(262199) on channel 9 (index 0)</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: Detected
digit '7'</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] sig_analog.c: End DTMF
digit: 0x37 '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] chan_dahdi.c: End DTMF
digit: 0x37 '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF end '7'
received on DAHDI/9-1, duration 0 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF end
accepted without begin '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF end
passthrough '7' on DAHDI/9-1</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DEBUG[5283] chan_dahdi.c: Enabled
echo cancellation on channel 9</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] VERBOSE[5283] sig_analog.c: --
Unknown extension '7' in context 'from-pstn' requested</tt></small></small><br>
...<br>
<br>
At this point I hear 'invalid extension' and get hung up on, but if
you grep out all the DTMF events from this call, you get:<br>
<br>
<small><small><tt>root@astsouth:/var/log/asterisk# grep 'DTMF end'
/tmp/foo | grep received</tt><tt><br>
</tt><tt>[Dec 5 00:51:38] DTMF[5283] channel.c: DTMF end '7'
received on DAHDI/9-1, duration 0 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '1'
received on DAHDI/9-1, duration 80 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '5'
received on DAHDI/9-1, duration 80 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '7'
received on DAHDI/9-1, duration 80 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '6'
received on DAHDI/9-1, duration 80 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '0'
received on DAHDI/9-1, duration 80 ms</tt><tt><br>
</tt><tt>[Dec 5 00:51:41] DTMF[5283] channel.c: DTMF end '0'
received on DAHDI/9-1, duration 80 ms</tt></small></small><br>
<br>
And '715-7600' is the DID number I dialed. So why is the new box
immediately answering the call before it gets all the ANI digits?
I've poured over all Sangoma's docs online for debugging RBS lines -
I'm not having any transmission errors, no overruns, and even the
audio of 'invalid extension' seems clear before it hangs up on me.<br>
<br>
The next maintenance window I swapped out the A104D for a Digium
TE122. Exactly the same issue! Some problem with Dahdi then?<br>
<br>
Any ideas?<br>
<br>
Thanks,<br>
<br>
Jeff LaCoursiere<br>
SunFone<br>
</body>
</html>