asterisk users - Jul 2010 - Problem with wct4xxp - cannot make calls

Hi,

I'm having problems with a TE420P card, in which I cannot make calls 
using spans 2 through 4.

After a couple of days of working correctly, spans 2, 3 and 4 start 
failing (can not make calls). The system is configured to work with SS7. 
After the ACM message goes out, immediately a REL message is returned.

I searched for error messages in /var/log/messages and could not find a 
clue about what is going on. I also asked the provider and they tell me 
that everything looks OK on their side. Then I found out that if I 
disconnected the lines, the spans started to work correctly. The error 
persists a system reboot. Asterisk also does not indicate what the 
trouble is.

When I enabled debug for wct4xxp, the following messages started to show:

Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 2 but 
debouncing for 2500 ms
Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 4 but 
debouncing for 2500 ms
Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 3 but 
debouncing for 2500 ms
Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 2 but 
debouncing for 2500 ms
Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 2 but 
debouncing for 2500 ms
Jul  6 11:15:03 server kernel: wct4xxp: LOF/LFA detected on span 4 but 
debouncing for 2500 ms
Jul  6 11:15:04 server kernel: wct4xxp: LOF/LFA detected on span 3 but 
debouncing for 2500 ms
Jul  6 11:15:04 server kernel: wct4xxp: LOF/LFA detected on span 2 but 
debouncing for 2500 ms
Jul  6 11:15:04 server kernel: wct4xxp: LOF/LFA detected on span 2 but 
debouncing for 2500 ms

The systems calls a number, then calls another and then does a bridge. A 
symptom that leads me to the sync problem is that when the call goes 
into the bridge with spans 2, 3 or 4, I start getting clicks and the 
call quality goes down. Also of note is that span 1 has always worked 
correctly, even when bridging is between channels in the same span 1.

Another thing that I find odd is the output of dadhi_scan, that 
indicates that syncsrc=0 for all spans. Is that correct?

[1]
active=yes
alarms=OK
description=T4XXP (PCI) Card 0 Span 1
name=TE4/0/1
manufacturer=Digium
devicetype=Wildcard TE420 (4th Gen) (VPMOCT128)
location=Board ID Switch 0
basechan=1
totchans=31
irq=177
type=digital-E1
syncsrc=0
lbo=0 db (CSU)/0-133 feet (DSX-1)
coding_opts=HDB3
framing_opts=CCS,CRC4
coding=HDB3
framing=CCS
[2]
active=yes
alarms=OK
description=T4XXP (PCI) Card 0 Span 2
name=TE4/0/2
manufacturer=Digium
devicetype=Wildcard TE420 (4th Gen) (VPMOCT128)
location=Board ID Switch 0
basechan=32
totchans=31
irq=177
type=digital-E1
syncsrc=0
lbo=0 db (CSU)/0-133 feet (DSX-1)
coding_opts=HDB3
framing_opts=CCS,CRC4
coding=HDB3
framing=CCS
[3]
active=yes
alarms=OK
description=T4XXP (PCI) Card 0 Span 3
name=TE4/0/3
manufacturer=Digium
devicetype=Wildcard TE420 (4th Gen) (VPMOCT128)
location=Board ID Switch 0
basechan=63
totchans=31
irq=177
type=digital-E1
syncsrc=0
lbo=0 db (CSU)/0-133 feet (DSX-1)
coding_opts=HDB3
framing_opts=CCS,CRC4
coding=HDB3
framing=CCS
[4]
active=yes
alarms=OK
description=T4XXP (PCI) Card 0 Span 4
name=TE4/0/4
manufacturer=Digium
devicetype=Wildcard TE420 (4th Gen) (VPMOCT128)
location=Board ID Switch 0
basechan=94
totchans=31
irq=177
type=digital-E1
syncsrc=0
lbo=0 db (CSU)/0-133 feet (DSX-1)
coding_opts=HDB3
framing_opts=CCS,CRC4
coding=HDB3
framing=CCS



dahdi_tool indicates that Sync Source is Internally clocked for all spans.

I have installed Rev 8852 of dahdi-complete without success. The system 
is CentOS 2.6.18-164.15.1.el5PAE.

Here is the output of wct4xxp startup and dahdi_cfg:

Jul  5 18:00:09 server kernel: dahdi: Telephony Interface Registered on 
major 196
Jul  5 18:00:09 server kernel: dahdi: Version: 2.3.0.1
Jul  5 18:00:09 server kernel: Found TE4XXP at base address fb7ffc00, 
remapped to f898ac00
Jul  5 18:00:09 server kernel: DMA memory base of size 2048 at 
e7eeb000.  Read: e7eeb400 and Write e7eeb000
Jul  5 18:00:09 server kernel: TE4XXP version c01a016c, burst ON
Jul  5 18:00:09 server kernel: Octasic optimized!
Jul  5 18:00:09 server kernel: card 0: FALC framer is v2.1 or earlier.
Jul  5 18:00:09 server kernel: FALC version: 00000005, Board ID: 00
Jul  5 18:00:09 server kernel: Reg 0: 0x27eeb400
Jul  5 18:00:09 server kernel: Reg 1: 0x27eeb000
Jul  5 18:00:09 server kernel: Reg 2: 0x00000000
Jul  5 18:00:09 server kernel: Reg 3: 0x00000000
Jul  5 18:00:09 server kernel: Reg 4: 0x00000101
Jul  5 18:00:09 server kernel: Reg 5: 0x00000000
Jul  5 18:00:09 server kernel: Reg 6: 0xc01a016c
Jul  5 18:00:09 server kernel: Reg 7: 0x00001f00
Jul  5 18:00:09 server kernel: Reg 8: 0x010200ff
Jul  5 18:00:09 server kernel: Reg 9: 0x00fd0001
Jul  5 18:00:10 server kernel: Reg 10: 0x0000004a
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled 1sec error 
counter interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled errored 
second interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled 1sec error 
counter interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled errored 
second interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled 1sec error 
counter interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled errored 
second interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled 1sec error 
counter interrupt
Jul  5 18:00:10 server kernel: wct4xxp 0000:06:08.0: Enabled errored 
second interrupt
Jul  5 18:00:10 server kernel: Found a Wildcard: Wildcard TE420 (4th Gen)
Jul  5 18:00:10 server kernel: TE4XXP: Launching card: 0
Jul  5 18:00:11 server kernel: TE4XXP: Setting up global serial parameters
Jul  5 18:00:36 server kernel: About to enter spanconfig!
Jul  5 18:00:36 server kernel: Done with spanconfig!
Jul  5 18:00:36 server kernel: About to enter spanconfig!
Jul  5 18:00:36 server kernel: Done with spanconfig!
Jul  5 18:00:36 server kernel: About to enter spanconfig!
Jul  5 18:00:36 server kernel: Done with spanconfig!
Jul  5 18:00:36 server kernel: About to enter spanconfig!
Jul  5 18:00:36 server kernel: Done with spanconfig!
Jul  5 18:00:36 server kernel: dahdi: Registered tone zone 0 (United 
States / North America)
Jul  5 18:00:36 server kernel: About to enter startup!
Jul  5 18:00:36 server kernel: TE4XXP: Span 1 configured for CCS/HDB3/CRC4
Jul  5 18:00:36 server kernel: 2G: Got interrupt, status = 0000000c, CIS 
= 0080
Jul  5 18:00:36 server kernel: wct4xxp 0000:06:08.0: timing source auto
Jul  5 18:00:36 server kernel: wct4xxp 0000:06:08.0: Evaluating spans 
for timing source
Jul  5 18:00:36 server kernel: wct4xxp 0000:06:08.0: span 1 is green : 
syncpos 1
Jul  5 18:00:36 server kernel: wct4xxp 0000:06:08.0: RCLK source set to 
span 1
Jul  5 18:00:36 server kernel: wct4xxp 0000:06:08.0: Recovered timing 
mode, RCLK set to span 1
Jul  5 18:00:36 server kernel: SPAN 1: Primary Sync Source
Jul  5 18:00:36 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:36 server kernel: Reg 5 is 00000000
Jul  5 18:00:36 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0081
Jul  5 18:00:36 server kernel: Reg 5 is 00000000
Jul  5 18:00:37 server kernel: VPM400: Support Disabled
Jul  5 18:00:37 server kernel: VPM450: Support Disabled
Jul  5 18:00:37 server kernel: Completed startup!
Jul  5 18:00:37 server kernel: About to enter startup!
Jul  5 18:00:37 server kernel: TE4XXP: Span 2 configured for CCS/HDB3/CRC4
Jul  5 18:00:37 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0081
Jul  5 18:00:37 server kernel: Reg 5 is 00000000
Jul  5 18:00:37 server kernel: SPAN 2: Secondary Sync Source
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: timing source auto
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: Evaluating spans 
for timing source
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: span 1 is green : 
syncpos 1
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: span 2 is green : 
syncpos 2
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: RCLK source set to 
span 1
Jul  5 18:00:37 server kernel: wct4xxp 0000:06:08.0: Recovered timing 
mode, RCLK set to span 1
Jul  5 18:00:38 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0082
Jul  5 18:00:38 server kernel: Reg 5 is 00000000
Jul  5 18:00:38 server kernel: VPM400: Support Disabled
Jul  5 18:00:38 server kernel: VPM450: Support Disabled
Jul  5 18:00:38 server kernel: Completed startup!
Jul  5 18:00:38 server kernel: About to enter startup!
Jul  5 18:00:38 server kernel: TE4XXP: Span 3 configured for CCS/HDB3/CRC4
Jul  5 18:00:38 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:38 server kernel: Reg 5 is 00000000
Jul  5 18:00:38 server kernel: SPAN 3: Tertiary Sync Source
Jul  5 18:00:38 server kernel: wct4xxp 0000:06:08.0: timing source auto
Jul  5 18:00:38 server kernel: wct4xxp 0000:06:08.0: Evaluating spans 
for timing source
Jul  5 18:00:38 server kernel: wct4xxp 0000:06:08.0: span 1 is green : 
syncpos 1
Jul  5 18:00:39 server kernel: wct4xxp 0000:06:08.0: span 2 is green : 
syncpos 2
Jul  5 18:00:39 server kernel: wct4xxp 0000:06:08.0: span 3 is green : 
syncpos 3
Jul  5 18:00:39 server kernel: wct4xxp 0000:06:08.0: RCLK source set to 
span 1
Jul  5 18:00:39 server kernel: wct4xxp 0000:06:08.0: Recovered timing 
mode, RCLK set to span 1
Jul  5 18:00:39 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0085
Jul  5 18:00:39 server kernel: Reg 5 is 00000000
Jul  5 18:00:39 server kernel: VPM400: Support Disabled
Jul  5 18:00:39 server kernel: VPM450: Support Disabled
Jul  5 18:00:39 server kernel: Completed startup!
Jul  5 18:00:39 server kernel: About to enter startup!
Jul  5 18:00:39 server kernel: TE4XXP: Span 4 configured for CCS/HDB3/CRC4
Jul  5 18:00:40 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:40 server kernel: Reg 5 is 00000000
Jul  5 18:00:40 server kernel: SPAN 4: Quaternary Sync Source
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: timing source auto
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: Evaluating spans 
for timing source
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: span 1 is green : 
syncpos 1
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: span 2 is green : 
syncpos 2
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: span 3 is green : 
syncpos 3
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: span 4 is green : 
syncpos 4
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: RCLK source set to 
span 1
Jul  5 18:00:40 server kernel: wct4xxp 0000:06:08.0: Recovered timing 
mode, RCLK set to span 1
Jul  5 18:00:40 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0088
Jul  5 18:00:40 server kernel: Reg 5 is 00000000
Jul  5 18:00:41 server kernel: VPM400: Support Disabled
Jul  5 18:00:41 server kernel: VPM450: Support Disabled
Jul  5 18:00:41 server kernel: Completed startup!
Jul  5 18:00:41 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0088
Jul  5 18:00:41 server kernel: Reg 5 is 00000000
Jul  5 18:00:41 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:41 server kernel: Reg 5 is 00000000
Jul  5 18:00:41 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:41 server kernel: Reg 5 is 00000000
Jul  5 18:00:41 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0082
Jul  5 18:00:41 server kernel: Reg 5 is 00000000
Jul  5 18:00:41 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0082
Jul  5 18:00:41 server kernel: Reg 5 is 00000000
Jul  5 18:00:42 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0084
Jul  5 18:00:42 server kernel: Reg 5 is 00000000
Jul  5 18:00:42 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:42 server kernel: Reg 5 is 00000000
Jul  5 18:00:42 server kernel: 2G: Got interrupt, status = 0000000b, CIS 
= 0088
Jul  5 18:00:42 server kernel: Reg 5 is 00000000
Jul  5 18:00:42 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:42 server kernel: Reg 5 is 00000000
Jul  5 18:00:42 server kernel: 2G: Got interrupt, status = 0000000a, CIS 
= 0080
Jul  5 18:00:42 server kernel: Reg 5 is 00000000

Here is system.conf:

# Span 1: TE4/0/1 "T4XXP (PCI) Card 0 Span 1" (MASTER) HDB3/CCS/CRC4
span=1,1,0,ccs,hdb3,crc4
# termtype: te
bchan=1-15,17-31
dchan=16
# echocanceller=mg2,1-15,17-31

# Span 2: TE4/0/2 "T4XXP (PCI) Card 0 Span 2" HDB3/CCS/CRC4
span=2,2,0,ccs,hdb3,crc4
# termtype: te
bchan=32-62
# dchan=47
# echocanceller=mg2,32-46,48-62

# Span 3: TE4/0/3 "T4XXP (PCI) Card 0 Span 3" HDB3/CCS/CRC4
span=3,3,0,ccs,hdb3,crc4
# termtype: te
bchan=63-93
# dchan=78
# echocanceller=mg2,63-77,79-93

# Span 4: TE4/0/4 "T4XXP (PCI) Card 0 Span 4" HDB3/CCS/CRC4
span=4,4,0,ccs,hdb3,crc4
# termtype: te
bchan=94-124
# dchan=109
# echocanceller=mg2,94-108,110-124

# Global data

loadzone        = us
defaultzone     = us



I appreciate your help in finding out what is wrong with my setup.

Thanks!

Mario
-------------- next part --------------
A non-text attachment was scrubbed...
Name: mmoran.vcf
Type: text/x-vcard
Size: 309 bytes
Desc: not available
Url :
http://lists.digium.com/pipermail/asterisk-users/attachments/20100706/8317b05d/attachment.vcf