users@glassfish.java.net

MessageDrivenBean takes 10 minutes to start after server start

From: <glassfish_at_javadesktop.org>
Date: Wed, 26 May 2010 05:09:23 PDT

At the DAS some EARs containing MDB's are deployed. Server starts at:

26.05.2010 12:52:13 com.sun.enterprise.admin.launcher.GFLauncherLogger info
INFO: JVM invocation command line:
C:\Programms\Java\jdk1.6.0_16\bin\java.exe

Then reports for each application something like:

[#|2010-05-26T12:52:32.057+0200|WARNING|glassfishv3.0|javax.enterprise.system.tools.monitor.org.glassfish.admin.monitor|_ThreadID=11;_ThreadName=Thread-1;|MNTG0201:Flashlight listener registration failed for listener class : com.sun.ejb.monitoring.stats.MessageDrivenBeanStatsProvider , will retry later |#]

[#|2010-05-26T12:52:32.139+0200|WARNING|glassfishv3.0|javax.enterprise.system.tools.monitor.org.glassfish.admin.monitor|_ThreadID=11;_ThreadName=Thread-1;|MNTG0201:Flashlight listener registration failed for listener class : com.sun.ejb.monitoring.stats.MessageDrivenBeanStatsProvider , will retry later |#]

[#|2010-05-26T12:52:32.220+0200|INFO|glassfishv3.0|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=11;_ThreadName=Thread-1;|Loading EeEventEngineReport Application done is 8201 ms|#]

Last log entries are:

[#|2010-05-26T12:52:36.591+0200|INFO|glassfishv3.0|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=11;_ThreadName=Thread-1;|GlassFish v3 (74.2) startup time : Felix(10108ms) startup services(13137ms) total(23245ms)|#]

[#|2010-05-26T12:52:37.355+0200|INFO|glassfishv3.0|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=23;_ThreadName=Thread-1;|Binding RMI port to single IP address = 10.50.196.60, port 18687|#]

[#|2010-05-26T12:52:37.366+0200|INFO|glassfishv3.0|javax.enterprise.system.tools.admin.org.glassfish.server|_ThreadID=23;_ThreadName=Thread-1;|JMXStartupService: Started JMXConnector, JMXService URL = service:jmx:rmi://10.50.196.60:18687/jndi/rmi://10.50.196.60:18687/jmxrmi|#]

[#|2010-05-26T12:52:37.396+0200|INFO|glassfishv3.0|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=24;_ThreadName=Thread-1;|[Thread[GlassFish Kernel Main Thread,5,main]] started|#]

[#|2010-05-26T12:52:37.486+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|{felix.fileinstall.poll (ms) = 5000, felix.fileinstall.dir = C:\glassfishv3\glassfish\modules\autostart, felix.fileinstall.debug = 1, felix.fileinstall.bundles.new.start = true, felix.fileinstall.tmpdir = C:\Users\ex50856\AppData\Local\Temp\fileinstall--7974842127471600155, felix.fileinstall.filter = null}|#]

[#|2010-05-26T12:52:42.547+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|Installed C:\glassfishv3\glassfish\modules\autostart\org.apache.felix.scr.jar|#]

[#|2010-05-26T12:52:42.571+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|Installed C:\glassfishv3\glassfish\modules\autostart\osgi-web-container.jar|#]

[#|2010-05-26T12:52:42.585+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|Installed C:\glassfishv3\glassfish\modules\autostart\org.apache.felix.fileinstall-autodeploy-bundles.cfg|#]

[#|2010-05-26T12:52:42.589+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=26;_ThreadName=Thread-1;|{felix.fileinstall.poll (ms) = 5000, felix.fileinstall.dir = C:\glassfishv3\glassfish\domains\domain1\autodeploy\bundles, felix.fileinstall.debug = 1, felix.fileinstall.bundles.new.start = true, felix.fileinstall.tmpdir = C:\Users\ex50856\AppData\Local\Temp\fileinstall--141873478114633097, felix.fileinstall.filter = null}|#]

[#|2010-05-26T12:52:42.597+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|Started bundle: file:/C:/glassfishv3/glassfish/modules/autostart/org.apache.felix.scr.jar|#]

[#|2010-05-26T12:52:42.613+0200|INFO|glassfishv3.0|javax.enterprise.system.std.com.sun.enterprise.v3.services.impl|_ThreadID=25;_ThreadName=Thread-1;|Started bundle: file:/C:/glassfishv3/glassfish/modules/autostart/osgi-web-container.jar|#]

After that nothing happens for araund 10 minutes. NO MDB is started. All Queues are without listener.
Then after 10 minutes all of a sudden the MDBs are started - everthing without any complaint!

I took some stack traces during the waiting time (dump 1-19) and when the MDBs are started (dump 20...) and it seems as if only after that time Ejb-Timer Threads are started. (Dumps see attachment)
So the question is what delays those threads? Why are they not started when the server starts?

Thanks
Petra
[Message sent by forum member 'petraleomue']

http://forums.java.net/jive/thread.jspa?messageID=471509