Hans Zeller created TRAFODION-2617: -------------------------------------- Summary: Error 9252 during update statistics of an encrypted Trafodion table Key: TRAFODION-2617 URL: https://issues.apache.org/jira/browse/TRAFODION-2617 Project: Apache Trafodion Issue Type: Bug Components: sql-cmp Affects Versions: 2.1-incubating Environment: Any, HBase encryption is enabled for the table. Reporter: Hans Zeller Assignee: David Wayne Birdsall
Anu tried an update statistics command for a table that is using HBase encryption. That failed with the following stack trace, as printed >>update statistics for table t on every column sample; .. *** ERROR[9252] Unable to get row count estimate: Error code 68, detail 4. Exception info (if any): Instead of showing the exception info printed to stdout, I'm showing the contents of the ulog file: UPDATE STATISTICS ===================================================================== [Wed 17 May 2017 10:38:30 PM UTC] update statistics for table t on every column sample; [Wed 17 May 2017 10:38:30 PM UTC] :BEGIN UpdateStats() [Wed 17 May 2017 10:38:30 PM UTC] :| BEGIN Setup CQDs prior to parsing [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT QUERY_CACHE '0' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT CACHE_HISTOGRAMS 'OFF' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT USTAT_MODIFY_DEFAULT_UEC '0.05' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT OUTPUT_DATE_FORMAT 'ANSI' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT HIST_MISSING_STATS_WARNING_LEVEL '0' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT USTAT_AUTOMATION_INTERVAL '0' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT MV_ALLOW_SELECT_SYSTEM_ADDED_COLUMNS 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT HIST_ON_DEMAND_STATS_SIZE '0' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT ISOLATION_LEVEL 'READ COMMITTED' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT ALLOW_DML_ON_NONAUDITED_TABLE 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT MV_ALLOW_SELECT_SYSTEM_ADDED_COLUMNS 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT ALLOW_NULLABLE_UNIQUE_KEY_CONSTRAINT 'OFF' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT CAT_ERROR_ON_NOTNULL_STOREBY 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT WMS_CHILD_QUERY_MONITORING 'OFF' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT WMS_QUERY_MONITORING 'OFF' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT TRAF_TINYINT_RETURN_VALUES 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT TRAF_BOOLEAN_IO 'ON' [Wed 17 May 2017 10:38:30 PM UTC] CONTROL QUERY DEFAULT TRAF_LARGEINT_UNSIGNED_IO 'ON' [Wed 17 May 2017 10:38:31 PM UTC] CONTROL QUERY DEFAULT TRAF_ALLOW_RESERVED_COLNAMES 'ON' [Wed 17 May 2017 10:38:31 PM UTC] CONTROL QUERY DEFAULT TRAF_BLOB_AS_VARCHAR 'OFF' [Wed 17 May 2017 10:38:31 PM UTC] CONTROL QUERY DEFAULT TRAF_CLOB_AS_VARCHAR 'OFF' [Wed 17 May 2017 10:38:31 PM UTC] :| END Setup CQDs prior to parsing elapsed time (00:00:00.420) [Wed 17 May 2017 10:38:31 PM UTC] :| BEGIN Parse statement [Wed 17 May 2017 10:38:31 PM UTC] call HSHbaseTableDef::objExists [Wed 17 May 2017 10:38:31 PM UTC] naTbl_->objectUid() is 6001738912217799228 [Wed 17 May 2017 10:38:31 PM UTC] CONTROL QUERY DEFAULT DISPLAY_DIVISION_BY_COLUMNS RESET [Wed 17 May 2017 10:38:31 PM UTC] CHECK SCHEMA VERSION FOR TABLE: XXXXXXXXXXXX [Wed 17 May 2017 10:38:31 PM UTC] UpdateStats: TABLE: XXXXXXXXXXXX; SCHEMA VERSION: 2600; AUTOMATION INTERVAL: 0 [Wed 17 May 2017 10:38:31 PM UTC] KEY: (_SALT_,PATH_ID,NAME_ID) [Wed 17 May 2017 10:38:31 PM UTC] GroupExists: argument: colSet [Wed 17 May 2017 10:38:31 PM UTC] colSet[0]: :_SALT_: 12 [Wed 17 May 2017 10:38:31 PM UTC] colSet[1]: :PATH_ID: 0 [Wed 17 May 2017 10:38:31 PM UTC] colSet[2]: :NAME_ID: 1 [Wed 17 May 2017 10:38:31 PM UTC] KEY: (_SALT_,PATH_ID) [Wed 17 May 2017 10:38:31 PM UTC] GroupExists: argument: colSet [Wed 17 May 2017 10:38:31 PM UTC] colSet[0]: :_SALT_: 12 [Wed 17 May 2017 10:38:31 PM UTC] colSet[1]: :PATH_ID: 0 [Wed 17 May 2017 10:38:31 PM UTC] GroupExists: mgroup->colSet [Wed 17 May 2017 10:38:31 PM UTC] colSet[0]: :_SALT_: 12 [Wed 17 May 2017 10:38:31 PM UTC] colSet[1]: :PATH_ID: 0 [Wed 17 May 2017 10:38:31 PM UTC] colSet[2]: :NAME_ID: 1 [Wed 17 May 2017 10:38:31 PM UTC] :| END Parse statement elapsed time (00:00:00.930) [Wed 17 May 2017 10:38:31 PM UTC] USTAT_CQDS_ALLOWED_FOR_SPAWNED_COMPILERS size of (0) is not acceptable [Wed 17 May 2017 10:38:31 PM UTC] :| BEGIN Initialize environment [Wed 17 May 2017 10:38:31 PM UTC] Creating histogram tables for schema TRAFODION.XXXXXXX on demand. [Wed 17 May 2017 10:38:31 PM UTC] :| | BEGIN Create histogram tables [Wed 17 May 2017 10:38:31 PM UTC] BEGIN WORK [Wed 17 May 2017 10:38:32 PM UTC] BEGINWORK(Create histogram tables.) [Wed 17 May 2017 10:38:32 PM UTC] Transaction started: 2017-05-17 22:38:32.007401 [Wed 17 May 2017 10:38:33 PM UTC] :| | END Create histogram tables elapsed time (00:00:01.090) [Wed 17 May 2017 10:38:33 PM UTC] COMMIT WORK [Wed 17 May 2017 10:38:33 PM UTC] COMMITWORK() [Wed 17 May 2017 10:38:33 PM UTC] Transaction committed: 2017-05-17 22:38:33.099332 [Wed 17 May 2017 10:38:33 PM UTC] :| | BEGIN getRowCount() [Wed 17 May 2017 10:38:33 PM UTC] :| | END getRowCount() elapsed time (00:00:00.065) [Wed 17 May 2017 10:38:33 PM UTC] currentRowCountIsEstimate_=1 from getRowCount() [Wed 17 May 2017 10:38:33 PM UTC] errorCode=68, breadCrumb=4 [Wed 17 May 2017 10:38:33 PM UTC] JNI exception info: [Wed 17 May 2017 10:38:33 PM UTC] org.apache.hadoop.hbase.io.hfile.CorruptHFileException: Problem reading HFile Trailer from file hdfs://ip-172-31-65-71.ec2.internal:8020/apps/hbase/data/data/default/XXXXXXXXXX/00c6a0e9c39b98bd04f188647bd50253/#1/033b3f07b7c84725b5bc9e7aaf75eb54 org.apache.hadoop.hbase.io.hfile.HFile.pickReaderVersion(HFile.java:481) org.apache.hadoop.hbase.io.hfile.HFile.createReader(HFile.java:524) org.trafodion.sql.HBaseClient.estimateRowCountBody(HBaseClient.java:1302) org.trafodion.sql.HBaseClient.estimateRowCount(HBaseClient.java:1207) Caused by java.lang.RuntimeException: java.lang.RuntimeException: java.io.FileNotFoundException: /etc/hbase/conf/hbase.jks (Permission denied) org.apache.hadoop.hbase.io.crypto.Encryption.getKeyProvider(Encryption.java:560) org.apache.hadoop.hbase.io.crypto.Encryption.getSecretKeyForSubject(Encryption.java:427) org.apache.hadoop.hbase.io.crypto.Encryption.decryptWithSubjectKey(Encryption.java:474) org.apache.hadoop.hbase.security.EncryptionUtil.getUnwrapKey(EncryptionUtil.java:129) org.apache.hadoop.hbase.security.EncryptionUtil.unwrapKey(EncryptionUtil.java:122) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.createHFileContext(HFileReaderV3.java:107) org.apache.hadoop.hbase.io.hfile.HFileReaderV2.<init>(HFileReaderV2.java:130) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.<init>(HFileReaderV3.java:77) org.apache.hadoop.hbase.io.hfile.HFile.pickReaderVersion(HFile.java:471) org.apache.hadoop.hbase.io.hfile.HFile.createReader(HFile.java:524) org.trafodion.sql.HBaseClient.estimateRowCountBody(HBaseClient.java:1302) org.trafodion.sql.HBaseClient.estimateRowCount(HBaseClient.java:1207) Caused by java.lang.RuntimeException: java.io.FileNotFoundException: /etc/hbase/conf/hbase.jks (Permission denied) org.apache.hadoop.hbase.io.crypto.KeyStoreKeyProvider.init(KeyStoreKeyProvider.java:153) org.apache.hadoop.hbase.io.crypto.Encryption.getKeyProvider(Encryption.java:553) org.apache.hadoop.hbase.io.crypto.Encryption.getSecretKeyForSubject(Encryption.java:427) org.apache.hadoop.hbase.io.crypto.Encryption.decryptWithSubjectKey(Encryption.java:474) org.apache.hadoop.hbase.security.EncryptionUtil.getUnwrapKey(EncryptionUtil.java:129) org.apache.hadoop.hbase.security.EncryptionUtil.unwrapKey(EncryptionUtil.java:122) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.createHFileContext(HFileReaderV3.java:107) org.apache.hadoop.hbase.io.hfile.HFileReaderV2.<init>(HFileReaderV2.java:130) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.<init>(HFileReaderV3.java:77) org.apache.hadoop.hbase.io.hfile.HFile.pickReaderVersion(HFile.java:471) org.apache.hadoop.hbase.io.hfile.HFile.createReader(HFile.java:524) org.trafodion.sql.HBaseClient.estimateRowCountBody(HBaseClient.java:1302) org.trafodion.sql.HBaseClient.estimateRowCount(HBaseClient.java:1207) Caused by java.io.FileNotFoundException: /etc/hbase/conf/hbase.jks (Permission denied) java.io.FileInputStream.open0(Native Method) java.io.FileInputStream.open(FileInputStream.java:195) java.io.FileInputStream.<init>(FileInputStream.java:138) org.apache.hadoop.hbase.io.crypto.KeyStoreKeyProvider.load(KeyStoreKeyProvider.java:124) org.apache.hadoop.hbase.io.crypto.KeyStoreKeyProvider.init(KeyStoreKeyProvider.java:147) org.apache.hadoop.hbase.io.crypto.Encryption.getKeyProvider(Encryption.java:553) org.apache.hadoop.hbase.io.crypto.Encryption.getSecretKeyForSubject(Encryption.java:427) org.apache.hadoop.hbase.io.crypto.Encryption.decryptWithSubjectKey(Encryption.java:474) org.apache.hadoop.hbase.security.EncryptionUtil.getUnwrapKey(EncryptionUtil.java:129) org.apache.hadoop.hbase.security.EncryptionUtil.unwrapKey(EncryptionUtil.java:122) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.createHFileContext(HFileReaderV3.java:107) org.apache.hadoop.hbase.io.hfile.HFileReaderV2.<init>(HFileReaderV2.java:130) org.apache.hadoop.hbase.io.hfile.HFileReaderV3.<init>(HFileReaderV3.java:77) org.apache.hadoop.hbase.io.hfile.HFile.pickReaderVersion(HFile.java:471) org.apache.hadoop.hbase.io.hfile.HFile.createReader(HFile.java:524) org.trafodion.sql.HBaseClient.estimateRowCountBody(HBaseClient.java:1302) org.trafodion.sql.HBaseClient.estimateRowCount(HBaseClient.java:1207) [Wed 17 May 2017 10:38:33 PM UTC] :| END Initialize environment elapsed time (00:00:01.169) [Wed 17 May 2017 10:38:33 PM UTC] *** ERROR[-1] in hs_update:445 -- This message was sent by Atlassian JIRA (v6.3.15#6346)