Uploaded image for project: 'Teiid'
  1. Teiid
  2. TEIID-4336

Errors in logs during server shutdown

    Details

    • Type: Quality Risk
    • Status: Resolved (View Workflow)
    • Priority: Major
    • Resolution: Done
    • Affects Version/s: 8.12.5
    • Fix Version/s: 9.0, 9.0.2
    • Component/s: Query Engine
    • Labels:
      None

      Description

      When using materialization and user shuts down the server without disabling the vdb with materialized view defined a few exceptions appear in the server logs.
      I attached full server.log to this issue.

      08:05:50,764 ERROR [org.teiid.CONNECTOR] (Worker3_QueryProcessorQueue626) Connector worker process failed for atomic-request=RsQyJH143A1D.0.0.953: java.lang.NullPointerException
      	at org.teiid.translator.jdbc.JDBCBaseExecution.getPreparedStatement(JDBCBaseExecution.java:196) [translator-jdbc-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.translator.jdbc.JDBCQueryExecution.execute(JDBCQueryExecution.java:121) [translator-jdbc-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:364)
      	at sun.reflect.GeneratedMethodAccessor135.invoke(Unknown Source) [:1.8.0_92]
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_92]
      	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_92]
      	at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:211)
      	at com.sun.proxy.$Proxy45.execute(Unknown Source)
      	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:306)
      	at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:142)
      	at org.teiid.query.processor.relational.AccessNode.nextBatchDirect(AccessNode.java:391)
      	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:282)
      	at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:145)
      	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:151)
      	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:114)
      	at org.teiid.query.processor.BatchIterator.finalRow(BatchIterator.java:69)
      	at org.teiid.common.buffer.AbstractTupleSource.getCurrentTuple(AbstractTupleSource.java:70)
      	at org.teiid.query.processor.BatchIterator.getCurrentTuple(BatchIterator.java:84)
      	at org.teiid.common.buffer.AbstractTupleSource.hasNext(AbstractTupleSource.java:92)
      	at org.teiid.query.processor.proc.ProcedurePlan.executePlan(ProcedurePlan.java:608)
      	at org.teiid.query.processor.proc.LoopInstruction.testCondition(LoopInstruction.java:108)
      	at org.teiid.query.processor.proc.ProcedurePlan.processProcedure(ProcedurePlan.java:379)
      	at org.teiid.query.processor.proc.ProcedurePlan.nextBatchDirect(ProcedurePlan.java:298)
      	at org.teiid.query.processor.proc.ProcedurePlan.nextBatch(ProcedurePlan.java:270)
      	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:151)
      	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:114)
      	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:164)
      	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:146)
      	at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:472)
      	at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:348)
      	at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:51)
      	at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:274)
      	at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:276)
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:119)
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:210)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_92]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_92]
      	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_92]
      

      and

      8:05:50,797 WARN  [org.teiid.PROCESSOR.MATVIEWS] (MSC service thread 1-2) org.teiid.core.TeiidException: java.sql.SQLException: org.teiid.core.TeiidException
      	at org.teiid.runtime.MaterializationManager.executeQuery(MaterializationManager.java:349) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.runtime.MaterializationManager$1.process(MaterializationManager.java:101) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.runtime.MaterializationManager.doMaterializationActions(MaterializationManager.java:199) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.runtime.MaterializationManager.beforeRemove(MaterializationManager.java:90) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.deployers.VDBRepository.notifyBeforeRemove(VDBRepository.java:394) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.deployers.VDBRepository.removeVDB(VDBRepository.java:264) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.deployers.VDBRepository.removeVDB(VDBRepository.java:260) [teiid-runtime-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.jboss.VDBService.cleanup(VDBService.java:264) [teiid-jboss-integration-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.jboss.VDBService.stop(VDBService.java:276) [teiid-jboss-integration-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.jboss.msc.service.ServiceControllerImpl$StopTask.stopService(ServiceControllerImpl.java:2088) [jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
      	at org.jboss.msc.service.ServiceControllerImpl$StopTask.run(ServiceControllerImpl.java:2049) [jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_92]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_92]
      	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_92]
      Caused by: org.teiid.core.TeiidException
      	at org.teiid.client.ResultsMessage.setException(ResultsMessage.java:196) [teiid-client-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.sendError(RequestWorkItem.java:1088) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.close(RequestWorkItem.java:578) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:374) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.AbstractWorkItem.run(AbstractWorkItem.java:51) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.run(RequestWorkItem.java:274) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.DQPWorkContext.runInContext(DQPWorkContext.java:276) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$RunnableWrapper.run(ThreadReuseExecutor.java:119) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.ThreadReuseExecutor$3.run(ThreadReuseExecutor.java:210) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	... 3 more
      Caused by: java.lang.NullPointerException
      	at org.teiid.translator.jdbc.JDBCBaseExecution.getPreparedStatement(JDBCBaseExecution.java:196)
      	at org.teiid.translator.jdbc.JDBCQueryExecution.execute(JDBCQueryExecution.java:121)
      	at org.teiid.dqp.internal.datamgr.ConnectorWorkItem.execute(ConnectorWorkItem.java:364) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at sun.reflect.GeneratedMethodAccessor135.invoke(Unknown Source) [:1.8.0_92]
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_92]
      	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_92]
      	at org.teiid.dqp.internal.datamgr.ConnectorManager$1.invoke(ConnectorManager.java:211) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at com.sun.proxy.$Proxy45.execute(Unknown Source)
      	at org.teiid.dqp.internal.process.DataTierTupleSource.getResults(DataTierTupleSource.java:306) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.DataTierTupleSource.nextTuple(DataTierTupleSource.java:142) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.relational.AccessNode.nextBatchDirect(AccessNode.java:391) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.relational.RelationalNode.nextBatch(RelationalNode.java:282) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.relational.RelationalPlan.nextBatch(RelationalPlan.java:145) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:151) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:114) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.BatchIterator.finalRow(BatchIterator.java:69) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.common.buffer.AbstractTupleSource.getCurrentTuple(AbstractTupleSource.java:70) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.BatchIterator.getCurrentTuple(BatchIterator.java:84) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.common.buffer.AbstractTupleSource.hasNext(AbstractTupleSource.java:92) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.proc.ProcedurePlan.executePlan(ProcedurePlan.java:608) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.proc.LoopInstruction.testCondition(LoopInstruction.java:108) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.proc.ProcedurePlan.processProcedure(ProcedurePlan.java:379) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.proc.ProcedurePlan.nextBatchDirect(ProcedurePlan.java:298) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.proc.ProcedurePlan.nextBatch(ProcedurePlan.java:270) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.QueryProcessor.nextBatchDirect(QueryProcessor.java:151) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.QueryProcessor.nextBatch(QueryProcessor.java:114) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:164) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.query.processor.BatchCollector.collectTuples(BatchCollector.java:146) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.processMore(RequestWorkItem.java:472) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	at org.teiid.dqp.internal.process.RequestWorkItem.process(RequestWorkItem.java:348) [teiid-engine-8.12.5.redhat-7.jar:8.12.5.redhat-7]
      	... 8 more
      

      and in the end:

      08:05:50,818 WARN  [org.jboss.msc.service.fail] (MSC service thread 1-2) MSC000004: Failure during stop of service jboss.teiid.vdb.saphana.1: java.lang.IllegalStateException
      	at org.jboss.msc.value.InjectedValue.getValue(InjectedValue.java:47) [jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
      	at org.teiid.jboss.DQPCoreService$1.removed(DQPCoreService.java:117)
      	at org.teiid.deployers.VDBRepository.notifyRemove(VDBRepository.java:387)
      	at org.teiid.deployers.VDBRepository.removeVDB(VDBRepository.java:271)
      	at org.teiid.deployers.VDBRepository.removeVDB(VDBRepository.java:260)
      	at org.teiid.jboss.VDBService.cleanup(VDBService.java:264)
      	at org.teiid.jboss.VDBService.stop(VDBService.java:276)
      	at org.jboss.msc.service.ServiceControllerImpl$StopTask.stopService(ServiceControllerImpl.java:2088) [jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
      	at org.jboss.msc.service.ServiceControllerImpl$StopTask.run(ServiceControllerImpl.java:2049) [jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_92]
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_92]
      	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_92]
      

        Gliffy Diagrams

          Attachments

            Issue Links

              Activity

                People

                • Assignee:
                  shawkins Steven Hawkins
                  Reporter:
                  jstastny Jan Stastny
                • Votes:
                  0 Vote for this issue
                  Watchers:
                  4 Start watching this issue

                  Dates

                  • Created:
                    Updated:
                    Resolved: