[asterisk-r2] Full Debug Libopenr2
"ROQUÉ, Francisco Emiliano"
froque at 3tech.com.ar
Fri May 8 10:25:20 CDT 2009
Thanks Melcon.
Fine, I understand...
If the call pass the SEIZE/ACK initial phase, can I see a debug at digit
level for the protocol? Like this in unicall:
-- Executing Dial("SIP/309-0890d2c8", "Unicall/g1/03743461253|120|Tt")
in new stack
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 Call control(1)
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 Make call
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 Making a new call with CRN 32769
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 0001 -> [1/ 1/Idle /Idle ]
-- Called g1/03743461253
May 8 13:11:24 WARNING[10543]: chan_unicall.c:2644 handle_uc_event:
Unicall/1 event Dialing
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1101 [1/ 40/Seize /Idle ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 0 on -> [2/ 40/Group I /Idle ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 0 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:24 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 7 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 7 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 4 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 4 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 4 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 4 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 6 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 on [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 6 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 off [2/ 40/Group I /DNIS ]
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 Calling party category 0x0
May 8 13:11:25 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 on [2/ 40/Group I /Category ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 off -> [2/ 40/Group I /Category ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 off [2/ 40/Group I /Category ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 on -> [2/ 40/Group I /Category ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 on [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 off -> [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 off [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 0 on -> [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 on [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 0 off -> [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 off [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 9 on -> [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 on [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 9 off -> [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 5 off [2/ 40/Group I /ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 F on -> [2/ 40/Group I /ANI ]
-- Remote UNIX connection
-- Remote UNIX connection disconnected
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /End of ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 F off -> [2/ 40/Group I /End of ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /End of ANI ]
May 8 13:11:26 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 on -> [2/ 40/Group I /End of ANI ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 2 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 2 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 5 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 5 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 on -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 on [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 3 off -> [2/ 40/Group I /DNIS ]
May 8 13:11:27 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 1 off [2/ 40/Group I /DNIS ]
-- Remote UNIX connection
-- Remote UNIX connection disconnected
May 8 13:11:32 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 3 on [2/ 40/Group I /Silent ]
May 8 13:11:32 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 3 off [2/ 40/Group I /Silent ]
May 8 13:11:32 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 on -> [2/ 40/Group I /Silent ]
May 8 13:11:33 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 6 on [2/ 40/Group II /Category ]
May 8 13:11:33 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 1 off -> [2/ 40/Group II /Category ]
May 8 13:11:33 WARNING[10543]: chan_unicall.c:627 unicall_report:
MFC/R2 UniCall/1 <- 6 off [2/ 40/Group II /Category ]
May 8 13:11:33 WARNING[10543]: chan_unicall.c:2644 handle_uc_event:
Unicall/1 event Alerting
Regards
Francisco
Melcon Moraes wrote:
> That's all you got. The other end isn't replying to your SEIZE, hence
> the timeout after 8 seconds. The call didn't pass the SEIZE/ACK
> initial phase. There's no more information about R2 than that. You
> would end up with pretty much the same on unicall in the same set up.
>
> If you want to see OpenR2 library debug, you can re-compile it with
> the proper option. Check ./configure --help
> HOWEVER, that wouldn't be of any less help cause you'll only see in
> deep how the library works and I don't think that is what you're
> looking for.
>
> -
> MM
>
> On Tue, May 5, 2009 at 2:01 PM, "ROQUÉ, Francisco Emiliano"
> <froque at 3tech.com.ar <mailto:froque at 3tech.com.ar>> wrote:
>
> Thanks for your reply Moises.
>
> this is the debug I see:
>
> [Apr 30 13:17:01] DEBUG[5308] chan_dahdi.c: Chan 30 - Attempting
> to make
> call (ANI=303, DNIS=91236, category=National Subscrib
> er)
> [Apr 30 13:17:01] DEBUG[5308] chan_dahdi.c: Chan 30 - Call started at
> Thu Apr 30 13:17:01 2009 on chan 30
> [Apr 30 13:17:01] DEBUG[5308] chan_dahdi.c: Chan 30 - CAS Tx >>
> [SEIZE] 0x00
> [Apr 30 13:17:01] DEBUG[5308] chan_dahdi.c: Chan 30 - CAS Raw Tx
> >> 0x01
> [Apr 30 13:17:01] VERBOSE[5308] logger.c: -- Called 30/91236
> [Apr 30 13:17:09] DEBUG[5308] chan_dahdi.c: Chan 30 - calling
> timer callback
> [Apr 30 13:17:09] WARNING[5308] chan_dahdi.c: Chan 30 - Seize Timeout
> Expired!
>
> [Apr 30 13:17:09] ERROR[5308] chan_dahdi.c: Chan 30 - Protocol error.
> Reason = Seize Timeout, R2 State = Seize Transmitted, MF
> state = MF Engine Off, MF Group = Forward MF init, CAS = 0x08
> DNIS = 91236, ANI = 303, MF = 0x20
> [Apr 30 13:17:09] DEBUG[5308] chan_dahdi.c: Chan 30 - CAS Tx >>
> [IDLE] 0x08
> [Apr 30 13:17:09] DEBUG[5308] chan_dahdi.c: Chan 30 - CAS Raw Tx
> >> 0x09
> [Apr 30 13:17:09] ERROR[5308] chan_dahdi.c: MFC/R2 protocol error on
> chan 30: Seize Timeout
> [Apr 30 13:17:09] DEBUG[5308] chan_dahdi.c: disconnecting MFC/R2
> call on
> chan 30
> [Apr 30 13:17:09] VERBOSE[5308] logger.c: -- Hungup 'DAHDI/30-1'
>
> I needed to have a greater level of detail....
>
> In my logger.conf i set:
> full => notice,warning,error,debug,verbose
>
> in my chan_dahdi.conf i set:
> mfcr2_logdir=log
> mfcr2_logging=all
> mfcr2_call_files=yes
>
>
> In this way the files *.call were created, but its content is very
> poor,
> for example, the file:
>
> /var/log/asterisk/mfcr2/log/chan-1-forward-0-20090430140630.call
> contain:
>
>
> 14:06:30:129] [Thread: 43326352] [Chan 1] - Call started at Thu Apr 30
> 14:06:30 2009 on chan 1
> [14:06:30:129] [Thread: 43326352] [Chan 1] - CAS Tx >> [SEIZE] 0x00
> [14:06:30:129] [Thread: 43326352] [Chan 1] - CAS Raw Tx >> 0x01
> [14:06:38:148] [Thread: 43326352] [Chan 1] - Attempting to cancel
> timer
> timer 2
> [14:06:38:148] [Thread: 43326352] [Chan 1] - timer id 2 found,
> cancelling it now
> [14:06:38:148] [Thread: 43326352] [Chan 1] - calling timer callback
> [14:06:38:148] [Thread: 43326352] [Chan 1] - Seize Timeout Expired!
> [14:06:38:148] [Thread: 43326352] [Chan 1] - Protocol error. Reason =
> Seize Timeout, R2 State = Seize Transmitted, MF state = MF Engine Off,
> MF Group = Forward MF init, CAS = 0x08
> DNIS = 90956, ANI = 60001, MF = 0x20
> [14:06:38:148] [Thread: 43326352] [Chan 1] - Attempting to cancel
> timer
> timer 0
> [14:06:38:148] [Thread: 43326352] [Chan 1] - Cannot cancel timer 0
>
> This is practically equal to that observed in
> /var/log/asterisk/full....
>
>
> How can I get more detail? like.... unicall debug....
>
> Regards
>
> Francisco
>
>
>
>
>
>
>
>
>
>
>
>
>
> --
> Ing Francisco Roqué
> 3Tech SRL
> Plaza Paso Nº92, EP B
> Buenos Aires, Argentina.
> Tel. +54 11 5252 8727
> +54 221 4255116
> www.3tech.com.ar <http://www.3tech.com.ar>
>
>
>
> Moises Silva wrote:
> >> In /etc/asterisk/chan_dahdi.conf i have enabled mfcr2_logging
> set to all.
> >>
> > This just enables debugging on the library itself, you still need to
> > enable Asterisk debugging output in /etc/asterisk/logger.conf, read
> > the comments there.
> >
> >
> >> In /etc/openr2/r2test.conf i have loglevel set to all....
> >>
> > This has nothing to do with Asterisk debugging, this file is
> only used
> > by the r2test program.
> >
> >
> >> Can i enable a debug very full?
> >>
> > Yes, mfcr2_logging=all has all you may want
> >
> >
> >> Also check the console: mfcr2 set debug all and configuration
> of call
> >> files, but the debugs are identically
> >>
> > I don't understand what you mean. call files ALWAYS have FULL
> > debugging information regardless of the mfcr2_logging parameter.
> Read
> > the comments in configs/chan_dahdi.conf.sample in Asterisk 1.6.2 or
> > download the sample .conf files in google code.
> >
> >
> >> Do you recommend the use of the package libopenr2-debuginfo?
> >>
> > No, that package probably just includes debugging symbols for the
> > openr2 library.
> >
> > --
> > 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
> <mailto:moy at sangoma.com>
> >
> > _______________________________________________
> > --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
> >
> >
> >
>
> _______________________________________________
> --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
>
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> --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
More information about the asterisk-r2
mailing list