dev@glassfish.java.net

b22 startup log & JDBC connection pool

From: Arun Gupta <Arun.Gupta_at_Sun.COM>
Date: Wed, 03 Sep 2008 09:23:53 +0200

Few questions about b22 startup log:

1). org.glassfish.core.kernel is shown started 25 times. Similarly
org.glassfish.admin.config-api is shown started multiple times and some
other bundles too. Are these bundles really loaded that many times or
these are just false INFO messages ?

2). What does the following error indicate ?

SEVERE: ERROR starting JMX connector: JmxConnector config: { name =
system, Protocol = rmi_jrmp, Address = 0.0.0.0, Port = 8686, AcceptAll =
false, AuthRealmName = admin-realm, SecurityEnabled = false}:
java.lang.IllegalArgumentException:
javax.management.remote.JMXConnectorServerProvider: Provider
com.sun.jmx.remote.protocol.jmxmp.ServerProvider not found

3). ping-connection-pool gives the following error:

~/tools/glassfish/v3/b22/glassfishv3-prelude/glassfish/bin/asadmin
ping-connection-pool jdbc/jndi_rails2_pool
remote failure: Ping JDBC Connection Pool for jdbc/jndi_rails2_pool is
Failed.

Command ping-connection-pool failed.

And throws the following warning on the server-side:

Sep 3, 2008 6:53:22 AM
com.sun.enterprise.connectors.service.ConnectorConnectionPoolAdminServiceImpl
testConnectionPool
WARNING: Connection object cannot be null

Filed: https://glassfish.dev.java.net/issues/show_bug.cgi?id=5837 for 3).

The complete log is now given below:

-- cut here --
Sep 3, 2008 6:56:01 AM HK2Main start
INFO: contextRootDir =
/Users/arungupta/tools/glassfish/v3/b22/glassfishv3-prelude/glassfish/modules
Sep 3, 2008 6:56:01 AM OSGiFactoryImpl initialize
INFO: Singleton already initialized as
com.sun.enterprise.module.impl.HK2Factory_at_5c219c51
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-mbeanserver [8]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.common-util [82]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-mbeanserver [8]
Sep 3, 2008 6:56:01 AM HK2Main$HK2ServiceTrackerCustomizer addingService
INFO: registering service =
org.apache.felix.framework.StartLevelImpl_at_1f427975, contract =
org.osgi.service.startlevel.StartLevel, name = null
Sep 3, 2008 6:56:01 AM HK2Main$HK2ServiceTrackerCustomizer addingService
INFO: registering service =
org.apache.felix.framework.PackageAdminImpl_at_631b380e, contract =
org.osgi.service.packageadmin.PackageAdmin, name = null
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:01 AM AppServerStartup run
INFO: [Thread[GlassFish Kernel Main Thread,5,main]] started
Sep 3, 2008 6:56:01 AM com.sun.enterprise.v3.server.AppServerStartup run
INFO: Module subsystem initialized in 808 ms
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-naming [87]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-naming [87]
Sep 3, 2008 6:56:01 AM com.sun.enterprise.naming.impl.ServicesHookup$1 run
INFO: **GFNaming==> InitialContextFactoryBuilder set
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.internal-api [84]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:01 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-mbeanserver [8]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.glassfish-mbeanserver [8]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.deployment.deployment-autodeploy [59]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.deployment.deployment-common [88]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.internal-api [84]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.deployment.deployment-autodeploy [59]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.flashlight.flashlight-framework [92]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.flashlight.flashlight-framework [92]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM
org.glassfish.admin.mbeanserver.ConnectorStartupService$ConnectorsStarterThread
run
SEVERE: ERROR starting JMX connector: JmxConnector config: { name =
system, Protocol = rmi_jrmp, Address = 0.0.0.0, Port = 8686, AcceptAll =
false, AuthRealmName = admin-realm, SecurityEnabled = false}:
java.lang.IllegalArgumentException:
javax.management.remote.JMXConnectorServerProvider: Provider
com.sun.jmx.remote.protocol.jmxmp.ServerProvider not found
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM
INFO: JMXMP connector server URL = service:jmx:jmxmp://localhost:8888
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM com.sun.enterprise.v3.services.impl.GrizzlyProxy
start
INFO: Listening on port 8080
Sep 3, 2008 6:56:02 AM
com.sun.enterprise.v3.services.impl.GrizzlyService postConstruct
INFO: Network listener http-listener-2 on port 8181 has been disabled
Sep 3, 2008 6:56:02 AM com.sun.enterprise.v3.services.impl.GrizzlyProxy
start
INFO: Listening on port 4848
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.container-common [101]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:02 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.common.internal-api [84]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM APIClassLoaderService createAPIClassLoader
INFO: APIClassLoader = Class Loader for Bundle
[GlassFish-Application-Common-Module [67] ]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM CommonClassLoaderManager Skipping creation of
CommonClassLoader as there are no libraries available
INFO: urls = []
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.admin.config-api [74]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.deployment.deployment-common [88]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.persistence.jpa-connector [63]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.scripting.gf-jruby-connector [58]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.core.kernel [98]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.scripting.grizzly-jruby-module [34]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.scripting.grizzly-jruby-module [34]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.web.war-util [85]
Sep 3, 2008 6:56:03 AM OSGiModuleImpl loadClass
INFO: Started bundle org.glassfish.deployment.deployment-common [88]
Sep 3, 2008 6:56:03 AM com.sun.grizzly.jruby.RailsAdapter
startRubyRuntimePool
INFO: Starting Rails instances
Sep 3, 2008 6:56:10 AM
SEVERE: JRuby limited openssl loaded. gem install jruby-openssl for full
support.
http://wiki.jruby.org/wiki/JRuby_Builtin_OpenSSL
Sep 3, 2008 6:56:12 AM com.sun.grizzly.jruby.RubyObjectPool$1 run
INFO: 8968 New Ruby instance created 8
Sep 3, 2008 6:56:12 AM org.glassfish.scripting.rails.RailsDeployer load
INFO: Loading application jndi_rails2 at /jndi_rails2
Sep 3, 2008 6:56:12 AM
com.sun.enterprise.v3.server.ApplicationLoaderService processApplication
INFO: Loading jndi_rails2 Application done is 9101 ms
Sep 3, 2008 6:56:12 AM com.sun.enterprise.v3.server.AppServerStartup run
INFO: Glassfish v3 started in 11142 ms
-- cut here --

Thanks,
-Arun
-- 
Application Platform, Sun Microsystems, Inc.
Blog: http://blogs.sun.com/arungupta