Re: Re: Re: Re: Re: Re: Binding takes for ages on Jonas-server. No additional info to troubleshoot

Stefaan Somers <[email protected]> Thu, 18 Aug 2011 21:34:45 +0200
Newsgroups gmane.comp.java.objectweb.jonas
Message-ID <CA+N_TpVOyUD4tdk_AF2SSNs8Bwb9Ovex08YRc8E+A7LJOuXcxA@mail.gmail.com>
I've succeeded to set the logging right now. What I can see in the
log-files are many of the following entries :
2011-08-19 00:30:36,953 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:30:36,953 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:30:36,953 : ContextImpl.bind : bind EJBContext
2011-08-19 00:30:36,953 : ContextImpl.bind : bind TimerService
2011-08-19 00:30:36,953 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:30:43,359 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:30:43,359 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:30:43,359 : ContextImpl.rebind : rebind ORB
2011-08-19 00:30:43,359 : ContextImpl.lookup : lookup comp
2011-08-19 00:30:43,359 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:30:43,359 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:30:43,359 : ContextImpl.bind : bind EJBContext
2011-08-19 00:30:43,359 : ContextImpl.bind : bind TimerService
2011-08-19 00:30:43,359 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:30:51,235 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:30:51,235 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:30:51,235 : ContextImpl.rebind : rebind ORB
2011-08-19 00:30:51,235 : ContextImpl.lookup : lookup comp
2011-08-19 00:30:51,250 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:30:51,250 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:30:51,250 : ContextImpl.bind : bind EJBContext
2011-08-19 00:30:51,250 : ContextImpl.bind : bind TimerService
2011-08-19 00:30:51,266 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:30:58,907 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:30:58,907 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:30:58,907 : ContextImpl.rebind : rebind ORB
2011-08-19 00:30:58,907 : ContextImpl.lookup : lookup comp
2011-08-19 00:30:58,907 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:30:58,907 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:30:58,907 : ContextImpl.bind : bind EJBContext
2011-08-19 00:30:58,907 : ContextImpl.bind : bind TimerService
2011-08-19 00:30:58,907 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:31:06,814 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:31:06,814 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:31:06,814 : ContextImpl.rebind : rebind ORB
2011-08-19 00:31:06,814 : ContextImpl.lookup : lookup comp
2011-08-19 00:31:06,814 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:31:06,814 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:31:06,814 : ContextImpl.bind : bind EJBContext
2011-08-19 00:31:06,814 : ContextImpl.bind : bind TimerService
2011-08-19 00:31:06,814 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:31:15,111 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:31:15,111 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:31:15,111 : ContextImpl.rebind : rebind ORB
2011-08-19 00:31:15,111 : ContextImpl.lookup : lookup comp
2011-08-19 00:31:15,127 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:31:15,127 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:31:15,127 : ContextImpl.bind : bind EJBContext
2011-08-19 00:31:15,127 : ContextImpl.bind : bind TimerService
2011-08-19 00:31:15,127 : ContextImpl.createSubcontext : createSubcontext e=
nv
2011-08-19 00:31:23,096 : ContextImpl.createSubcontext : createSubcontext c=
omp
2011-08-19 00:31:23,096 : ContextImpl.rebind : rebind UserTransaction
2011-08-19 00:31:23,096 : ContextImpl.rebind : rebind ORB
2011-08-19 00:31:23,096 : ContextImpl.lookup : lookup comp
2011-08-19 00:31:23,096 : JavaCompExtensionListener.handle : Bean is
container managed so remove availability of java:comp/UserTransaction
object
2011-08-19 00:31:23,112 : ContextImpl.unbind : unbind UserTransaction
2011-08-19 00:31:23,112 : ContextImpl.bind : bind EJBContext


Any idea what is going wrong, or is this normal behaviour??

Already many thnks for your help.

Greetings
On 18 August 2011 17:13, Guillaume Sauthier (OW2)
<[email protected]> wrote:
> Please set back the monolog configuration to javaLog:
> log.config.classname
> org.objectweb.util.monolog.wrapper.javaLog.LoggerFactory
>
> Easybeans is printing its debug information on a logger backed by Java ut=
il
> Log.
>
> --G
>
> 2011/8/18 Stefaan Somers <[email protected]>
>>
>> No luck either.
>>
>> I hereby include the content of the trace.properties :
>>
>>
>> # ----------------------------------------------------------------------=
-
>> # This is a default configuration file for monolog.
>> #
>> # 2 handlers have been defined : tty (System.out) and logf (file)
>> #
>> # Patterns for each handler may include these possible values :
>> # %h =A0 =A0the thread name
>> # %O{1} the Class name (basename only)
>> # %M =A0 =A0the method name
>> # %L =A0 =A0the line number
>> # %d =A0 =A0the date
>> # %l =A0 =A0the level
>> # %m =A0 =A0the message itself
>> # %n =A0 =A0a new line
>> #
>> # A list of predefined loggers is given at the end of the file.
>> # Each logger inherits from its parent for properties not defined.
>> # The root logger is "root". It must always be defined.
>> #
>> # Each logger is associated with a level that can be one of :
>> # ERROR | WARN | INFO | DEBUG | FATAL | INHERIT
>> #
>> # -> More info on http://www.objectweb.org/monolog/doc.html
>> # ----------------------------------------------------------------------=
-
>>
>> # ----------------------------------------------------------------------=
-
>> # Define which wrapper to use (=3D javaLog)
>> # ----------------------------------------------------------------------=
-
>> # For Log4j you need to add log4j.jar
>> log.config.classname
>> org.objectweb.util.monolog.wrapper.log4j.MonologLoggerFactory
>> # log.config.classname
>> org.objectweb.util.monolog.wrapper.javaLog.LoggerFactory
>>
>>
>> #----------
>> # PrivateCompany
>> #----------
>>
>> logger.net.democritus.level INFO
>> logger.com.privatecompany.level INFO
>> logger.net.palver.level INFO
>> logger.org.ow2.easybeans.level DEBUG
>>
>>
>>
>> On 18 August 2011 15:57, Guillaume Sauthier (OW2)
>> <[email protected]> wrote:
>> > the format is:
>> > logger.<name>.level <level>
>> >
>> > you missed the .level
>> >
>> > --G
>> >
>> >
>> > 2011/8/18 Stefaan Somers <[email protected]>
>> >>
>> >> Hi,
>> >>
>> >> I included the following line in trace.properties, but I see no
>> >> additional logging :
>> >> logger.org.ow2.easybeans DEBUG
>> >>
>> >> Greetings
>> >>
>> >> On 18 August 2011 13:26, Guillaume Sauthier (OW2)
>> >> <[email protected]> wrote:
>> >> > Maybe you can add traces for the org.ow2.easybeans logger ?
>> >> > So that we can see where time is consumed.
>> >> > --G
>> >> >
>> >> > 2011/8/18 Stefaan Somers <[email protected]>
>> >> >>
>> >> >> Hi,
>> >> >>
>> >> >> we have an application with many EJB's. Is it normal that the
>> >> >> startup
>> >> >> takes more than 10 minutes. Are there any steps I can do, to
>> >> >> troubleshoot this performance-problem.
>> >> >>
>> >> >> 18/08/11 13:02:26 (I) JCLLoggerAdapter.info : Not binding factory =
to
>> >> >> JNDI, no JNDI name configured
>> >> >> 18/08/11 13:16:39 (I) JContainer3.start : Container
>> >> >>
>> >> >>
>> >> >>
>> >> >> 'D:\jonas-full-5.1.4\BASE\work\apps\jonas\pocAppEAR_2011.08.18-12.=
56.31.ear\pocAppEJB.jar'
>> >> >> [104 SLSB, 0 SFSB, 0 MDB] started in 891.270 ms
>> >> >>
>> >> >>
>> >> >> Greetings,
>> >> >>
>> >> >> Stefaan Somers
>> >> >
>> >> >
>> >
>> >
>
>