Hibernate Failing
rickprice407 Jun 13, 2005 10:28 AMJboss 4.0.2
Portal RC3
MySQL JConnector 3.1.8 (com.mysql.jdbc.driver)
My MySQL seems to be doing alright now but Hibernate is failing. Here is what I get when I first see Hibernate startup.
class org.jboss.cache.interceptors.UnlockInterceptor
10:05:14,827 INFO [TreeImpl$FixedTreeCache] cache mode is local, will not create the channel
10:05:14,921 INFO [Environment] Hibernate 3.0.2
10:05:14,936 INFO [Environment] hibernate.properties not found
10:05:14,936 INFO [Environment] using CGLIB reflection optimizer
10:05:14,936 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
10:05:15,046 INFO [Configuration] configuring from url: file:/C:/jboss-4.0.2/server/default/deploy/jboss-portal.sar/conf/hibernate/hibernate.cfg.xml
10:05:15,061 INFO [Configuration] Mapping resource: org/jboss/portal/core/impl/role/RoleImpl.hbm.xml
Here is what I see for MySQL:
10:05:15,405 INFO [NamingHelper] JNDI InitialContext properties:{}
10:05:15,405 INFO [DatasourceConnectionProvider] Using datasource: java:PortalDS
10:05:15,483 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: --> NonRegisteringDriver.java:223 com.mysql.jdbc.NonRegisteringDriver.acceptsURL("jdbc:mysql://localhost:3306/jbossportal?useServerPrepStmts=false")
10:05:15,483 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: --> NonRegisteringDriver.java:339 com.mysql.jdbc.NonRegisteringDriver.parseURL("jdbc:mysql://localhost:3306/jbossportal?useServerPrepStmts=false", null)
10:05:15,499 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: --> StringUtils.java:722 com.mysql.jdbc.StringUtils.startsWithIgnoreCase("jdbc:mysql://localhost:3306/jbossportal?useServerPrepStmts=false", "jdbc:mysql://")
10:05:15,499 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: --> StringUtils.java:738 com.mysql.jdbc.StringUtils.startsWithIgnoreCase("jdbc:mysql://local
host:3306/jbossportal?useServerPrepStmts=false", 0, "jdbc:mysql://")
10:05:15,499 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: <-- StringUtils.java:738 com.mysql.jdbc.StringUtils.startsWithIgnoreCase(..) returning true
10:05:15,499 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: <-- StringUtils.java:722 com.mysql.jdbc.StringUtils.startsWithIgnoreCase(..) returning true
10:05:15,499 INFO [STDOUT] Mon Jun 13 10:05:15 EDT 2005 TRACE: <-- NonRegisteringDriver.java:339 com.mysql.jdbc.NonRegisteringDriver.parseURL(..) returning {HOST=localhost:3306, DBNAME=jbossportal, useServerPrepStmts=false}
Many more INFO lines follow this.
10:05:21,311 INFO [STDOUT] Mon Jun 13 10:05:21 EDT 2005 TRACE: <-- ResultSet.java:7079 com.mysql.jdbc.ResultSet.finalize(..) returning null
10:05:21,311 INFO [SettingsFactory] RDBMS: MySQL, version: 4.1.12a-nt
10:05:21,311 INFO [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-3.1.8 ( $Date: 2005/04/14 20:36:13 $, $Revision: 1.27.4.64 $ )
10:05:21,374 INFO [Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
10:05:21,374 INFO [SettingsFactory] JDBC batch size: 15
10:05:21,374 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
10:05:21,374 INFO [SettingsFactory] Scrollable result sets: enabled
10:05:21,374 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
10:05:21,374 INFO [SettingsFactory] Aggressive release : disabled
10:05:21,374 INFO [SettingsFactory] Maximum outer join fetch depth: 2
10:05:21,374 INFO [SettingsFactory] Default batch fetch size: 1
10:05:21,374 INFO [SettingsFactory] Generate SQL with comments: disabled
10:05:21,374 INFO [SettingsFactory] Order SQL updates by primary key: disabled
10:05:21,374 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
10:05:21,390 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
10:05:21,390 INFO [SettingsFactory] Query language substitutions: {}
10:05:21,390 INFO [TransactionFactoryFactory] Using default transaction strategy (direct JDBC transactions)
10:05:21,405 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
Here's the error I get:
10:05:22,358 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: java:portal/SessionFactory
10:05:22,358 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
10:05:22,358 INFO [NamingHelper] JNDI InitialContext properties:{}
10:05:22,358 INFO [SessionFactoryImpl] Checking 0 named queries
10:05:22,358 INFO [SessionFactoryBinder] Hibernate dialect used org.hibernate.dialect.MySQLDialect
10:05:22,780 ERROR [SessionFactoryBinder] Starting failed portal:service=Hibernate java.lang.NullPointerException
at com.mysql.jdbc.PreparedStatement.asSql(PreparedStatement.java:1845)
at com.mysql.jdbc.PreparedStatement.toString(PreparedStatement.java:1770
)
at java.lang.String.valueOf(String.java:2577)
at java.lang.StringBuffer.append(StringBuffer.java:220)
at com.mysql.jdbc.trace.Tracer.printParameters(Tracer.aj:240)
at com.mysql.jdbc.trace.Tracer.printEntering(Tracer.aj:167)
at com.mysql.jdbc.trace.Tracer.entry(Tracer.aj:126)
at com.mysql.jdbc.trace.Tracer.ajc$before$com_mysql_jdbc_trace_Tracer$1$
f51c62b8(Tracer.aj:45)
at com.mysql.jdbc.Connection.registerStatement(Connection.java)
at com.mysql.jdbc.Statement.(Statement.java:171)
at com.mysql.jdbc.PreparedStatement.(PreparedStatement.java:139)
at com.mysql.jdbc.Connection.clientPrepareStatement(Connection.java:1053
)
..........
10:05:23,280 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=file:/C:/jboss-4.0.2/server/default/deploy/jmx-console.war/
10:05:23,374 INFO [STDOUT] Mon Jun 13 10:05:23 EDT 2005 TRACE: --> Statement.java:1627 com.mysql.jdbc.Statement.finalize()
10:05:23,374 INFO [STDOUT] Mon Jun 13 10:05:23 EDT 2005 TRACE: --> PreparedStatement.java:2087 com.mysql.jdbc.PreparedStatement.realClose(false)
10:05:23,374 INFO [STDOUT] Mon Jun 13 10:05:23 EDT 2005 TRACE: --> Statement.java:1576 com.mysql.jdbc.Statement.realClose(false)
10:05:23,468 ERROR [URLDeploymentScanner] Incomplete Deployment listing:
--- MBeans waiting for other MBeans ---
ObjectName: portal:service=Hibernate
State: FAILED
Reason: java.lang.NullPointerException
I Depend On:
jboss.jca:service=DataSourceBinding,name=PortalDS
Depends On Me:
portal:service=Module,type=User
portal:service=Module,type=Role
portal:service=Module,type=Security
portal:service=ServerManager
--- MBEANS THAT ARE THE ROOT CAUSE OF THE PROBLEM ---
ObjectName: portal:service=Hibernate
State: FAILED
Reason: java.lang.NullPointerException
I Depend On:
jboss.jca:service=DataSourceBinding,name=PortalDS
Depends On Me:
portal:service=Module,type=User
portal:service=Module,type=Role
portal:service=Module,type=Security
portal:service=ServerManager
10:05:23,655 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-0.0.0.0-8080
10:05:23,780 INFO [ChannelSocket] JK: ajp13 listening on /0.0.0.0:8009
10:05:23,796 INFO [JkMain] Jk running ID=0 time=0/47 config=null
10:05:23,827 INFO [Server] JBoss (MX MicroKernel) [4.0.2 (build: CVSTag=JBoss_4_0_2 date=200505022023)] Started in 30s:937ms
The database exists in MySQL and the user has full access to the database. It is empty since the docs say the portal will build it automatically and I don't need to run a script first. Being new to Hibernate and the portal, this problem is eating my lunch! Any help would be greatly appreciated.