9 Replies Latest reply on Mar 25, 2010 1:32 PM by Ramesh Reddy

    Requests taking over 2 minutes

    Immo Benjes Apprentice

      Hi

       

      we are currently evaluating teiid (6.2) but I am having major problems with requests taking more than 2 minutes when the same request from Squirel to the DB (a Progress DB) takes less than a second. The odd thing is that the same request can sometimes take only a few seconds with Teiid (when run shortly after a clean start of teiid).

       

      2010-03-23 15:02:27,688 INFO [Worker9_UVOSProgress Connector<1>3] org.teiid.COMMAND_LOG - 2010.03.23 15:02:27.688       START DATA SRC COMMAND: startTime=2010.03.23 15:02:27.688       requestID=5.1   sourceCommandID=2      subTxID=a000041:c3e6:4ba8d4ed:4 modelName=UVOS  connectorBindingName=UVOSProgress Connector<1>  sessionID=5     principal=admin sql=SELECT DISTINCT * FROM UVOS.PORDER.PUB.DETAILS AS g_0, UVOS.PORDER.PUB.HEADER AS g_1 WHERE g_0.HDR_ID = g_1.HDR_ID
      2010-03-23 15:05:48,488 INFO [Worker13_QueryProcessorQueue91] org.teiid.COMMAND_LOG - 2010.03.23 15:05:48.489   END USER COMMAND:       endTime=2010.03.23 15:05:48.488 requestID=5.1   txID=a000041:c3e6:4ba8d4ed:4   sessionID=5     principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=100       isCancelled=false       errorOccurred=false

       

      Somehow this sounds more like a timeout problem somewhere.

       

      Another thing I've noticed is that when Hibernate starts up (we are running Seam 2.2/Hibernate3 on JBoss 4.2.3, I know old) a lot of request are generated. These request only finish a few minutes later.

       

      Here is one of those request:

      2010-03-23 14:49:56,409 INFO [Worker3_SocketWorker13] org.teiid.COMMAND_LOG - 2010.03.23 14:49:56.409   START USER COMMAND:     startTime=2010.03.23 14:49:56.409       requestID=1.1   txID=null       sessionID=1    applicationName=JDBC    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    sql=SELECT NULL AS TABLE_CAT, v.Name AS TABLE_SCHEM, GroupFullName AS TABLE_NAME, e.Name AS COLUMN_NAME, convert(decodeString(DataType, 'string,12,boolean,-7,time,92,date,91,timestamp,93,integer,4,float,7,double,8,bigdecimal,2,biginteger,2,byte,-6,short,5,long,-5,char,1,object,2000,clob,2005,blob,2004,xml,2009', ','), short) AS DATA_TYPE, DataType AS TYPE_NAME, CASE WHEN (DataType IN ('string', 'char', 'clob', 'blob', 'boolean', 'date', 'time', 'timestamp', 'object')) THEN CASE WHEN ElementLength <= 0 THEN convert(decodeString(DataType,'string,4000,boolean,1,time,8,date,10,timestamp,29,integer,10,float,20,double,20,bigdecimal,20,biginteger,19,byte,3,short,5,long,19,char,1,object,2147483647,clob,2147483647,blob,2147483647,xml,2147483647',','), integer) ELSE ElementLength END ELSE CASE WHEN Precision <= 0 THEN convert(decodeString(DataType,'string,4000,boolean,1,time,8,date,10,timestamp,29,integer,10,float,20,double,20,bigdecimal,20,biginteger,19,byte,3,short,5,long,19,char,1,object,2147483647,clob,2147483647,blob,2147483647,xml,2147483647',','), integer) ELSE Precision END END AS COLUMN_SIZE, NULL AS BUFFER_LENGTH, Scale AS DECIMAL_DIGITS, Radix AS NUM_PREC_RADIX, convert(decodeString(NullType, 'No Nulls, 0, Nullable, 1, Unknown, 2', ','), integer) AS NULLABLE, Description AS REMARKS, DefaultValue AS COLUMN_DEF, NULL AS SQL_DATA_TYPE, NULL AS SQL_DATETIME_SUB, CharOctetLength AS CHAR_OCTET_LENGTH, Position AS ORDINAL_POSITION, decodeString(NullType, 'No Nulls, YES, Nullable, NO, Unknown, '' ''', ',') AS IS_NULLABLE, NULL AS SCOPE_CATALOG, NULL AS SCOPE_SCHEMA, NULL AS SCOPE_TABLE, NULL AS SOURCE_DATA_TYPE, CASE WHEN e.IsAutoIncremented = 'true' THEN 'YES' ELSE 'NO' END AS IS_AUTOINCREMENT FROM System.Elements e CROSS JOIN System.VirtualDatabases v WHERE UCASE(v.Name) LIKE ? ESCAPE '\' AND UCASE(GroupFullName) LIKE ? ESCAPE '\' AND UCASE(e.Name) LIKE ? ESCAPE '\'  ORDER BY TABLE_NAME, ORDINAL_POSITION

       

      and about 14 minutes later:

      2010-03-23 15:12:22,749 INFO [Worker17_QueryProcessorQueue92] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.749   END USER COMMAND:       endTime=2010.03.23 15:12:22.749 requestID=1.1   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=20        isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,763 INFO [Worker15_QueryProcessorQueue93] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.763   END USER COMMAND:       endTime=2010.03.23 15:12:22.763 requestID=1.0   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=1 isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,764 INFO [Worker18_QueryProcessorQueue94] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.764   END USER COMMAND:       endTime=2010.03.23 15:12:22.764 requestID=1.2   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=1 isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,771 INFO [Worker18_QueryProcessorQueue97] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.771   END USER COMMAND:       endTime=2010.03.23 15:12:22.771 requestID=1.4   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=1 isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,771 INFO [Worker15_QueryProcessorQueue96] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.771   END USER COMMAND:       endTime=2010.03.23 15:12:22.771 requestID=1.5   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=35        isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,778 INFO [Worker19_QueryProcessorQueue95] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.778   END USER COMMAND:       endTime=2010.03.23 15:12:22.778 requestID=1.3   txID=null       sessionID=1    principal=admin vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=41        isCancelled=false       errorOccurred=false

      2010-03-23 15:12:22,781 INFO [Worker9_SocketWorker178] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.781  END USER COMMAND:       endTime=2010.03.23 15:12:22.781 requestID=1.6   txID=null       sessionID=1   principal=admin  vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=0 isCancelled=true        errorOccurred=false

      2010-03-23 15:12:22,781 INFO [Worker9_SocketWorker178] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.781  END USER COMMAND:       endTime=2010.03.23 15:12:22.781 requestID=1.7   txID=null       sessionID=1   principal=admin  vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=0 isCancelled=true        errorOccurred=false

      2010-03-23 15:12:22,781 INFO [Worker9_SocketWorker178] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.781  END USER COMMAND:       endTime=2010.03.23 15:12:22.781 requestID=1.8   txID=null       sessionID=1   principal=admin  vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=0 isCancelled=true        errorOccurred=false

      2010-03-23 15:12:22,781 INFO [Worker9_SocketWorker178] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.781  END USER COMMAND:       endTime=2010.03.23 15:12:22.781 requestID=1.9   txID=null       sessionID=1   principal=admin  vdbName=VIRT_UVOS       vdbVersion=1    finalRowCount=0 isCancelled=true        errorOccurred=false

      2010-03-23 15:12:22,804 INFO [Worker19_QueryProcessorQueue98] org.teiid.COMMAND_LOG - 2010.03.23 15:12:22.804   END USER COMMAND:       endTime=2010.03.23 15:12:22.804 requestID=C.6   txID=null       sessionID=null principal=null  vdbName=null    vdbVersion=null finalRowCount=1 isCancelled=false       errorOccurred=false

       

      Any ideas what might be going wrong?

      Other problems I often see is 'Socket Closed Exception' (my session timeout is commented out in deploy.properties

       

      or

      com.metamatrix.jdbc.MMSQLException: The specified session ID "5" is invalid. It cannot be found in the userbase.

       

       

      Any ideas what might be going wrong?

       

      Regards

       

      Immo

        • 1. Re: Requests taking over 2 minutes
          Ramesh Reddy Master

          Immo,

           

          In the log above "Command Log" there are two types of messages. One for the "User Command" and one for the "Data Command".

           

          User Command are the queries that application has issued against the Teiid. The Data Command are one issued by the Teiid to the underlaying data sources. See in your log for those with data command to determine if Teiid or the source itself is taking the addtional time.

           

          For socket error seems like https://jira.jboss.org/jira/browse/TEIID-876

           

          Depending upon your model size the query from the hibernate could be long, but 14 mins seems overly excessive. You coud possibly tweak the JVM size and buffer manager size in deploy.properties to reduce any disk access.

           

          Ramesh..

          • 2. Re: Requests taking over 2 minutes
            Immo Benjes Apprentice

            I saw that socket error post (https://jira.jboss.org/jira/browse/TEIID-876) so I tried 7M2 today. 7M2 seems to have a problem with one of my tables. When hibernate starts up and validates the entities I get the following exception:

             

             

            org.hibernate.exception.GenericJDBCException: could not execute query
            at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:126)
            at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:114)
            at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
            at org.hibernate.loader.Loader.doList(Loader.java:2235)
            at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2129)
            at org.hibernate.loader.Loader.list(Loader.java:2124)
            at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:401)
            ...
            Caused by: com.metamatrix.jdbc.MMSQLException: ResultSet cursor is after the last row.
            at com.metamatrix.jdbc.MMResultSet.getObject(MMResultSet.java:225)
            at com.metamatrix.jdbc.MMResultSet.getInt(MMResultSet.java:849)
            at com.metamatrix.jdbc.MMResultSet.getInt(MMResultSet.java:862)
            at org.hibernate.type.IntegerType.get(IntegerType.java:51)
            at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:184)
            at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:173)
            at org.hibernate.loader.hql.QueryLoader.getResultColumnOrRow(QueryLoader.java:356)
            at org.hibernate.loader.Loader.getRowFromResultSet(Loader.java:629)
            at org.hibernate.loader.Loader.doQuery(Loader.java:724)
            at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:259)
            at org.hibernate.loader.Loader.doList(Loader.java:2232)

             

             

             

            Using the HQL editor to get data from that table fails as well. I didn't had the problem with Teiid 6.

             

            Are there any work arounds for the 876 issue when using Teiid 6 (any patches?)

             

            Regards

             

            Immo

            • 3. Re: Requests taking over 2 minutes
              Ramesh Reddy Master

              Did you have error stack from the server? That may explain more about the issue. Change "log4j.xml" to adjust the logging levels.

               

              As per the patch for 6.2, we do not have it. However, the solution is on the JIRA's commits list, if you want to build one. Just see the fisheye changes, build svn patch file and apply it on the 6.2 source and build.

              • 4. Re: Requests taking over 2 minutes
                Immo Benjes Apprentice

                No the stack trace comes from my JBoss server not the Teiid server.

                • 5. Re: Requests taking over 2 minutes
                  Ramesh Reddy Master

                  In the Teiid Server not in JBoss Server log. JBoss server is your application layer hosting the Hibernate and calling Teiid as data source. Look in "<teiid-install>/log" directory.

                   

                  Ramesh..

                  • 6. Re: Requests taking over 2 minutes
                    Immo Benjes Apprentice

                    Hi,

                     

                    I did some more testing.

                    When hitting teiid from Squirrel doing a select * from TableA everything is fine.

                    When I do

                    select a from TableA a

                    with the Hibernate HQL editor in Eclipse I get the following exception:

                     

                     

                    org.hibernate.exception.GenericJDBCException: could not execute query
                    at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:126)
                    at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:114)
                    at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
                    at org.hibernate.loader.Loader.doList(Loader.java:2235)
                    at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2129)
                    at org.hibernate.loader.Loader.list(Loader.java:2124)
                    at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:401)
                    at org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:363)
                    at org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:196)
                    at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1149)
                    at org.hibernate.impl.QueryImpl.list(QueryImpl.java:102)
                    at org.hibernate.console.HQLQueryPage.getList(HQLQueryPage.java:49)
                    at org.hibernate.eclipse.console.views.QueryPageViewer$ContentProviderImpl.getElements(QueryPageViewer.java:120)
                    at org.eclipse.jface.viewers.StructuredViewer.getRawChildren(StructuredViewer.java:959)
                    at org.eclipse.jface.viewers.ColumnViewer.getRawChildren(ColumnViewer.java:703)
                    at org.eclipse.jface.viewers.AbstractTableViewer.getRawChildren(AbstractTableViewer.java:1087)
                    at org.eclipse.jface.viewers.StructuredViewer.getFilteredChildren(StructuredViewer.java:893)
                    at org.eclipse.jface.viewers.StructuredViewer.getSortedChildren(StructuredViewer.java:1016)
                    at org.eclipse.jface.viewers.AbstractTableViewer.internalRefreshAll(AbstractTableViewer.java:701)
                    at org.eclipse.jface.viewers.AbstractTableViewer.internalRefresh(AbstractTableViewer.java:649)
                    at org.eclipse.jface.viewers.AbstractTableViewer.internalRefresh(AbstractTableViewer.java:636)
                    at org.eclipse.jface.viewers.AbstractTableViewer$2.run(AbstractTableViewer.java:592)
                    at org.eclipse.jface.viewers.StructuredViewer.preservingSelection(StructuredViewer.java:1392)
                    at org.eclipse.jface.viewers.StructuredViewer.preservingSelection(StructuredViewer.java:1353)
                    at org.eclipse.jface.viewers.AbstractTableViewer.inputChanged(AbstractTableViewer.java:590)
                    at org.eclipse.jface.viewers.ContentViewer.setInput(ContentViewer.java:274)
                    at org.eclipse.jface.viewers.StructuredViewer.setInput(StructuredViewer.java:1634)
                    at org.hibernate.eclipse.console.views.QueryPageViewer.createTable(QueryPageViewer.java:225)
                    at org.hibernate.eclipse.console.views.QueryPageViewer.createControl(QueryPageViewer.java:203)
                    at org.hibernate.eclipse.console.views.QueryPageViewer.<init>(QueryPageViewer.java:160)
                    at org.hibernate.eclipse.console.views.QueryPageTabView.rebuild(QueryPageTabView.java:109)
                    at org.hibernate.eclipse.console.views.QueryPageTabView$1.contentsChanged(QueryPageTabView.java:75)
                    at org.hibernate.eclipse.console.views.QueryPageTabView$1.intervalAdded(QueryPageTabView.java:85)
                    at javax.swing.AbstractListModel.fireIntervalAdded(AbstractListModel.java:130)
                    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:620)
                    at org.hibernate.console.ConsoleConfiguration.access$6(ConsoleConfiguration.java:618)
                    at org.hibernate.console.ConsoleConfiguration$5.execute(ConsoleConfiguration.java:583)
                    at org.hibernate.console.execution.DefaultExecutionContext.execute(DefaultExecutionContext.java:63)
                    at org.hibernate.console.ConsoleConfiguration.executeHQLQuery(ConsoleConfiguration.java:575)
                    at org.hibernate.eclipse.hqleditor.HQLEditor.executeQuery(HQLEditor.java:423)
                    at org.hibernate.eclipse.console.actions.ExecuteQueryAction.execute(ExecuteQueryAction.java:80)
                    at org.hibernate.eclipse.console.actions.ExecuteQueryAction.run(ExecuteQueryAction.java:55)
                    at org.eclipse.jface.action.Action.runWithEvent(Action.java:498)
                    at org.hibernate.eclipse.console.actions.ExecuteQueryAction.runWithEvent(ExecuteQueryAction.java:59)
                    at org.eclipse.jface.action.ActionContributionItem.handleWidgetSelection(ActionContributionItem.java:584)
                    at org.eclipse.jface.action.ActionContributionItem.access$2(ActionContributionItem.java:501)
                    at org.eclipse.jface.action.ActionContributionItem$6.handleEvent(ActionContributionItem.java:452)
                    at org.eclipse.swt.widgets.EventTable.sendEvent(EventTable.java:84)
                    at org.eclipse.swt.widgets.Display.sendEvent(Display.java:3543)
                    at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java:1250)
                    at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java:1273)
                    at org.eclipse.swt.widgets.Widget.sendEvent(Widget.java:1258)
                    at org.eclipse.swt.widgets.Widget.notifyListeners(Widget.java:1079)
                    at org.eclipse.swt.widgets.Display.runDeferredEvents(Display.java:3441)
                    at org.eclipse.swt.widgets.Display.readAndDispatch(Display.java:3100)
                    at org.eclipse.ui.internal.Workbench.runEventLoop(Workbench.java:2405)
                    at org.eclipse.ui.internal.Workbench.runUI(Workbench.java:2369)
                    at org.eclipse.ui.internal.Workbench.access$4(Workbench.java:2221)
                    at org.eclipse.ui.internal.Workbench$5.run(Workbench.java:500)
                    at org.eclipse.core.databinding.observable.Realm.runWithDefault(Realm.java:332)
                    at org.eclipse.ui.internal.Workbench.createAndRunWorkbench(Workbench.java:493)
                    at org.eclipse.ui.PlatformUI.createAndRunWorkbench(PlatformUI.java:149)
                    at org.eclipse.ui.internal.ide.application.IDEApplication.start(IDEApplication.java:113)
                    at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:194)
                    at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:110)
                    at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:79)
                    at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:368)
                    at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:179)
                    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
                    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
                    at java.lang.reflect.Method.invoke(Method.java:597)
                    at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:559)
                    at org.eclipse.equinox.launcher.Main.basicRun(Main.java:514)
                    at org.eclipse.equinox.launcher.Main.run(Main.java:1311)
                    Caused by: com.metamatrix.jdbc.MMSQLException: ResultSet cursor is after the last row.
                    at com.metamatrix.jdbc.MMResultSet.getObject(MMResultSet.java:225)
                    at com.metamatrix.jdbc.MMResultSet.getInt(MMResultSet.java:849)
                    at com.metamatrix.jdbc.MMResultSet.getInt(MMResultSet.java:862)
                    at org.hibernate.type.IntegerType.get(IntegerType.java:51)
                    at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:184)
                    at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:173)
                    at org.hibernate.loader.Loader.getKeyFromResultSet(Loader.java:1121)
                    at org.hibernate.loader.Loader.getRowFromResultSet(Loader.java:588)
                    at org.hibernate.loader.Loader.doQuery(Loader.java:724)
                    at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:259)
                    at org.hibernate.loader.Loader.doList(Loader.java:2232)
                    ... 73 more

                     

                    The teiid log has this exception:

                    org.teiid.connector.api.ConnectorException: The number of result rows has exceeded the maximum result rows "10,000"

                            at org.teiid.dqp.internal.datamgr.impl.ConnectorWorkItem.handleBatch(ConnectorWorkItem.java:406)

                            at org.teiid.dqp.internal.datamgr.impl.ConnectorWorkItem.processMoreRequest(ConnectorWorkItem.java:500)

                            at org.teiid.dqp.internal.datamgr.impl.ConnectorWorkItem.process(ConnectorWorkItem.java:167)

                            at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:44)

                            at org.teiid.dqp.internal.datamgr.impl.SynchConnectorWorkItem.run(SynchConnectorWorkItem.java:60)

                            at com.metamatrix.common.queue.WorkerPoolFactory$StatsCapturingSharedThreadPoolExecutor$1.run(WorkerPoolFactory.java:211)

                            at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)

                            at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

                            at java.lang.Thread.run(Thread.java:637)

                     

                    The max result for the hibernate query is set to 50.

                    This is the command log:

                     

                    2010-03-25 15:13:39,571 INFO [Worker1_SocketWorker6] org.teiid.COMMAND_LOG - 2010.03.25 15:13:39.571     START USER COMMAND:     startTime=2010.03.25 15:13:39.571     requestID=2.0     txID=null     sessionID=2     applicationName=JDBC     principal=admin     vdbName=VIRT_UVOS     vdbVersion=1     sql=select poalloc0_.UID_ALLOC as UID1_0_, poalloc0_.CARTONS as CARTONS0_, poalloc0_.COMM_VALUE as COMM3_0_, poalloc0_.DELETED as DELETED0_, poalloc0_.GOH as GOH0_, poalloc0_.ITEM_NO as ITEM6_0_, poalloc0_.LINE_NO as LINE7_0_, poalloc0_.ORDER_QUANTITY as ORDER8_0_, poalloc0_.PIECES as PIECES0_, poalloc0_.PIECES_UNITS as PIECES10_0_, poalloc0_.UID_DETAILS as UID17_0_, poalloc0_.HDR_ID as HDR18_0_, poalloc0_.PO_NUMBER as PO11_0_, poalloc0_.SHIP_ID as SHIP19_0_, poalloc0_.UID_ITEMS as UID20_0_, poalloc0_.SKU as SKU0_, poalloc0_.VOLUME as VOLUME0_, poalloc0_.VOLUME_UNITS as VOLUME14_0_, poalloc0_.WEIGHT as WEIGHT0_, poalloc0_.WEIGHT_UNITS as WEIGHT16_0_ from VIRT_UVOS.UVOSi.PORDER.PUB.PO_ALLOC poalloc0_

                    2010-03-25 15:13:41,662 INFO [Worker1_UVOSProgress Connector<0>1] org.teiid.COMMAND_LOG - 2010.03.25 15:13:41.662     START DATA SRC COMMAND:     startTime=2010.03.25 15:13:41.662     requestID=2.0     sourceCommandID=1     subTxID=null     modelName=UVOS     connectorBindingName=UVOSProgress Connector<0>     sessionID=2     principal=admin     sql=SELECT g_0.UID_ALLOC, g_0.CARTONS, g_0.COMM_VALUE, g_0.DELETED, g_0.GOH, g_0.ITEM_NO, g_0.LINE_NO, g_0.ORDER_QUANTITY, g_0.PIECES, g_0.PIECES_UNITS, g_0.UID_DETAILS, g_0.HDR_ID, g_0.PO_NUMBER, g_0.SHIP_ID, g_0.UID_ITEMS, g_0.SKU, g_0.VOLUME, g_0.VOLUME_UNITS, g_0.WEIGHT, g_0.WEIGHT_UNITS FROM UVOS.PORDER.PUB.PO_ALLOC AS g_0

                    2010-03-25 15:14:11,963 INFO [Worker1_UVOSProgress Connector<0>1] org.teiid.COMMAND_LOG - 2010.03.25 15:14:11.964     END DATA SRC COMMAND:     endTime=2010.03.25 15:14:11.963     requestID=2.0     sourceCommandID=1     subTxID=null     modelName=UVOS     connectorBindingName=UVOSProgress Connector<0>     sessionID=2     principal=admin     finalRowCount=-1     isCancelled=false     errorOccurred=true

                    2010-03-25 15:14:11,971 INFO [Worker0_QueryProcessorQueue13] org.teiid.COMMAND_LOG - 2010.03.25 15:14:11.971     END USER COMMAND:     endTime=2010.03.25 15:14:11.971     requestID=2.0     txID=null     sessionID=2     principal=admin     vdbName=VIRT_UVOS     vdbVersion=1     finalRowCount=-1     isCancelled=false     errorOccurred=true

                     

                    Okay the table is massive but why isn't the result set limited? Or does it mean teiid can't handle tables larger than what ever is set in the deploy.config. What happens if during deploy time a table grows over that limit?

                     

                    I will try to increase the value of maxRowsPerTable for now. I still don't understand why the limit isn't set.

                    I see in the deploy.properties file that maxRowsFetchSize= 20480 by default. Why is that bigger than the max number of rows a table can have?

                     

                    Regards

                     

                    Immo

                    • 7. Re: Requests taking over 2 minutes
                      Ramesh Reddy Master

                      Immo,

                       

                      By default Teiid connectors are setup for MaxResultsRows at 10,000. This is default being changed in the 7.0. See https://jira.jboss.org/jira/browse/TEIID-1023

                       

                      For solution to this problem see the last couple comments in this thread http://community.jboss.org/thread/149284

                       

                      Ramesh..

                      • 8. Re: Requests taking over 2 minutes
                        Immo Benjes Apprentice

                        I found another problem when moving from Teiid 6.2 to Teiid 7.

                         

                        My entities were created with the hibernate tools (hbm2java).

                        Using teiid 6.2 the tables created where

                        @Table(name = "UVOSi.PORDER.PUB.PO_ALLOC", schema = "VIRT_UVOS")

                        in the package com.company.entity.Uvosi.porder.pub

                         

                        while with teiid 7 the entities are created as

                        @Table(name = "PORDER.PUB.PO_ALLOC", schema = "UVOSi", catalog = "VIRT_UVOS")

                        in the package com.company.entity.Porder.pub

                         

                        So if you try to use entities created with hibernate while using  teiid 6.2 under teiid 7 you get an error that hibernate can't find the tables.

                         

                        Are you aware of that problem?

                         

                        Regards

                         

                        Immo

                        • 9. Re: Requests taking over 2 minutes
                          Ramesh Reddy Master

                          See JIRA https://jira.jboss.org/jira/browse/TEIID-870 for explanation.

                           

                          Please note that between major releases, there will be some backward compatibility issues to make/correct required changes to software. Teiid strives to keep things compatible in given version series.