[HIVE-13691] No record with CQ_ID=0 found in COMPACTION_QUEUE

• Type: Bug
• Status: Closed
• Priority: Critical
• Resolution: Fixed
• Affects Version/s: 1.3.0
• Fix Version/s: 1.3.0, 2.1.0
• Component/s: Transactions
• Target Version/s: 1.3.0, 2.1.0
• Assignee: Eugene Koifman
• Reporter: Eugene Koifman
• Created: 04/May/16 23:40
• Updated: 21/Jun/16 15:08
• Resolved: 18/May/16 17:49

Description

2016-04-29 18:49:31,594 ERROR [Thread-11]: compactor.Initiator (Initiator.java:run(141)) - Caught exception while trying to determine if we should compact id:0,dbname:default,tableName:service_logs_v2,par
tName:ds=2016-04-21,state:^@,type:null,runAs:null,tooManyAborts:false,highestTxnId:0.  Marking clean to avoid repeated failures, MetaException(message:Timeout when executing method: getTable)
        at org.apache.hadoop.hive.metastore.Deadline.newMetaException(Deadline.java:187)
        at org.apache.hadoop.hive.metastore.Deadline.check(Deadline.java:177)
        at org.apache.hadoop.hive.metastore.Deadline.checkTimeout(Deadline.java:160)
        at org.apache.hadoop.hive.metastore.ObjectStore.convertToParts(ObjectStore.java:1839)
        at org.apache.hadoop.hive.metastore.ObjectStore.getPartitionsViaOrmFilter(ObjectStore.java:2255)
        at org.apache.hadoop.hive.metastore.ObjectStore.access$300(ObjectStore.java:165)
        at org.apache.hadoop.hive.metastore.ObjectStore$3.getJdoResult(ObjectStore.java:2051)
        at org.apache.hadoop.hive.metastore.ObjectStore$3.getJdoResult(ObjectStore.java:2043)
        at org.apache.hadoop.hive.metastore.ObjectStore$GetHelper.run(ObjectStore.java:2400)
        at org.apache.hadoop.hive.metastore.ObjectStore.getPartitionsByNamesInternal(ObjectStore.java:2043)
        at org.apache.hadoop.hive.metastore.ObjectStore.getPartitionsByNames(ObjectStore.java:2037)
        at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at org.apache.hadoop.hive.metastore.RawStoreProxy.invoke(RawStoreProxy.java:114)
        at com.sun.proxy.$Proxy0.getPartitionsByNames(Unknown Source)
        at org.apache.hadoop.hive.ql.txn.compactor.CompactorThread.resolvePartition(CompactorThread.java:111)
        at org.apache.hadoop.hive.ql.txn.compactor.Initiator.run(Initiator.java:129)
Caused by: org.apache.hadoop.hive.metastore.DeadlineException: Timeout when executing method: getTable
        at org.apache.hadoop.hive.metastore.Deadline.check(Deadline.java:174)
        ... 16 more

2016-04-29 18:49:31,595 ERROR [Thread-11]: compactor.Initiator (Initiator.java:run(154)) - Initiator loop caught unexpected exception this time through the loop: java.lang.IllegalStateException: No record with CQ_ID=0 found in COMPACTION_QUEUE
        at org.apache.hadoop.hive.metastore.txn.CompactionTxnHandler.markFailed(CompactionTxnHandler.java:861)
        at org.apache.hadoop.hive.ql.txn.compactor.Initiator.run(Initiator.java:144)

2016-04-29 18:49:31,595 ERROR [Thread-11]: compactor.Initiator (Initiator.java:run(154)) - Initiator loop caught unexpected exception this time through the loop: java.lang.IllegalStateException: No record with CQ_ID=0 found in COMPACTION_QUEUE
        at org.apache.hadoop.hive.metastore.txn.CompactionTxnHandler.markFailed(CompactionTxnHandler.java:861)
        at org.apache.hadoop.hive.ql.txn.compactor.Initiator.run(Initiator.java:144)

is triggered by DeadlineException: Timeout when executing method but is nonetheless an issue.

We should be able to record an entry in completed_compaction_queue to represent a failed compaction even if an entry in compaction_queue was never made, as is the case here.

Issue Links

• relates to: HIVE-12353 When Compactor fails it calls CompactionTxnHandler.markedCleaned(). it should not.