[asterisk-r2] OpenR2 and A104DE (Sangoma Technologies)

Mon Feb 23 20:51:18 CST 2015

Dear Mc Grath, thanks in advanced.
The last log:
[18:52:41:506] [Thread: 3049012080] [Chan 108] - Call started at Mon Feb 23
18:52:41 2015 on chan 108 [openr2 version 1.3.3, revision (release)]
[18:52:41:506] [Thread: 3049012080] [Chan 108] - Initialized R2 MF detector
[18:52:41:506] [Thread: 3049012080] [Chan 108] - CAS Tx >> [SEIZE ACK] 0x0C
[18:52:41:506] [Thread: 3049012080] [Chan 108] - CAS Raw Tx >> 0x0D
[18:52:41:827] [Thread: 3049012080] [Chan 108] - MF Rx << 9 [ON]
[18:52:41:827] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 0
[18:52:41:827] [Thread: 3049012080] [Chan 108] - Cannot cancel timer 0
[18:52:41:827] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 9
[18:52:41:827] [Thread: 3049012080] [Chan 108] - DNIS so far: 9, expected
length: 20
[18:52:41:827] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:41:827] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:41:827] [Thread: 3049012080] [Chan 108] - scheduled timer id 2
(mf_back_cycle)
[18:52:41:907] [Thread: 3049012080] [Chan 108] - MF Rx << 9 [OFF]
[18:52:41:907] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:42:067] [Thread: 3049012080] [Chan 108] - MF Rx << 8 [ON]
[18:52:42:067] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 2
[18:52:42:067] [Thread: 3049012080] [Chan 108] - timer id 2 found,
cancelling it now
[18:52:42:067] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 8
[18:52:42:067] [Thread: 3049012080] [Chan 108] - DNIS so far: 98, expected
length: 20
[18:52:42:067] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:42:067] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:42:067] [Thread: 3049012080] [Chan 108] - scheduled timer id 3
(mf_back_cycle)
[18:52:42:147] [Thread: 3049012080] [Chan 108] - MF Rx << 8 [OFF]
[18:52:42:147] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:42:307] [Thread: 3049012080] [Chan 108] - MF Rx << 6 [ON]
[18:52:42:307] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 3
[18:52:42:307] [Thread: 3049012080] [Chan 108] - timer id 3 found,
cancelling it now
[18:52:42:307] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 6
[18:52:42:307] [Thread: 3049012080] [Chan 108] - DNIS so far: 986, expected
length: 20
[18:52:42:307] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:42:307] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:42:307] [Thread: 3049012080] [Chan 108] - scheduled timer id 4
(mf_back_cycle)
[18:52:42:387] [Thread: 3049012080] [Chan 108] - MF Rx << 6 [OFF]
[18:52:42:387] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:42:527] [Thread: 3049012080] [Chan 108] - MF Rx << 9 [ON]
[18:52:42:527] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 4
[18:52:42:527] [Thread: 3049012080] [Chan 108] - timer id 4 found,
cancelling it now
[18:52:42:527] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 9
[18:52:42:527] [Thread: 3049012080] [Chan 108] - DNIS so far: 9869,
expected length: 20
[18:52:42:527] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:42:527] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:42:527] [Thread: 3049012080] [Chan 108] - scheduled timer id 5
(mf_back_cycle)
[18:52:42:607] [Thread: 3049012080] [Chan 108] - MF Rx << 9 [OFF]
[18:52:42:607] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:42:747] [Thread: 3049012080] [Chan 108] - MF Rx << 3 [ON]
[18:52:42:747] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 5
[18:52:42:747] [Thread: 3049012080] [Chan 108] - timer id 5 found,
cancelling it now
[18:52:42:747] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 3
[18:52:42:747] [Thread: 3049012080] [Chan 108] - DNIS so far: 98693,
expected length: 20
[18:52:42:747] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:42:747] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:42:747] [Thread: 3049012080] [Chan 108] - scheduled timer id 6
(mf_back_cycle)
[18:52:42:827] [Thread: 3049012080] [Chan 108] - MF Rx << 3 [OFF]
[18:52:42:827] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:43:027] [Thread: 3049012080] [Chan 108] - MF Rx << 8 [ON]
[18:52:43:027] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 6
[18:52:43:027] [Thread: 3049012080] [Chan 108] - timer id 6 found,
cancelling it now
[18:52:43:027] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 8
[18:52:43:027] [Thread: 3049012080] [Chan 108] - DNIS so far: 986938,
expected length: 20
[18:52:43:027] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:43:027] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:43:027] [Thread: 3049012080] [Chan 108] - scheduled timer id 7
(mf_back_cycle)
[18:52:43:107] [Thread: 3049012080] [Chan 108] - MF Rx << 8 [OFF]
[18:52:43:107] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:43:267] [Thread: 3049012080] [Chan 108] - MF Rx << 6 [ON]
[18:52:43:267] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 7
[18:52:43:267] [Thread: 3049012080] [Chan 108] - timer id 7 found,
cancelling it now
[18:52:43:267] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 6
[18:52:43:267] [Thread: 3049012080] [Chan 108] - DNIS so far: 9869386,
expected length: 20
[18:52:43:267] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:43:267] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:43:267] [Thread: 3049012080] [Chan 108] - scheduled timer id 8
(mf_back_cycle)
[18:52:43:347] [Thread: 3049012080] [Chan 108] - MF Rx << 6 [OFF]
[18:52:43:347] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:43:507] [Thread: 3049012080] [Chan 108] - MF Rx << 5 [ON]
[18:52:43:507] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 8
[18:52:43:507] [Thread: 3049012080] [Chan 108] - timer id 8 found,
cancelling it now
[18:52:43:507] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 5
[18:52:43:507] [Thread: 3049012080] [Chan 108] - DNIS so far: 98693865,
expected length: 20
[18:52:43:507] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:43:507] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:43:507] [Thread: 3049012080] [Chan 108] - scheduled timer id 9
(mf_back_cycle)
[18:52:43:567] [Thread: 3049012080] [Chan 108] - MF Rx << 5 [OFF]
[18:52:43:567] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:43:767] [Thread: 3049012080] [Chan 108] - MF Rx << 0 [ON]
[18:52:43:767] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 9
[18:52:43:767] [Thread: 3049012080] [Chan 108] - timer id 9 found,
cancelling it now
[18:52:43:767] [Thread: 3049012080] [Chan 108] - Getting DNIS digit 0
[18:52:43:767] [Thread: 3049012080] [Chan 108] - DNIS so far: 986938650,
expected length: 20
[18:52:43:767] [Thread: 3049012080] [Chan 108] - Requesting next DNIS with
signal 0x31.
[18:52:43:767] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [ON]
[18:52:43:767] [Thread: 3049012080] [Chan 108] - scheduled timer id 10
(mf_back_cycle)
[18:52:43:847] [Thread: 3049012080] [Chan 108] - MF Rx << 0 [OFF]
[18:52:43:847] [Thread: 3049012080] [Chan 108] - MF Tx >> 1 [OFF]
[18:52:44:027] [Thread: 3049012080] [Chan 108] - MF Rx << F [ON]
[18:52:44:027] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 10
[18:52:44:027] [Thread: 3049012080] [Chan 108] - timer id 10 found,
cancelling it now
[18:52:44:027] [Thread: 3049012080] [Chan 108] - Protocol error. Reason =
Invalid Multi Frequency Tone, R2 State = Seize ACK Transmitted, MF state =
DNIS Request Transmitted, MF Group = Backward Group A, CAS = 0x00
DNIS = 986938650, ANI = , MF = 0x46
[18:52:44:027] [Thread: 3049012080] [Chan 108] - Attempting to cancel timer
timer 0
[18:52:44:027] [Thread: 3049012080] [Chan 108] - Cannot cancel timer 0

The cli message is:
[2015-02-23 18:52:44] ERROR[4618]: chan_dahdi.c:2004 dahdi_r2_write_log:
Chan 108 - Protocol error. Reason = Invalid Multi Frequency Tone, R2 State
= Seize ACK Transmitted, MF state = DNIS Request Transmitted, MF Group =
Backward Group A, CAS = 0x00
DNIS = 986938650, ANI = , MF = 0x46
[2015-02-23 18:52:44] ERROR[4618]: chan_dahdi.c:1789
dahdi_r2_on_protocol_error: MFC/R2 protocol error on chan 108: Invalid
Multi Frequency Tone

This last log is just with the mf_g1_tones.no_more_dnis_available=F
parameter enable, ok?

My current r2proto.conf
# X means invalid tone (when you dont want to support a tone)
mf_g1_tones.no_more_dnis_available=F
#mf_gb_tones.unallocated_number=7
#mf_gb_tones.reject_collect_call=7
#timers.mf_back_cycle=1500
#timers.mf_back_resume_cycle=150
#timers.mf_fwd_safety=10000
#timers.r2_seize=8000
#timers.r2_answer=80000
#timers.r2_metering_pulse=4000
#timers.r2_double_answer=400
#timers.r2_answer_delay=150
#timers.cas_persistence_check=500
#timers.dtmf_start_dial=100
#mf_threshold=50

But, when I set enable mfcr2_advanced_protocol_file=/usr/etc/r2proto.conf
on chan_dahdi.conf, the cli errors are:

CLI errors:
[2015-02-23 23:43:30] WARNING[6346]: chan_dahdi.c:2001 dahdi_r2_write_log:
Chan 101 - MF back cycle timed out!
[2015-02-23 23:43:30] ERROR[6346]: chan_dahdi.c:2004 dahdi_r2_write_log:
Chan 101 - Protocol error. Reason = Multi Frequency Cycle Timeout, R2 State
= Seize ACK Transmitted, MF state = DNIS Request Transmitted, MF Group =
Backward Group A, CAS = 0x00
DNIS = 00316239993972, ANI = , MF = 0x20
[2015-02-23 23:43:30] ERROR[6346]: chan_dahdi.c:1789
dahdi_r2_on_protocol_error: MFC/R2 protocol error on chan 101: Multi
Frequency Cycle Timeout
Chan 101 - Handling persistent pattern 0x08
[2015-02-23 23:43:30] NOTICE[6346]: chan_dahdi.c:2035
dahdi_r2_on_line_idle: Far end unblocked on chan 101

The call trace

[23:44:18:632] [Thread: 3048766320] [Chan 102] - M - Call started at Mon
Feb 23 23:44:18 2015 on chan 102 [openr2 version 1.3.3, revision (release)]
[23:44:18:632] [Thread: 3048766320] [Chan 102] - M - Initialized R2 MF
detector
[23:44:18:632] [Thread: 3048766320] [Chan 102] - M - CAS Tx >> [SEIZE ACK]
0x0C
[23:44:18:632] [Thread: 3048766320] [Chan 102] - M - CAS Raw Tx >> 0x0D
[23:44:18:912] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:18:932] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:18:952] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - MF Rx << 0 [ON]
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 0
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - Cannot cancel timer 0
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 0
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 0,
expected length: 20
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:18:972] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 4
(mf_back_cycle)
[23:44:19:052] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:072] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:092] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:112] [Thread: 3048766320] [Chan 102] - M - MF Rx << 0 [OFF]
[23:44:19:112] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:19:272] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:19:292] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:19:312] [Thread: 3048766320] [Chan 102] - M - Tone 0 ignored
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - MF Rx << 0 [ON]
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 4
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - timer id 4 found,
cancelling it now
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 0
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 00,
expected length: 20
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:19:332] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 5
(mf_back_cycle)
[23:44:19:412] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:432] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:452] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:472] [Thread: 3048766320] [Chan 102] - M - MF Rx << 0 [OFF]
[23:44:19:472] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:19:692] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:19:712] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:19:732] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [ON]
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 5
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - timer id 5 found,
cancelling it now
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 3
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 003,
expected length: 20
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:19:752] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 6
(mf_back_cycle)
[23:44:19:832] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:852] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:872] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:19:892] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [OFF]
[23:44:19:892] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:20:052] [Thread: 3048766320] [Chan 102] - M - Tone 1 ignored
[23:44:20:072] [Thread: 3048766320] [Chan 102] - M - Tone 1 ignored
[23:44:20:092] [Thread: 3048766320] [Chan 102] - M - Tone 1 ignored
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - MF Rx << 1 [ON]
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 6
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - timer id 6 found,
cancelling it now
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 1
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 0031,
expected length: 20
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:20:112] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 7
(mf_back_cycle)
[23:44:20:192] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:212] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:232] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:252] [Thread: 3048766320] [Chan 102] - M - MF Rx << 1 [OFF]
[23:44:20:252] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:20:412] [Thread: 3048766320] [Chan 102] - M - Tone 6 ignored
[23:44:20:432] [Thread: 3048766320] [Chan 102] - M - Tone 6 ignored
[23:44:20:452] [Thread: 3048766320] [Chan 102] - M - Tone 6 ignored
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - MF Rx << 6 [ON]
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 7
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - timer id 7 found,
cancelling it now
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 6
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 00316,
expected length: 20
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:20:472] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 8
(mf_back_cycle)
[23:44:20:552] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:572] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:592] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:612] [Thread: 3048766320] [Chan 102] - M - MF Rx << 6 [OFF]
[23:44:20:612] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:20:792] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:20:812] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:20:832] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - MF Rx << 2 [ON]
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 8
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - timer id 8 found,
cancelling it now
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 2
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 003162,
expected length: 20
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:20:852] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 9
(mf_back_cycle)
[23:44:20:932] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:952] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:972] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:20:992] [Thread: 3048766320] [Chan 102] - M - MF Rx << 2 [OFF]
[23:44:20:992] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:21:152] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:21:172] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:21:192] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [ON]
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 9
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - timer id 9 found,
cancelling it now
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 3
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 0031623,
expected length: 20
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:21:212] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 10
(mf_back_cycle)
[23:44:21:292] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:312] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:332] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:352] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [OFF]
[23:44:21:352] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:21:512] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:532] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:552] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [ON]
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 10
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - timer id 10 found,
cancelling it now
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 9
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - DNIS so far: 00316239,
expected length: 20
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:21:572] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 11
(mf_back_cycle)
[23:44:21:672] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:692] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:712] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:21:732] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [OFF]
[23:44:21:732] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:21:892] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:912] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:932] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [ON]
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 11
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - timer id 11 found,
cancelling it now
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 9
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
003162399, expected length: 20
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:21:952] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 12
(mf_back_cycle)
[23:44:22:032] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:052] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:072] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:092] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [OFF]
[23:44:22:092] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:22:232] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:22:252] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:22:272] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [ON]
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 12
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - timer id 12 found,
cancelling it now
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 9
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
0031623999, expected length: 20
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:22:292] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 13
(mf_back_cycle)
[23:44:22:372] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:392] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:412] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:432] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [OFF]
[23:44:22:432] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:22:692] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:22:712] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:22:732] [Thread: 3048766320] [Chan 102] - M - Tone 3 ignored
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [ON]
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 13
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - timer id 13 found,
cancelling it now
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 3
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
00316239993, expected length: 20
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:22:752] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 14
(mf_back_cycle)
[23:44:22:832] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:852] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:872] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:22:892] [Thread: 3048766320] [Chan 102] - M - MF Rx << 3 [OFF]
[23:44:22:892] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:23:052] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:23:072] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:23:092] [Thread: 3048766320] [Chan 102] - M - Tone 9 ignored
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [ON]
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 14
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - timer id 14 found,
cancelling it now
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 9
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
003162399939, expected length: 20
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:23:112] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 15
(mf_back_cycle)
[23:44:23:192] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:212] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:232] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:252] [Thread: 3048766320] [Chan 102] - M - MF Rx << 9 [OFF]
[23:44:23:252] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:23:412] [Thread: 3048766320] [Chan 102] - M - Tone 7 ignored
[23:44:23:432] [Thread: 3048766320] [Chan 102] - M - Tone 7 ignored
[23:44:23:452] [Thread: 3048766320] [Chan 102] - M - Tone 7 ignored
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - MF Rx << 7 [ON]
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 15
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - timer id 15 found,
cancelling it now
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 7
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
0031623999397, expected length: 20
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:23:472] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 16
(mf_back_cycle)
[23:44:23:552] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:572] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:592] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:612] [Thread: 3048766320] [Chan 102] - M - MF Rx << 7 [OFF]
[23:44:23:612] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:23:772] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:23:792] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:23:812] [Thread: 3048766320] [Chan 102] - M - Tone 2 ignored
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - MF Rx << 2 [ON]
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 16
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - timer id 16 found,
cancelling it now
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - Getting DNIS digit 2
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - DNIS so far:
00316239993972, expected length: 20
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - Requesting next DNIS
with signal 0x31.
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [ON]
[23:44:23:832] [Thread: 3048766320] [Chan 102] - M - scheduled timer id 17
(mf_back_cycle)
[23:44:23:912] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:932] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:952] [Thread: 3048766320] [Chan 102] - M - Tone off ignored
[23:44:23:972] [Thread: 3048766320] [Chan 102] - M - MF Rx << 2 [OFF]
[23:44:23:972] [Thread: 3048766320] [Chan 102] - M - MF Tx >> 1 [OFF]
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 17
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - timer id 17 found,
cancelling it now
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - calling timer 17
(mf_back_cycle) callback
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - MF back cycle timed
out!
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - Protocol error. Reason
= Multi Frequency Cycle Timeout, R2 State = Seize ACK Transmitted, MF state
= DNIS Request Transmitted, MF Group = Backward Group A, CAS = 0x00
DNIS = 00316239993972, ANI = , MF = 0x20
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - Attempting to cancel
timer timer 0
[23:44:25:332] [Thread: 3048766320] [Chan 102] - M - Cannot cancel timer 0

With Best Regards

Josue Conti

2015-02-23 22:19 GMT-03:00 Mc GRATH Ricardo <mcgrathr at mail2web.com>:

>  Josue
>
> Hi, could you send a complete trace? (mfcr2_logdir=hipath)
> Tks
>
>  Mc GRATH Ricardo
>
>
>  ------------------------------
> *From:* asterisk-r2-bounces at lists.digium.com [
> asterisk-r2-bounces at lists.digium.com] On Behalf Of Josué Conti [
> josueconti at gmail.com]
> *Sent:* 23 February 2015 15:12
> *To:* asterisk-r2 at lists.digium.com
> *Subject:* [asterisk-r2] OpenR2 and A104DE (Sangoma Technologies)
>
>    Hy guys, how are you?
> I´m trying make calls from Siemens HiPath to Asterisk-1.6.2.24, DAHDI
> Version: 2.9.1.1 Echo Canceller: HWEC, using openR2 version 1.3.3 (release)
> and report these erros below:
>
> For sample, trying call to Sangoma Technologies:
> [2015-02-19 11:23:27] ERROR[10903]: chan_dahdi.c:2004 dahdi_r2_write_log:
> Chan 105 - Protocol error. Reason = Invalid Multi Frequency Tone, R2 State
> = Seize ACK Transmitted, MF state = DNIS Request Transmitted, MF Group =
> Backward Group A, CAS = 0x00
> DNIS = 00019054741990, ANI = , MF = 0x46
> [2015-02-19 11:23:27] ERROR[10903]: chan_dahdi.c:1789
> dahdi_r2_on_protocol_error: MFC/R2 protocol error on chan 105: Invalid
> Multi Frequency Tone
> [2015-02-19 11:23:27] NOTICE[10903]: chan_dahdi.c:2035
> dahdi_r2_on_line_idle: Far end unblocked on chan 105
>
>
> My chan_dahdi.conf is:
> ;Sangoma A104 port 4 [slot:4 bus:9 span:4] <wanpipe4> MFCR2 S/CRC4 HIPATH
> 4000 v6.0
> signalling=mfcr2
> mfcr2_variant=br
> mfcr2_immediate_accept=no
> mfcr2_get_ani_first=no
> mfcr2_max_ani=8
> mfcr2_max_dnis=20
> mfcr2_category=national_subscriber
> mfcr2_logdir=hipath
> mfcr2_call_files=yes
> mfcr2_logging=all
> mfcr2_allow_collect_calls=yes
> mfcr2_double_answer=no
> mfcr2_metering_pulse_timeout=4000
> ; Needed because this PABX delay digits forward
> mfcr2_mfback_timeout=5000
> mfcr2_charge_calls=yes
> ; This PABX tends to bug without this
> mfcr2_forced_release=yes
> ;mfcr2_metering_pulse_timeout=-1
> ;mfcr2_advanced_protocol_file=/usr/etc/r2proto.conf
> context=default
> group=2
> channel => 94-108:1101
> channel => 110-124:1101
>
>
> Do you have any idea why reason this happening?
>
> With Best Regards
>
> Josue
>
> --
> _____________________________________________________________________
> -- Bandwidth and Colocation Provided by http://www.api-digital.com --
>
> asterisk-r2 mailing list
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-r2
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-r2/attachments/20150224/bc890ef6/attachment.html>