You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@phoenix.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/10/28 18:12:19 UTC
Build failed in Jenkins: Phoenix | Master #1458
See <https://builds.apache.org/job/Phoenix-master/1458/changes>
Changes:
[jamestaylor] PHOENIX-3396 Valid Multi-byte strings whose total byte size is greater
------------------------------------------
[...truncated 343 lines...]
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.031 sec - in org.apache.phoenix.mapreduce.BulkLoadToolTest
Running org.apache.phoenix.mapreduce.CsvBulkImportUtilTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.229 sec - in org.apache.phoenix.jdbc.PhoenixResultSetMetadataTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 1.448 sec - in org.apache.phoenix.jdbc.PhoenixDriverTest
Running org.apache.phoenix.util.PrefixByteEncoderDecoderTest
Running org.apache.phoenix.util.JDBCUtilTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec - in org.apache.phoenix.util.PrefixByteEncoderDecoderTest
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.019 sec - in org.apache.phoenix.util.JDBCUtilTest
Running org.apache.phoenix.util.json.JsonUpsertExecutorTest
Running org.apache.phoenix.util.csv.StringToArrayConverterTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.331 sec - in org.apache.phoenix.util.csv.StringToArrayConverterTest
Running org.apache.phoenix.util.csv.CsvUpsertExecutorTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.601 sec - in org.apache.phoenix.mapreduce.CsvBulkImportUtilTest
Running org.apache.phoenix.util.ByteUtilTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.161 sec - in org.apache.phoenix.util.ByteUtilTest
Running org.apache.phoenix.util.PhoenixRuntimeTest
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.972 sec - in org.apache.phoenix.mapreduce.util.PhoenixConfigurationUtilTest
Running org.apache.phoenix.util.ScanUtilTest
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.085 sec - in org.apache.phoenix.util.ScanUtilTest
Running org.apache.phoenix.util.StringUtilTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 sec - in org.apache.phoenix.util.StringUtilTest
Running org.apache.phoenix.util.SequenceUtilTest
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec - in org.apache.phoenix.util.SequenceUtilTest
Running org.apache.phoenix.util.Base62EncoderTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.025 sec - in org.apache.phoenix.util.Base62EncoderTest
Running org.apache.phoenix.util.TenantIdByteConversionTest
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.059 sec - in org.apache.phoenix.util.TenantIdByteConversionTest
Running org.apache.phoenix.util.DateUtilTest
Tests run: 22, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.117 sec - in org.apache.phoenix.util.DateUtilTest
Running org.apache.phoenix.util.PropertiesUtilTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 sec - in org.apache.phoenix.util.PropertiesUtilTest
Running org.apache.phoenix.util.ColumnInfoTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.114 sec - in org.apache.phoenix.util.json.JsonUpsertExecutorTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 sec - in org.apache.phoenix.util.ColumnInfoTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.777 sec - in org.apache.phoenix.util.csv.CsvUpsertExecutorTest
Running org.apache.phoenix.util.PhoenixEncodeDecodeTest
Running org.apache.phoenix.util.LogUtilTest
Running org.apache.phoenix.util.MetaDataUtilTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.068 sec - in org.apache.phoenix.util.MetaDataUtilTest
Running org.apache.phoenix.util.QueryUtilTest
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.008 sec - in org.apache.phoenix.util.QueryUtilTest
Running org.apache.phoenix.util.IndexUtilTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.phoenix.util.IndexUtilTest
Running org.apache.phoenix.util.LikeExpressionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.phoenix.util.LikeExpressionTest
Running org.apache.phoenix.util.PhoenixContextExecutorTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.phoenix.util.PhoenixContextExecutorTest
Running org.apache.phoenix.index.IndexMaintainerTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.36 sec - in org.apache.phoenix.util.PhoenixEncodeDecodeTest
Running org.apache.phoenix.index.automated.MRJobSubmitterTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.531 sec - in org.apache.phoenix.util.LogUtilTest
Running org.apache.phoenix.trace.TraceMetricsSourceTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.248 sec - in org.apache.phoenix.index.automated.MRJobSubmitterTest
Running org.apache.phoenix.memory.MemoryManagerTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 0.099 sec - in org.apache.phoenix.memory.MemoryManagerTest
Running org.apache.phoenix.cache.TenantCacheTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.011 sec - in org.apache.phoenix.cache.TenantCacheTest
Running org.apache.phoenix.cache.JodaTimezoneCacheTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.036 sec - in org.apache.phoenix.cache.JodaTimezoneCacheTest
Running org.apache.phoenix.compile.WhereOptimizerTest
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.532 sec - in org.apache.phoenix.util.PhoenixRuntimeTest
Running org.apache.phoenix.compile.SelectStatementRewriterTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.548 sec - in org.apache.phoenix.query.ScannerLeaseRenewalTest
Running org.apache.phoenix.compile.ViewCompilerTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.53 sec - in org.apache.phoenix.trace.TraceMetricsSourceTest
Running org.apache.phoenix.compile.LimitCompilerTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.598 sec - in org.apache.phoenix.compile.SelectStatementRewriterTest
Running org.apache.phoenix.compile.QueryOptimizerTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.874 sec - in org.apache.phoenix.compile.LimitCompilerTest
Running org.apache.phoenix.compile.QueryMetaDataTest
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.895 sec - in org.apache.phoenix.index.IndexMaintainerTest
Running org.apache.phoenix.compile.ScanRangesTest
Tests run: 34, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.099 sec - in org.apache.phoenix.compile.ScanRangesTest
Running org.apache.phoenix.compile.JoinQueryCompilerTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.431 sec - in org.apache.phoenix.compile.JoinQueryCompilerTest
Running org.apache.phoenix.compile.HavingCompilerTest
Tests run: 33, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.734 sec - in org.apache.phoenix.compile.QueryMetaDataTest
Running org.apache.phoenix.compile.TenantSpecificViewIndexCompileTest
Tests run: 109, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.408 sec - in org.apache.phoenix.compile.WhereOptimizerTest
Running org.apache.phoenix.compile.StatementHintsCompilationTest
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.612 sec - in org.apache.phoenix.compile.HavingCompilerTest
Running org.apache.phoenix.compile.WhereCompilerTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.281 sec - in org.apache.phoenix.compile.ViewCompilerTest
Running org.apache.phoenix.compile.SaltedScanRangesTest
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.054 sec - in org.apache.phoenix.compile.SaltedScanRangesTest
Running org.apache.phoenix.compile.QueryCompilerTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.135 sec - in org.apache.phoenix.compile.StatementHintsCompilationTest
Running org.apache.phoenix.compile.CreateTableCompilerTest
Tests run: 45, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 1.175 sec - in org.apache.phoenix.compile.WhereCompilerTest
Running org.apache.phoenix.compile.ScanRangesIntersectTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 sec - in org.apache.phoenix.compile.ScanRangesIntersectTest
Running org.apache.phoenix.parse.BuiltInFunctionInfoTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.parse.BuiltInFunctionInfoTest
Running org.apache.phoenix.parse.CastParseNodeTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.parse.CastParseNodeTest
Running org.apache.phoenix.parse.QueryParserTest
Tests run: 60, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.337 sec - in org.apache.phoenix.parse.QueryParserTest
Running org.apache.phoenix.hbase.index.write.TestIndexWriter
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.573 sec - in org.apache.phoenix.compile.CreateTableCompilerTest
Running org.apache.phoenix.hbase.index.write.TestParalleWriterIndexCommitter
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.371 sec - in org.apache.phoenix.compile.TenantSpecificViewIndexCompileTest
Running org.apache.phoenix.hbase.index.write.TestCachingHTableFactory
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.27 sec - in org.apache.phoenix.hbase.index.write.TestCachingHTableFactory
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.364 sec - in org.apache.phoenix.hbase.index.write.TestParalleWriterIndexCommitter
Running org.apache.phoenix.hbase.index.write.recovery.TestPerRegionIndexWriteCache
Running org.apache.phoenix.hbase.index.write.TestParalleIndexWriter
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.662 sec - in org.apache.phoenix.hbase.index.write.TestIndexWriter
Running org.apache.phoenix.hbase.index.write.TestWALRecoveryCaching
Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.016 sec - in org.apache.phoenix.hbase.index.write.TestWALRecoveryCaching
Running org.apache.phoenix.hbase.index.parallel.TestThreadPoolManager
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.phoenix.hbase.index.parallel.TestThreadPoolManager
Running org.apache.phoenix.hbase.index.parallel.TestThreadPoolBuilder
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.011 sec - in org.apache.phoenix.hbase.index.parallel.TestThreadPoolBuilder
Running org.apache.phoenix.hbase.index.covered.filter.TestNewerTimestampFilter
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.phoenix.hbase.index.covered.filter.TestNewerTimestampFilter
Running org.apache.phoenix.hbase.index.covered.filter.TestApplyAndFilterDeletesFilter
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.hbase.index.covered.filter.TestApplyAndFilterDeletesFilter
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.238 sec - in org.apache.phoenix.hbase.index.write.TestParalleIndexWriter
Running org.apache.phoenix.hbase.index.covered.example.TestColumnTracker
Running org.apache.phoenix.hbase.index.covered.example.TestCoveredColumnIndexCodec
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.hbase.index.covered.example.TestColumnTracker
Running org.apache.phoenix.hbase.index.covered.example.TestCoveredIndexSpecifierBuilder
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 sec - in org.apache.phoenix.hbase.index.covered.example.TestCoveredIndexSpecifierBuilder
Running org.apache.phoenix.hbase.index.covered.update.TestIndexUpdateManager
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 sec - in org.apache.phoenix.hbase.index.covered.update.TestIndexUpdateManager
Running org.apache.phoenix.hbase.index.covered.TestLocalTableState
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.085 sec - in org.apache.phoenix.hbase.index.covered.example.TestCoveredColumnIndexCodec
Running org.apache.phoenix.hbase.index.covered.data.TestIndexMemStore
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.hbase.index.covered.data.TestIndexMemStore
Running org.apache.phoenix.hbase.index.covered.TestCoveredColumns
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.phoenix.hbase.index.covered.TestCoveredColumns
Running org.apache.phoenix.hbase.index.util.TestIndexManagementUtil
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.038 sec - in org.apache.phoenix.hbase.index.util.TestIndexManagementUtil
Running org.apache.phoenix.schema.SchemaUtilTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.011 sec - in org.apache.phoenix.schema.SchemaUtilTest
Running org.apache.phoenix.schema.PCharPadTest
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.schema.PCharPadTest
Running org.apache.phoenix.schema.SaltingUtilTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.schema.SaltingUtilTest
Running org.apache.phoenix.schema.RowKeyValueAccessorTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.192 sec - in org.apache.phoenix.hbase.index.covered.TestLocalTableState
Running org.apache.phoenix.schema.ValueBitSetTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 sec - in org.apache.phoenix.schema.ValueBitSetTest
Running org.apache.phoenix.schema.RowKeySchemaTest
Tests run: 42, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.352 sec - in org.apache.phoenix.compile.QueryOptimizerTest
Running org.apache.phoenix.schema.types.PVarcharArrayToStringTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.phoenix.schema.types.PVarcharArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveIntPhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.schema.types.PrimitiveIntPhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveShortPhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.phoenix.schema.types.PrimitiveShortPhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveBooleanPhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.schema.types.PrimitiveBooleanPhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveFloatPhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.012 sec - in org.apache.phoenix.schema.types.PrimitiveFloatPhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PDateArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 sec - in org.apache.phoenix.schema.types.PDateArrayToStringTest
Running org.apache.phoenix.schema.types.PDataTypeForArraysTest
Tests run: 68, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.129 sec - in org.apache.phoenix.schema.types.PDataTypeForArraysTest
Running org.apache.phoenix.schema.types.PDataTypeTest
Tests run: 35, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.137 sec - in org.apache.phoenix.schema.types.PDataTypeTest
Running org.apache.phoenix.schema.types.PrimitiveBytePhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.schema.types.PrimitiveBytePhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveLongPhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.schema.types.PrimitiveLongPhoenixArrayToStringTest
Running org.apache.phoenix.schema.types.PrimitiveDoublePhoenixArrayToStringTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.schema.types.PrimitiveDoublePhoenixArrayToStringTest
Running org.apache.phoenix.schema.SequenceAllocationTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.schema.SequenceAllocationTest
Running org.apache.phoenix.schema.SortOrderTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 sec - in org.apache.phoenix.schema.SortOrderTest
Running org.apache.phoenix.schema.MutationTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.62 sec - in org.apache.phoenix.schema.RowKeyValueAccessorTest
Running org.apache.phoenix.schema.PMetaDataImplTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.024 sec - in org.apache.phoenix.schema.PMetaDataImplTest
Running org.apache.phoenix.schema.stats.StatisticsScannerTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.727 sec - in org.apache.phoenix.schema.RowKeySchemaTest
Running org.apache.phoenix.execute.CorrelatePlanTest
Tests run: 2, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.258 sec <<< FAILURE! - in org.apache.phoenix.schema.MutationTest
testSizeConstraint(org.apache.phoenix.schema.MutationTest) Time elapsed: 0.017 sec <<< FAILURE!
java.lang.AssertionError
at org.apache.phoenix.schema.MutationTest.testSizeConstraint(MutationTest.java:87)
Running org.apache.phoenix.execute.DescVarLengthFastByteComparisonsTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.execute.DescVarLengthFastByteComparisonsTest
Running org.apache.phoenix.execute.LiteralResultIteratorPlanTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 sec - in org.apache.phoenix.execute.LiteralResultIteratorPlanTest
Running org.apache.phoenix.execute.MutationStateTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.phoenix.execute.MutationStateTest
Running org.apache.phoenix.execute.UnnestArrayPlanTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.019 sec - in org.apache.phoenix.execute.UnnestArrayPlanTest
Running org.apache.hadoop.hbase.ipc.PhoenixIndexRpcSchedulerTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.594 sec - in org.apache.phoenix.schema.stats.StatisticsScannerTest
Running org.apache.hadoop.hbase.regionserver.PhoenixRpcSchedulerFactoryTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.055 sec - in org.apache.hadoop.hbase.regionserver.PhoenixRpcSchedulerFactoryTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.571 sec - in org.apache.phoenix.execute.CorrelatePlanTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.959 sec - in org.apache.phoenix.hbase.index.write.recovery.TestPerRegionIndexWriteCache
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.891 sec - in org.apache.hadoop.hbase.ipc.PhoenixIndexRpcSchedulerTest
Tests run: 144, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.518 sec - in org.apache.phoenix.compile.QueryCompilerTest
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 39.779 sec - in org.apache.phoenix.jdbc.SecureUserConnectionsTest
Results :
Failed tests:
MutationTest.testSizeConstraint:87
Tests run: 1430, Failures: 1, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Apache Phoenix ..................................... SUCCESS [ 5.074 s]
[INFO] Phoenix Core ....................................... FAILURE [02:17 min]
[INFO] Phoenix - Flume .................................... SKIPPED
[INFO] Phoenix - Pig ...................................... SKIPPED
[INFO] Phoenix Query Server Client ........................ SKIPPED
[INFO] Phoenix Query Server ............................... SKIPPED
[INFO] Phoenix - Pherf .................................... SKIPPED
[INFO] Phoenix - Spark .................................... SKIPPED
[INFO] Phoenix - Hive ..................................... SKIPPED
[INFO] Phoenix Client ..................................... SKIPPED
[INFO] Phoenix Server ..................................... SKIPPED
[INFO] Phoenix Assembly ................................... SKIPPED
[INFO] Phoenix - Tracing Web Application .................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:26 min
[INFO] Finished at: 2016-10-28T17:52:36+00:00
[INFO] Final Memory: 84M/1358M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19.1:test (default-test) on project phoenix-core: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/surefire-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :phoenix-core
Build step 'Invoke top-level Maven targets' marked build as failure
Archiving artifacts
Compressed 658.10 MB of artifacts by 58.6% relative to #1455
Updating PHOENIX-3396
Recording test results
Jenkins build is back to normal : Phoenix | Master #1463
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Phoenix-master/1463/>
Build failed in Jenkins: Phoenix | Master #1462
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Phoenix-master/1462/changes>
Changes:
[jamestaylor] PHOENIX-3424 Backward compatibility failure: 4.8 -> 4.9 upgrade
------------------------------------------
[...truncated 776279 lines...]
2016-10-30 15:56:28,696 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x438f7b86 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:28,725 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x438f7b860x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:28,729 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x438f7b86-0x158164c460900ab connected
2016-10-30 15:56:28,735 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@7851b066, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:28,747 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900ab
2016-10-30 15:56:28,767 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:28,768 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t5] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x19"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x18"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x17"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:28,777 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0xf1f9b37 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:28,817 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0xf1f9b370x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:28,818 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@1c3fb044, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:28,819 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0xf1f9b37-0x158164c460900ac connected
2016-10-30 15:56:28,832 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900ac
2016-10-30 15:56:28,858 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:28,860 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t6] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1A"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1C"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1B"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:28,875 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x6f691fa2 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:28,914 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x6f691fa20x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:28,915 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x6f691fa2-0x158164c460900ad connected
2016-10-30 15:56:28,915 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@57b60f76, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:28,920 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900ad
2016-10-30 15:56:28,939 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:28,940 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t7] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1E"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1D"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x1F"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:28,970 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x7b7699e7 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,006 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x7b7699e70x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,006 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@1a6ef09b, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,010 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x7b7699e7-0x158164c460900ae connected
2016-10-30 15:56:29,012 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900ae
2016-10-30 15:56:29,030 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,031 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t8] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842988548000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00 "}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,041 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: a4a5bb9a-4993-b831-76a8-1ab06a76274b, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":3}
2016-10-30 15:56:29,048 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x43077b35 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,081 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x43077b350x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,082 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@596b599a, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,082 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x43077b35-0x158164c460900af connected
2016-10-30 15:56:29,086 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900af
2016-10-30 15:56:29,106 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,107 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t9] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00!"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\""}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00#"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,118 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x66294606 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,164 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x662946060x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,165 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@11c040b2, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,165 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x66294606-0x158164c460900b0 connected
2016-10-30 15:56:29,168 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b0
2016-10-30 15:56:29,189 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,190 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t10] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00$"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00%"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00&"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,199 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x1973bbe6 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,217 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x1973bbe60x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,217 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@57e15201, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,218 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x1973bbe6-0x158164c460900b1 connected
2016-10-30 15:56:29,238 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b1
2016-10-30 15:56:29,250 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,253 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t1] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00'"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00("}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00)"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,266 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x12ff28c4 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,289 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x12ff28c40x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,290 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@68372e1a, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,291 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x12ff28c4-0x158164c460900b2 connected
2016-10-30 15:56:29,295 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b2
2016-10-30 15:56:29,315 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,315 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t3] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00*"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00+"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00,"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,322 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x158af624 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,340 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x158af6240x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,342 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x158af624-0x158164c460900b3 connected
2016-10-30 15:56:29,346 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@715d528f, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,366 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b3
2016-10-30 15:56:29,390 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,393 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t4] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00-"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00."}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00/"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,405 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x1a0fb78e connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,456 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x1a0fb78e0x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,456 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@364e2ef3, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,457 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x1a0fb78e-0x158164c460900b4 connected
2016-10-30 15:56:29,461 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b4
2016-10-30 15:56:29,489 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,489 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t2] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x000"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x002"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x001"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,496 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x600a49d7 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,523 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x600a49d70x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,525 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x600a49d7-0x158164c460900b5 connected
2016-10-30 15:56:29,534 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@18922ce0, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,545 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b5
2016-10-30 15:56:29,581 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,581 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t5] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x004"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x003"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x005"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,589 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0xb1e8696 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,618 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0xb1e86960x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,619 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0xb1e8696-0x158164c460900b6 connected
2016-10-30 15:56:29,620 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@2fa265bd, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,625 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b6
2016-10-30 15:56:29,666 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,667 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t6] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x008"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x007"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x006"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,681 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x24aba11c connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,695 DEBUG [org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@7e752f44] org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(1500): BLOCK* neededReplications = 0 pendingReplications = 0
2016-10-30 15:56:29,707 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@125a7256, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,708 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x24aba11c0x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,710 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x24aba11c-0x158164c460900b7 connected
2016-10-30 15:56:29,711 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b7
2016-10-30 15:56:29,742 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,742 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t7] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00:"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x009"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00;"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,753 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x7a6ffae4 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,774 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x7a6ffae40x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,775 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@2bf66009, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,776 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x7a6ffae4-0x158164c460900b8 connected
2016-10-30 15:56:29,780 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b8
2016-10-30 15:56:29,782 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.jdbc.PhoenixEmbeddedDriver$ConnectionInfo(376): Principal and keytab not provided, not attempting Kerberos login
2016-10-30 15:56:29,797 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.phoenix.coprocessor.MetaDataEndpointImpl(549): Caching table \x00TBL_T001055\x00IDX_T001056 at seqNum 0 with newer timestamp 1477842982818 versus -1
2016-10-30 15:56:29,798 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.phoenix.coprocessor.MetaDataEndpointImpl(549): Caching table \x00TBL_T001055\x00TBL_T0010551 at seqNum 0 with newer timestamp 1477842982818 versus -1
2016-10-30 15:56:29,802 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,802 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t8] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00<"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00>"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00="}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,817 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 441f796f-4d89-6ef1-42b2-ea83da591a69, Time: 0ms, Scan: {"timeRange":[0,1477842989806],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":2,"cacheBlocks":true,"families":{"0":["FK1B","_0"]},"maxResultSize":-1,"maxVersions":1,"caching":2147483647}
2016-10-30 15:56:29,819 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver(380): Starting ungrouped coprocessor scan {"timeRange":[0,1477842989808000001],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":true,"totalColumns":3,"cacheBlocks":true,"families":{"0":["","FK1B","_0"]},"maxResultSize":2097152,"maxVersions":2147483647,"filter":"CellSkipFilter","caching":2147483647} {ENCODED => 54e182838458f72caf4245ceec299d52, NAME => 'TBL_T001055.TBL_T0010551,,1477842976117.54e182838458f72caf4245ceec299d52.', STARTKEY => '', ENDKEY => ''}
2016-10-30 15:56:29,819 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.phoenix.coprocessor.UngroupedAggregateRegionObserver(639): Finished scanning 0 rows for ungrouped coprocessor scan {"timeRange":[0,1477842989808000001],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":true,"totalColumns":3,"cacheBlocks":true,"families":{"0":["","FK1B","_0"]},"maxResultSize":2097152,"maxVersions":2147483647,"filter":"CellSkipFilter","caching":2147483647}
2016-10-30 15:56:29,823 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x56d0820 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,826 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.phoenix.coprocessor.MetaDataEndpointImpl(549): Caching table \x00TBL_T001055\x00IDX_T001056 at seqNum 0 with newer timestamp 1477842989821 versus -1
2016-10-30 15:56:29,830 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.phoenix.coprocessor.MetaDataEndpointImpl(549): Caching table \x00TBL_T001055\x00TBL_T0010551 at seqNum 0 with newer timestamp 1477842989821 versus -1
2016-10-30 15:56:29,831 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.phoenix.coprocessor.MetaDataEndpointImpl(549): Caching table \x00TBL_T001055\x00TBL_T0010552 at seqNum 0 with newer timestamp 1477842980424 versus -1
2016-10-30 15:56:29,831 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x56d08200x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,832 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@6d54dd17, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,834 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x56d0820-0x158164c460900b9 connected
2016-10-30 15:56:29,834 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x2f886548 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,837 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900b9
2016-10-30 15:56:29,848 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x2f8865480x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,849 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x2f886548-0x158164c460900ba connected
2016-10-30 15:56:29,865 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,865 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@795aaa7, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,865 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t9] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00?"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989036000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00@"}] to table: TBL_T001048.TBL_T001048
2016-10-30 15:56:29,869 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900ba
2016-10-30 15:56:29,881 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,882 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t10] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989828000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x03"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989828000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x03\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02"}, {"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989828000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x04\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:29,882 INFO [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t10] org.apache.hadoop.hbase.regionserver.HRegion(8030): writing data to region TBL_T001055.TBL_T0010551,,1477842976117.54e182838458f72caf4245ceec299d52. with WAL disabled. Data may be lost in the event of a crash.
2016-10-30 15:56:29,900 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: fe5b74be-2775-d57f-6dec-b8f56e97db9b, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FilterList AND (2/2): [FirstKeyOnlyFilter, (\"ID1\" = TO_INTEGER(\"FK1B\") AND TO_INTEGER(\"FK1B\") = \"ID1\")]","caching":2147483647}
2016-10-30 15:56:29,900 DEBUG [phoenix-7-thread-0] org.apache.phoenix.util.ReadOnlyProps(317): Creating new ReadOnlyProps due to phoenix.table.istransactional.default with null!=true
2016-10-30 15:56:29,908 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x7bf2dda4 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:29,924 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x7bf2dda40x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:29,925 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x7bf2dda4-0x158164c460900bb connected
2016-10-30 15:56:29,934 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@561fc7ab, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:29,945 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900bb
2016-10-30 15:56:29,956 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:29,957 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t1] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989891000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x03\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:29,963 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: e2840cec-83a2-d90f-6924-bb2ebd1053a1, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["ALL"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:29,971 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 7ba89eba-47d6-2485-8c40-e4b0f3be06b9, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,009 DEBUG [phoenix-7-thread-0] org.apache.phoenix.compile.FromCompiler$BaseColumnResolver(572): Re-resolved stale table TBL_T001055.TBL_T0010551 with seqNum 0 at timestamp 1477842989821 with 3 columns: [ID1, 0.FK1A, 0.FK1B]
2016-10-30 15:56:30,010 DEBUG [phoenix-7-thread-0] org.apache.phoenix.execute.BaseQueryPlan(338): Scan ready for iteration: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["FK1A"]},"maxResultSize":-1,"maxVersions":1,"caching":2147483647}
2016-10-30 15:56:30,010 DEBUG [phoenix-7-thread-0] org.apache.phoenix.execute.BaseQueryPlan(355): Iterator ready: org.apache.phoenix.execute.BaseQueryPlan$1@67704fcc
2016-10-30 15:56:30,010 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.BaseResultIterators(680): Getting iterators for ResultIterators [name=PARALLEL,id=63f115db-b9b3-54f8-3145-0f6288c25d2e,scans=[[{"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02","loadColumnFamiliesOnDemand":null,"totalColumns":2,"cacheBlocks":true,"families":{"0":["FK1A","_0"]},"maxResultSize":-1,"maxVersions":1,"caching":2147483647}]]]
2016-10-30 15:56:30,018 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver(371): Grouped aggregation over unordered rows with scan {"timeRange":[0,1477842989993000001],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02","loadColumnFamiliesOnDemand":true,"totalColumns":3,"cacheBlocks":true,"families":{"0":["","FK1A","_0"]},"maxResultSize":2097152,"maxVersions":2147483647,"filter":"CellSkipFilter","caching":2147483647}, group by [TBL_T001055.TBL_T0010551.FK1A], aggregators org.apache.phoenix.expression.aggregator.ServerAggregators [0]:
2016-10-30 15:56:30,018 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache(157): Instantiating LRU groupby cache of element size: 1000
2016-10-30 15:56:30,018 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver(397): Spillable groupby enabled: true
2016-10-30 15:56:30,018 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache(244): Adding new aggregate bucket for row key \x80\x00\x00\x03
2016-10-30 15:56:30,019 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache$2(363): Adding new distinct group: \x80\x00\x00\x03 with aggregators [Lorg.apache.phoenix.expression.aggregator.Aggregator;@3df3516a value =
2016-10-30 15:56:30,021 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(318): Removing Cache [B@4012629 from servers.
2016-10-30 15:56:30,022 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(179): Adding cache entry to be sent for region=TBL_T001055.TBL_T0010551,,1477842976117.54e182838458f72caf4245ceec299d52., hostname=priapus.apache.org,36659,1477842847800, seqNum=5
2016-10-30 15:56:30,024 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(291): Cache [B@430d710a successfully added to servers.
2016-10-30 15:56:30,031 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: cbf26e7b-1dca-c81b-f1eb-18e9be7048c1, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,031 DEBUG [phoenix-7-thread-0] org.apache.phoenix.util.ReadOnlyProps(317): Creating new ReadOnlyProps due to phoenix.table.istransactional.default with null!=true
2016-10-30 15:56:30,063 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0xc1acae3 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,074 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@75f4c60e, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,078 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900bc
2016-10-30 15:56:30,081 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0xc1acae30x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,115 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,115 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0xc1acae3-0x158164c460900bc connected
2016-10-30 15:56:30,115 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t3] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989993000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x03"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,119 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: af1fa162-a030-734e-f33e-101fff3c8a55, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["ALL"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,125 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 7ade0704-700f-da19-da61-2693041fe31c, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,152 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 544a7714-72f3-b965-e6ef-79e1db7e6d5f, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,152 DEBUG [phoenix-7-thread-0] org.apache.phoenix.util.ReadOnlyProps(317): Creating new ReadOnlyProps due to phoenix.table.istransactional.default with null!=true
2016-10-30 15:56:30,196 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x30b47922 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,214 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x30b479220x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,215 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@69d4bbe2, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,216 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x30b47922-0x158164c460900bd connected
2016-10-30 15:56:30,219 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900bd
2016-10-30 15:56:30,247 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,247 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t4] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"totalColumns":1,"families":{"L#0":[{"timestamp":1477842990149000000,"tag":[],"qualifier":"_0","vlen":2}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,262 DEBUG [phoenix-7-thread-0] org.apache.phoenix.compile.FromCompiler$BaseColumnResolver(572): Re-resolved stale table TBL_T001055.TBL_T0010551 with seqNum 0 at timestamp 1477842989821 with 3 columns: [ID1, 0.FK1A, 0.FK1B]
2016-10-30 15:56:30,263 DEBUG [phoenix-7-thread-0] org.apache.phoenix.execute.BaseQueryPlan(338): Scan ready for iteration: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04\\x00","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["FK1A"]},"maxResultSize":-1,"maxVersions":1,"filter":"SkipScanFilter [[\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01, \\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04]]","caching":2147483647}
2016-10-30 15:56:30,263 DEBUG [phoenix-7-thread-0] org.apache.phoenix.execute.BaseQueryPlan(355): Iterator ready: org.apache.phoenix.execute.BaseQueryPlan$1@5556163b
2016-10-30 15:56:30,264 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.BaseResultIterators(680): Getting iterators for ResultIterators [name=PARALLEL,id=d1410137-650f-f9fa-13b4-5238aa667e35,scans=[[{"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04\\x00","loadColumnFamiliesOnDemand":null,"totalColumns":2,"cacheBlocks":true,"families":{"0":["FK1A","_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"SkipScanFilter [[\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01, \\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04]]","caching":2147483647}]]]
2016-10-30 15:56:30,267 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver(371): Grouped aggregation over unordered rows with scan {"timeRange":[0,1477842990255000001],"batch":-1,"startRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01","stopRow":"\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04\\x00","loadColumnFamiliesOnDemand":true,"totalColumns":3,"cacheBlocks":true,"families":{"0":["","FK1A","_0"]},"maxResultSize":2097152,"maxVersions":2147483647,"filter":"CellSkipFilter","caching":2147483647}, group by [TBL_T001055.TBL_T0010551.FK1A], aggregators org.apache.phoenix.expression.aggregator.ServerAggregators [0]:
2016-10-30 15:56:30,267 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache(157): Instantiating LRU groupby cache of element size: 1000
2016-10-30 15:56:30,267 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.coprocessor.GroupedAggregateRegionObserver(397): Spillable groupby enabled: true
2016-10-30 15:56:30,268 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache(244): Adding new aggregate bucket for row key \x80\x00\x00\x03
2016-10-30 15:56:30,268 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache(244): Adding new aggregate bucket for row key \x80\x00\x00\x01
2016-10-30 15:56:30,268 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache$2(363): Adding new distinct group: \x80\x00\x00\x03 with aggregators [Lorg.apache.phoenix.expression.aggregator.Aggregator;@49959e51 value =
2016-10-30 15:56:30,268 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.phoenix.cache.aggcache.SpillableGroupByCache$2(363): Adding new distinct group: \x80\x00\x00\x01 with aggregators [Lorg.apache.phoenix.expression.aggregator.Aggregator;@7dccfd94 value =
2016-10-30 15:56:30,270 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(318): Removing Cache [B@3e49a68f from servers.
2016-10-30 15:56:30,271 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(179): Adding cache entry to be sent for region=TBL_T001055.TBL_T0010551,,1477842976117.54e182838458f72caf4245ceec299d52., hostname=priapus.apache.org,36659,1477842847800, seqNum=5
2016-10-30 15:56:30,273 DEBUG [phoenix-7-thread-0] org.apache.phoenix.cache.ServerCacheClient(291): Cache [B@7b754b3 successfully added to servers.
2016-10-30 15:56:30,275 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 5ae1c772-dc39-2ebf-65e8-f8abbd0446db, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,275 DEBUG [phoenix-7-thread-0] org.apache.phoenix.util.ReadOnlyProps(317): Creating new ReadOnlyProps due to phoenix.table.istransactional.default with null!=true
2016-10-30 15:56:30,283 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x2d16319c connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,315 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x2d16319c0x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,315 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@7d9f31f0, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,316 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x2d16319c-0x158164c460900be connected
2016-10-30 15:56:30,319 INFO [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900be
2016-10-30 15:56:30,353 DEBUG [B.defaultRpcServer.handler=0,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,355 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t2] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842990255000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x04\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,470 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 127c9e6c-5ae1-574b-a9b2-487a30554ad7, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["ALL"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,502 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 9fbfa539-ac69-69f8-f792-a5db1c35d977, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,524 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x4925522f connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,542 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x4925522f0x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,545 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x4925522f-0x158164c460900bf connected
2016-10-30 15:56:30,546 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@7d282e60, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,551 INFO [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900bf
2016-10-30 15:56:30,556 DEBUG [B.defaultRpcServer.handler=1,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,557 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t5] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989891000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x03\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x02"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,560 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x6c4b5df7 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,565 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@22ff52d8, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,566 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x6c4b5df70x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,567 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x6c4b5df7-0x158164c460900c0 connected
2016-10-30 15:56:30,569 INFO [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900c0
2016-10-30 15:56:30,572 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,577 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t6] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842989993000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x03"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,581 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x4f26dd57 connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,590 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x4f26dd570x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,590 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@6328647d, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,591 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x4f26dd57-0x158164c460900c1 connected
2016-10-30 15:56:30,620 INFO [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900c1
2016-10-30 15:56:30,632 DEBUG [B.defaultRpcServer.handler=3,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,632 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t7] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842990149000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x02\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x04"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,652 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(120): Process identifier=hconnection-0x778e89ad connecting to ZooKeeper ensemble=localhost:61610
2016-10-30 15:56:30,673 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x778e89ad0x0, quorum=localhost:61610, baseZNode=/hbase Received ZooKeeper Event, type=None, state=SyncConnected, path=null
2016-10-30 15:56:30,673 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.AbstractRpcClient(115): Codec=org.apache.hadoop.hbase.codec.KeyValueCodec@3d99d3ad, compressor=null, tcpKeepAlive=true, tcpNoDelay=true, connectTO=10000, readTO=20000, writeTO=60000, minIdleTimeBeforeClose=120000, maxRetries=0, fallbackAllowed=false, bind address=null
2016-10-30 15:56:30,674 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(686): hconnection-0x778e89ad-0x158164c460900c2 connected
2016-10-30 15:56:30,677 INFO [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158164c460900c2
2016-10-30 15:56:30,681 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=36659] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 15:56:30,681 DEBUG [priapus.apache.org,36659,1477842847800-tx-index-writer--pool2229-t8] org.apache.phoenix.hbase.index.write.ParallelWriterIndexCommitter$1(147): Writing index update:[{"ts":9223372036854775807,"totalColumns":1,"families":{"L#0":[{"timestamp":1477842990255000000,"tag":[],"qualifier":"","vlen":0}]},"row":"\\x00\\x00\\xC1\\x04\\x00\\x80\\x00\\x00\\x00\\x00\\x00\\x00\\x01"}] to table: TBL_T001055.TBL_T0010551
2016-10-30 15:56:30,687 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 78fddece-3bcd-c0f4-456d-22d038a84052, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"0":["ALL"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
2016-10-30 15:56:30,694 DEBUG [phoenix-7-thread-0] org.apache.phoenix.iterate.ParallelIterators$1(112): Id: 292326cf-5d2f-8662-c664-e76a521f9843, Time: 0ms, Scan: {"timeRange":[0,9223372036854775807],"batch":-1,"startRow":"","stopRow":"","loadColumnFamiliesOnDemand":null,"totalColumns":1,"cacheBlocks":true,"families":{"L#0":["_0"]},"maxResultSize":-1,"maxVersions":1,"filter":"FirstKeyOnlyFilter","caching":2147483647}
Running [TxCheckpointIT_localIndex=true,mutable=true]
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 59.723 sec - in [TxCheckpointIT_localIndex=true,mutable=true]
2016-10-30 15:56:30,777 INFO [Thread-13015] org.apache.phoenix.query.BaseTest$1(488): SHUTDOWN: halting JVM now
2016-10-30 15:56:30,778 INFO [Thread-162] org.apache.phoenix.query.BaseTest$1(488): SHUTDOWN: halting JVM now
2016-10-30 15:56:30,779 INFO [Thread-8195] org.apache.phoenix.query.BaseTest$1(488): SHUTDOWN: halting JVM now
Build timed out (after 200 minutes). Marking the build as failed.
Build was aborted
Archiving artifacts
Results :
Tests run: 1640, Failures: 0, Errors: 0, Skipped: 1
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (ClientManagedTimeTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Results :
Tests run: 0, Failures: 0, Errors: 0, Skipped: 0
Compressed 700.08 MB of artifacts by 55.2% relative to #1455
Updating PHOENIX-3424
Recording test results
Build failed in Jenkins: Phoenix | Master #1461
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Phoenix-master/1461/changes>
Changes:
[jamestaylor] PHOENIX-3424 Backward compatibility failure: 4.8 -> 4.9 upgrade
------------------------------------------
[...truncated 775960 lines...]
2016-10-30 00:51:07,583 INFO [main-EventThread] org.apache.hadoop.hbase.zookeeper.RegionServerTracker(118): RegionServer ephemeral node deleted, processing expiration [pietas.apache.org,54158,1477788349446]
2016-10-30 00:51:07,584 INFO [main-EventThread] org.apache.hadoop.hbase.master.ServerManager(612): Cluster shutdown set; pietas.apache.org,54158,1477788349446 expired; onlineServers=0
2016-10-30 00:51:07,584 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): regionserver:54158-0x158130cb1e00001, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=NodeDeleted, state=SyncConnected, path=/hbase/rs/pietas.apache.org,54158,1477788349446
2016-10-30 00:51:07,584 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): regionserver:54158-0x158130cb1e00001, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=NodeChildrenChanged, state=SyncConnected, path=/hbase/rs
2016-10-30 00:51:07,584 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): master:42311-0x158130cb1e00000, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=NodeChildrenChanged, state=SyncConnected, path=/hbase/rs
2016-10-30 00:51:07,584 DEBUG [M:0;pietas:42311] org.apache.hadoop.hbase.master.HMaster(1126): Stopping service threads
2016-10-30 00:51:07,585 INFO [RS:0;pietas:54158] org.apache.hadoop.hbase.regionserver.HRegionServer(1104): stopping server pietas.apache.org,54158,1477788349446; zookeeper connection closed.
2016-10-30 00:51:07,585 INFO [RS:0;pietas:54158] org.apache.hadoop.hbase.regionserver.HRegionServer(1107): RS:0;pietas:54158 exiting
2016-10-30 00:51:07,585 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x158130cb1e00004
2016-10-30 00:51:07,586 DEBUG [M:0;pietas:42311] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-30 00:51:07,586 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): master:42311-0x158130cb1e00000, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=NodeDeleted, state=SyncConnected, path=/hbase/master
2016-10-30 00:51:07,587 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZKUtil(370): master:42311-0x158130cb1e00000, quorum=localhost:52122, baseZNode=/hbase Set watcher on znode that does not yet exist, /hbase/master
2016-10-30 00:51:07,587 INFO [Shutdown of org.apache.hadoop.hbase.fs.HFileSystem@1f7a5e46] org.apache.hadoop.hbase.MiniHBaseCluster$SingleFileSystemShutdownThread(191): Hook closing fs=org.apache.hadoop.hbase.fs.HFileSystem@1f7a5e46
2016-10-30 00:51:07,588 INFO [main] org.apache.hadoop.hbase.util.JVMClusterUtil(317): Shutdown of 1 master(s) and 1 regionserver(s) complete
2016-10-30 00:51:07,597 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.ChoreService(323): Chore service for: pietas.apache.org,42311,1477788349359_splitLogManager_ had [] on shutdown
2016-10-30 00:51:07,598 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.procedure.flush.MasterFlushTableProcedureManager(79): stop: server shutting down.
2016-10-30 00:51:07,598 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.ipc.RpcServer(2277): Stopping server on 42311
2016-10-30 00:51:07,599 INFO [RpcServer.listener,port=42311] org.apache.hadoop.hbase.ipc.RpcServer$Listener(761): RpcServer.listener,port=42311: stopping
2016-10-30 00:51:07,599 INFO [RpcServer.responder] org.apache.hadoop.hbase.ipc.RpcServer$Responder(1003): RpcServer.responder: stopped
2016-10-30 00:51:07,599 INFO [RpcServer.responder] org.apache.hadoop.hbase.ipc.RpcServer$Responder(906): RpcServer.responder: stopping
2016-10-30 00:51:07,601 DEBUG [M:0;pietas:42311] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(188): Node /hbase/rs/pietas.apache.org,42311,1477788349359 already deleted, retry=false
2016-10-30 00:51:07,601 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.regionserver.HRegionServer(1104): stopping server pietas.apache.org,42311,1477788349359; zookeeper connection closed.
2016-10-30 00:51:07,601 INFO [M:0;pietas:42311] org.apache.hadoop.hbase.regionserver.HRegionServer(1107): M:0;pietas:42311 exiting
2016-10-30 00:51:07,606 INFO [main] org.apache.hadoop.hbase.zookeeper.MiniZooKeeperCluster(316): Shutdown MiniZK cluster with all ZK servers
2016-10-30 00:51:07,606 WARN [main] org.apache.hadoop.hdfs.server.datanode.DirectoryScanner(378): DirectoryScanner: shutdown has been called
2016-10-30 00:51:07,704 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=54158-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0x232bb8ba-0x158130cb1e00013, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=None, state=Disconnected, path=null
2016-10-30 00:51:07,704 DEBUG [B.defaultRpcServer.handler=2,queue=0,port=54158-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(691): hconnection-0x232bb8ba-0x158130cb1e00013, quorum=localhost:52122, baseZNode=/hbase Received Disconnected from ZooKeeper, ignoring
2016-10-30 00:51:07,704 DEBUG [pietas:42311.activeMasterManager-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): replicationLogCleaner-0x158130cb1e00005, quorum=localhost:52122, baseZNode=/hbase Received ZooKeeper Event, type=None, state=Disconnected, path=null
2016-10-30 00:51:07,704 DEBUG [pietas:42311.activeMasterManager-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(691): replicationLogCleaner-0x158130cb1e00005, quorum=localhost:52122, baseZNode=/hbase Received Disconnected from ZooKeeper, ignoring
2016-10-30 00:51:07,755 WARN [DataNode: [[[DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/76bc23a5-9da8-4f73-a14d-c9f981f311d5/dfscluster_da06be4f-c420-4ba8-abdd-a2674bdcc1d8/dfs/data/data1/,> [DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/76bc23a5-9da8-4f73-a14d-c9f981f311d5/dfscluster_da06be4f-c420-4ba8-abdd-a2674bdcc1d8/dfs/data/data2/]]> heartbeating to localhost/127.0.0.1:32864] org.apache.hadoop.hdfs.server.datanode.BPServiceActor(704): BPOfferService for Block pool BP-1921219741-67.195.81.190-1477788348118 (Datanode Uuid 171f8b3b-83c7-4a94-b336-fad77a40895c) service to localhost/127.0.0.1:32864 interrupted
2016-10-30 00:51:07,755 WARN [DataNode: [[[DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/76bc23a5-9da8-4f73-a14d-c9f981f311d5/dfscluster_da06be4f-c420-4ba8-abdd-a2674bdcc1d8/dfs/data/data1/,> [DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/76bc23a5-9da8-4f73-a14d-c9f981f311d5/dfscluster_da06be4f-c420-4ba8-abdd-a2674bdcc1d8/dfs/data/data2/]]> heartbeating to localhost/127.0.0.1:32864] org.apache.hadoop.hdfs.server.datanode.BPServiceActor(834): Ending block pool service for: Block pool BP-1921219741-67.195.81.190-1477788348118 (Datanode Uuid 171f8b3b-83c7-4a94-b336-fad77a40895c) service to localhost/127.0.0.1:32864
2016-10-30 00:51:07,995 INFO [main] org.apache.hadoop.hbase.HBaseTestingUtility(1103): Minicluster is down
2016-10-30 00:51:08,000 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(111): Shutdown hook starting; hbase.shutdown.hook=true; fsShutdownHook=org.apache.hadoop.fs.FileSystem$Cache$ClientFinalizer@5cf0c115
2016-10-30 00:51:08,000 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(133): Shutdown hook finished.
2016-10-30 00:51:08,001 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(111): Shutdown hook starting; hbase.shutdown.hook=true; fsShutdownHook=org.apache.hadoop.fs.FileSystem$Cache$ClientFinalizer@5cf0c115
2016-10-30 00:51:08,001 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(133): Shutdown hook finished.
2016-10-30 00:51:08,001 INFO [Thread-2002] org.apache.phoenix.query.BaseTest$1(488): SHUTDOWN: halting JVM now
2016-10-30 00:51:08,001 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(111): Shutdown hook starting; hbase.shutdown.hook=true; fsShutdownHook=org.apache.hadoop.fs.FileSystem$Cache$ClientFinalizer@5cf0c115
2016-10-30 00:51:08,001 INFO [Thread-2] org.apache.hadoop.hbase.regionserver.ShutdownHook$ShutdownHookThread(120): Starting fs shutdown hook thread.
Results :
Failed tests:
UpgradeIT.testAcquiringAndReleasingUpgradeMutex:709
UpgradeIT.testConcurrentUpgradeThrowsUprgadeInProgressException:739 One of the threads should have acquired the mutex
Tests in error:
AlterTableIT.testAddNewColumnFamilyProperties:1620 » PhoenixIO org.apache.phoe...
Tests run: 1655, Failures: 2, Errors: 1, Skipped: 1
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (ClientManagedTimeTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.apache.phoenix.end2end.AggregateQueryIT
Running org.apache.phoenix.end2end.ColumnProjectionOptimizationIT
Running org.apache.phoenix.end2end.ClientTimeArithmeticQueryIT
Running org.apache.phoenix.end2end.CaseStatementIT
Running org.apache.phoenix.end2end.ArrayIT
Running org.apache.phoenix.end2end.CastAndCoerceIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.005 sec - in org.apache.phoenix.end2end.ColumnProjectionOptimizationIT
Running org.apache.phoenix.end2end.CreateSchemaIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.329 sec - in org.apache.phoenix.end2end.CreateSchemaIT
Running org.apache.phoenix.end2end.CreateTableIT
Tests run: 49, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 43.326 sec - in org.apache.phoenix.end2end.CastAndCoerceIT
Running org.apache.phoenix.end2end.CustomEntityDataIT
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 49.26 sec - in org.apache.phoenix.end2end.CaseStatementIT
Running org.apache.phoenix.end2end.DerivedTableIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.524 sec - in org.apache.phoenix.end2end.CustomEntityDataIT
Running org.apache.phoenix.end2end.DistinctCountIT
Tests run: 49, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 60.508 sec - in org.apache.phoenix.end2end.AggregateQueryIT
Running org.apache.phoenix.end2end.DropSchemaIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 18.928 sec - in org.apache.phoenix.end2end.DerivedTableIT
Running org.apache.phoenix.end2end.ExtendedQueryExecIT
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 19.8 sec - in org.apache.phoenix.end2end.DistinctCountIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.998 sec - in org.apache.phoenix.end2end.DropSchemaIT
Running org.apache.phoenix.end2end.FunkyNamesIT
Running org.apache.phoenix.end2end.GroupByIT
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.77 sec - in org.apache.phoenix.end2end.ExtendedQueryExecIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.415 sec - in org.apache.phoenix.end2end.FunkyNamesIT
Running org.apache.phoenix.end2end.NotQueryIT
Running org.apache.phoenix.end2end.NativeHBaseTypesIT
Tests run: 79, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 87.883 sec - in org.apache.phoenix.end2end.ArrayIT
Running org.apache.phoenix.end2end.PointInTimeQueryIT
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.705 sec - in org.apache.phoenix.end2end.NativeHBaseTypesIT
Running org.apache.phoenix.end2end.ProductMetricsIT
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.043 sec - in org.apache.phoenix.end2end.PointInTimeQueryIT
Running org.apache.phoenix.end2end.QueryDatabaseMetaDataIT
Tests run: 61, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.839 sec - in org.apache.phoenix.end2end.ProductMetricsIT
Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 96.038 sec - in org.apache.phoenix.end2end.CreateTableIT
Running org.apache.phoenix.end2end.ReadIsolationLevelIT
Tests run: 77, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 48.763 sec - in org.apache.phoenix.end2end.NotQueryIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.271 sec - in org.apache.phoenix.end2end.ReadIsolationLevelIT
Running org.apache.phoenix.end2end.RowValueConstructorIT
Tests run: 105, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 68.683 sec - in org.apache.phoenix.end2end.GroupByIT
Running org.apache.phoenix.end2end.QueryIT
Tests run: 245, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 145.792 sec - in org.apache.phoenix.end2end.ClientTimeArithmeticQueryIT
Running org.apache.phoenix.end2end.ScanQueryIT
Running org.apache.phoenix.end2end.SequenceIT
Running org.apache.phoenix.end2end.SequenceBulkAllocationIT
Tests run: 56, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.867 sec - in org.apache.phoenix.end2end.SequenceBulkAllocationIT
Running org.apache.phoenix.end2end.ToNumberFunctionIT
Tests run: 126, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 61.325 sec - in org.apache.phoenix.end2end.QueryIT
Tests run: 119, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 52.628 sec - in org.apache.phoenix.end2end.ScanQueryIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.825 sec - in org.apache.phoenix.end2end.ToNumberFunctionIT
Running org.apache.phoenix.end2end.UpsertSelectIT
Tests run: 54, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 49.875 sec - in org.apache.phoenix.end2end.SequenceIT
Running org.apache.phoenix.end2end.UpsertValuesIT
Running org.apache.phoenix.end2end.TopNIT
Running org.apache.phoenix.end2end.TruncateFunctionIT
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.146 sec - in org.apache.phoenix.end2end.TopNIT
Running org.apache.phoenix.end2end.VariableLengthPKIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.804 sec - in org.apache.phoenix.end2end.TruncateFunctionIT
Running org.apache.phoenix.end2end.salted.SaltedTableIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.719 sec - in org.apache.phoenix.end2end.salted.SaltedTableIT
Running org.apache.phoenix.rpc.UpdateCacheWithScnIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.125 sec - in org.apache.phoenix.rpc.UpdateCacheWithScnIT
Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 132.691 sec - in org.apache.phoenix.end2end.QueryDatabaseMetaDataIT
Tests run: 46, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 124.461 sec - in org.apache.phoenix.end2end.RowValueConstructorIT
Tests run: 50, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 42.61 sec - in org.apache.phoenix.end2end.VariableLengthPKIT
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 117.207 sec - in org.apache.phoenix.end2end.UpsertValuesIT
Tests run: 22, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 134.22 sec - in org.apache.phoenix.end2end.UpsertSelectIT
Results :
Tests run: 1358, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (HBaseManagedTimeTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Results :
Tests run: 0, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (NeedTheirOwnClusterTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.apache.hadoop.hbase.regionserver.wal.WALReplayWithIndexWritesAndCompressedWALIT
Running org.apache.phoenix.end2end.ConnectionUtilIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.644 sec - in org.apache.hadoop.hbase.regionserver.wal.WALReplayWithIndexWritesAndCompressedWALIT
Running org.apache.phoenix.end2end.CountDistinctCompressionIT
Running org.apache.phoenix.end2end.CsvBulkLoadToolIT
Running org.apache.phoenix.end2end.ContextClassloaderIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.289 sec - in org.apache.phoenix.end2end.ConnectionUtilIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 9.366 sec - in org.apache.phoenix.end2end.ContextClassloaderIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.204 sec - in org.apache.phoenix.end2end.CountDistinctCompressionIT
Running org.apache.phoenix.end2end.IndexExtendedIT
Running org.apache.phoenix.end2end.QueryTimeoutIT
Running org.apache.phoenix.end2end.QueryWithLimitIT
Running org.apache.phoenix.end2end.RenewLeaseIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.339 sec - in org.apache.phoenix.end2end.RenewLeaseIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 21.955 sec - in org.apache.phoenix.end2end.QueryTimeoutIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.814 sec - in org.apache.phoenix.end2end.QueryWithLimitIT
Running org.apache.phoenix.end2end.SpillableGroupByIT
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 72.933 sec - in org.apache.phoenix.end2end.CsvBulkLoadToolIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.002 sec - in org.apache.phoenix.end2end.SpillableGroupByIT
Running org.apache.phoenix.end2end.UserDefinedFunctionsIT
Running org.apache.phoenix.end2end.index.ImmutableIndexIT
Running org.apache.phoenix.end2end.index.MutableIndexFailureIT
Running org.apache.phoenix.end2end.index.ReadOnlyIndexFailureIT
Running org.apache.phoenix.end2end.index.MutableIndexReplicationIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 24.573 sec - in org.apache.phoenix.end2end.index.ReadOnlyIndexFailureIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 26.635 sec - in org.apache.phoenix.end2end.index.MutableIndexReplicationIT
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 52.099 sec - in org.apache.phoenix.end2end.UserDefinedFunctionsIT
Running org.apache.phoenix.hbase.index.FailForUnsupportedHBaseVersionsIT
Running org.apache.phoenix.end2end.index.txn.TxWriteFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 19.328 sec - in org.apache.phoenix.hbase.index.FailForUnsupportedHBaseVersionsIT
Running org.apache.phoenix.execute.PartialCommitIT
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.645 sec - in org.apache.phoenix.execute.PartialCommitIT
Running org.apache.phoenix.hbase.index.covered.EndToEndCoveredColumnsIndexBuilderIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 30.718 sec - in org.apache.phoenix.end2end.index.txn.TxWriteFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 18.111 sec - in org.apache.phoenix.hbase.index.covered.EndToEndCoveredColumnsIndexBuilderIT
Running org.apache.phoenix.hbase.index.covered.example.EndToEndCoveredIndexingIT
Running org.apache.phoenix.hbase.index.covered.example.FailWithoutRetriesIT
Running org.apache.phoenix.hbase.index.covered.example.EndtoEndIndexingWithCompressionIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.383 sec - in org.apache.phoenix.hbase.index.covered.example.FailWithoutRetriesIT
Running org.apache.phoenix.iterate.RoundRobinResultIteratorWithStatsIT
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 162.692 sec - in org.apache.phoenix.end2end.index.ImmutableIndexIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 12.664 sec - in org.apache.phoenix.iterate.RoundRobinResultIteratorWithStatsIT
Running org.apache.phoenix.iterate.ScannerLeaseRenewalIT
Running org.apache.phoenix.monitoring.PhoenixMetricsIT
Tests run: 80, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 281.582 sec - in org.apache.phoenix.end2end.IndexExtendedIT
Running org.apache.phoenix.rpc.PhoenixClientRpcIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.091 sec - in org.apache.phoenix.rpc.PhoenixClientRpcIT
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 133.723 sec - in org.apache.phoenix.hbase.index.covered.example.EndToEndCoveredIndexingIT
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 140.861 sec - in org.apache.phoenix.hbase.index.covered.example.EndtoEndIndexingWithCompressionIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 67.142 sec - in org.apache.phoenix.monitoring.PhoenixMetricsIT
Running org.apache.phoenix.rpc.PhoenixServerRpcIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.277 sec - in org.apache.phoenix.rpc.PhoenixServerRpcIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 334.465 sec - in org.apache.phoenix.end2end.index.MutableIndexFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 172.889 sec - in org.apache.phoenix.iterate.ScannerLeaseRenewalIT
Results :
Tests run: 211, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:verify (ParallelStatsEnabledTest) @ phoenix-core ---
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Apache Phoenix ..................................... SUCCESS [ 4.084 s]
[INFO] Phoenix Core ....................................... FAILURE [27:03 min]
[INFO] Phoenix - Flume .................................... SKIPPED
[INFO] Phoenix - Pig ...................................... SKIPPED
[INFO] Phoenix Query Server Client ........................ SKIPPED
[INFO] Phoenix Query Server ............................... SKIPPED
[INFO] Phoenix - Pherf .................................... SKIPPED
[INFO] Phoenix - Spark .................................... SKIPPED
[INFO] Phoenix - Hive ..................................... SKIPPED
[INFO] Phoenix Client ..................................... SKIPPED
[INFO] Phoenix Server ..................................... SKIPPED
[INFO] Phoenix Assembly ................................... SKIPPED
[INFO] Phoenix - Tracing Web Application .................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 27:09 min
[INFO] Finished at: 2016-10-30T01:05:51+00:00
[INFO] Final Memory: 75M/1044M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-failsafe-plugin:2.19.1:verify (ParallelStatsEnabledTest) on project phoenix-core: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/failsafe-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :phoenix-core
Build step 'Invoke top-level Maven targets' marked build as failure
Archiving artifacts
Compressed 803.25 MB of artifacts by 41.6% relative to #1455
Error updating JIRA issues. Saving issues for next build.
java.lang.NullPointerException
Recording test results
Build failed in Jenkins: Phoenix | Master #1460
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Phoenix-master/1460/changes>
Changes:
[jamestaylor] PHOENIX-3396 Valid Multi-byte strings whose total byte size is greater
------------------------------------------
[...truncated 724546 lines...]
Caused by: java.util.NoSuchElementException
at java.util.LinkedList.removeFirst(LinkedList.java:268)
at org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:494)
2016-10-29 00:03:19,232 DEBUG [IPC Server handler 0 on 46477] org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer(1350): *BLOCK* NameNode.blockReceivedAndDeleted: from DatanodeRegistration(127.0.0.1:54527, datanodeUuid=cb504f38-05fb-4cd2-a28e-ee2ff0071303, infoPort=35194, infoSecurePort=0, ipcPort=33394, storageInfo=lv=-56;cid=testClusterID;nsid=1860871222;c=0) 1 blocks.
2016-10-29 00:03:19,232 INFO [IPC Server handler 0 on 46477] org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(2624): BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54527 is added to blk_1073741829_1005{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-956647ed-bb38-4be9-a397-12a72192c937:NORMAL:127.0.0.1:54527|RBW]]} size 2729526
2016-10-29 00:03:19,232 DEBUG [IPC Server handler 0 on 46477] org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3235): BLOCK* block RECEIVED_BLOCK: blk_1073741829_1005 is received from DatanodeRegistration(127.0.0.1:54527, datanodeUuid=cb504f38-05fb-4cd2-a28e-ee2ff0071303, infoPort=35194, infoSecurePort=0, ipcPort=33394, storageInfo=lv=-56;cid=testClusterID;nsid=1860871222;c=0)
2016-10-29 00:03:19,232 DEBUG [IPC Server handler 0 on 46477] org.apache.hadoop.hdfs.server.blockmanagement.BlockManager(3238): *BLOCK* NameNode.processIncrementalBlockReport: from DatanodeRegistration(127.0.0.1:54527, datanodeUuid=cb504f38-05fb-4cd2-a28e-ee2ff0071303, infoPort=35194, infoSecurePort=0, ipcPort=33394, storageInfo=lv=-56;cid=testClusterID;nsid=1860871222;c=0) receiving: 0, received: 1, deleted: 0
2016-10-29 00:03:19,264 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): master:52986-0x1580db47b300000, quorum=localhost:63447, baseZNode=/hbase Received ZooKeeper Event, type=NodeDeleted, state=SyncConnected, path=/hbase/master
2016-10-29 00:03:19,264 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation(1709): Closing zookeeper sessionid=0x1580db47b300004
2016-10-29 00:03:19,265 DEBUG [main-EventThread] org.apache.hadoop.hbase.zookeeper.ZKUtil(370): master:52986-0x1580db47b300000, quorum=localhost:63447, baseZNode=/hbase Set watcher on znode that does not yet exist, /hbase/master
2016-10-29 00:03:19,273 DEBUG [M:0;asf910:52986] org.apache.hadoop.hbase.ipc.RpcClientImpl(1157): Stopping rpc client
2016-10-29 00:03:19,283 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.ChoreService(323): Chore service for: asf910.gq1.ygridcore.net,52986,1477698682115_splitLogManager_ had [] on shutdown
2016-10-29 00:03:19,283 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.procedure.flush.MasterFlushTableProcedureManager(79): stop: server shutting down.
2016-10-29 00:03:19,283 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.ipc.RpcServer(2277): Stopping server on 52986
2016-10-29 00:03:19,284 INFO [RpcServer.listener,port=52986] org.apache.hadoop.hbase.ipc.RpcServer$Listener(761): RpcServer.listener,port=52986: stopping
2016-10-29 00:03:19,286 INFO [RpcServer.responder] org.apache.hadoop.hbase.ipc.RpcServer$Responder(1003): RpcServer.responder: stopped
2016-10-29 00:03:19,287 INFO [RpcServer.responder] org.apache.hadoop.hbase.ipc.RpcServer$Responder(906): RpcServer.responder: stopping
2016-10-29 00:03:19,291 WARN [main] org.apache.hadoop.hbase.util.JVMClusterUtil(294): RegionServerThreads taking too long to stop, interrupting
2016-10-29 00:03:19,292 WARN [main] org.apache.hadoop.hbase.util.JVMClusterUtil(284): RegionServerThreads remaining, give one more chance before interrupting
2016-10-29 00:03:19,292 WARN [RS:0;asf910:51511] org.apache.hadoop.hbase.util.Threads(113): Shutdown of org.apache.hadoop.hbase.fs.HFileSystem@6f028b1c; joinwait=30000
java.lang.InterruptedException
at java.lang.Object.wait(Native Method)
at java.lang.Thread.join(Thread.java:1289)
at org.apache.hadoop.hbase.util.Threads.shutdown(Threads.java:111)
at org.apache.hadoop.hbase.MiniHBaseCluster$MiniHBaseClusterRegionServer.run(MiniHBaseCluster.java:150)
at java.lang.Thread.run(Thread.java:745)
2016-10-29 00:03:19,331 DEBUG [M:0;asf910:52986] org.apache.hadoop.hbase.zookeeper.RecoverableZooKeeper(188): Node /hbase/rs/asf910.gq1.ygridcore.net,52986,1477698682115 already deleted, retry=false
2016-10-29 00:03:19,369 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.regionserver.HRegionServer(1104): stopping server asf910.gq1.ygridcore.net,52986,1477698682115; zookeeper connection closed.
2016-10-29 00:03:19,370 INFO [M:0;asf910:52986] org.apache.hadoop.hbase.regionserver.HRegionServer(1107): M:0;asf910:52986 exiting
2016-10-29 00:03:19,634 INFO [main] org.apache.hadoop.hbase.util.JVMClusterUtil(317): Shutdown of 1 master(s) and 1 regionserver(s) complete
2016-10-29 00:03:19,655 INFO [main] org.apache.hadoop.hbase.zookeeper.MiniZooKeeperCluster(316): Shutdown MiniZK cluster with all ZK servers
2016-10-29 00:03:19,656 WARN [main] org.apache.hadoop.hdfs.server.datanode.DirectoryScanner(378): DirectoryScanner: shutdown has been called
2016-10-29 00:03:19,751 DEBUG [asf910:52986.activeMasterManager-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): replicationLogCleaner-0x1580db47b300005, quorum=localhost:63447, baseZNode=/hbase Received ZooKeeper Event, type=None, state=Disconnected, path=null
2016-10-29 00:03:19,751 DEBUG [asf910:52986.activeMasterManager-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(691): replicationLogCleaner-0x1580db47b300005, quorum=localhost:63447, baseZNode=/hbase Received Disconnected from ZooKeeper, ignoring
2016-10-29 00:03:19,752 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=51511-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(602): hconnection-0xfb04670-0x1580db47b300014, quorum=localhost:63447, baseZNode=/hbase Received ZooKeeper Event, type=None, state=Disconnected, path=null
2016-10-29 00:03:19,752 DEBUG [B.defaultRpcServer.handler=4,queue=0,port=51511-EventThread] org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher(691): hconnection-0xfb04670-0x1580db47b300014, quorum=localhost:63447, baseZNode=/hbase Received Disconnected from ZooKeeper, ignoring
2016-10-29 00:03:19,778 WARN [DataNode: [[[DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/fbeb8618-2b05-4aca-b6a3-c6b996c79e11/dfscluster_246b5fe2-4de2-4c26-adf5-30dbdef895a7/dfs/data/data1/,> [DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/fbeb8618-2b05-4aca-b6a3-c6b996c79e11/dfscluster_246b5fe2-4de2-4c26-adf5-30dbdef895a7/dfs/data/data2/]]> heartbeating to localhost/127.0.0.1:46477] org.apache.hadoop.hdfs.server.datanode.BPServiceActor(704): BPOfferService for Block pool BP-1491600830-67.195.81.154-1477698678918 (Datanode Uuid cb504f38-05fb-4cd2-a28e-ee2ff0071303) service to localhost/127.0.0.1:46477 interrupted
2016-10-29 00:03:19,779 WARN [DataNode: [[[DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/fbeb8618-2b05-4aca-b6a3-c6b996c79e11/dfscluster_246b5fe2-4de2-4c26-adf5-30dbdef895a7/dfs/data/data1/,> [DISK]<https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/test-data/fbeb8618-2b05-4aca-b6a3-c6b996c79e11/dfscluster_246b5fe2-4de2-4c26-adf5-30dbdef895a7/dfs/data/data2/]]> heartbeating to localhost/127.0.0.1:46477] org.apache.hadoop.hdfs.server.datanode.BPServiceActor(834): Ending block pool service for: Block pool BP-1491600830-67.195.81.154-1477698678918 (Datanode Uuid cb504f38-05fb-4cd2-a28e-ee2ff0071303) service to localhost/127.0.0.1:46477
2016-10-29 00:03:20,263 INFO [main] org.apache.hadoop.hbase.HBaseTestingUtility(1103): Minicluster is down
2016-10-29 00:03:20,268 INFO [Thread-162] org.apache.phoenix.query.BaseTest$1(488): SHUTDOWN: halting JVM now
Results :
Tests in error:
DistinctPrefixFilterIT.initTables:93 » Commit org.apache.hadoop.hbase.client.R...
DistinctPrefixFilterIT.initTables:93 » Commit org.apache.hadoop.hbase.client.R...
DistinctPrefixFilterIT.initTables:93 » Commit org.apache.hadoop.hbase.client.R...
DistinctPrefixFilterIT.initTables:93 » Commit org.apache.hadoop.hbase.client.R...
Tests run: 1655, Failures: 0, Errors: 4, Skipped: 1
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (ClientManagedTimeTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.apache.phoenix.end2end.CastAndCoerceIT
Running org.apache.phoenix.end2end.ArrayIT
Running org.apache.phoenix.end2end.CaseStatementIT
Running org.apache.phoenix.end2end.ColumnProjectionOptimizationIT
Running org.apache.phoenix.end2end.AggregateQueryIT
Running org.apache.phoenix.end2end.ClientTimeArithmeticQueryIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 45.836 sec - in org.apache.phoenix.end2end.ColumnProjectionOptimizationIT
Running org.apache.phoenix.end2end.CreateSchemaIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.345 sec - in org.apache.phoenix.end2end.CreateSchemaIT
Running org.apache.phoenix.end2end.CreateTableIT
Tests run: 49, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 54.731 sec - in org.apache.phoenix.end2end.CastAndCoerceIT
Running org.apache.phoenix.end2end.CustomEntityDataIT
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 60.459 sec - in org.apache.phoenix.end2end.CaseStatementIT
Running org.apache.phoenix.end2end.DerivedTableIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 12.071 sec - in org.apache.phoenix.end2end.CustomEntityDataIT
Running org.apache.phoenix.end2end.DistinctCountIT
Tests run: 49, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 84.508 sec - in org.apache.phoenix.end2end.AggregateQueryIT
Running org.apache.phoenix.end2end.DropSchemaIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 30.848 sec - in org.apache.phoenix.end2end.DerivedTableIT
Running org.apache.phoenix.end2end.ExtendedQueryExecIT
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 34.795 sec - in org.apache.phoenix.end2end.DistinctCountIT
Running org.apache.phoenix.end2end.FunkyNamesIT
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 12.334 sec - in org.apache.phoenix.end2end.ExtendedQueryExecIT
Running org.apache.phoenix.end2end.GroupByIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 23.618 sec - in org.apache.phoenix.end2end.DropSchemaIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.865 sec - in org.apache.phoenix.end2end.FunkyNamesIT
Running org.apache.phoenix.end2end.NotQueryIT
Running org.apache.phoenix.end2end.NativeHBaseTypesIT
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.858 sec - in org.apache.phoenix.end2end.NativeHBaseTypesIT
Running org.apache.phoenix.end2end.PointInTimeQueryIT
Tests run: 79, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 153.101 sec - in org.apache.phoenix.end2end.ArrayIT
Running org.apache.phoenix.end2end.ProductMetricsIT
Tests run: 77, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 65.03 sec - in org.apache.phoenix.end2end.NotQueryIT
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 45.776 sec - in org.apache.phoenix.end2end.PointInTimeQueryIT
Running org.apache.phoenix.end2end.QueryDatabaseMetaDataIT
Tests run: 245, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 191.753 sec - in org.apache.phoenix.end2end.ClientTimeArithmeticQueryIT
Tests run: 61, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 44.352 sec - in org.apache.phoenix.end2end.ProductMetricsIT
Tests run: 105, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 96.205 sec - in org.apache.phoenix.end2end.GroupByIT
Running org.apache.phoenix.end2end.QueryIT
Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 179.446 sec - in org.apache.phoenix.end2end.CreateTableIT
Running org.apache.phoenix.end2end.SequenceBulkAllocationIT
Running org.apache.phoenix.end2end.ScanQueryIT
Running org.apache.phoenix.end2end.ReadIsolationLevelIT
Running org.apache.phoenix.end2end.RowValueConstructorIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.779 sec - in org.apache.phoenix.end2end.ReadIsolationLevelIT
Running org.apache.phoenix.end2end.SequenceIT
Tests run: 56, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 50.396 sec - in org.apache.phoenix.end2end.SequenceBulkAllocationIT
Running org.apache.phoenix.end2end.ToNumberFunctionIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.991 sec - in org.apache.phoenix.end2end.ToNumberFunctionIT
Running org.apache.phoenix.end2end.TopNIT
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 12.409 sec - in org.apache.phoenix.end2end.TopNIT
Running org.apache.phoenix.end2end.TruncateFunctionIT
Tests run: 119, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 73.522 sec - in org.apache.phoenix.end2end.ScanQueryIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.722 sec - in org.apache.phoenix.end2end.TruncateFunctionIT
Running org.apache.phoenix.end2end.UpsertSelectIT
Tests run: 126, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 113.559 sec - in org.apache.phoenix.end2end.QueryIT
Tests run: 54, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 91.582 sec - in org.apache.phoenix.end2end.SequenceIT
Running org.apache.phoenix.end2end.salted.SaltedTableIT
Running org.apache.phoenix.end2end.UpsertValuesIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 13.374 sec - in org.apache.phoenix.end2end.salted.SaltedTableIT
Running org.apache.phoenix.rpc.UpdateCacheWithScnIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.675 sec - in org.apache.phoenix.rpc.UpdateCacheWithScnIT
Running org.apache.phoenix.end2end.VariableLengthPKIT
Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 220.531 sec - in org.apache.phoenix.end2end.QueryDatabaseMetaDataIT
Tests run: 50, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 83.807 sec - in org.apache.phoenix.end2end.VariableLengthPKIT
Tests run: 46, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 226.561 sec - in org.apache.phoenix.end2end.RowValueConstructorIT
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 226.172 sec - in org.apache.phoenix.end2end.UpsertValuesIT
Tests run: 22, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 265.963 sec - in org.apache.phoenix.end2end.UpsertSelectIT
Results :
Tests run: 1358, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (HBaseManagedTimeTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Results :
Tests run: 0, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:integration-test (NeedTheirOwnClusterTests) @ phoenix-core ---
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.apache.hadoop.hbase.regionserver.wal.WALReplayWithIndexWritesAndCompressedWALIT
Running org.apache.phoenix.end2end.ConnectionUtilIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 49.907 sec - in org.apache.hadoop.hbase.regionserver.wal.WALReplayWithIndexWritesAndCompressedWALIT
Running org.apache.phoenix.end2end.ContextClassloaderIT
Running org.apache.phoenix.end2end.CsvBulkLoadToolIT
Running org.apache.phoenix.end2end.CountDistinctCompressionIT
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.383 sec - in org.apache.phoenix.end2end.ContextClassloaderIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.551 sec - in org.apache.phoenix.end2end.CountDistinctCompressionIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.737 sec - in org.apache.phoenix.end2end.ConnectionUtilIT
Running org.apache.phoenix.end2end.IndexExtendedIT
Running org.apache.phoenix.end2end.QueryTimeoutIT
Running org.apache.phoenix.end2end.QueryWithLimitIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.892 sec - in org.apache.phoenix.end2end.QueryTimeoutIT
Running org.apache.phoenix.end2end.RenewLeaseIT
Running org.apache.phoenix.end2end.SpillableGroupByIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 26.057 sec - in org.apache.phoenix.end2end.QueryWithLimitIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.781 sec - in org.apache.phoenix.end2end.RenewLeaseIT
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 109.538 sec - in org.apache.phoenix.end2end.CsvBulkLoadToolIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.026 sec - in org.apache.phoenix.end2end.SpillableGroupByIT
Running org.apache.phoenix.end2end.UserDefinedFunctionsIT
Running org.apache.phoenix.end2end.index.MutableIndexFailureIT
Running org.apache.phoenix.end2end.index.ImmutableIndexIT
Running org.apache.phoenix.end2end.index.ReadOnlyIndexFailureIT
Running org.apache.phoenix.end2end.index.MutableIndexReplicationIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 42.22 sec - in org.apache.phoenix.end2end.index.ReadOnlyIndexFailureIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.413 sec - in org.apache.phoenix.end2end.index.MutableIndexReplicationIT
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 89.789 sec - in org.apache.phoenix.end2end.UserDefinedFunctionsIT
Running org.apache.phoenix.hbase.index.FailForUnsupportedHBaseVersionsIT
Running org.apache.phoenix.end2end.index.txn.TxWriteFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.159 sec - in org.apache.phoenix.hbase.index.FailForUnsupportedHBaseVersionsIT
Running org.apache.phoenix.execute.PartialCommitIT
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.68 sec - in org.apache.phoenix.execute.PartialCommitIT
Running org.apache.phoenix.hbase.index.covered.EndToEndCoveredColumnsIndexBuilderIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.628 sec - in org.apache.phoenix.end2end.index.txn.TxWriteFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 26.295 sec - in org.apache.phoenix.hbase.index.covered.EndToEndCoveredColumnsIndexBuilderIT
Running org.apache.phoenix.hbase.index.covered.example.EndToEndCoveredIndexingIT
Running org.apache.phoenix.hbase.index.covered.example.FailWithoutRetriesIT
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 199.74 sec - in org.apache.phoenix.end2end.index.ImmutableIndexIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.4 sec - in org.apache.phoenix.hbase.index.covered.example.FailWithoutRetriesIT
Running org.apache.phoenix.hbase.index.covered.example.EndtoEndIndexingWithCompressionIT
Running org.apache.phoenix.iterate.RoundRobinResultIteratorWithStatsIT
Running org.apache.phoenix.iterate.ScannerLeaseRenewalIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 19.615 sec - in org.apache.phoenix.iterate.RoundRobinResultIteratorWithStatsIT
Tests run: 80, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 399.172 sec - in org.apache.phoenix.end2end.IndexExtendedIT
Running org.apache.phoenix.rpc.PhoenixClientRpcIT
Running org.apache.phoenix.monitoring.PhoenixMetricsIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 16.49 sec - in org.apache.phoenix.rpc.PhoenixClientRpcIT
Running org.apache.phoenix.rpc.PhoenixServerRpcIT
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.314 sec - in org.apache.phoenix.rpc.PhoenixServerRpcIT
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 430.599 sec - in org.apache.phoenix.end2end.index.MutableIndexFailureIT
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 176.717 sec - in org.apache.phoenix.iterate.ScannerLeaseRenewalIT
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 123.54 sec - in org.apache.phoenix.monitoring.PhoenixMetricsIT
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 256.558 sec - in org.apache.phoenix.hbase.index.covered.example.EndToEndCoveredIndexingIT
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 257.463 sec - in org.apache.phoenix.hbase.index.covered.example.EndtoEndIndexingWithCompressionIT
Results :
Tests run: 211, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-failsafe-plugin:2.19.1:verify (ParallelStatsEnabledTest) @ phoenix-core ---
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Apache Phoenix ..................................... SUCCESS [ 3.041 s]
[INFO] Phoenix Core ....................................... FAILURE [41:18 min]
[INFO] Phoenix - Flume .................................... SKIPPED
[INFO] Phoenix - Pig ...................................... SKIPPED
[INFO] Phoenix Query Server Client ........................ SKIPPED
[INFO] Phoenix Query Server ............................... SKIPPED
[INFO] Phoenix - Pherf .................................... SKIPPED
[INFO] Phoenix - Spark .................................... SKIPPED
[INFO] Phoenix - Hive ..................................... SKIPPED
[INFO] Phoenix Client ..................................... SKIPPED
[INFO] Phoenix Server ..................................... SKIPPED
[INFO] Phoenix Assembly ................................... SKIPPED
[INFO] Phoenix - Tracing Web Application .................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 41:23 min
[INFO] Finished at: 2016-10-29T00:25:35+00:00
[INFO] Final Memory: 75M/779M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-failsafe-plugin:2.19.1:verify (ParallelStatsEnabledTest) on project phoenix-core: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/failsafe-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :phoenix-core
Build step 'Invoke top-level Maven targets' marked build as failure
Archiving artifacts
Compressed 853.87 MB of artifacts by 76.9% relative to #1455
Updating PHOENIX-3396
Recording test results
Build failed in Jenkins: Phoenix | Master #1459
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Phoenix-master/1459/changes>
Changes:
[samarth] PHOENIX-3375 Upgrade from v4.8.1 to 4.9.0 fails
------------------------------------------
[...truncated 291 lines...]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.014 sec - in org.apache.phoenix.expression.function.InstrFunctionTest
Running org.apache.phoenix.expression.function.BuiltinFunctionConstructorTest
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.062 sec - in org.apache.phoenix.expression.ArrayToStringFunctionTest
Running org.apache.phoenix.expression.function.ExternalSqlTypeIdFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.187 sec - in org.apache.phoenix.expression.RegexpReplaceFunctionTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.expression.function.ExternalSqlTypeIdFunctionTest
Running org.apache.phoenix.expression.SignFunctionTest
Running org.apache.phoenix.expression.RegexpSubstrFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 sec - in org.apache.phoenix.expression.RegexpSubstrFunctionTest
Running org.apache.phoenix.expression.SqrtFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.022 sec - in org.apache.phoenix.expression.SignFunctionTest
Running org.apache.phoenix.expression.SortOrderExpressionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.018 sec - in org.apache.phoenix.expression.SqrtFunctionTest
Running org.apache.phoenix.expression.ArrayFillFunctionTest
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.032 sec - in org.apache.phoenix.expression.ArrayFillFunctionTest
Running org.apache.phoenix.expression.ArrayPrependFunctionTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.211 sec - in org.apache.phoenix.expression.function.BuiltinFunctionConstructorTest
Running org.apache.phoenix.expression.RoundFloorCeilExpressionsTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.121 sec - in org.apache.phoenix.util.PhoenixEncodeDecodeTest
Running org.apache.phoenix.expression.PowerFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.038 sec - in org.apache.phoenix.expression.PowerFunctionTest
Running org.apache.phoenix.expression.GetSetByteBitFunctionTest
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.243 sec - in org.apache.phoenix.expression.SortOrderExpressionTest
Running org.apache.phoenix.expression.ColumnExpressionTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 sec - in org.apache.phoenix.expression.ColumnExpressionTest
Running org.apache.phoenix.expression.RegexpSplitFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.022 sec - in org.apache.phoenix.expression.RegexpSplitFunctionTest
Running org.apache.phoenix.expression.LnLogFunctionTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.491 sec - in org.apache.phoenix.expression.NullValueTest
Running org.apache.phoenix.expression.AbsFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.034 sec - in org.apache.phoenix.expression.LnLogFunctionTest
Running org.apache.phoenix.expression.LikeExpressionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.031 sec - in org.apache.phoenix.expression.AbsFunctionTest
Running org.apache.phoenix.expression.ExpFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.011 sec - in org.apache.phoenix.expression.ExpFunctionTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 sec - in org.apache.phoenix.expression.LikeExpressionTest
Running org.apache.phoenix.expression.ArrayAppendFunctionTest
Running org.apache.phoenix.expression.ILikeExpressionTest
Tests run: 37, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.379 sec - in org.apache.phoenix.expression.ArrayPrependFunctionTest
Running org.apache.phoenix.expression.CbrtFunctionTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.012 sec - in org.apache.phoenix.expression.CbrtFunctionTest
Running org.apache.phoenix.expression.ArrayConcatFunctionTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.205 sec - in org.apache.phoenix.expression.ILikeExpressionTest
Running org.apache.phoenix.expression.StringToArrayFunctionTest
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.028 sec - in org.apache.phoenix.expression.StringToArrayFunctionTest
Running org.apache.phoenix.filter.DistinctPrefixFilterTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.026 sec - in org.apache.phoenix.filter.DistinctPrefixFilterTest
Running org.apache.phoenix.filter.SkipScanFilterTest
Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.139 sec - in org.apache.phoenix.filter.SkipScanFilterTest
Running org.apache.phoenix.filter.SkipScanBigFilterTest
Tests run: 25, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.468 sec - in org.apache.phoenix.expression.ArrayAppendFunctionTest
Running org.apache.phoenix.filter.SkipScanFilterIntersectTest
Tests run: 23, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.704 sec - in org.apache.phoenix.expression.RoundFloorCeilExpressionsTest
Running org.apache.phoenix.cache.JodaTimezoneCacheTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.018 sec - in org.apache.phoenix.cache.JodaTimezoneCacheTest
Running org.apache.phoenix.cache.TenantCacheTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.008 sec - in org.apache.phoenix.cache.TenantCacheTest
Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.061 sec - in org.apache.phoenix.filter.SkipScanFilterIntersectTest
Running org.apache.phoenix.jdbc.SecureUserConnectionsTest
Running org.apache.phoenix.jdbc.ReadOnlyPropertiesTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 sec - in org.apache.phoenix.jdbc.ReadOnlyPropertiesTest
Running org.apache.phoenix.jdbc.PhoenixPreparedStatementTest
Tests run: 35, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.496 sec - in org.apache.phoenix.expression.ArrayConcatFunctionTest
Running org.apache.phoenix.jdbc.PhoenixResultSetMetadataTest
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.049 sec - in org.apache.phoenix.util.PhoenixRuntimeTest
Running org.apache.phoenix.jdbc.PhoenixEmbeddedDriverTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.916 sec - in org.apache.phoenix.expression.GetSetByteBitFunctionTest
Running org.apache.phoenix.jdbc.PhoenixDriverTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.189 sec - in org.apache.phoenix.jdbc.PhoenixEmbeddedDriverTest
Running org.apache.phoenix.index.IndexMaintainerTest
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.447 sec - in org.apache.phoenix.jdbc.PhoenixPreparedStatementTest
Running org.apache.phoenix.index.automated.MRJobSubmitterTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.152 sec - in org.apache.phoenix.index.automated.MRJobSubmitterTest
Running org.apache.phoenix.trace.TraceMetricsSourceTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.826 sec - in org.apache.phoenix.jdbc.PhoenixResultSetMetadataTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.264 sec - in org.apache.phoenix.trace.TraceMetricsSourceTest
Running org.apache.phoenix.execute.MutationStateTest
Running org.apache.phoenix.execute.CorrelatePlanTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.phoenix.execute.MutationStateTest
Running org.apache.phoenix.execute.LiteralResultIteratorPlanTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.722 sec - in org.apache.phoenix.jdbc.PhoenixDriverTest
Running org.apache.phoenix.execute.DescVarLengthFastByteComparisonsTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 sec - in org.apache.phoenix.execute.DescVarLengthFastByteComparisonsTest
Running org.apache.phoenix.execute.UnnestArrayPlanTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.14 sec - in org.apache.phoenix.filter.SkipScanBigFilterTest
Running org.apache.phoenix.compile.HavingCompilerTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.229 sec - in org.apache.phoenix.execute.LiteralResultIteratorPlanTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.18 sec - in org.apache.phoenix.execute.UnnestArrayPlanTest
Running org.apache.phoenix.compile.StatementHintsCompilationTest
Running org.apache.phoenix.compile.SaltedScanRangesTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.294 sec - in org.apache.phoenix.execute.CorrelatePlanTest
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.034 sec - in org.apache.phoenix.compile.SaltedScanRangesTest
Running org.apache.phoenix.compile.ScanRangesIntersectTest
Running org.apache.phoenix.compile.QueryOptimizerTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 sec - in org.apache.phoenix.compile.ScanRangesIntersectTest
Running org.apache.phoenix.compile.TenantSpecificViewIndexCompileTest
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.355 sec - in org.apache.phoenix.compile.HavingCompilerTest
Running org.apache.phoenix.compile.CreateTableCompilerTest
Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.103 sec - in org.apache.phoenix.index.IndexMaintainerTest
Running org.apache.phoenix.compile.QueryMetaDataTest
Tests run: 33, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.662 sec - in org.apache.phoenix.compile.QueryMetaDataTest
Running org.apache.phoenix.compile.JoinQueryCompilerTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.778 sec - in org.apache.phoenix.compile.CreateTableCompilerTest
Running org.apache.phoenix.compile.WhereOptimizerTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.16 sec - in org.apache.phoenix.compile.TenantSpecificViewIndexCompileTest
Running org.apache.phoenix.compile.ViewCompilerTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.31 sec - in org.apache.phoenix.compile.StatementHintsCompilationTest
Running org.apache.phoenix.compile.QueryCompilerTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.73 sec - in org.apache.phoenix.compile.JoinQueryCompilerTest
Running org.apache.phoenix.compile.SelectStatementRewriterTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.555 sec - in org.apache.phoenix.compile.ViewCompilerTest
Running org.apache.phoenix.compile.WhereCompilerTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.181 sec - in org.apache.phoenix.compile.SelectStatementRewriterTest
Running org.apache.phoenix.compile.LimitCompilerTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.271 sec - in org.apache.phoenix.compile.LimitCompilerTest
Running org.apache.phoenix.compile.ScanRangesTest
Tests run: 34, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.063 sec - in org.apache.phoenix.compile.ScanRangesTest
Running org.apache.phoenix.parse.BuiltInFunctionInfoTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.012 sec - in org.apache.phoenix.parse.BuiltInFunctionInfoTest
Running org.apache.phoenix.parse.QueryParserTest
Tests run: 109, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.359 sec - in org.apache.phoenix.compile.WhereOptimizerTest
Running org.apache.phoenix.parse.CastParseNodeTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.parse.CastParseNodeTest
Running org.apache.phoenix.iterate.AggregateResultScannerTest
Tests run: 60, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.176 sec - in org.apache.phoenix.parse.QueryParserTest
Running org.apache.phoenix.iterate.RowKeyOrderedAggregateResultIteratorTest
Tests run: 45, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.79 sec - in org.apache.phoenix.compile.WhereCompilerTest
Running org.apache.phoenix.iterate.MergeSortResultIteratorTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 sec - in org.apache.phoenix.iterate.MergeSortResultIteratorTest
Running org.apache.phoenix.iterate.ConcatResultIteratorTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.iterate.ConcatResultIteratorTest
Running org.apache.phoenix.iterate.SpoolingResultIteratorTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.068 sec - in org.apache.phoenix.iterate.SpoolingResultIteratorTest
Running org.apache.phoenix.hbase.index.parallel.TestThreadPoolManager
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.024 sec - in org.apache.phoenix.hbase.index.parallel.TestThreadPoolManager
Running org.apache.phoenix.hbase.index.parallel.TestThreadPoolBuilder
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.hbase.index.parallel.TestThreadPoolBuilder
Running org.apache.phoenix.hbase.index.util.TestIndexManagementUtil
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.014 sec - in org.apache.phoenix.hbase.index.util.TestIndexManagementUtil
Running org.apache.phoenix.hbase.index.covered.update.TestIndexUpdateManager
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec - in org.apache.phoenix.hbase.index.covered.update.TestIndexUpdateManager
Running org.apache.phoenix.hbase.index.covered.TestCoveredColumns
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.hbase.index.covered.TestCoveredColumns
Running org.apache.phoenix.hbase.index.covered.filter.TestApplyAndFilterDeletesFilter
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 sec - in org.apache.phoenix.hbase.index.covered.filter.TestApplyAndFilterDeletesFilter
Running org.apache.phoenix.hbase.index.covered.filter.TestNewerTimestampFilter
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.hbase.index.covered.filter.TestNewerTimestampFilter
Running org.apache.phoenix.hbase.index.covered.TestLocalTableState
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.378 sec - in org.apache.phoenix.iterate.RowKeyOrderedAggregateResultIteratorTest
Running org.apache.phoenix.hbase.index.covered.example.TestColumnTracker
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.phoenix.hbase.index.covered.example.TestColumnTracker
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.457 sec - in org.apache.phoenix.iterate.AggregateResultScannerTest
Running org.apache.phoenix.hbase.index.covered.example.TestCoveredColumnIndexCodec
Running org.apache.phoenix.hbase.index.covered.example.TestCoveredIndexSpecifierBuilder
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.hbase.index.covered.example.TestCoveredIndexSpecifierBuilder
Running org.apache.phoenix.hbase.index.covered.data.TestIndexMemStore
Tests run: 42, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.844 sec - in org.apache.phoenix.compile.QueryOptimizerTest
Running org.apache.phoenix.hbase.index.write.TestIndexWriter
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 sec - in org.apache.phoenix.hbase.index.covered.data.TestIndexMemStore
Running org.apache.phoenix.hbase.index.write.recovery.TestPerRegionIndexWriteCache
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.084 sec - in org.apache.phoenix.hbase.index.covered.example.TestCoveredColumnIndexCodec
Running org.apache.phoenix.hbase.index.write.TestParalleWriterIndexCommitter
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.183 sec - in org.apache.phoenix.hbase.index.write.TestParalleWriterIndexCommitter
Running org.apache.phoenix.hbase.index.write.TestCachingHTableFactory
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.303 sec - in org.apache.phoenix.hbase.index.write.TestIndexWriter
Running org.apache.phoenix.hbase.index.write.TestWALRecoveryCaching
Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.007 sec - in org.apache.phoenix.hbase.index.write.TestWALRecoveryCaching
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.037 sec - in org.apache.phoenix.hbase.index.write.TestCachingHTableFactory
Running org.apache.phoenix.memory.MemoryManagerTest
Running org.apache.phoenix.hbase.index.write.TestParalleIndexWriter
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.392 sec - in org.apache.phoenix.hbase.index.covered.TestLocalTableState
Running org.apache.phoenix.query.OrderByTest
Tests run: 144, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.964 sec - in org.apache.phoenix.compile.QueryCompilerTest
Running org.apache.phoenix.query.PhoenixStatsCacheRemovalListenerTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.phoenix.query.PhoenixStatsCacheRemovalListenerTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.082 sec - in org.apache.phoenix.hbase.index.write.TestParalleIndexWriter
Running org.apache.phoenix.query.KeyRangeCoalesceTest
Running org.apache.phoenix.query.KeyRangeUnionTest
Tests run: 6, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 0.117 sec - in org.apache.phoenix.memory.MemoryManagerTest
Running org.apache.phoenix.query.ConnectionlessTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.046 sec - in org.apache.phoenix.query.KeyRangeUnionTest
Running org.apache.phoenix.query.HBaseFactoryProviderTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.phoenix.query.HBaseFactoryProviderTest
Running org.apache.phoenix.query.ScannerLeaseRenewalTest
Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.075 sec - in org.apache.phoenix.query.KeyRangeCoalesceTest
Running org.apache.phoenix.query.ParallelIteratorsSplitTest
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.353 sec - in org.apache.phoenix.query.OrderByTest
Running org.apache.phoenix.query.KeyRangeIntersectTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.07 sec - in org.apache.phoenix.query.KeyRangeIntersectTest
Running org.apache.phoenix.query.QueryPlanTest
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.39 sec - in org.apache.phoenix.query.ParallelIteratorsSplitTest
Running org.apache.hadoop.hbase.regionserver.PhoenixRpcSchedulerFactoryTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.468 sec - in org.apache.phoenix.query.ConnectionlessTest
Running org.apache.hadoop.hbase.ipc.PhoenixIndexRpcSchedulerTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.918 sec - in org.apache.phoenix.hbase.index.write.recovery.TestPerRegionIndexWriteCache
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.046 sec - in org.apache.hadoop.hbase.regionserver.PhoenixRpcSchedulerFactoryTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.345 sec - in org.apache.hadoop.hbase.ipc.PhoenixIndexRpcSchedulerTest
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.566 sec - in org.apache.phoenix.query.QueryPlanTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.929 sec - in org.apache.phoenix.query.ScannerLeaseRenewalTest
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.918 sec - in org.apache.phoenix.jdbc.SecureUserConnectionsTest
Results :
Failed tests:
MutationTest.testSizeConstraint:87
Tests run: 1430, Failures: 1, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Apache Phoenix ..................................... SUCCESS [ 2.468 s]
[INFO] Phoenix Core ....................................... FAILURE [01:31 min]
[INFO] Phoenix - Flume .................................... SKIPPED
[INFO] Phoenix - Pig ...................................... SKIPPED
[INFO] Phoenix Query Server Client ........................ SKIPPED
[INFO] Phoenix Query Server ............................... SKIPPED
[INFO] Phoenix - Pherf .................................... SKIPPED
[INFO] Phoenix - Spark .................................... SKIPPED
[INFO] Phoenix - Hive ..................................... SKIPPED
[INFO] Phoenix Client ..................................... SKIPPED
[INFO] Phoenix Server ..................................... SKIPPED
[INFO] Phoenix Assembly ................................... SKIPPED
[INFO] Phoenix - Tracing Web Application .................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 01:35 min
[INFO] Finished at: 2016-10-28T20:40:01+00:00
[INFO] Final Memory: 80M/1331M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19.1:test (default-test) on project phoenix-core: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Phoenix-master/ws/phoenix-core/target/surefire-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :phoenix-core
Build step 'Invoke top-level Maven targets' marked build as failure
Archiving artifacts
Compressed 658.20 MB of artifacts by 99.7% relative to #1455
Updating PHOENIX-3375
Recording test results