[asterisk-users] Trouble with upgrading - RBS T1

Mon Dec 9 16:56:00 CST 2013

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.

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:

[Dec  5 00:51:37] VERBOSE[5283] sig_analog.c:     -- Starting simple 
switch on 'DAHDI/9-1'
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=1.17E+04, 
Et=1.45E+06, s/n=      0.01
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=2.76E+03, 
Et=1.10E+06, s/n=      0.00
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=2.06E+04, 
Et=1.39E+06, s/n=      0.01
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=4.68E+03, 
Et=1.40E+06, s/n=      0.00
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=0.00E+00, 
Et=0.00E+00, s/n=      -nan
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=0.00E+00, 
Et=0.00E+00, s/n=      -nan
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=0.00E+00, 
Et=0.00E+00, s/n=      -nan
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=0.00E+00, 
Et=0.00E+00, s/n=      -nan
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=0.00E+00, 
Et=0.00E+00, s/n=      -nan
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=1.30E+10, 
Et=2.11E+12, s/n=      0.01
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: analog_exception 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Exception on 15, channel 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: __analog_handle_event 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Got event 
UNKNOWN/OTHER(131127) on channel 9 (index 0)
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: DTMF Down '7'
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Begin DTMF digit: 0x37 '7' 
on DAHDI/9-1
[Dec  5 00:51:38] DEBUG[5283] chan_dahdi.c: Begin DTMF digit: 0x37 '7' 
on DAHDI/9-1
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF begin '7' received on DAHDI/9-1
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF begin ignored '7' on DAHDI/9-1
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=2.02E+10, 
Et=4.01E+12, s/n=      0.01
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=1.88E+10, 
Et=3.89E+12, s/n=      0.00
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=4.78E+10, 
Et=1.17E+12, s/n=      0.04
[Dec  5 00:51:38] DEBUG[5283] dsp.c: tone 1100, Ew=5.10E+03, 
Et=6.26E+06, s/n=      0.00
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: analog_exception 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Exception on 15, channel 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: __analog_handle_event 9
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Got event 
UNKNOWN/OTHER(262199) on channel 9 (index 0)
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: Detected digit '7'
[Dec  5 00:51:38] DEBUG[5283] sig_analog.c: End DTMF digit: 0x37 '7' on 
DAHDI/9-1
[Dec  5 00:51:38] DEBUG[5283] chan_dahdi.c: End DTMF digit: 0x37 '7' on 
DAHDI/9-1
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF end '7' received on 
DAHDI/9-1, duration 0 ms
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF end accepted without begin 
'7' on DAHDI/9-1
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF end passthrough '7' on 
DAHDI/9-1
[Dec  5 00:51:38] DEBUG[5283] chan_dahdi.c: Enabled echo cancellation on 
channel 9
[Dec  5 00:51:38] VERBOSE[5283] sig_analog.c:     -- Unknown extension 
'7' in context 'from-pstn' requested
...

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:

root at astsouth:/var/log/asterisk# grep 'DTMF end' /tmp/foo | grep received
[Dec  5 00:51:38] DTMF[5283] channel.c: DTMF end '7' received on 
DAHDI/9-1, duration 0 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '1' received on 
DAHDI/9-1, duration 80 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '5' received on 
DAHDI/9-1, duration 80 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '7' received on 
DAHDI/9-1, duration 80 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '6' received on 
DAHDI/9-1, duration 80 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '0' received on 
DAHDI/9-1, duration 80 ms
[Dec  5 00:51:41] DTMF[5283] channel.c: DTMF end '0' received on 
DAHDI/9-1, duration 80 ms

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.

The next maintenance window I swapped out the A104D for a Digium TE122.  
Exactly the same issue!  Some problem with Dahdi then?

Any ideas?

Thanks,

Jeff LaCoursiere
SunFone
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-users/attachments/20131209/dd9081ac/attachment.html>