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

Mon Apr 27 11:05:22 CDT 2009

Then it seems that is not a metering pulse. You should try to talk to
your telco to see what are they sending. I could try a couple of
things more to see if I can detect what are they expecting us to do,
but I have not much time these days, then, contacting the telco is a
faster way to determine the meaning of that signal.

Moy

On Fri, Apr 24, 2009 at 2:09 PM, Rubens Lacerda Almeida
<rubens at totvs.com.br> wrote:
> 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
>
> _______________________________________________
> --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
>

-- 
-- 
Moises Silva
Software Developer
Sangoma Technologies Inc. | 50 McIntosh Drive, Suite 120, Markham ON
L3R 9T3 Canada
t. 1 905 474 1990 x 128 | e. moy at sangoma.com