[asterisk-r2] RES: asterisk-r2 Digest, Vol 8, Issue 45

Fri Apr 24 13:09:10 CDT 2009

Hy Moy,

Yesterday I formated my Server and reinstalled OpenR2. The calls
continue dropping after a time. Follow the logs:

(Remember - PSTN ->X AsteriskR2 -> AsteriskChannels -> User )

Cli>
[Apr 24 06:47:08] NOTICE[2685]: chan_dahdi.c:1023 dahdi_r2_on_call_init:
New MFC/R2 call detected on chan 19.
[Apr 24 06:47:10] NOTICE[2685]: chan_dahdi.c:1090
dahdi_r2_on_call_offered: MFC/R2 call offered on chan 19. ANI =
1130103000, DNIS = 7320, Category = National Subscriber
[Apr 24 06:47:10] NOTICE[2685]: chan_dahdi.c:1153
dahdi_r2_on_call_accepted: MFC/R2 call has been accepted on chan 19
    -- Executing [7320 at e1:1] Set("Zap/19-1", "CALLERID(num)=1130103000")
in new stack
    -- Executing [7320 at e1:2] Dial("Zap/19-1",
"IAX2/astpstnr2:XXXX at 172.16.78.11/7320") in new stack
    -- Called astpstnr2:XXXX at 172.16.78.XXX/7320
    -- Call accepted by 172.16.78.XXX (format ulaw)
    -- Format for call is ulaw
    -- IAX2/172.16.78.XXX:4569-7893 answered Zap/19-1

OpenR2 Log:

[06:47:08:082] [Thread: 3080784816] [Chan 19] - Call started at Fri Apr
24 06:47:08 2009 on chan 19
[06:47:08:082] [Thread: 3080784816] [Chan 19] - CAS Tx >> [SEIZE ACK]
0x0C
[06:47:08:082] [Thread: 3080784816] [Chan 19] - CAS Raw Tx >> 0x0D
[06:47:08:152] [Thread: 3080784816] [Chan 19] - MF Rx << 7 [ON]
[06:47:08:152] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 0
[06:47:08:152] [Thread: 3080784816] [Chan 19] - Cannot cancel timer 0
[06:47:08:152] [Thread: 3080784816] [Chan 19] - Getting DNIS digit 7
[06:47:08:152] [Thread: 3080784816] [Chan 19] - DNIS so far: 7, expected
length: 20
[06:47:08:152] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:152] [Thread: 3080784816] [Chan 19] - scheduled timer id 2
(mf_back_cycle)
[06:47:08:212] [Thread: 3080784816] [Chan 19] - MF Rx << 7 [OFF]
[06:47:08:212] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:272] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [ON]
[06:47:08:272] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 2
[06:47:08:272] [Thread: 3080784816] [Chan 19] - timer id 2 found,
cancelling it now
[06:47:08:272] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:272] [Thread: 3080784816] [Chan 19] - scheduled timer id 3
(mf_back_cycle)
[06:47:08:352] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [OFF]
[06:47:08:352] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:412] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [ON]
[06:47:08:412] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 3
[06:47:08:412] [Thread: 3080784816] [Chan 19] - timer id 3 found,
cancelling it now
[06:47:08:412] [Thread: 3080784816] [Chan 19] - Getting ANI digit 1
[06:47:08:412] [Thread: 3080784816] [Chan 19] - ANI so far: 1, expected
length: 20
[06:47:08:412] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:412] [Thread: 3080784816] [Chan 19] - scheduled timer id 4
(mf_back_cycle)
[06:47:08:492] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [OFF]
[06:47:08:492] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:552] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [ON]
[06:47:08:552] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 4
[06:47:08:552] [Thread: 3080784816] [Chan 19] - timer id 4 found,
cancelling it now
[06:47:08:552] [Thread: 3080784816] [Chan 19] - Getting ANI digit 1
[06:47:08:552] [Thread: 3080784816] [Chan 19] - ANI so far: 11, expected
length: 20
[06:47:08:552] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:552] [Thread: 3080784816] [Chan 19] - scheduled timer id 5
(mf_back_cycle)
[06:47:08:632] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [OFF]
[06:47:08:632] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:692] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [ON]
[06:47:08:692] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 5
[06:47:08:692] [Thread: 3080784816] [Chan 19] - timer id 5 found,
cancelling it now
[06:47:08:692] [Thread: 3080784816] [Chan 19] - Getting ANI digit 3
[06:47:08:692] [Thread: 3080784816] [Chan 19] - ANI so far: 113,
expected length: 20
[06:47:08:692] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:692] [Thread: 3080784816] [Chan 19] - scheduled timer id 6
(mf_back_cycle)
[06:47:08:772] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [OFF]
[06:47:08:772] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:832] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:08:832] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 6
[06:47:08:832] [Thread: 3080784816] [Chan 19] - timer id 6 found,
cancelling it now
[06:47:08:832] [Thread: 3080784816] [Chan 19] - Getting ANI digit 0
[06:47:08:832] [Thread: 3080784816] [Chan 19] - ANI so far: 1130,
expected length: 20
[06:47:08:832] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:832] [Thread: 3080784816] [Chan 19] - scheduled timer id 7
(mf_back_cycle)
[06:47:08:892] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:08:892] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:08:952] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [ON]
[06:47:08:952] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 7
[06:47:08:952] [Thread: 3080784816] [Chan 19] - timer id 7 found,
cancelling it now
[06:47:08:952] [Thread: 3080784816] [Chan 19] - Getting ANI digit 1
[06:47:08:952] [Thread: 3080784816] [Chan 19] - ANI so far: 11301,
expected length: 20
[06:47:08:952] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:08:952] [Thread: 3080784816] [Chan 19] - scheduled timer id 8
(mf_back_cycle)
[06:47:09:032] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [OFF]
[06:47:09:032] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:092] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:09:092] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 8
[06:47:09:092] [Thread: 3080784816] [Chan 19] - timer id 8 found,
cancelling it now
[06:47:09:092] [Thread: 3080784816] [Chan 19] - Getting ANI digit 0
[06:47:09:092] [Thread: 3080784816] [Chan 19] - ANI so far: 113010,
expected length: 20
[06:47:09:092] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:09:092] [Thread: 3080784816] [Chan 19] - scheduled timer id 9
(mf_back_cycle)
[06:47:09:172] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:09:172] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:232] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [ON]
[06:47:09:232] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 9
[06:47:09:232] [Thread: 3080784816] [Chan 19] - timer id 9 found,
cancelling it now
[06:47:09:232] [Thread: 3080784816] [Chan 19] - Getting ANI digit 3
[06:47:09:232] [Thread: 3080784816] [Chan 19] - ANI so far: 1130103,
expected length: 20
[06:47:09:232] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:09:232] [Thread: 3080784816] [Chan 19] - scheduled timer id 10
(mf_back_cycle)
[06:47:09:292] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [OFF]
[06:47:09:292] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:372] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:09:372] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 10
[06:47:09:372] [Thread: 3080784816] [Chan 19] - timer id 10 found,
cancelling it now
[06:47:09:372] [Thread: 3080784816] [Chan 19] - Getting ANI digit 0
[06:47:09:372] [Thread: 3080784816] [Chan 19] - ANI so far: 11301030,
expected length: 20
[06:47:09:372] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:09:372] [Thread: 3080784816] [Chan 19] - scheduled timer id 11
(mf_back_cycle)
[06:47:09:452] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:09:452] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:512] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:09:512] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 11
[06:47:09:512] [Thread: 3080784816] [Chan 19] - timer id 11 found,
cancelling it now
[06:47:09:512] [Thread: 3080784816] [Chan 19] - Getting ANI digit 0
[06:47:09:512] [Thread: 3080784816] [Chan 19] - ANI so far: 113010300,
expected length: 20
[06:47:09:512] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:09:512] [Thread: 3080784816] [Chan 19] - scheduled timer id 12
(mf_back_cycle)
[06:47:09:572] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:09:572] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:632] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:09:632] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 12
[06:47:09:632] [Thread: 3080784816] [Chan 19] - timer id 12 found,
cancelling it now
[06:47:09:632] [Thread: 3080784816] [Chan 19] - Getting ANI digit 0
[06:47:09:632] [Thread: 3080784816] [Chan 19] - ANI so far: 1130103000,
expected length: 20
[06:47:09:632] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [ON]
[06:47:09:632] [Thread: 3080784816] [Chan 19] - scheduled timer id 13
(mf_back_cycle)
[06:47:09:692] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:09:692] [Thread: 3080784816] [Chan 19] - MF Tx >> 5 [OFF]
[06:47:09:752] [Thread: 3080784816] [Chan 19] - MF Rx << F [ON]
[06:47:09:752] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 13
[06:47:09:752] [Thread: 3080784816] [Chan 19] - timer id 13 found,
cancelling it now
[06:47:09:752] [Thread: 3080784816] [Chan 19] - Got end of ANI
[06:47:09:752] [Thread: 3080784816] [Chan 19] - Requesting next DNIS
with signal 0x31.
[06:47:09:752] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [ON]
[06:47:09:752] [Thread: 3080784816] [Chan 19] - scheduled timer id 14
(mf_back_cycle)
[06:47:09:832] [Thread: 3080784816] [Chan 19] - MF Rx << F [OFF]
[06:47:09:832] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [OFF]
[06:47:09:892] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [ON]
[06:47:09:892] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 14
[06:47:09:892] [Thread: 3080784816] [Chan 19] - timer id 14 found,
cancelling it now
[06:47:09:892] [Thread: 3080784816] [Chan 19] - Getting DNIS digit 3
[06:47:09:892] [Thread: 3080784816] [Chan 19] - DNIS so far: 73,
expected length: 20
[06:47:09:892] [Thread: 3080784816] [Chan 19] - Requesting next DNIS
with signal 0x31.
[06:47:09:892] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [ON]
[06:47:09:892] [Thread: 3080784816] [Chan 19] - scheduled timer id 15
(mf_back_cycle)
[06:47:09:952] [Thread: 3080784816] [Chan 19] - MF Rx << 3 [OFF]
[06:47:09:952] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [OFF]
[06:47:10:032] [Thread: 3080784816] [Chan 19] - MF Rx << 2 [ON]
[06:47:10:032] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 15
[06:47:10:032] [Thread: 3080784816] [Chan 19] - timer id 15 found,
cancelling it now
[06:47:10:032] [Thread: 3080784816] [Chan 19] - Getting DNIS digit 2
[06:47:10:032] [Thread: 3080784816] [Chan 19] - DNIS so far: 732,
expected length: 20
[06:47:10:032] [Thread: 3080784816] [Chan 19] - Requesting next DNIS
with signal 0x31.
[06:47:10:032] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [ON]
[06:47:10:032] [Thread: 3080784816] [Chan 19] - scheduled timer id 16
(mf_back_cycle)
[06:47:10:112] [Thread: 3080784816] [Chan 19] - MF Rx << 2 [OFF]
[06:47:10:112] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [OFF]
[06:47:10:172] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [ON]
[06:47:10:172] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 16
[06:47:10:172] [Thread: 3080784816] [Chan 19] - timer id 16 found,
cancelling it now
[06:47:10:172] [Thread: 3080784816] [Chan 19] - Getting DNIS digit 0
[06:47:10:172] [Thread: 3080784816] [Chan 19] - DNIS so far: 7320,
expected length: 20
[06:47:10:172] [Thread: 3080784816] [Chan 19] - User requested us to
stop getting DNIS!
[06:47:10:172] [Thread: 3080784816] [Chan 19] - Requesting change to
Group II with signal 0x33
[06:47:10:172] [Thread: 3080784816] [Chan 19] - MF Tx >> 3 [ON]
[06:47:10:172] [Thread: 3080784816] [Chan 19] - scheduled timer id 17
(mf_back_cycle)
[06:47:10:252] [Thread: 3080784816] [Chan 19] - MF Rx << 0 [OFF]
[06:47:10:252] [Thread: 3080784816] [Chan 19] - MF Tx >> 3 [OFF]
[06:47:10:312] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [ON]
[06:47:10:312] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 17
[06:47:10:312] [Thread: 3080784816] [Chan 19] - timer id 17 found,
cancelling it now
[06:47:10:312] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [ON]
[06:47:10:312] [Thread: 3080784816] [Chan 19] - scheduled timer id 18
(mf_back_cycle)
[06:47:10:392] [Thread: 3080784816] [Chan 19] - MF Rx << 1 [OFF]
[06:47:10:392] [Thread: 3080784816] [Chan 19] - MF Tx >> 1 [OFF]
[06:47:10:392] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 18
[06:47:10:392] [Thread: 3080784816] [Chan 19] - timer id 18 found,
cancelling it now
[06:47:10:392] [Thread: 3080784816] [Chan 19] - scheduled timer id 19
(r2_answer_delay)
[06:47:10:552] [Thread: 3080784816] [Chan 19] - Attempting to cancel
timer timer 19
[06:47:10:552] [Thread: 3080784816] [Chan 19] - timer id 19 found,
cancelling it now
[06:47:10:552] [Thread: 3080784816] [Chan 19] - calling timer 19
(r2_answer_delay) callback
[06:47:10:555] [Thread: 3067853744] [Chan 19] - CAS Tx >> [ANSWER] 0x04
[06:47:10:555] [Thread: 3067853744] [Chan 19] - CAS Raw Tx >> 0x05
[06:48:05:873] [Thread: 3067853744] [Chan 19] - Bits changed from 0x00
to 0x08
[06:48:05:873] [Thread: 3067853744] [Chan 19] - CAS Rx << [CLEAR
FORWARD] 0x08
[06:48:05:873] [Thread: 3067853744] [Chan 19] - scheduled timer id 20
(r2_metering_pulse_fwd)
[06:48:05:889] [Thread: 3067853744] [Chan 19] - Bits changed from 0x08
to 0x00
[06:48:05:889] [Thread: 3067853744] [Chan 19] - CAS Rx << [0x00] 0x00
[06:48:05:889] [Thread: 3067853744] [Chan 19] - Protocol error. Reason =
Invalid CAS, R2 State = Clear Forward Received, MF state = MF Engine
Off, MF Group = Backward Group B, CAS = 0x00
DNIS = 7320, ANI = 1130103000, MF = 0x20
[06:48:05:889] [Thread: 3067853744] [Chan 19] - Attempting to cancel
timer timer 0

Thanks,

Rubens Almeida