Problem connecting B400E to AVM Controller in NT mode

Hi,

I have set ports to NT Mode for connecting to AVM Controller C2. The AVM card is on corporate Fax Server.

If I connect to B400E an ISDN Phone all works fine. When connect to AVM card the connections fails. I explain better. The link is up and active (green led on B400E), but when I try to call to extension assigned, after two rings the connection drop. In asterisk log I find "sig_pri.c: -- Span 2: Channel 0/1 got hangup, cause 18".

Here my configurations and at last debug on span 2

dahdi system.conf

#
# This file is parsed by the Dahdi Configurator, dahdi_cfg
#
# Span 1: D130/0/1 "D130 (E1|T1) Card 0 Span 1" (MASTER)
span=1,1,0,ccs,hdb3,crc4
# termtype: te
bchan=1-15,17-31
dchan=16
echocanceller=oslec,1-15,17-31

# Span 2: B4/0/1 "B4XXP (PCI) Card 0 Span 1"
span=2,0,0,ccs,ami
# termtype: nt
bchan=32-33
hardhdlc=34
echocanceller=oslec,32-33

# Span 3: B4/0/2 "B4XXP (PCI) Card 0 Span 2"
span=3,0,0,ccs,ami
# termtype: nt
bchan=35-36
hardhdlc=37
echocanceller=oslec,35-36

# Span 4: B4/0/3 "B4XXP (PCI) Card 0 Span 3"
span=4,0,0,ccs,ami
# termtype: nt
bchan=38-39
hardhdlc=40
echocanceller=oslec,38-39

# Span 5: B4/0/4 "B4XXP (PCI) Card 0 Span 4"
span=5,0,0,ccs,ami
# termtype: nt
bchan=41-42
hardhdlc=43
echocanceller=oslec,41-42

# Global data

loadzone = it
defaultzone = it

chan_dahdi.conf

; Auto-generated by /usr/sbin/hardware_detector
[trunkgroups]

[channels]
context=from-pstn
signalling=fxs_ks
rxwink=300 ; Atlas seems to use long (250ms) winks
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
faxdetect=both
echotraining=800
rxgain=0.0
txgain=0.0
callgroup=1
pickupgroup=1
relaxdtmf=yes

;Uncomment these lines if you have problems with the disconection of your analog lines
;busydetect=yes
;busycount=3


immediate=no

internationalprefix = 00
nationalprefix = 0
localprefix = 089
language = it

#include dahdi-channels.conf
#include chan_dahdi_additional.conf

dahdi-channels.conf

; Span 1: D130/0/1 "D130 (E1|T1) Card 0 Span 1" (MASTER)
group=0,11
context=from-pstn
switchtype = euroisdn
signalling = pri_cpe
channel => 1-15,17-31
context = default
group = 63

; Span 2: B4/0/1 "B4XXP (PCI) Card 0 Span 1"
group=0,12
context=from-internal
switchtype = euroisdn
signalling = bri_net_ptmp
channel => 32-33
context = default
group = 63

; Span 3: B4/0/2 "B4XXP (PCI) Card 0 Span 2"
group=0,13
context=from-internal
switchtype = euroisdn
signalling = bri_net_ptmp
channel => 35-36
context = default
group = 63

; Span 4: B4/0/3 "B4XXP (PCI) Card 0 Span 3"
group=0,14
context=from-internal
switchtype = euroisdn
signalling = bri_net_ptmp
channel => 38-39
context = default
group = 63

; Span 5: B4/0/4 "B4XXP (PCI) Card 0 Span 4"
group=0,15
context=from-internal
switchtype = euroisdn
signalling = bri_net_ptmp
channel => 41-42
context = default
group = 63

chan_dahdi_additional.conf

;;;;;;[7001]
signalling=fxo_ks
pickupgroup=
mailbox=7001@device
immediate=no
echotraining=800
echocancelwhenbridged=no
echocancel=yes
context=from-internal
callprogress=no
callgroup=
callerid=device <7001>
busydetect=no
busycount=7
accountcode=
channel=>33

;;;;;;[7000]
signalling=pri_cpe
pickupgroup=
mailbox=7000@device
immediate=no
echotraining=800
echocancelwhenbridged=no
echocancel=yes
context=from-internal
callprogress=no
callgroup=
callerid=device <7000>
busydetect=no
busycount=7
accountcode=
channel=>32

debug of span 2 on call to assigned extension

[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 -- Making new call for cref 32771
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 Sending message for call 0x2ad44c09aa20 on call->link: 0x2ad420152ab0 with TEI/SAPI 127/63
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 ( len=44
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > TEI=127 Call Ref: len= 1 (reference 3/0x3) (Sent from originator)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Message Type: SETUP (5)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [04 03 80 90 a3]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > User information layer 1: A-Law (35)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [18 01 89]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Channel ID (len= 3) [ Ext: 1 IntID: Implicit BRI Spare: 0 Exclusive Dchan: 0
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > ChanSel: B1 channel
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > ]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [28 0b 30 33 34 30 38 31 32 38 39 31 39]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Display (len=11) [ 03408128919 ]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [6c 0d 21 80 30 33 34 30 38 31 32 38 39 31 39]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Calling Party Number (len=15) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Presentation: Presentation allowed, User-provided, not screened (0) '03408128919' ]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [70 01 80]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Called Party Number (len= 3) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '' ]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > [a1]
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 > Sending Complete (len= 1)
[Oct 29 08:51:14] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 q931.c:6334 q931_setup: Call 32771 enters state 1 (Call Initiated). Hold state: Idle
[Oct 29 08:51:14] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 Got SABME from cpe peer.
[Oct 29 08:51:14] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 Sending UA
[Oct 29 08:51:14] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 Changing from state 4(TEI assigned) to 7(Multi-frame established)
[Oct 29 08:51:14] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 DL event: Q931_DL_EVENT_DL_ESTABLISH_IND(1)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 T303 timed out. cref:32771
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 Sending message for call 0x2ad44c09aa20 on call->link: 0x2ad420152ab0 with TEI/SAPI 127/63
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Protocol Discriminator: Q.931 ( len=44
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > TEI=127 Call Ref: len= 1 (reference 3/0x3) (Sent from originator)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Message Type: SETUP (5)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [04 03 80 90 a3]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > User information layer 1: A-Law (35)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [18 01 89]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Channel ID (len= 3) [ Ext: 1 IntID: Implicit BRI Spare: 0 Exclusive Dchan: 0
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > ChanSel: B1 channel
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > ]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [28 0b 30 33 34 30 38 31 32 38 39 31 39]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Display (len=11) [ 03408128919 ]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [6c 0d 21 80 30 33 34 30 38 31 32 38 39 31 39]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Calling Party Number (len=15) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Presentation: Presentation allowed, User-provided, not screened (0) '03408128919' ]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [70 01 80]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Called Party Number (len= 3) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '' ]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > [a1]
[Oct 29 08:51:18] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 > Sending Complete (len= 1)
[Oct 29 08:51:22] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 T303 timed out. cref:32771
[Oct 29 08:51:22] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 q931.c:6221 t303_expiry: Call 32771 enters state 22 (Call Abort). Hold state: Idle
[Oct 29 08:51:22] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 Fake clearing. cref:32771
[Oct 29 08:51:22] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 q931.c:9606 pri_internal_clear: alive 1, hangupack 1
[Oct 29 08:51:22] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 q931.c:7073 q931_hangup: Hangup master cref:32771
[Oct 29 08:51:22] VERBOSE[21771] chan_dahdi.c: PRI Span: 2 q931.c:7115 q931_hangup: Remaining slaves 0
[Oct 29 08:51:24] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 T312 timed out. cref:32771
[Oct 29 08:51:24] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 Destroying call 0x2ad44c09aa20, ourstate Call Abort, peerstate Call Present, hold-state Idle
[Oct 29 08:51:33] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 Got DISC
[Oct 29 08:51:33] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 Sending UA
[Oct 29 08:51:33] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 TEI=64 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
[Oct 29 08:51:33] VERBOSE[12719] chan_dahdi.c: PRI Span: 2 Changing from state 7(Multi-frame established) to 4(TEI assigned)

Can anyone help me ?

Regards
Pasquale

i] Last edited by pdifeo at 2013-10-29 16:36 [/i

Other info

Connected to Asterisk 11.5.0 currently running on pbx (pid = 12701)
pbx*CLI> pri show spa
spans span
pbx*CLI> pri show spans
PRI span 1/0: Up, Active
PRI span 2/0: Up, Active
PRI span 3/0: Up, Active
PRI span 4/0: In Alarm, Up, Active
PRI span 5/0: In Alarm, Up, Active
pbx*CLI>

Hi,
Why there is only sending message but none message coming from other side. Please add my Skyep upper.hua . I will check it .

upper.hua

I'm not able to make a connection for you. The server is in customer site.

Another info. On the client side, the log say "Protocol error in Layer 2" and "Network out order". I inform you that on client side the line is configured in Point-to-Multipoint.

Thanks in advance for your future answers.
Pasquale

Another info. In dmesg log I find

wcb4xxp 0000:02:00.0: Got FIFO TX int from non-d-chan FIFO 0??
wcb4xxp 0000:02:00.0: Got FIFO RX int from non-d-chan FIFO 0??
wcb4xxp 0000:02:00.0: Got FIFO TX int from non-d-chan FIFO 1??
wcb4xxp 0000:02:00.0: Got FIFO RX int from non-d-chan FIFO 1??
wcb4xxp 0000:02:00.0: Got FIFO TX int from non-d-chan FIFO 2??
wcb4xxp 0000:02:00.0: Got FIFO RX int from non-d-chan FIFO 2??
wcb4xxp 0000:02:00.0: Got FIFO TX int from non-d-chan FIFO 3??
wcb4xxp 0000:02:00.0: Got FIFO RX int from non-d-chan FIFO 3??
wcb4xxp 0000:02:00.0: Got FIFO TX int from non-d-chan FIFO 4??
wcb4xxp 0000:02:00.0: Got FIFO RX int from non-d-chan FIFO 4??

many times.
Regards
Pasquale

Solved. Thanks anyway.
The problem was on AVM card settings.

But now I have another issue. Outgoing faxes works, incoming not. Any help is welcome.

Regards
Pasquale