Digium b410p with i4b
Mikael Batard
support at wanagain.net
Wed Jul 11 22:48:21 UTC 2007
Hi,
I have some problems with incoming calls with digium b410p and i4b.
Sometimes it doesn't work and I don't know why.
Hardphones doesn't ring at all, but I can see that these missed calls
are handle by asterisk.
But they're not handle normally by chan_capi.
Here's the log for a missed call (verbose+debug+capi debug) :
Jul 12 00:26:24 DEBUG[2917] channel.c: Set channel CAPI/ISDN3/0987-0 to
write format slin
Jul 12 00:26:24 DEBUG[2917] res_musiconhold.c: CAPI/ISDN3/0987-0 Opened
file 0 '/usr/local/share/asterisk/mohmp3/Pilot'
Jul 12 00:26:24 WARNING[2917] interface.c: Junk at the beginning of
frame 49443303
Jul 12 00:26:24 VERBOSE[2917] logger.c: ==
chan_capi_write:4799:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
==
/// Lots of "chan_capi_write" lines same as the one above ///
Jul 12 00:31:42 VERBOSE[2917] logger.c: CAPI_INFO_IND {
header {
WORD wLen = 0x000f
WORD wApp = 0x0000
WORD wCmd = 0x8492
WORD wNum = 0x0000
DWORD dwCid = 0x0000380a
}
data {
WORD wInfoNum = 0x805a
STRUCT InfoElement.ptr = 0x00 '?'.
}
}
Jul 12 00:31:42 VERBOSE[2917] logger.c: CAPI_INFO_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x846d
WORD wNum = 0x0000
DWORD dwCid = 0x0000380a
}
data {
}
}
Jul 12 00:31:42 VERBOSE[2917] logger.c: --
capi_handle_info_indication:5200:ENTRY=ISDN3:PLCI=0x380a:PBX_CHAN=CAPI/ISDN3/0987-2:
-- RELEASE COMPLETE
Jul 12 00:31:42 VERBOSE[2917] logger.c: CAPI_DISCONNECT_IND {
header {
WORD wLen = 0x000e
WORD wApp = 0x0000
WORD wCmd = 0x848c
WORD wNum = 0x0000
DWORD dwCid = 0x0000380a
}
data {
WORD wReason = 0x3451
}
}
Jul 12 00:31:42 VERBOSE[2917] logger.c: CAPI_DISCONNECT_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x8473
WORD wNum = 0x0000
DWORD dwCid = 0x0000380a
}
data {
}
Jul 12 00:31:42 VERBOSE[2917] logger.c: ==
cd_free:2152:ENTRY=ISDN3:PLCI=0x380a:PBX_CHAN=CAPI/ISDN3/0987-2:
==
Jul 12 00:31:42 VERBOSE[2917] logger.c: > CAPI:
Command=DISCONNECT_IND, 0x848c: no call descriptor for PLCI=0x380a,
MSGNUM=0x0000:
Jul 12 00:31:42 ERROR[2917] chan_capi.c: PBX channel has no interface!
Jul 12 00:31:42 ERROR[2917] chan_capi.c: PBX channel has no interface!
Jul 12 00:31:42 VERBOSE[2917] logger.c: -- Stopped music on hold on
CAPI/ISDN3/0987-2
Jul 12 00:31:42 DEBUG[2917] channel.c: Set channel CAPI/ISDN3/0987-2 to
write format alaw
Jul 12 00:31:42 DEBUG[2917] channel.c: Scheduling timer at 0 sample
intervals
Here's the log for a normal call :
Jul 12 00:26:24 DEBUG[2917] channel.c: Set channel CAPI/ISDN3/0987-0 to
write format slin
Jul 12 00:26:24 DEBUG[2917] res_musiconhold.c: CAPI/ISDN3/0987-0 Opened
file 0 '/usr/local/share/asterisk/mohmp3/Pilot'
Jul 12 00:26:24 WARNING[2917] interface.c: Junk at the beginning of
frame 49443303
Jul 12 00:26:24 VERBOSE[2917] logger.c: ==
chan_capi_write:4799:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
==
/// Lots of "chan_capi_write" lines same as the one above ///
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_ACTIVE_IND {
header {
WORD wLen = 0x0010
WORD wApp = 0x0000
WORD wCmd = 0x8484
WORD wNum = 0x0000
DWORD dwCid = 0x0000360a
}
data {
STRUCT connected_telno.ptr = 0x00 '?'.
STRUCT connected_subaddr.ptr= 0x00 '?'.
STRUCT LLC.ptr = 0x00 '?'.
STRUCT date_time.ptr = 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_ACTIVE_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847b
WORD wNum = 0x0000
DWORD dwCid = 0x0000360a
}
data {
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_INFO_IND {
header {
WORD wLen = 0x000f
WORD wApp = 0x0000
WORD wCmd = 0x8492
WORD wNum = 0x0000
DWORD dwCid = 0x0000360a
}
data {
WORD wInfoNum = 0x800f
STRUCT InfoElement.ptr = 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_INFO_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x846d
WORD wNum = 0x0000
DWORD dwCid = 0x0000360a
}
data {
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: --
capi_handle_info_indication:5187:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
-- CONNECT ACK
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_B3_IND {
header {
WORD wLen = 0x000d
WORD wApp = 0x0000
WORD wCmd = 0x8486
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
STRUCT NCPI.ptr = 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_B3_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x8479
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
WORD wReject = 0x0000
STRUCT NCPI.ptr = (empty)
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_B3_ACTIVE_IND {
header {
WORD wLen = 0x000d
WORD wApp = 0x0000
WORD wCmd = 0x8488
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
STRUCT NCPI.ptr = 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_CONNECT_B3_ACTIVE_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x8477
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x0008
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x08135012
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x0009
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x081350b2
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x000a
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x08135152
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x000b
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x081351f2
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: ==
capi_send_echo_cancel_req:3402:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
== Setting up echo canceller, function=1, options=4, tail=64.
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_FACILITY_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x8468
WORD wNum = 0x000c
DWORD dwCid = 0x0000360a
}
data {
WORD wSelector = 0x0008
STRUCT Param.ptr = 0x09 '?', 0x01 '?', 0x00 '?', 0x06 '?',
= 0x04 '?', 0x00 '?', 0x40 '@', 0x00 '?',
= 0x00 '?', 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: ==
capi_send_detect_dtmf_req:3447:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
== Setting up DTMF detector, flag=1
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_CONF {
header {
WORD wLen = 0x0010
WORD wApp = 0x0000
WORD wCmd = 0x8481
WORD wNum = 0x0008
DWORD dwCid = 0x0001360a
}
data {
WORD wHandle = 0x0000
WORD wInfo = 0x0000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x000d
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x08135292
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_FACILITY_CONF {
header {
WORD wLen = 0x0016
WORD wApp = 0x0000
WORD wCmd = 0x8497
WORD wNum = 0x000c
DWORD dwCid = 0x0000360a
}
data {
WORD wInfo = 0x0000
WORD wSelector = 0x0008
STRUCT Param.ptr = 0x05 '?', 0x01 '?', 0x00 '?', 0x02 '?',
= 0x00 '?', 0x00 '?'.
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: --
capi_handle_facility_confirmation_cd:6173:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
-- Echo canceller successfully set up
Jul 12 00:26:25 VERBOSE[2917] logger.c: ==
chan_capi_read:4773:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
==
Jul 12 00:26:25 DEBUG[2917] channel.c: Dropping duplicate answer!
Jul 12 00:26:25 VERBOSE[2917] logger.c: ==
chan_capi_write:4799:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
==
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_IND {
header {
WORD wLen = 0x001e
WORD wApp = 0x0000
WORD wCmd = 0x8480
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x089bd026
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_RESP {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847f
WORD wNum = 0x0000
DWORD dwCid = 0x0001360a
}
data {
WORD wHandle = 0x0000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: --
cd_send_pbx_voice:2676:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
-- temp_fr.datalen=160, temp_fr.subclass=8
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_CONF {
header {
WORD wLen = 0x0010
WORD wApp = 0x0000
WORD wCmd = 0x8481
WORD wNum = 0x0009
DWORD dwCid = 0x0001360a
}
data {
WORD wHandle = 0x0000
WORD wInfo = 0x0000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: CAPI_DATA_B3_REQ {
header {
WORD wLen = 0x0000
WORD wApp = 0x0000
WORD wCmd = 0x847e
WORD wNum = 0x000e
DWORD dwCid = 0x0001360a
}
data {
DWORD dwPtr_1 = 0x08135332
WORD wLen = 0x00a0
WORD wHandle = 0x0000
WORD wFlags = 0x0000
QWORD qwPtr_2 = 0x0000000000000000
}
}
Jul 12 00:26:25 VERBOSE[2917] logger.c: ==
chan_capi_read:4773:ENTRY=ISDN3:PLCI=0x360a:PBX_CHAN=CAPI/ISDN3/0987-0:
==
Jul 12 00:26:25 DEBUG[2917] channel.c: Generator got voice, switching to
phase locked mode
Jul 12 00:26:25 DEBUG[2917] channel.c: Scheduling timer at 0 sample
intervals
Maybe there is something to tune in isdnconfig configuration ?
Thanks for your help.
Regards
--
Mikael Batard
Wan Again
12 Route de l'Isle
84510 Caumont sur Durance
Tel: +33 (0)4 90 01 26 86
Fax: +33 (0)4 90 23 10 58
http://www.wanagain.fr/
More information about the freebsd-isdn
mailing list