Problem creating query when using Hibernate and Axis2

[Bai Shen]

I've created a web service using Axis2. I want to use Hibernate to connect to my database back end. However, it's not working correctly. I'm not sure what the exact problem is. I'm using Tomcat 6.0.18 with Axis2 1.4.1 running inside Eclipse 3.4. I'm using Hibernate 3.3.1 and talking to HSQLDB 1.8.0

I can create and insert an object into the database, but then when I run a query to retrieve it, I get an AxisFault.

Here's my Tomcat log.

Oct 28, 2008 3:08:10 PM org.apache.tomcat.util.digester.SetPropertiesRule begin
WARNING: [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'source' to 'org.eclipse.jst.jee.server:Sample' did not find a matching property.
Oct 28, 2008 3:08:10 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Sun\SDK\jdk\bin;.;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;C:/Sun/SDK/jdk/bin/../jre/bin/client;C:/Sun/SDK/jdk/bin/../jre/bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\QuickTime\QTSystem\;C:\Sun\SDK\bin;C:\Sun\SDK\jdk\bin;C:\Program Files\OpenVPN\bin;c:\ant\bin
Oct 28, 2008 3:08:10 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Oct 28, 2008 3:08:10 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 760 ms
Oct 28, 2008 3:08:11 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Oct 28, 2008 3:08:11 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
[INFO] Deploying module: addressing-1.41 - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/addressing-1.41.mar
[INFO] Deploying module: metadataExchange-1.41 - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/mex-1.41.mar
[INFO] Deploying module: ping-1.41 - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/ping-1.41.mar
[INFO] Deploying module: script-1.41 - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/scripting-1.41.mar
[INFO] Deploying module: smtpfault - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/smtpfault.mar
[INFO] Deploying module: soapmonitor-1.41 - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/modules/soapmonitor-1.41.mar
[INFO] Deploying Web service: MessageService - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/services/MessageService/
[INFO] Deploying Web service: version.aar - file:/C:/Documents and Settings/Bai Shen/workspace/.metadata/.plugins/org.eclipse.wst.server.core/tmp0/wtpwebapps/Sample/WEB-INF/services/version.aar
Oct 28, 2008 3:08:13 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Oct 28, 2008 3:08:13 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Oct 28, 2008 3:08:13 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/63  config=null
Oct 28, 2008 3:08:13 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 2463 ms
16 [http-8080-2] INFO org.hibernate.cfg.Environment - Hibernate 3.3.1.GA
32 [http-8080-2] INFO org.hibernate.cfg.Environment - hibernate.properties not found
32 [http-8080-2] INFO org.hibernate.cfg.Environment - Bytecode provider name : javassist
47 [http-8080-2] INFO org.hibernate.cfg.Environment - using JDK 1.4 java.sql.Timestamp handling
172 [http-8080-2] INFO org.hibernate.cfg.Configuration - configuring from resource: /hibernate.cfg.xml
172 [http-8080-2] INFO org.hibernate.cfg.Configuration - Configuration resource: /hibernate.cfg.xml
282 [http-8080-2] INFO org.hibernate.cfg.Configuration - Reading mappings from resource : Schema.hbm.xml
407 [http-8080-2] INFO org.hibernate.cfg.HbmBinder - Mapping class: Schema -> SCHEMAS
438 [http-8080-2] INFO org.hibernate.cfg.Configuration - Configured SessionFactory: null
547 [http-8080-2] INFO org.hibernate.connection.DriverManagerConnectionProvider - Using Hibernate built-in connection pool (not for production use!)
547 [http-8080-2] INFO org.hibernate.connection.DriverManagerConnectionProvider - Hibernate connection pool size: 20
547 [http-8080-2] INFO org.hibernate.connection.DriverManagerConnectionProvider - autocommit mode: false
563 [http-8080-2] INFO org.hibernate.connection.DriverManagerConnectionProvider - using driver: org.hsqldb.jdbcDriver at URL: jdbc:hsqldb:hsql://localhost
563 [http-8080-2] INFO org.hibernate.connection.DriverManagerConnectionProvider - connection properties: {user=sa, password=****}
641 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - RDBMS: HSQL Database Engine, version: 1.8.0
641 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - JDBC driver: HSQL Database Engine Driver, version: 1.8.0
672 [http-8080-2] INFO org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.HSQLDialect
672 [http-8080-2] INFO org.hibernate.transaction.TransactionFactoryFactory - Using default transaction strategy (direct JDBC transactions)
688 [http-8080-2] INFO org.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - JDBC batch size: 15
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - JDBC batch updates for versioned data: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Connection release mode: auto
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Order SQL inserts for batching: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
688 [http-8080-2] INFO org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Query language substitutions: {}
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - JPA-QL strict compliance: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Query cache: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
688 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
704 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Echoing all SQL to stdout
704 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Statistics: disabled
704 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
704 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
704 [http-8080-2] INFO org.hibernate.cfg.SettingsFactory - Named query checking : enabled
766 [http-8080-2] INFO org.hibernate.impl.SessionFactoryImpl - building session factory
1204 [http-8080-2] INFO org.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
1219 [http-8080-2] INFO org.hibernate.tool.hbm2ddl.SchemaExport - Running hbm2ddl schema export
1219 [http-8080-2] INFO org.hibernate.tool.hbm2ddl.SchemaExport - exporting generated schema to database
1219 [http-8080-2] INFO org.hibernate.tool.hbm2ddl.SchemaExport - schema export complete
Hibernate: insert into SCHEMAS (SCHEMA_ID, name, xsd) values (null, ?, ?)
Hibernate: call identity()
*** STARTING! ***
*** BEFORE QUERY! ***
[ERROR] org.apache.axis2.AxisFault

This is the code that generates the error.

Session session = HibernateUtil.getSessionFactory().getCurrentSession();
session.beginTransaction();
System.out.println("*** BEFORE QUERY! ***");
Query q = session.createQuery("from Schema");
System.out.println("*** AFTER QUERY! ***");
List result = q.list();
session.getTransaction().commit();

As you can see, as soon as I create the query, I get an AxisFault. But I don't know what the problem is.
[ October 28, 2008: Message edited by: Bai Shen ]

[Jaikiran Pai]

Is this all that you see in the exception? Also are you sure in your code, the exception being caught is not eaten?

[Bai Shen]

Originally posted by Jaikiran Pai:
Is this all that you see in the exception? Also are you sure in your code, the exception being caught is not eaten?

Yup. What I posted was my entire Tomcat log from startup to one invocation of the web service. As for the code, I'm not using any try/catch blocks, and the method doesn't have any throws clauses.

All the other errors I ran into while implementing this printed stack traces to the console, so I'm not sure why this one is different.

[Bai Shen]

So after reading your message, I decided to put a try/catch block around my code. It caught an UndeclaredThrowableException caused by a CNF exception. What I don't get, however, is why it never showed up 'til I did a try/catch, and why Axis2 still sends back a 202 Accepted message.