SQL Error: 17003, Invalid Column Index from HQL editor query

rlgoodman · **Joined:** Thu Feb 15, 2007 3:06 pm **Posts:** 4

Hibernate V3.1, Hibernate tools 3.2.0 beta9, Eclipse 3.2.0, database is Oracle 10.2.0.2.0

Doing a simple query in the HQL editor such as "from tablename" produces the following output in the Hibernate log console window:

Code:

2007-02-15 14:15:31,812 WARN main org.hibernate.util.JDBCExceptionReporter - SQL Error: 17003, SQLState: null
2007-02-15 14:15:31,812 ERROR main org.hibernate.util.JDBCExceptionReporter - Invalid column index

The same query from a small Java program works just fine, within the same Eclipse project. Also, running the code from the Dynamic SQL Preview view against the database directly works fine. The tree in the Hibernate Configurations view looks okay--all tables and session factories are there and look correct.

I can provide the .xml and mapping files if that will help, I just didn't want to clutter up this first post with all that.

Can queries done in the HQL editor be made to produce debug logging information? I can't find it if it's being logged anywhere by default. I have show_sql set to true, but I don't know where it would come out for queries in the HQL editor.

Thanks for any help you can provide.

max · **Posted:** Thu Feb 15, 2007 5:10 pm

DEBUG output is shown in the console view (eclipse's console view) . there should be one for each open sessionfactory.

you can configure the amount of logging via the hibernate-log4j.xml/properties file found in the plugins directory.

rlgoodman · **Joined:** Thu Feb 15, 2007 3:06 pm **Posts:** 4

Thanks max.

Here is the debug output, and the SQL does look a little suspect (or at least it's not a syntax I'm familiar with). It is certainly different from the SQL that shows up in the "Dynamic SQL Preview" view.

However, the SQL that shows up when I run a Java program that passes the same string to createQuery() looks the same as below, so I guess it must be something else. This is from the Java code run:

Code:

2007-02-15 21:59:06,390 [main] DEBUG org.hibernate.SQL - select * from ( select rlgc0_.KEYCOL as col_0_0_ from RLGDB.RLG_COMPANY rlgc0_ ) where rownum <= ?

Can you tell why this query would not work in the HQL Query window but work fine when the same string is passed to createQuery()?

From the Hibernate Log console view:

Code:

2007-02-15 16:45:36,921 INFO Worker-6 org.hibernate.cfg.Configuration - configuring from file: hibernate.cfg.xml
2007-02-15 16:45:37,500 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/CoAdesind.hbm.xml
2007-02-15 16:45:37,593 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.CoAdesind -> CO_ADESIND
2007-02-15 16:45:37,609 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/RlgAnnfund.hbm.xml
2007-02-15 16:45:37,640 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.RlgAnnfund -> RLG_ANNFUND
2007-02-15 16:45:37,640 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/RlgCompany.hbm.xml
2007-02-15 16:45:37,671 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.RlgCompany -> RLG_COMPANY
2007-02-15 16:45:37,671 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/RlgDprc.hbm.xml
2007-02-15 16:45:37,687 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.RlgDprc -> RLG_DPRC
2007-02-15 16:45:37,687 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/RlgQtrfund.hbm.xml
2007-02-15 16:45:37,703 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.RlgQtrfund -> RLG_QTRFUND
2007-02-15 16:45:37,703 INFO Worker-6 org.hibernate.cfg.Configuration - Reading mappings from resource: com/company/rlg/hibernate/RlgSecurity.hbm.xml
2007-02-15 16:45:37,703 INFO Worker-6 org.hibernate.cfg.HbmBinder - Mapping class: com.company.rlg.hibernate.RlgSecurity -> RLG_SECURITY
2007-02-15 16:45:37,703 INFO Worker-6 org.hibernate.cfg.Configuration - Configured SessionFactory: null
2007-02-15 16:45:39,796 INFO Worker-4 org.hibernate.connection.DriverManagerConnectionProvider - Using Hibernate built-in connection pool (not for production use!)
2007-02-15 16:45:39,796 INFO Worker-4 org.hibernate.connection.DriverManagerConnectionProvider - Hibernate connection pool size: 20
2007-02-15 16:45:39,796 INFO Worker-4 org.hibernate.connection.DriverManagerConnectionProvider - autocommit mode: false
2007-02-15 16:45:39,796 INFO Worker-4 org.hibernate.connection.DriverManagerConnectionProvider - using driver: oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@grolsch.company.com:1521:riskatl
2007-02-15 16:45:39,796 INFO Worker-4 org.hibernate.connection.DriverManagerConnectionProvider - connection properties: {user=rlgdb, password=****}
2007-02-15 16:45:40,796 INFO Worker-4 org.hibernate.cfg.SettingsFactory - RDBMS: Oracle, version: Oracle Database 10g Enterprise Edition Release 10.2.0.2.0 - 64bit Production
With the Partitioning, OLAP and Data Mining options
2007-02-15 16:45:40,796 INFO Worker-4 org.hibernate.cfg.SettingsFactory - JDBC driver: Oracle JDBC driver, version: 10.2.0.2.0
2007-02-15 16:45:40,828 INFO Worker-4 org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.OracleDialect
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.transaction.TransactionFactoryFactory - Using default transaction strategy (direct JDBC transactions)
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - JDBC batch size: 15
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - JDBC batch updates for versioned data: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Connection release mode: auto
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Default schema: RLGDB
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Query language substitutions: {}
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - JPA-QL strict compliance: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Query cache: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Cache provider: org.hibernate.cache.NoCacheProvider
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Echoing all SQL to stdout
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Statistics: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
2007-02-15 16:45:40,843 INFO Worker-4 org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
2007-02-15 16:45:40,906 INFO Worker-4 org.hibernate.impl.SessionFactoryImpl - building session factory
2007-02-15 16:45:41,500 INFO Worker-4 org.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.ConnectionManager - opening JDBC connection
2007-02-15 16:47:38,781 DEBUG main org.hibernate.SQL - select * from ( select rlgc0_.KEYCOL as col_0_0_ from RLGDB.RLG_COMPANY rlgc0_ ) where rownum <= ?
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.AbstractBatcher - preparing statement
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.AbstractBatcher - closing statement
2007-02-15 16:47:38,781 WARN main org.hibernate.util.JDBCExceptionReporter - SQL Error: 17003, SQLState: null
2007-02-15 16:47:38,781 ERROR main org.hibernate.util.JDBCExceptionReporter - Invalid column index
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.JDBCContext - after autocommit
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2007-02-15 16:47:38,781 DEBUG main org.hibernate.jdbc.ConnectionManager - connection already null in cleanup : no action

Cheers...

max · **Posted:** Fri Feb 16, 2007 5:03 am

there are about 5 hours difference between the first and second log ...is that on purpose ?

the select * thing is done for oracle when .iterate() is used (which we use instead of .list() to avoid loading *all* objects)

For some reason hibernate asks for a different column than what is actually returned ......have you tried to use .iterate in your normal hibernate app and see what happens there ?

rlgoodman · **Joined:** Thu Feb 15, 2007 3:06 pm **Posts:** 4

max wrote:

there are about 5 hours difference between the first and second log ...is that on purpose ?

the select * thing is done for oracle when .iterate() is used (which we use instead of .list() to avoid loading *all* objects)

For some reason hibernate asks for a different column than what is actually returned ......have you tried to use .iterate in your normal hibernate app and see what happens there ?

No, it's not on purpose ... for some reason the log4j log from my Java test comes out in UTC rather than local time. I have no idea why.

I tried using Query.iterate() (instead of Query.list()) in my Java test and everything works fine--the SQL in the log looks the same as it did when I used Query.list().

So, I'm still wondering why the query won't work in the HQL editor window.

I'm off on vacation next week, so if you don't hear from me, that's why.

Thanks for your help,

max · **Posted:** Fri Feb 16, 2007 3:49 pm

i have no good explanation on why you see different things in the ui and your code.

rlgoodman · **Joined:** Thu Feb 15, 2007 3:06 pm **Posts:** 4

I finally figured out how to get at the exception that was generated. The exception only occurs when I use the Max Results dropdown at the top of the HQL GUI window. There must be a bug in how the GUI specifies the max results to the Hibernate layer. The same query works fine without selecting a value in the Max Results dropdown.

Code:

2007-02-26 14:48:29,406 DEBUG main org.hibernate.SQL - select * from ( select rlgs0_.KEYCOL as col_0_0_, rlgs0_.ID as col_0_1_ from RLGDB.RLG_SECURITY rlgs0_ where rlgs0_.SEARCHCOL='keyval' ) where rownum <= ?
2007-02-26 14:48:29,406 DEBUG main org.hibernate.jdbc.AbstractBatcher - preparing statement
2007-02-26 14:48:29,421 DEBUG main org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-02-26 14:48:29,421 DEBUG main org.hibernate.jdbc.AbstractBatcher - closing statement
2007-02-26 14:48:29,421 DEBUG main org.hibernate.util.JDBCExceptionReporter - could not execute query [select rlgs0_.KEYCOL as col_0_0_, rlgs0_.ID as col_0_1_ from RLGDB.RLG_SECURITY rlgs0_ where rlgs0_.SEARCHCOL='keyval']
java.sql.SQLException: Invalid column index
   at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:111)
   at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:145)
   at oracle.jdbc.driver.DatabaseError.throwSqlException(DatabaseError.java:207)
   at oracle.jdbc.driver.OraclePreparedStatement.setIntInternal(OraclePreparedStatement.java:4611)
   at oracle.jdbc.driver.OraclePreparedStatement.setInt(OraclePreparedStatement.java:4603)
   at org.hibernate.loader.Loader.bindLimitParameters(Loader.java:1618)
   at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1557)
   at org.hibernate.loader.Loader.doQuery(Loader.java:661)
   at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:224)
   at org.hibernate.loader.Loader.doList(Loader.java:2157)
   at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2041)
   at org.hibernate.loader.Loader.list(Loader.java:2036)
   at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:388)
   at org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:338)
   at org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:172)
   at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1121)
   at org.hibernate.impl.QueryImpl.list(QueryImpl.java:79)
   at org.hibernate.console.HQLQueryPage.getList(HQLQueryPage.java:49)
   at org.hibernate.eclipse.console.views.QueryPageViewer$ContentProviderImpl.getElements(QueryPageViewer.java:110)
   at org.eclipse.jface.viewers.StructuredViewer.getRawChildren(StructuredViewer.java:927)
   at org.eclipse.jface.viewers.TableViewer.getRawChildren(TableViewer.java:1279)
   at org.eclipse.jface.viewers.StructuredViewer.getFilteredChildren(StructuredViewer.java:868)
   at org.eclipse.jface.viewers.StructuredViewer.getSortedChildren(StructuredViewer.java:984)
   at org.eclipse.jface.viewers.TableViewer.internalRefreshAll(TableViewer.java:870)
   at org.eclipse.jface.viewers.TableViewer.internalRefresh(TableViewer.java:818)
   at org.eclipse.jface.viewers.TableViewer.internalRefresh(TableViewer.java:807)
   at org.eclipse.jface.viewers.StructuredViewer$7.run(StructuredViewer.java:1388)
   at org.eclipse.jface.viewers.StructuredViewer.preservingSelection(StructuredViewer.java:1323)
   at org.eclipse.jface.viewers.StructuredViewer.refresh(StructuredViewer.java:1386)
   at org.eclipse.jface.viewers.StructuredViewer.refresh(StructuredViewer.java:1345)
   at org.eclipse.jface.viewers.TableViewer.inputChanged(TableViewer.java:767)
   at org.eclipse.jface.viewers.ContentViewer.setInput(ContentViewer.java:250)
   at org.eclipse.jface.viewers.StructuredViewer.setInput(StructuredViewer.java:1542)
   at org.hibernate.eclipse.console.views.QueryPageViewer.createTable(QueryPageViewer.java:213)
   at org.hibernate.eclipse.console.views.QueryPageViewer.createControl(QueryPageViewer.java:192)
   at org.hibernate.eclipse.console.views.QueryPageViewer.<init>(QueryPageViewer.java:150)
   at org.hibernate.eclipse.console.views.QueryPageTabView.rebuild(QueryPageTabView.java:114)
   at org.hibernate.eclipse.console.views.QueryPageTabView$1.contentsChanged(QueryPageTabView.java:78)
   at org.hibernate.eclipse.console.views.QueryPageTabView$1.intervalAdded(QueryPageTabView.java:88)
   at javax.swing.AbstractListModel.fireIntervalAdded(Unknown Source)
   at org.hibernate.console.QueryPageModel.add(QueryPageModel.java:67)
   at org.hibernate.console.KnownConfigurations$1.queryPageCreated(KnownConfigurations.java:88)
   at org.hibernate.console.ConsoleConfiguration.fireQueryPageCreated(ConsoleConfiguration.java:375)
   at org.hibernate.console.ConsoleConfiguration.access$5(ConsoleConfiguration.java:371)
   at org.hibernate.console.ConsoleConfiguration$3.execute(ConsoleConfiguration.java:347)
   at org.hibernate.console.execution.DefaultExecutionContext.execute(DefaultExecutionContext.java:65)
   at org.hibernate.console.ConsoleConfiguration.executeHQLQuery(ConsoleConfiguration.java:339)
   at org.hibernate.eclipse.hqleditor.HQLEditor.executeQuery(HQLEditor.java:374)
   at org.hibernate.eclipse.console.actions.ExecuteQueryAction.execute(ExecuteQueryAction.java:72)
   at org.hibernate.eclipse.console.actions.ExecuteQueryAction.run(ExecuteQueryAction.java:52)
   at org.eclipse.jface.action.Action.runWithEvent(Action.java:499)
   at org.hibernate.eclipse.console.actions.ExecuteQueryAction.runWithEvent(ExecuteQueryAction.java:56)
   at org.eclipse.jface.action.ActionContributionItem.handleWidgetSelection(ActionContributionItem.java:539)
   at org.eclipse.jface.action.ActionContributionItem.access$2(ActionContributionItem.java:488)
   at org.eclipse.jface.action.ActionContributionItem$6.handleEvent(ActionContributionItem.java:441)
   at org.eclipse.swt.widgets.EventTable.sendEvent(EventTable.java:66)
   at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java:928)
   at org.eclipse.swt.widgets.Display.runDeferredEvents(Display.java:3348)
   at org.eclipse.swt.widgets.Display.readAndDispatch(Display.java:2968)
   at org.eclipse.ui.internal.Workbench.runEventLoop(Workbench.java:1914)
   at org.eclipse.ui.internal.Workbench.runUI(Workbench.java:1878)
   at org.eclipse.ui.internal.Workbench.createAndRunWorkbench(Workbench.java:419)
   at org.eclipse.ui.PlatformUI.createAndRunWorkbench(PlatformUI.java:149)
   at org.eclipse.ui.internal.ide.IDEApplication.run(IDEApplication.java:95)
   at org.eclipse.core.internal.runtime.PlatformActivator$1.run(PlatformActivator.java:78)
   at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:92)
   at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:68)
   at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:400)
   at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:177)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.eclipse.core.launcher.Main.invokeFramework(Main.java:336)
   at org.eclipse.core.launcher.Main.basicRun(Main.java:280)
   at org.eclipse.core.launcher.Main.run(Main.java:977)
   at org.eclipse.core.launcher.Main.main(Main.java:952)

2007-02-26 14:48:29,421 WARN main org.hibernate.util.JDBCExceptionReporter - SQL Error: 17003, SQLState: null
2007-02-26 14:48:29,421 ERROR main org.hibernate.util.JDBCExceptionReporter - Invalid column index

Thanks for your time.

max · **Posted:** Mon Mar 19, 2007 10:54 am

please put it in jira with mention of specific database version and driver.

zlatan24 · **Joined:** Thu Jul 22, 2010 7:00 pm **Posts:** 2

For working out given condition the next tool can help repair mdf file. It works under any system requirements and uses contemporary methods of restoring info. The tool doesn't modify source data during working.