[asterisk-r2] Problem Get ANI Number

Sun Oct 31 00:31:04 CDT 2010

Hi , after change to your latest svn source, we now have getting ANI
digit successfully. so far, our AGI now can run normal, 
this is the result :

New MFC/R2 call detected on chan 127.
MFC/R2 call offered on chan 127. ANI = 811176889, DNIS = 109, Category =
National Priority Subscriber
MFC/R2 call has been accepted on backward channel 127
    -- Executing [109 at incoming:1] Answer("DAHDI/127-1", "") in new stack
    -- Executing [109 at incoming:2] Set("DAHDI/127-1",
"CHANNEL(language)=id") in new stack
    -- Executing [109 at incoming:3] AGI("DAHDI/127-1", "v109") in new
stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/v109
v109: v109
    -- Playing 'bhs109_5' (escape_digits=1234567890#*) (sample_offset 0)
    -- <DAHDI/127-1> Playing 'bhs109_6.gsm' (language 'id')
Chan 127 - Far end disconnected. Reason: Normal Clearing
MFC/R2 call disconnected on channel 127
    -- <DAHDI/127-1>AGI Script v109 completed, returning -1
MFC/R2 call end on channel 127
    -- Hungup 'DAHDI/127-1'
vrs01*CLI> 

and log :

[12:29:21:786] [Thread: 3077569424] [Chan 127] - Call started at Sun Oct
31 12:29:21 2010 on chan 127 [openr2 version 1.3.1, revision ]
[12:29:21:786] [Thread: 3077569424] [Chan 127] - Initialized R2 MF
detector
[12:29:21:786] [Thread: 3077569424] [Chan 127] - CAS Tx >> [SEIZE ACK]
0x0C
[12:29:21:786] [Thread: 3077569424] [Chan 127] - CAS Raw Tx >> 0x0D
[12:29:21:885] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [ON]
[12:29:21:885] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 0
[12:29:21:885] [Thread: 3077569424] [Chan 127] - Cannot cancel timer 0
[12:29:21:885] [Thread: 3077569424] [Chan 127] - Getting DNIS digit 1
[12:29:21:885] [Thread: 3077569424] [Chan 127] - DNIS so far: 1,
expected length: 4
[12:29:21:885] [Thread: 3077569424] [Chan 127] - Requesting next DNIS
with signal 0x31.
[12:29:21:885] [Thread: 3077569424] [Chan 127] - MF Tx >> 1 [ON]
[12:29:21:885] [Thread: 3077569424] [Chan 127] - scheduled timer id 2
(mf_back_cycle)
[12:29:21:965] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [OFF]
[12:29:21:965] [Thread: 3077569424] [Chan 127] - MF Tx >> 1 [OFF]
[12:29:22:045] [Thread: 3077569424] [Chan 127] - MF Rx << 0 [ON]
[12:29:22:045] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 2
[12:29:22:045] [Thread: 3077569424] [Chan 127] - timer id 2 found,
cancelling it now
[12:29:22:045] [Thread: 3077569424] [Chan 127] - Getting DNIS digit 0
[12:29:22:045] [Thread: 3077569424] [Chan 127] - DNIS so far: 10,
expected length: 4
[12:29:22:045] [Thread: 3077569424] [Chan 127] - Requesting next DNIS
with signal 0x31.
[12:29:22:045] [Thread: 3077569424] [Chan 127] - MF Tx >> 1 [ON]
[12:29:22:045] [Thread: 3077569424] [Chan 127] - scheduled timer id 3
(mf_back_cycle)[12:29:22:125] [Thread: 3077569424] [Chan 127] - MF Rx <<
0 [OFF]
[12:29:22:125] [Thread: 3077569424] [Chan 127] - MF Tx >> 1 [OFF]
[12:29:22:185] [Thread: 3077569424] [Chan 127] - MF Rx << 9 [ON]
[12:29:22:185] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 3
[12:29:22:185] [Thread: 3077569424] [Chan 127] - timer id 3 found,
cancelling it now
[12:29:22:185] [Thread: 3077569424] [Chan 127] - Getting DNIS digit 9
[12:29:22:185] [Thread: 3077569424] [Chan 127] - DNIS so far: 109,
expected length: 4
[12:29:22:185] [Thread: 3077569424] [Chan 127] - User requested us to
stop getting DNIS!
[12:29:22:185] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:185] [Thread: 3077569424] [Chan 127] - scheduled timer id 4
(mf_back_cycle)
[12:29:22:265] [Thread: 3077569424] [Chan 127] - MF Rx << 9 [OFF]
[12:29:22:265] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:22:345] [Thread: 3077569424] [Chan 127] - MF Rx << 2 [ON]
[12:29:22:345] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 4
[12:29:22:345] [Thread: 3077569424] [Chan 127] - timer id 4 found,
cancelling it now
[12:29:22:345] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:345] [Thread: 3077569424] [Chan 127] - scheduled timer id 5
(mf_back_cycle)
[12:29:22:425] [Thread: 3077569424] [Chan 127] - MF Rx << 2 [OFF]
[12:29:22:425] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:22:505] [Thread: 3077569424] [Chan 127] - MF Rx << 8 [ON]
[12:29:22:505] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 5
[12:29:22:505] [Thread: 3077569424] [Chan 127] - timer id 5 found,
cancelling it now
[12:29:22:505] [Thread: 3077569424] [Chan 127] - Getting ANI digit 8
[12:29:22:505] [Thread: 3077569424] [Chan 127] - ANI so far: 8, expected
length: 20
[12:29:22:505] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:505] [Thread: 3077569424] [Chan 127] - scheduled timer id 6
(mf_back_cycle)
[12:29:22:585] [Thread: 3077569424] [Chan 127] - MF Rx << 8 [OFF]
[12:29:22:585] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:22:645] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [ON]
[12:29:22:645] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 6
[12:29:22:645] [Thread: 3077569424] [Chan 127] - timer id 6 found,
cancelling it now
[12:29:22:645] [Thread: 3077569424] [Chan 127] - Getting ANI digit 1
[12:29:22:645] [Thread: 3077569424] [Chan 127] - ANI so far: 81,
expected length: 20
[12:29:22:645] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:645] [Thread: 3077569424] [Chan 127] - scheduled timer id 7
(mf_back_cycle)
[12:29:22:725] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [OFF]
[12:29:22:725] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:22:785] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [ON]
[12:29:22:785] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 7
[12:29:22:785] [Thread: 3077569424] [Chan 127] - timer id 7 found,
cancelling it now
[12:29:22:785] [Thread: 3077569424] [Chan 127] - Getting ANI digit 1
[12:29:22:785] [Thread: 3077569424] [Chan 127] - ANI so far: 811,
expected length: 20
[12:29:22:785] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:785] [Thread: 3077569424] [Chan 127] - scheduled timer id 8
(mf_back_cycle)
[12:29:22:885] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [OFF]
[12:29:22:885] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:22:945] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [ON]
[12:29:22:945] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 8
[12:29:22:945] [Thread: 3077569424] [Chan 127] - timer id 8 found,
cancelling it now
[12:29:22:945] [Thread: 3077569424] [Chan 127] - Getting ANI digit 1
[12:29:22:945] [Thread: 3077569424] [Chan 127] - ANI so far: 8111,
expected length: 20
[12:29:22:945] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:22:945] [Thread: 3077569424] [Chan 127] - scheduled timer id 9
(mf_back_cycle)
[12:29:23:025] [Thread: 3077569424] [Chan 127] - MF Rx << 1 [OFF]
[12:29:23:025] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:23:085] [Thread: 3077569424] [Chan 127] - MF Rx << 7 [ON]
[12:29:23:085] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 9
[12:29:23:085] [Thread: 3077569424] [Chan 127] - timer id 9 found,
cancelling it now
[12:29:23:085] [Thread: 3077569424] [Chan 127] - Getting ANI digit 7
[12:29:23:085] [Thread: 3077569424] [Chan 127] - ANI so far: 81117,
expected length: 20
[12:29:23:085] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:23:085] [Thread: 3077569424] [Chan 127] - scheduled timer id 10
(mf_back_cycle)
[12:29:23:165] [Thread: 3077569424] [Chan 127] - MF Rx << 7 [OFF]
[12:29:23:165] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:23:225] [Thread: 3077569424] [Chan 127] - MF Rx << 6 [ON]
[12:29:23:365] [Thread: 3077569424] [Chan 127] - ANI so far: 8111768,
expected length: 20
[12:29:23:365] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:23:365] [Thread: 3077569424] [Chan 127] - scheduled timer id 12
(mf_back_cycle)
[12:29:23:445] [Thread: 3077569424] [Chan 127] - MF Rx << 8 [OFF]
[12:29:23:445] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:23:505] [Thread: 3077569424] [Chan 127] - MF Rx << 8 [ON]
[12:29:23:505] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 12
[12:29:23:505] [Thread: 3077569424] [Chan 127] - timer id 12 found,
cancelling it now
[12:29:23:505] [Thread: 3077569424] [Chan 127] - Getting ANI digit 8
[12:29:23:505] [Thread: 3077569424] [Chan 127] - ANI so far: 81117688,
expected length: 20
[12:29:23:505] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:23:505] [Thread: 3077569424] [Chan 127] - scheduled timer id 13
(mf_back_cycle)
[12:29:23:585] [Thread: 3077569424] [Chan 127] - MF Rx << 8 [OFF]
[12:29:23:585] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:23:665] [Thread: 3077569424] [Chan 127] - MF Rx << 9 [ON]
[12:29:23:665] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 13
[12:29:23:665] [Thread: 3077569424] [Chan 127] - timer id 13 found,
cancelling it now
[12:29:23:665] [Thread: 3077569424] [Chan 127] - Getting ANI digit 9
[12:29:23:665] [Thread: 3077569424] [Chan 127] - ANI so far: 811176889,
expected length: 20
[12:29:23:665] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:23:665] [Thread: 3077569424] [Chan 127] - scheduled timer id 14
(mf_back_cycle)
[12:29:23:745] [Thread: 3077569424] [Chan 127] - MF Rx << 9 [OFF]
[12:29:23:745] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:23:825] [Thread: 3077569424] [Chan 127] - MF Rx << F [ON]
[12:29:23:825] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 14
[12:29:23:825] [Thread: 3077569424] [Chan 127] - timer id 14 found,
cancelling it now
[12:29:23:825] [Thread: 3077569424] [Chan 127] - Got end of ANI
[12:29:23:825] [Thread: 3077569424] [Chan 127] - Requesting change to
Group II with signal 0x33
[12:29:23:825] [Thread: 3077569424] [Chan 127] - MF Tx >> 3 [ON]
[12:29:23:825] [Thread: 3077569424] [Chan 127] - scheduled timer id 15
(mf_back_cycle)
[12:29:23:905] [Thread: 3077569424] [Chan 127] - MF Rx << F [OFF]
[12:29:23:905] [Thread: 3077569424] [Chan 127] - MF Tx >> 3 [OFF]
[12:29:23:985] [Thread: 3077569424] [Chan 127] - MF Rx << 2 [ON]
[12:29:23:985] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 15
[12:29:23:985] [Thread: 3077569424] [Chan 127] - timer id 15 found,
cancelling it now
[12:29:23:985] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [ON]
[12:29:23:985] [Thread: 3077569424] [Chan 127] - scheduled timer id 16
(mf_back_cycle)
[12:29:24:065] [Thread: 3077569424] [Chan 127] - MF Rx << 2 [OFF]
[12:29:24:065] [Thread: 3077569424] [Chan 127] - MF Tx >> 6 [OFF]
[12:29:24:065] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 16
[12:29:24:065] [Thread: 3077569424] [Chan 127] - timer id 16 found,
cancelling it now
[12:29:24:065] [Thread: 3077569424] [Chan 127] - scheduled timer id 17
(r2_answer_delay)
[12:29:24:225] [Thread: 3077569424] [Chan 127] - Attempting to cancel
timer timer 17
[12:29:24:225] [Thread: 3077569424] [Chan 127] - timer id 17 found,
cancelling it now
[12:29:24:225] [Thread: 3077569424] [Chan 127] - calling timer 17
(r2_answer_delay) callback
[12:29:24:226] [Thread: 3067276176] [Chan 127] - CAS Tx >> [ANSWER] 0x04
[12:29:24:226] [Thread: 3067276176] [Chan 127] - CAS Raw Tx >> 0x05
[12:29:29:551] [Thread: 3067276176] [Chan 127] - Bits changed from 0x00
to 0x08
[12:29:29:551] [Thread: 3067276176] [Chan 127] - CAS Rx << [CLEAR
FORWARD] 0x08
[12:29:29:551] [Thread: 3067276176] [Chan 127] - Far end disconnected.
Reason: Normal Clearing
[12:29:29:569] [Thread: 3067276176] [Chan 127] - Call ended
[12:29:29:569] [Thread: 3067276176] [Chan 127] - Attempting to cancel
timer timer 0
[12:29:29:569] [Thread: 3067276176] [Chan 127] - Cannot cancel timer 0

many thanks for helps :)

best regards,

roelee