Statistics SQL not showing, not sure how to apply the configs in the user guide

Jonathan Bartels

unread,

Oct 25, 2011, 1:44:46 PM10/25/11

to javamelody

The "Statistics SQL" section is not showing.

I see in the UserGuide that setting some hibernate properties, but my
application is using EclipseLink and I'm not sure if I should be
setting Hibernate or setting something specific to EclipseLink.

My datasource is defined in Glassfish as jdbc/myresource. If I'm
reading the guide right, it should just work.

Where should I start looking to find out what is missing and why the
SQL stats won't display?

Thanks!

Emeric Vernat

unread,

Oct 29, 2011, 10:02:01 AM10/29/11

to javam...@googlegroups.com

Hi,

I suppose that Hibernate settings will not work in EclipseLink.
There is currently no specific settings known for EclipseLink

And there is an issue with sql monitoring in Glassfish v3. See:
http://code.google.com/p/javamelody/issues/detail?id=138

Sorry,
Emeric

Le 25/10/2011 19:44, Jonathan Bartels a �crit :

David Karlsen

unread,

Oct 29, 2011, 10:04:44 AM10/29/11

to javam...@googlegroups.com

Is sql enabled in web.xml?
<init-param>
<description>Which metrics to display</description>
<param-name>displayed-counters</param-name>
<param-value>http,sql,error,log,jsp,spring,services</param-value>
</init-param>

You can also try using
net.bull.javamelody.SpringDataSourceFactoryBean directly instead of
importing the javamelody spring context file - I had to do that due to
the javamelody PostProcessor not working properly.

2011/10/25 Jonathan Bartels <jona...@mirthcorp.com>:

--
--
David J. M. Karlsen - http://www.linkedin.com/in/davidkarlsen

Jonathan Bartels

unread,

Nov 3, 2011, 11:34:03 AM11/3/11

to javam...@googlegroups.com

Thanks for replying.

I tired adding the init-params for displayed-counters. I don't think
that's the problem though. Before adding those I see charts for jdbc
connections and I also see a header for SQL statistics.

I'm reading the JDBC section of the user guide and I wonder if I need to
define the datasources parameter.
http://code.google.com/p/javamelody/wiki/UserGuide#7._JDBC

My applications database connection is the usual jdbc/myAppsDB . I'm
trying to explicitly define this to make sure.

Can I just add an init param to the filter? Like this:
<filter>
<filter-name>monitoring</filter-name>
<filter-class>net.bull.javamelody.MonitoringFilter</filter-class>
<init-param>
<param-name>datasources</param-name>
<param-value>jdbc/myAppDB</param-value>
</init-param>
<init-param>
<param-name>log</param-name>
<param-value>true</param-value>
</init-param>
</filter>

After adding this I get:
[#|2011-11-03T08:25:16.906-0700|SEVERE|sun-appserver9.1|javax.enterprise.s
ystem.core.naming|_ThreadID=18;_ThreadName=httpWorkerThread-4848-0;_Reques
tID=be5dca94-f396-4121-8803-a40e212fa5ea;|NAM0002: Exception in
NamingManagerImpl copyMutableObject().
java.io.NotSerializableException: net.bull.javamelody.JdbcWrapper$4

Can I define the datasources param in the filter? Is the jdbc resource
enough or do I need to have the full JNDI path?

Jonathan Bartels

unread,

Nov 3, 2011, 1:30:38 PM11/3/11

to javam...@googlegroups.com

Turned on debug logs. Whether I explicitly define my datasource or let
javamelody discover it, I get the same output in the log:

[#|2011-11-03T10:17:09.940-0700|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=16;_ThreadName=httpWorkerThread-4848-1;|10:17:09,
939 DEBUG javamelody:119 - rebinding datasources failed, skipping
javax.naming.NamingException: java:comp namespace cannot be modified
at
com.sun.enterprise.naming.java.javaURLContext.rebind(javaURLContext.java:2
62)
at
com.sun.enterprise.naming.SerialContext.rebind(SerialContext.java:489)
at javax.naming.InitialContext.rebind(InitialContext.java:408)
at
net.bull.javamelody.JdbcWrapperHelper.rebindDataSource(JdbcWrapperHelper.j
ava:57)
at
net.bull.javamelody.JdbcWrapper.rebindDataSources(JdbcWrapper.java:436)
at net.bull.javamelody.FilterContext.<init>(FilterContext.java:78)
at
net.bull.javamelody.MonitoringFilter.init(MonitoringFilter.java:98)

What I think this is telling me is that Javamelody cannot recreate the
jdbc connection with its own driver to intercept the requests. My jdbc
resource is defined in Glassfish (v2.1.1). What do I need to change to
allow javamelody to rebind the resource?

Emeric Vernat

unread,

Nov 5, 2011, 10:35:56 AM11/5/11

to javam...@googlegroups.com

Hi,

It is quite strange. "javax.naming.InitialContext.rebind" in the
stack-trace is not supposed to be called when using Glassfish.

Could you post the rest of the debug logs. I am particularly interested
by the line which should say:
"Server: ... GlassFish ..."

bye,
Emeric

Le 03/11/2011 18:30, Jonathan Bartels a �crit :

Jonathan Bartels

unread,

Nov 11, 2011, 11:15:54 AM11/11/11

to javam...@googlegroups.com

Logs parsed from Glassfish server.log by searching for "javamelody":

Search "javamelody" (42 hits in 1 files)
C:\Sun\GF2\domains\domain1\logs\server.log (42 hits)
Line 3030:
[#|2011-11-11T08:11:45.907-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
904 DEBUG javamelody:105 - JavaMelody listener init started
Line 3030:
[#|2011-11-11T08:11:45.907-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
904 DEBUG javamelody:105 - JavaMelody listener init started
Line 3033:
[#|2011-11-11T08:11:45.922-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
921 DEBUG javamelody:105 - datasources found in JNDI:
[java:comp/env/jdbc/mirthresults, jdbc/mirthresults__pm, jdbc/__TimerPool,
jdbc/mirthresults, jdbc/__TimerPool__pm]
Line 3036:
[#|2011-11-11T08:11:45.929-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
926 DEBUG javamelody:119 - rebinding datasources failed, skipping
Line 3041: at
net.bull.javamelody.JdbcWrapperHelper.rebindDataSource(JdbcWrapperHelper.j
ava:57)
Line 3042: at
net.bull.javamelody.JdbcWrapper.rebindDataSources(JdbcWrapper.java:436)
Line 3043: at
net.bull.javamelody.SessionListener.contextInitialized(SessionListener.jav
a:193)
Line 3121:
[#|2011-11-11T08:11:45.930-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
930 DEBUG javamelody:105 - JavaMelody listener init done
Line 3121:
[#|2011-11-11T08:11:45.930-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
930 DEBUG javamelody:105 - JavaMelody listener init done
Line 3124:
[#|2011-11-11T08:11:45.933-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
932 DEBUG javamelody:105 - JavaMelody filter init started
Line 3124:
[#|2011-11-11T08:11:45.933-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
932 DEBUG javamelody:105 - JavaMelody filter init started
Line 3127:
[#|2011-11-11T08:11:45.936-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
936 DEBUG javamelody:105 - OS: Windows Vista Service Pack 2, x86/32
Line 3130:
[#|2011-11-11T08:11:45.937-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
936 DEBUG javamelody:105 - Java: Java(TM) SE Runtime Environment,
1.6.0_17-b04
Line 3133:
[#|2011-11-11T08:11:45.937-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
937 DEBUG javamelody:105 - Server: Sun Java System Application Server
9.1_02
Line 3136:
[#|2011-11-11T08:11:45.938-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
938 DEBUG javamelody:105 - Webapp context: /mirthresults
Line 3139:
[#|2011-11-11T08:11:45.938-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
938 DEBUG javamelody:105 - JavaMelody version: 1.32.1
Line 3139:
[#|2011-11-11T08:11:45.938-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
938 DEBUG javamelody:105 - JavaMelody version: 1.32.1
Line 3142:
[#|2011-11-11T08:11:45.942-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
942 DEBUG javamelody:105 - Host: Jonat...@10.10.3.190
Line 3145:
[#|2011-11-11T08:11:45.943-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
942 DEBUG javamelody:105 - parameter defined: log=true
Line 3148:
[#|2011-11-11T08:11:45.946-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
945 DEBUG javamelody:105 - log listeners initialized
Line 3151:
[#|2011-11-11T08:11:45.948-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
948 DEBUG javamelody:105 - datasources found in JNDI:
[java:comp/env/jdbc/mirthresults, jdbc/mirthresults__pm, jdbc/__TimerPool,
jdbc/mirthresults, jdbc/__TimerPool__pm]
Line 3154:
[#|2011-11-11T08:11:45.949-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
948 DEBUG javamelody:119 - rebinding datasources failed, skipping
Line 3159: at
net.bull.javamelody.JdbcWrapperHelper.rebindDataSource(JdbcWrapperHelper.j
ava:57)
Line 3160: at
net.bull.javamelody.JdbcWrapper.rebindDataSources(JdbcWrapper.java:436)
Line 3161: at
net.bull.javamelody.FilterContext.<init>(FilterContext.java:78)
Line 3162: at
net.bull.javamelody.MonitoringFilter.init(MonitoringFilter.java:98)
Line 3243:
[#|2011-11-11T08:11:45.955-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:45,
955 DEBUG javamelody:105 - counters initialized
Line 3246:
[#|2011-11-11T08:11:46.030-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
029 DEBUG javamelody:105 - counters data read from files in
C:\Users\JONATH~1\AppData\Local\Temp\javamelody\mirthresults_Jonathan-PC
Line 3246:
[#|2011-11-11T08:11:46.030-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
029 DEBUG javamelody:105 - counters data read from files in
C:\Users\JONATH~1\AppData\Local\Temp\javamelody\mirthresults_Jonathan-PC
Line 3249:
[#|2011-11-11T08:11:46.045-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
044 DEBUG javamelody:105 - collect task scheduled every 60s
Line 3252:
[#|2011-11-11T08:11:46.301-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
300 DEBUG javamelody:105 - first collect of data done
Line 3255:
[#|2011-11-11T08:11:46.302-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
301 DEBUG javamelody:105 - JavaMelody filter init done
Line 3255:
[#|2011-11-11T08:11:46.302-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:46,
301 DEBUG javamelody:105 - JavaMelody filter init done
Line 3338:
[#|2011-11-11T08:11:49.492-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:49,
491 DEBUG javamelody:105 - ejb interceptor initialized
Line 3511:
[#|2011-11-11T08:11:52.660-0800|INFO|sun-appserver9.1|com.mirth.kana.plugi
n.PluginManager|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|
org.osgi.framework.system.packages.extra=about_files;version=1.0.0,addons;
version=1.0.0,addons.jbi-components;version=1.0.0,au;version=1.0.0,au.id;v
ersion=1.0.0,au.id.jericho;version=1.0.0,au.id.jericho.lib;version=1.0.0,a
u.id.jericho.lib.html;version=1.0.0,au.id.jericho.lib.html.nodoc;version=1
.0.0,bin;version=1.0.0,cli;version=1.0.0,com;version=1.0.0,com.csvreader;v
ersion=1.0.0,com.ctc;version=1.0.0,com.ctc.wstx;version=1.0.0,com.ctc.wstx
.api;version=1.0.0,com.ctc.wstx.cfg;version=1.0.0,com.ctc.wstx.compat;vers
ion=1.0.0,com.ctc.wstx.dom;version=1.0.0,com.ctc.wstx.dtd;version=1.0.0,co
m.ctc.wstx.ent;version=1.0.0,com.ctc.wstx.evt;version=1.0.0,com.ctc.wstx.e
xc;version=1.0.0,com.ctc.wstx.io;version=1.0.0,com.ctc.wstx.msv;version=1.
0.0,com.ctc.wstx.sax;version=1.0.0,com.ctc.wstx.sr;version=1.0.0,com.ctc.w
stx.stax;version=1.0.0,com.ctc.wstx.sw;version=1...
Line 3673:
[#|2011-11-11T08:11:54.234-0800|INFO|sun-appserver9.1|javax.enterprise.sys
tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|08:11:54,
233 DEBUG javamelody:105 - ejb interceptor initialized

-----Original Message-----
From: javam...@googlegroups.com [mailto:javam...@googlegroups.com] On
Behalf Of Emeric Vernat
Sent: Saturday, November 05, 2011 10:36 AM
To: javam...@googlegroups.com
Subject: Re: [javamelody] Statistics SQL not showing, not sure how to
apply the configs in the user guide

Hi,

It is quite strange. "javax.naming.InitialContext.rebind" in the
stack-trace is not supposed to be called when using Glassfish.

Could you post the rest of the debug logs. I am particularly interested
by the line which should say:
"Server: ... GlassFish ..."

bye,
Emeric

Emeric Vernat

unread,

Nov 11, 2011, 5:33:44 PM11/11/11

to javam...@googlegroups.com

Hi,

So it is now clear. This server identifies itself as "Sun Java System
Application Server 9.1_02".

I have taken the current trunk and made a change in JdbcWrapper to
recognize it as GlassFish.
And I have made a new build which is available at:
http://javamelody.googlecode.com/files/javamelody-20111111.jar
And I hope that it will work with your server.

Can you try it?

Thanks,
Emeric

Le 11/11/2011 17:15, Jonathan Bartels a �crit :

> Le 03/11/2011 18:30, Jonathan Bartels a �crit :

Jonathan Bartels

unread,

Nov 14, 2011, 10:31:41 AM11/14/11

to javam...@googlegroups.com

Thanks for looking at this.

I swapped out the JAR and I no longer get the error. Unfortunately
"Statistics SQL" is still blank.

The logs appear to find and rewrap the datasources:

Full logs:

[java:comp/env/jdbc/mirthresults, jdbc/mirthresults__pm, jdbc/__TimerPool,
jdbc/mirthresults, jdbc/__TimerPool__pm]

tem.stream.out|_ThreadID=17;_ThreadName=httpWorkerThread-4848-1;|07:05:19,

[java:comp/env/jdbc/mirthresults, jdbc/mirthresults__pm, jdbc/__TimerPool,
jdbc/mirthresults, jdbc/__TimerPool__pm]

C:\Users\JONATH~1\AppData\Local\Temp\javamelody\mirthresults_Jonathan-PC

C:\Users\JONATH~1\AppData\Local\Temp\javamelody\mirthresults_Jonathan-PC

-----Original Message-----
From: javam...@googlegroups.com [mailto:javam...@googlegroups.com] On
Behalf Of Emeric Vernat
Sent: Friday, November 11, 2011 5:34 PM
To: javam...@googlegroups.com
Subject: Re: [javamelody] Statistics SQL not showing, not sure how to
apply the configs in the user guide

Hi,

So it is now clear. This server identifies itself as "Sun Java System
Application Server 9.1_02".

I have taken the current trunk and made a change in JdbcWrapper to
recognize it as GlassFish.
And I have made a new build which is available at:
http://javamelody.googlecode.com/files/javamelody-20111111.jar
And I hope that it will work with your server.

Can you try it?

Thanks,
Emeric

Emeric Vernat

unread,

Nov 14, 2011, 6:14:51 PM11/14/11

to javam...@googlegroups.com

Hi,

Sorry if it does not work for this server.

You could debug in javamelody sources if you want.

Source files for v1.32.1 are:
http://search.maven.org/remotecontent?filepath=net/bull/javamelody/javamelody-core/1.32.1/javamelody-core-1.32.1-sources.jar
And source files for the current trunk can be checked out or browsed from:
http://code.google.com/p/javamelody/source/checkout

The dev guide may be useful if you want to rebuild sources:
http://code.google.com/p/javamelody/wiki/DevGuide

What I have tried to change was the following in JdbcWrapper.java:
glassfish = serverInfo.contains("GlassFish")
|| serverInfo.contains("Sun Java System Application
Server");

bye,
Emeric

Le 14/11/2011 16:31, Jonathan Bartels a �crit :

> Le 11/11/2011 17:15, Jonathan Bartels a �crit :

>> Le 03/11/2011 18:30, Jonathan Bartels a �crit :

Jonathan Bartels

unread,

Nov 15, 2011, 9:01:45 AM11/15/11

to javam...@googlegroups.com

Yea I can check the code out and see what's up.

Thanks for your help so far. Javamelody has already helped us spot a few
other minor issues. We like it!

-----Original Message-----
From: javam...@googlegroups.com [mailto:javam...@googlegroups.com] On
Behalf Of Emeric Vernat
Sent: Monday, November 14, 2011 6:15 PM
To: javam...@googlegroups.com
Subject: Re: [javamelody] Statistics SQL not showing, not sure how to
apply the configs in the user guide

Hi,

Sorry if it does not work for this server.

You could debug in javamelody sources if you want.

Source files for v1.32.1 are:
http://search.maven.org/remotecontent?filepath=net/bull/javamelody/javamel
ody-core/1.32.1/javamelody-core-1.32.1-sources.jar
And source files for the current trunk can be checked out or browsed from:
http://code.google.com/p/javamelody/source/checkout

The dev guide may be useful if you want to rebuild sources:
http://code.google.com/p/javamelody/wiki/DevGuide

What I have tried to change was the following in JdbcWrapper.java:
glassfish = serverInfo.contains("GlassFish")
|| serverInfo.contains("Sun Java System Application
Server");

bye,
Emeric

Reply all

Reply to author

Forward