[ https://issues.apache.org/jira/browse/HIVE-23221?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Miklos Gergely updated HIVE-23221: ---------------------------------- Description: These tests are failing in an indeterministic fashion with such error messages as the one attached. (was: These tests are failing in an indeterministic fashion with such error messages: {code:java} Error MessageNo thread with name metastore_task_thread_test_impl_1 found.Stacktracejava.lang.AssertionError: No thread with name metastore_task_thread_test_impl_1 found. at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.apache.hadoop.hive.metastore.TestMetastoreHousekeepingLeaderEmptyConfig.testHouseKeepingThreadExistence(TestMetastoreHousekeepingLeaderEmptyConfig.java:51) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:379) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:340) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:125) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:413) Standard OutputFormatting using clusterid: testClusterID Starting MetaStore Server on port 57893 Standard ErrorSLF4J: Class path contains multiple SLF4J bindings. SLF4J: Found binding in [jar:file:/home/hiveptest/35.224.55.83-hiveptest-2/maven/org/apache/logging/log4j/log4j-slf4j-impl/2.12.1/log4j-slf4j-impl-2.12.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] SLF4J: Found binding in [jar:file:/home/hiveptest/35.224.55.83-hiveptest-2/maven/org/slf4j/slf4j-log4j12/1.7.25/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] DEBUG StatusLogger Using ShutdownCallbackRegistry class org.apache.logging.log4j.core.util.DefaultShutdownCallbackRegistry DEBUG StatusLogger Took 0.073789 seconds to load 222 plugins from sun.misc.Launcher$AppClassLoader@135fbaa4 DEBUG StatusLogger PluginManager 'Converter' found 44 plugins DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false-1 DEBUG StatusLogger Starting LoggerContext[name=135fbaa4, org.apache.logging.log4j.core.LoggerContext@223191a6]... DEBUG StatusLogger Reconfiguration started for context[name=135fbaa4] at URI null (org.apache.logging.log4j.core.LoggerContext@223191a6) with optional ClassLoader: null DEBUG StatusLogger PluginManager 'ConfigurationFactory' found 4 plugins DEBUG StatusLogger Missing dependencies for Yaml support, ConfigurationFactory org.apache.logging.log4j.core.config.yaml.YamlConfigurationFactory is inactive DEBUG StatusLogger Using configurationFactory org.apache.logging.log4j.core.config.ConfigurationFactory$Factory@4135c3b DEBUG StatusLogger Apache Log4j Core 2.12.1 initializing configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@557caf28 DEBUG StatusLogger Installed 3 script engines DEBUG StatusLogger Scala Interpreter version: 1.0, language: Scala, threading: Not Thread Safe, compile: true, names: [scala], factory class: scala.tools.nsc.interpreter.IMain$Factory DEBUG StatusLogger Groovy Scripting Engine version: 2.0, language: Groovy, threading: MULTITHREADED, compile: true, names: [groovy, Groovy], factory class: org.codehaus.groovy.jsr223.GroovyScriptEngineFactory DEBUG StatusLogger Oracle Nashorn version: 1.8.0_102, language: ECMAScript, threading: Not Thread Safe, compile: true, names: [nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript], factory class: jdk.nashorn.api.scripting.NashornScriptEngineFactory INFO StatusLogger Scanning for classes in '/home/hiveptest/35.224.55.83-hiveptest-2/maven/org/apache/hive/hive-common/4.0.0-SNAPSHOT/hive-common-4.0.0-SNAPSHOT.jar' matching criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.PerfLogger matches criteria annotated with @Plugin INFO StatusLogger Scanning for classes in '/home/hiveptest/35.224.55.83-hiveptest-2/maven/org/apache/hive/hive-exec/4.0.0-SNAPSHOT/hive-exec-4.0.0-SNAPSHOT.jar' matching criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.HiveEventCounter$1 matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.LogDivertAppenderForTest matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.syslog.SyslogSerDe matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.syslog.SyslogInputFormat$Location matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.syslog.SyslogInputFormat matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.syslog.SyslogParser matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.syslog.SyslogStorageHandler matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.LogDivertAppenderForTest$TestFilter matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.LogDivertAppender matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.PidFilePatternConverter matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.HiveEventCounter matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.HiveEventCounter$EventCounts matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.NullAppender matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.HushableRandomAccessFileAppender$1 matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.LogDivertAppender$NameFilter matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.SlidingFilenameRolloverStrategy$1 matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.SlidingFilenameRolloverStrategy matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.HushableRandomAccessFileAppender matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.PerfLogger matches criteria annotated with @Plugin INFO StatusLogger Scanning for classes in '/home/hiveptest/35.224.55.83-hiveptest-2/maven/org/apache/hive/hive-exec/4.0.0-SNAPSHOT/hive-exec-4.0.0-SNAPSHOT-tests.jar' matching criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.TestLog4j2Appenders matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.TestSlidingFilenameRolloverStrategy matches criteria annotated with @Plugin DEBUG StatusLogger Checking to see if class org.apache.hadoop.hive.ql.log.TestSyslogInputFormat matches criteria annotated with @Plugin DEBUG StatusLogger Took 0.061118 seconds to load 7 plugins from package org.apache.hadoop.hive.ql.log DEBUG StatusLogger PluginManager 'Core' found 129 plugins DEBUG StatusLogger PluginManager 'Level' found 0 plugins DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property]. TRACE StatusLogger TypeConverterRegistry initializing. DEBUG StatusLogger PluginManager 'TypeConverter' found 26 plugins DEBUG StatusLogger createProperty(name="hive.log.file", value="hive.log") DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property]. DEBUG StatusLogger createProperty(name="hive.log.dir", value="/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log") DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property]. DEBUG StatusLogger createProperty(name="hive.root.logger", value="DRFA") DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property]. DEBUG StatusLogger createProperty(name="hive.log.level", value="DEBUG") DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property]. DEBUG StatusLogger createProperty(name="hive.test.console.log.level", value="INFO") DEBUG StatusLogger Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin]. DEBUG StatusLogger configureSubstitutor(={hive.log.file=hive.log, hive.log.dir=/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log, hive.root.logger=DRFA, hive.log.level=DEBUG, hive.test.console.log.level=INFO}, Configuration(HiveLog4j2Test)) DEBUG StatusLogger PluginManager 'Lookup' found 14 plugins DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.hadoop.ipc", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.security", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.hdfs", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.hadoop.hdfs.server", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.metrics2", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.mortbay", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.yarn", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.hadoop.yarn.server", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.tez", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="ERROR", name="org.apache.hadoop.conf.Configuration", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.zookeeper", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.zookeeper.server.ServerCnxn", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.zookeeper.server.NIOServerCnxn", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.zookeeper.ClientCnxn", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.zookeeper.ClientCnxnSocket", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.zookeeper.ClientCnxnSocketNIO", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="ERROR", name="DataNucleus", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="ERROR", name="Datastore", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="ERROR", name="JPOX", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.hive.ql.exec.Operator", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.hive.serde2.lazy", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.hadoop.hive.metastore.ObjectStore", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.calcite.plan.RelOptPlanner", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="com.amazonaws", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="INFO", name="org.apache.http", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.apache.thrift", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="org.eclipse.jetty", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig]. DEBUG StatusLogger createLogger(additivity="true", level="WARN", name="BlockStateChange", includeLocation="null", ={}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. DEBUG StatusLogger createAppenderRef(ref="console", level="INFO", Filter=null) DEBUG StatusLogger Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. DEBUG StatusLogger createAppenderRef(ref="DRFA", level="null", Filter=null) DEBUG StatusLogger Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger]. DEBUG StatusLogger createLogger(additivity="null", level="DEBUG", includeLocation="null", ={console, DRFA}, ={}, Configuration(HiveLog4j2Test), Filter=null) DEBUG StatusLogger Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin]. DEBUG StatusLogger createLoggers(={org.apache.hadoop.ipc, org.apache.hadoop.security, org.apache.hadoop.hdfs, org.apache.hadoop.hdfs.server, org.apache.hadoop.metrics2, org.mortbay, org.apache.hadoop.yarn, org.apache.hadoop.yarn.server, org.apache.tez, org.apache.hadoop.conf.Configuration, org.apache.zookeeper, org.apache.zookeeper.server.ServerCnxn, org.apache.zookeeper.server.NIOServerCnxn, org.apache.zookeeper.ClientCnxn, org.apache.zookeeper.ClientCnxnSocket, org.apache.zookeeper.ClientCnxnSocketNIO, DataNucleus, Datastore, JPOX, org.apache.hadoop.hive.ql.exec.Operator, org.apache.hadoop.hive.serde2.lazy, org.apache.hadoop.hive.metastore.ObjectStore, org.apache.calcite.plan.RelOptPlanner, com.amazonaws, org.apache.http, org.apache.thrift, org.eclipse.jetty, BlockStateChange, root}) DEBUG StatusLogger Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout]. DEBUG StatusLogger PatternLayout$Builder(pattern="%d{ISO8601} %5p [%t] %c{2}: %m%n", PatternSelector=null, Configuration(HiveLog4j2Test), Replace=null, charset="null", alwaysWriteExceptions="null", disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null") DEBUG StatusLogger PluginManager 'Converter' found 44 plugins DEBUG StatusLogger Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender]. DEBUG StatusLogger ConsoleAppender$Builder(target="SYSTEM_ERR", follow="null", direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{ISO8601} %5p [%t] %c{2}: %m%n), name="console", Configuration(HiveLog4j2Test), Filter=null, ={}) DEBUG StatusLogger Starting OutputStreamManager SYSTEM_ERR.false.false DEBUG StatusLogger Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout]. DEBUG StatusLogger PatternLayout$Builder(pattern="%d{ISO8601} %5p [%t] %c{2}: %m%n", PatternSelector=null, Configuration(HiveLog4j2Test), Replace=null, charset="null", alwaysWriteExceptions="null", disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null") DEBUG StatusLogger Building Plugin[name=TimeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.TimeBasedTriggeringPolicy]. DEBUG StatusLogger TimeBasedTriggeringPolicy$Builder(interval="1", modulate="true", maxRandomDelay="null") DEBUG StatusLogger Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy]. DEBUG StatusLogger createPolicy(={TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)}) DEBUG StatusLogger Building Plugin[name=DefaultRolloverStrategy, class=org.apache.logging.log4j.core.appender.rolling.DefaultRolloverStrategy]. DEBUG StatusLogger DefaultRolloverStrategy$Builder(max="30", min="null", fileIndex="null", compressionLevel="null", ={}, stopCustomActionsOnError="null", tempCompressedFilePattern="null", Configuration(HiveLog4j2Test)) DEBUG StatusLogger Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender]. DEBUG StatusLogger RollingRandomAccessFileAppender$Builder(fileName="/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log/hive.log", filePattern="/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log/hive.log.%d{yyyy-MM-dd}", append="null", Policies(CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)])), DefaultRolloverStrategy(DefaultRolloverStrategy(min=1, max=30, useMax=true)), advertise="null", advertiseURI="null", filePermissions="null", fileOwner="null", fileGroup="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{ISO8601} %5p [%t] %c{2}: %m%n), name="DRFA", Configuration(HiveLog4j2Test), Filter=null, ={}) TRACE StatusLogger RandomAccessFile /home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log/hive.log seek to 0 DEBUG StatusLogger Starting RollingRandomAccessFileManager /home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/log/hive.log DEBUG StatusLogger PluginManager 'FileConverter' found 3 plugins DEBUG StatusLogger Setting prev file time to 2020-04-15T20:53:05.716-0700 DEBUG StatusLogger Initializing triggering policy CompositeTriggeringPolicy(policies=[TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true)]) DEBUG StatusLogger Initializing triggering policy TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=true) TRACE StatusLogger PatternProcessor.getNextTime returning 2020/04/16-00:00:00.000, nextFileTime=2020/04/15-00:00:00.000, prevFileTime=1969/12/31-16:00:00.000, current=2020/04/15-20:53:05.728, freq=DAILY TRACE StatusLogger PatternProcessor.getNextTime returning 2020/04/16-00:00:00.000, nextFileTime=2020/04/15-00:00:00.000, prevFileTime=2020/04/15-00:00:00.000, current=2020/04/15-20:53:05.729, freq=DAILY DEBUG StatusLogger Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin]. DEBUG StatusLogger createAppenders(={console, DRFA}) DEBUG StatusLogger Configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@557caf28 initialized DEBUG StatusLogger Starting configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@557caf28 DEBUG StatusLogger Started configuration org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@557caf28 OK. TRACE StatusLogger Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@5a4aa2f2... TRACE StatusLogger DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped. TRACE StatusLogger DefaultConfiguration stopping root LoggerConfig. TRACE StatusLogger DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped. TRACE StatusLogger DefaultConfiguration stopping remaining Appenders. DEBUG StatusLogger Shutting down OutputStreamManager SYSTEM_OUT.false.false-1 DEBUG StatusLogger Shut down OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true DEBUG StatusLogger Appender DefaultConsole-1 stopped with status true TRACE StatusLogger DefaultConfiguration stopped 1 remaining Appenders. TRACE StatusLogger DefaultConfiguration cleaning Appenders from 1 LoggerConfigs. DEBUG StatusLogger Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@5a4aa2f2 OK TRACE StatusLogger Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@76b0ae1b TRACE StatusLogger Reregistering context (1/1): '135fbaa4' org.apache.logging.log4j.core.LoggerContext@223191a6 TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=StatusLogger' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=ContextSelector' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=*' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=Appenders,name=*' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=AsyncAppenders,name=*' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=AsyncLoggerRingBuffer' TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=*,subtype=RingBuffer' DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4 DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=StatusLogger DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=ContextSelector DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name= DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.tez DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=com.amazonaws DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.thrift DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.eclipse.jetty DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=BlockStateChange DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.metrics2 DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper.ClientCnxn DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.security DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=JPOX DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.yarn.server DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.conf.Configuration DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.yarn DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocketNIO DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper.server.ServerCnxn DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.hdfs DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.hdfs.server DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.hive.serde2.lazy DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper.ClientCnxnSocket DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.mortbay DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.http DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.hive.ql.exec.Operator DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=DataNucleus DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=Datastore DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.hive.metastore.ObjectStore DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.hadoop.ipc DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.zookeeper.server.NIOServerCnxn DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Loggers,name=org.apache.calcite.plan.RelOptPlanner DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Appenders,name=console DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=135fbaa4,component=Appenders,name=DRFA TRACE StatusLogger Using default SystemClock for timestamps. DEBUG StatusLogger org.apache.logging.log4j.core.util.SystemClock does not support precise timestamps. TRACE StatusLogger Using DummyNanoClock for nanosecond timestamps. DEBUG StatusLogger Reconfiguration complete for context[name=135fbaa4] at URI /home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/testconf/hive-log4j2.properties (org.apache.logging.log4j.core.LoggerContext@223191a6) with optional ClassLoader: null DEBUG StatusLogger Shutdown hook enabled. Registering a new one. DEBUG StatusLogger LoggerContext[name=135fbaa4, org.apache.logging.log4j.core.LoggerContext@223191a6] started OK. 2020-04-15T20:53:05,844 INFO [main] conf.MetastoreConf: Found configuration file: file:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/testconf/hive-site.xml DEBUG StatusLogger AsyncLogger.ThreadNameStrategy=UNCACHED (user specified null, default is UNCACHED) TRACE StatusLogger Using default SystemClock for timestamps. DEBUG StatusLogger org.apache.logging.log4j.core.util.SystemClock does not support precise timestamps. 2020-04-15T20:53:06,020 WARN [main] util.NativeCodeLoader: Unable to load native-hadoop library for your platform... using builtin-java classes where applicable 2020-04-15T20:53:06,104 INFO [main] conf.MetastoreConf: Found configuration file: file:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/testconf/hivemetastore-site.xml 2020-04-15T20:53:06,105 INFO [main] conf.MetastoreConf: Unable to find config file: metastore-site.xml 2020-04-15T20:53:06,202 INFO [main] hdfs.MiniDFSCluster: starting cluster: numNameNodes=1, numDataNodes=1 2020-04-15T20:53:06,819 INFO [main] util.GSet: Computing capacity for map BlocksMap 2020-04-15T20:53:06,820 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:06,822 INFO [main] util.GSet: 2.0% max memory 1.8 GB = 36.4 MB 2020-04-15T20:53:06,822 INFO [main] util.GSet: capacity = 2^22 = 4194304 entries 2020-04-15T20:53:06,918 INFO [main] util.GSet: Computing capacity for map INodeMap 2020-04-15T20:53:06,918 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:06,918 INFO [main] util.GSet: 1.0% max memory 1.8 GB = 18.2 MB 2020-04-15T20:53:06,918 INFO [main] util.GSet: capacity = 2^21 = 2097152 entries 2020-04-15T20:53:06,940 INFO [main] util.GSet: Computing capacity for map cachedBlocks 2020-04-15T20:53:06,940 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:06,941 INFO [main] util.GSet: 0.25% max memory 1.8 GB = 4.6 MB 2020-04-15T20:53:06,941 INFO [main] util.GSet: capacity = 2^19 = 524288 entries 2020-04-15T20:53:06,966 INFO [main] util.GSet: Computing capacity for map NameNodeRetryCache 2020-04-15T20:53:06,966 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:06,967 INFO [main] util.GSet: 0.029999999329447746% max memory 1.8 GB = 559.3 KB 2020-04-15T20:53:06,967 INFO [main] util.GSet: capacity = 2^16 = 65536 entries 2020-04-15T20:53:07,523 INFO [main] beanutils.FluentPropertyBeanIntrospector: Error when creating PropertyDescriptor for public final void org.apache.commons.configuration2.AbstractConfiguration.setProperty(java.lang.String,java.lang.Object)! Ignoring this property. 2020-04-15T20:53:07,559 WARN [main] impl.MetricsConfig: Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties 2020-04-15T20:53:07,586 INFO [main] impl.MetricsSystemImpl: Scheduled Metric snapshot period at 10 second(s). 2020-04-15T20:53:07,587 INFO [main] impl.MetricsSystemImpl: NameNode metrics system started 2020-04-15T20:53:07,673 INFO [org.apache.hadoop.util.JvmPauseMonitor$Monitor@5aaaa446] util.JvmPauseMonitor: Starting JVM pause monitor 2020-04-15T20:53:07,688 INFO [main] hdfs.DFSUtil: Starting Web-server for hdfs at: http://localhost:0 2020-04-15T20:53:07,859 INFO [main] server.AuthenticationFilter: Unable to initialize FileSignerSecretProvider, falling back to use random secrets. 2020-04-15T20:53:07,869 WARN [main] http.HttpRequestLog: Jetty request log can only be enabled using Log4j 2020-04-15T20:53:07,888 INFO [main] http.HttpServer2: Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter) 2020-04-15T20:53:07,893 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context hdfs 2020-04-15T20:53:07,894 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static 2020-04-15T20:53:07,894 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context logs 2020-04-15T20:53:07,932 INFO [main] http.HttpServer2: Added filter 'org.apache.hadoop.hdfs.web.AuthFilter' (class=org.apache.hadoop.hdfs.web.AuthFilter) 2020-04-15T20:53:07,932 INFO [main] http.HttpServer2: addJerseyResourcePackage: packageName=org.apache.hadoop.hdfs.server.namenode.web.resources;org.apache.hadoop.hdfs.web.resources, pathSpec=/webhdfs/v1/* 2020-04-15T20:53:07,944 INFO [main] http.HttpServer2: Jetty bound to port 60465 2020-04-15T20:53:08,213 INFO [main] util.GSet: Computing capacity for map BlocksMap 2020-04-15T20:53:08,213 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:08,214 INFO [main] util.GSet: 2.0% max memory 1.8 GB = 36.4 MB 2020-04-15T20:53:08,214 INFO [main] util.GSet: capacity = 2^22 = 4194304 entries 2020-04-15T20:53:08,217 INFO [main] util.GSet: Computing capacity for map INodeMap 2020-04-15T20:53:08,217 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:08,217 INFO [main] util.GSet: 1.0% max memory 1.8 GB = 18.2 MB 2020-04-15T20:53:08,217 INFO [main] util.GSet: capacity = 2^21 = 2097152 entries 2020-04-15T20:53:08,219 INFO [main] util.GSet: Computing capacity for map cachedBlocks 2020-04-15T20:53:08,219 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:08,220 INFO [main] util.GSet: 0.25% max memory 1.8 GB = 4.6 MB 2020-04-15T20:53:08,220 INFO [main] util.GSet: capacity = 2^19 = 524288 entries 2020-04-15T20:53:08,221 INFO [main] util.GSet: Computing capacity for map NameNodeRetryCache 2020-04-15T20:53:08,221 INFO [main] util.GSet: VM type = 64-bit 2020-04-15T20:53:08,221 INFO [main] util.GSet: 0.029999999329447746% max memory 1.8 GB = 559.3 KB 2020-04-15T20:53:08,221 INFO [main] util.GSet: capacity = 2^16 = 65536 entries 2020-04-15T20:53:08,828 INFO [main] hdfs.StateChange: STATE* Leaving safe mode after 0 secs 2020-04-15T20:53:08,828 INFO [main] hdfs.StateChange: STATE* Network topology has 0 racks and 0 datanodes 2020-04-15T20:53:08,828 INFO [main] hdfs.StateChange: STATE* UnderReplicatedBlocks has 0 blocks 2020-04-15T20:53:08,835 INFO [Reconstruction Queue Initializer] hdfs.StateChange: STATE* Replication Queue initialization scan for invalid, over- and under-replicated blocks completed in 7 msec 2020-04-15T20:53:08,878 WARN [main] common.MetricsLoggerTask: Metrics logging will not be async since the logger is not log4j 2020-04-15T20:53:08,896 INFO [main] hdfs.MiniDFSCluster: Starting DataNode 0 with dfs.datanode.data.dir: [DISK]file:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data1,[DISK]file:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data2 2020-04-15T20:53:09,075 INFO [main] impl.MetricsSystemImpl: DataNode metrics system started (again) 2020-04-15T20:53:09,118 INFO [main] server.AuthenticationFilter: Unable to initialize FileSignerSecretProvider, falling back to use random secrets. 2020-04-15T20:53:09,120 WARN [main] http.HttpRequestLog: Jetty request log can only be enabled using Log4j 2020-04-15T20:53:09,121 INFO [main] http.HttpServer2: Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter) 2020-04-15T20:53:09,122 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context datanode 2020-04-15T20:53:09,123 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context logs 2020-04-15T20:53:09,123 INFO [main] http.HttpServer2: Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static 2020-04-15T20:53:09,131 INFO [main] http.HttpServer2: Jetty bound to port 60676 2020-04-15T20:53:09,477 INFO [org.apache.hadoop.util.JvmPauseMonitor$Monitor@e9474f] util.JvmPauseMonitor: Starting JVM pause monitor 2020-04-15T20:53:09,542 WARN [main] common.MetricsLoggerTask: Metrics logging will not be async since the logger is not log4j 2020-04-15T20:53:10,405 INFO [IPC Server handler 1 on 52650] hdfs.StateChange: BLOCK* registerDatanode: from DatanodeRegistration(127.0.0.1:49891, datanodeUuid=b6489697-d507-4259-af95-984cde3077a6, infoPort=35025, infoSecurePort=0, ipcPort=49729, storageInfo=lv=-57;cid=testClusterID;nsid=1271801433;c=1587009186993) storage b6489697-d507-4259-af95-984cde3077a6 2020-04-15T20:53:10,407 INFO [IPC Server handler 1 on 52650] net.NetworkTopology: Adding a new node: /default-rack/127.0.0.1:49891 2020-04-15T20:53:10,447 INFO [main] hdfs.MiniDFSCluster: No heartbeat from DataNode: 127.0.0.1:49891 2020-04-15T20:53:10,447 INFO [main] hdfs.MiniDFSCluster: Waiting for cluster to become active 2020-04-15T20:53:10,553 INFO [main] hdfs.MiniDFSCluster: Cluster is active 2020-04-15T20:53:10,827 INFO [MetaStoreThread-57893] metastore.AuthFactory: Using authentication NOSASL with kerberos authentication disabled 2020-04-15T20:53:11,007 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: 0: Opening raw store with implementation class:org.apache.hadoop.hive.metastore.ObjectStore 2020-04-15T20:53:11,062 INFO [MetaStoreThread-57893] metastore.PersistenceManagerProvider: Configuration datanucleus.autoStartMechanismMode is not set. Defaulting to 'ignored' 2020-04-15T20:53:11,071 INFO [MetaStoreThread-57893] metastore.PersistenceManagerProvider: Updating the pmf due to property change 2020-04-15T20:53:11,071 INFO [MetaStoreThread-57893] metastore.PersistenceManagerProvider: Current pmf properties are uninitialized 2020-04-15T20:53:11,115 INFO [MetaStoreThread-57893] hikari.HikariDataSource: HikariPool-1 - Starting... 2020-04-15T20:53:12,461 INFO [MetaStoreThread-57893] pool.PoolBase: HikariPool-1 - Driver does not support get/set network timeout for connections. (Feature not implemented: No details.) 2020-04-15T20:53:12,467 INFO [MetaStoreThread-57893] hikari.HikariDataSource: HikariPool-1 - Start completed. 2020-04-15T20:53:13,319 INFO [MetaStoreThread-57893] metastore.PersistenceManagerProvider: Setting MetaStore object pin classes with hive.metastore.cache.pinobjtypes="Table,StorageDescriptor,SerDeInfo,Partition,Database,Type,FieldSchema,Order" 2020-04-15T20:53:13,320 INFO [MetaStoreThread-57893] metastore.ObjectStore: RawStore: org.apache.hadoop.hive.metastore.ObjectStore@5c54cd77, with PersistenceManager: null will be shutdown 2020-04-15T20:53:13,358 INFO [MetaStoreThread-57893] metastore.ObjectStore: RawStore: org.apache.hadoop.hive.metastore.ObjectStore@5c54cd77, with PersistenceManager: org.datanucleus.api.jdo.JDOPersistenceManager@f408f44 created in the thread with id: 133 2020-04-15T20:53:18,269 WARN [MetaStoreThread-57893] metastore.ObjectStore: Version information not found in metastore. metastore.schema.verification is not enabled so recording the schema version 4.0.0 2020-04-15T20:53:18,269 WARN [MetaStoreThread-57893] metastore.ObjectStore: setMetaStoreSchemaVersion called but recording version is disabled: version = 4.0.0, comment = Set by MetaStore hiveptest@10.128.0.72 2020-04-15T20:53:18,269 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Created RawStore: org.apache.hadoop.hive.metastore.ObjectStore@5c54cd77 from thread id: 133 2020-04-15T20:53:18,623 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Added admin role in metastore 2020-04-15T20:53:18,626 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Added public role in metastore 2020-04-15T20:53:18,836 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Added hive_admin_user to admin role 2020-04-15T20:53:18,848 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: HMS server filtering is disabled by configuration 2020-04-15T20:53:19,103 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Starting DB backed MetaStore Server with SetUGI enabled 2020-04-15T20:53:19,103 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Binding host localhost for metastore server 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Started the new metaserver on port [57893]... 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Options.minWorkerThreads = 200 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Options.maxWorkerThreads = 1000 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: TCP keepalive = true 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Enable SSL = false 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Compaction HMS parameters: 2020-04-15T20:53:19,110 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.initiator.on = true 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.worker.threads = 1 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: hive.metastore.runworker.in = metastore 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.history.reaper.interval = 2 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.history.retention.attempted = 2 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.history.retention.failed = 3 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.history.retention.succeeded = 3 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.initiator.failed.compacts.threshold = 2 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.compactor.enable.stats.compression 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: Direct SQL optimization = true 2020-04-15T20:53:19,111 INFO [MetaStoreThread-57893] metastore.HiveMetaStore: metastore.housekeeping.leader.hostname is empty. Start all the housekeeping threads. 2020-04-15T20:53:19,774 INFO [pool-23-thread-1] HiveMetaStore.audit: ugi=hiveptest ip=127.0.0.1 cmd=Done cleaning up thread local RawStore 2020-04-15T20:53:19,782 INFO [main] metastore.MetaStoreTestUtils: MetaStore warehouse root dir (pfile:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/warehouse/57893) is created 2020-04-15T20:53:19,782 INFO [main] metastore.MetaStoreTestUtils: MetaStore Thrift Server started on port: 57893 with warehouse dir: pfile:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/warehouse/57893 with jdbcUrl: jdbc:derby:;databaseName=/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/junit_metastore_db_57893;create=true 2020-04-15T20:53:19,793 INFO [main] metastore.HiveMetaStoreClient: HMS client filtering is enabled. 2020-04-15T20:53:19,795 INFO [main] metastore.HiveMetaStoreClient: Trying to connect to metastore with URI (thrift://localhost:57893) 2020-04-15T20:53:19,813 INFO [main] metastore.HiveMetaStoreClient: Opened a connection to metastore, URI (thrift://localhost:57893) current connections: 1 2020-04-15T20:53:19,814 INFO [main] metastore.HiveMetaStoreClient: Connected to metastore. 2020-04-15T20:53:20,122 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Starting metastore thread of type org.apache.hadoop.hive.ql.txn.compactor.Initiator 2020-04-15T20:53:20,388 INFO [Metastore threads starter thread] conf.HiveConf: Found configuration file file:/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/testconf/hive-site.xml 2020-04-15T20:53:20,807 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.size does not exist 2020-04-15T20:53:20,807 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.override does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.metadb.dir does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.min does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.hivesite does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.max does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.runworker.in does not exist 2020-04-15T20:53:20,808 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.version does not exist 2020-04-15T20:53:20,809 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.root does not exist 2020-04-15T20:53:20,809 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.metastoresite does not exist 2020-04-15T20:53:20,810 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.arena.size does not exist 2020-04-15T20:53:20,810 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.stats.key.prefix.reserve.length does not exist 2020-04-15T20:53:20,810 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.test.console.log.level does not exist 2020-04-15T20:53:20,840 INFO [Metastore threads starter thread] txn.TxnHandler: Hacking in canned values for transaction manager 2020-04-15T20:53:23,565 INFO [Metastore threads starter thread] txn.TxnDbUtil: TBLS table already exist, ignoring 2020-04-15T20:53:23,605 INFO [Metastore threads starter thread] txn.TxnDbUtil: TBLS table already exist, ignoring 2020-04-15T20:53:23,621 INFO [Metastore threads starter thread] txn.TxnDbUtil: PARTITIONS table already exist, ignoring 2020-04-15T20:53:23,637 INFO [Metastore threads starter thread] txn.TxnDbUtil: TABLE_PARAMS table already exist, ignoring 2020-04-15T20:53:23,653 INFO [Metastore threads starter thread] txn.TxnDbUtil: PARTITION_PARAMS table already exist, ignoring 2020-04-15T20:53:23,678 INFO [Metastore threads starter thread] txn.TxnDbUtil: SEQUENCE_TABLE table already exist, ignoring 2020-04-15T20:53:23,695 INFO [Metastore threads starter thread] txn.TxnDbUtil: NOTIFICATION_SEQUENCE table already exist, ignoring 2020-04-15T20:53:23,710 INFO [Metastore threads starter thread] txn.TxnDbUtil: NOTIFICATION_LOG table already exist, ignoring 2020-04-15T20:53:23,800 INFO [Metastore threads starter thread] hikari.HikariDataSource: HikariPool-2 - Starting... 2020-04-15T20:53:23,805 INFO [Metastore threads starter thread] pool.PoolBase: HikariPool-2 - Driver does not support get/set network timeout for connections. (Feature not implemented: No details.) 2020-04-15T20:53:23,809 INFO [Metastore threads starter thread] hikari.HikariDataSource: HikariPool-2 - Start completed. 2020-04-15T20:53:23,814 INFO [Metastore threads starter thread] hikari.HikariDataSource: HikariPool-3 - Starting... 2020-04-15T20:53:23,821 INFO [Metastore threads starter thread] pool.PoolBase: HikariPool-3 - Driver does not support get/set network timeout for connections. (Feature not implemented: No details.) 2020-04-15T20:53:23,822 INFO [Metastore threads starter thread] hikari.HikariDataSource: HikariPool-3 - Start completed. 2020-04-15T20:53:23,823 INFO [Metastore threads starter thread] metastore.HiveMetaStore: This HMS instance will act as a Compactor Initiator. 2020-04-15T20:53:23,826 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Starting metastore thread of type org.apache.hadoop.hive.ql.txn.compactor.Cleaner 2020-04-15T20:53:23,924 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.size does not exist 2020-04-15T20:53:23,925 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.override does not exist 2020-04-15T20:53:23,928 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.metadb.dir does not exist 2020-04-15T20:53:23,928 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.min does not exist 2020-04-15T20:53:23,932 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.hivesite does not exist 2020-04-15T20:53:23,932 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.max does not exist 2020-04-15T20:53:23,932 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.runworker.in does not exist 2020-04-15T20:53:23,933 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.version does not exist 2020-04-15T20:53:23,933 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.root does not exist 2020-04-15T20:53:23,934 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.metastoresite does not exist 2020-04-15T20:53:23,934 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.arena.size does not exist 2020-04-15T20:53:23,934 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.stats.key.prefix.reserve.length does not exist 2020-04-15T20:53:23,941 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.test.console.log.level does not exist 2020-04-15T20:53:23,949 INFO [Metastore threads starter thread] txn.TxnHandler: Hacking in canned values for transaction manager 2020-04-15T20:53:24,029 INFO [Metastore threads starter thread] txn.TxnDbUtil: Txn tables already exist, returning 2020-04-15T20:53:24,041 INFO [Metastore threads starter thread] metastore.HiveMetaStore: This HMS instance will act as a Compactor Cleaner. 2020-04-15T20:53:24,050 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Scheduling for org.apache.hadoop.hive.metastore.RemoteMetastoreTaskThreadTestImpl2 service. 2020-04-15T20:53:24,071 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Scheduling for org.apache.hadoop.hive.metastore.RemoteMetastoreTaskThreadTestImpl1 service. 2020-04-15T20:53:24,091 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Starting metastore thread of type org.apache.hadoop.hive.ql.stats.StatsUpdaterThread 2020-04-15T20:53:24,092 INFO [Metastore threads starter thread] txn.TxnHandler: Hacking in canned values for transaction manager 2020-04-15T20:53:24,127 INFO [Thread-101] txn.CompactionTxnHandler: Removed 0 rows from TXN_TO_WRITE_ID with Txn Low-Water-Mark: 1 2020-04-15T20:53:24,144 INFO [Metastore threads starter thread] txn.TxnDbUtil: Txn tables already exist, returning 2020-04-15T20:53:24,144 INFO [Metastore threads starter thread] metastore.PersistenceManagerProvider: Configuration datanucleus.autoStartMechanismMode is not set. Defaulting to 'ignored' 2020-04-15T20:53:24,149 INFO [Metastore threads starter thread] metastore.ObjectStore: RawStore: org.apache.hadoop.hive.metastore.ObjectStore@3c2c9210, with PersistenceManager: null will be shutdown 2020-04-15T20:53:24,150 INFO [Metastore threads starter thread] metastore.ObjectStore: RawStore: org.apache.hadoop.hive.metastore.ObjectStore@3c2c9210, with PersistenceManager: org.datanucleus.api.jdo.JDOPersistenceManager@6f2db3de created in the thread with id: 142 2020-04-15T20:53:24,272 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.size does not exist 2020-04-15T20:53:24,273 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.override does not exist 2020-04-15T20:53:24,273 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.metadb.dir does not exist 2020-04-15T20:53:24,273 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.min does not exist 2020-04-15T20:53:24,273 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.hivesite does not exist 2020-04-15T20:53:24,273 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.max does not exist 2020-04-15T20:53:24,274 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.runworker.in does not exist 2020-04-15T20:53:24,274 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.version does not exist 2020-04-15T20:53:24,274 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.root does not exist 2020-04-15T20:53:24,274 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.metastoresite does not exist 2020-04-15T20:53:24,275 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.arena.size does not exist 2020-04-15T20:53:24,275 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.stats.key.prefix.reserve.length does not exist 2020-04-15T20:53:24,275 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.test.console.log.level does not exist 2020-04-15T20:53:24,276 INFO [Thread-113] stats.StatsUpdaterThread: Stats updater thread started 2020-04-15T20:53:24,276 INFO [Thread-113] stats.StatsUpdaterThread: Stats updater worker thread Stats updater worker 0 started 2020-04-15T20:53:24,279 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Scheduling for org.apache.hadoop.hive.metastore.MetastoreTaskThreadAlwaysTestImpl service with frequency 7000ms. 2020-04-15T20:53:24,281 INFO [Metastore threads starter thread] metastore.HiveMetaStore: Starting metastore thread of type org.apache.hadoop.hive.ql.txn.compactor.Worker Hive Session ID = 3dab4a9e-00e7-46ed-bb93-b27579ba1d93 2020-04-15T20:53:24,323 INFO [Stats updater worker 0] SessionState: Hive Session ID = 3dab4a9e-00e7-46ed-bb93-b27579ba1d93 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.size does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.override does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.metadb.dir does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.min does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.hivesite does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.alloc.max does not exist 2020-04-15T20:53:24,332 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.metastore.runworker.in does not exist 2020-04-15T20:53:24,333 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.version does not exist 2020-04-15T20:53:24,333 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.root does not exist 2020-04-15T20:53:24,333 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.dummyparam.test.server.specific.config.metastoresite does not exist 2020-04-15T20:53:24,334 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.llap.io.cache.orc.arena.size does not exist 2020-04-15T20:53:24,334 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.stats.key.prefix.reserve.length does not exist 2020-04-15T20:53:24,334 WARN [Metastore threads starter thread] conf.HiveConf: HiveConf of name hive.test.console.log.level does not exist 2020-04-15T20:53:24,352 INFO [Metastore threads starter thread] metastore.HiveMetaStoreClient: HMS client filtering is enabled. 2020-04-15T20:53:24,352 INFO [Metastore threads starter thread] metastore.HiveMetaStoreClient: Trying to connect to metastore with URI (thrift://localhost:57893) 2020-04-15T20:53:24,352 INFO [Metastore threads starter thread] metastore.HiveMetaStoreClient: Opened a connection to metastore, URI (thrift://localhost:57893) current connections: 2 2020-04-15T20:53:24,353 INFO [Metastore threads starter thread] metastore.HiveMetaStoreClient: Connected to metastore. 2020-04-15T20:53:24,353 INFO [Metastore threads starter thread] metastore.RetryingMetaStoreClient: RetryingMetaStoreClient proxy=class org.apache.hadoop.hive.metastore.HiveMetaStoreClient ugi=hiveptest (auth:SIMPLE) retries=1 delay=1 lifetime=0 ivysettings.xml file not found in HIVE_HOME or HIVE_CONF_DIR,/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/conf/ivysettings.xml will be used 2020-04-15T20:53:24,366 INFO [Stats updater worker 0] DependencyResolver: ivysettings.xml file not found in HIVE_HOME or HIVE_CONF_DIR,/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/conf/ivysettings.xml will be used 2020-04-15T20:53:24,393 INFO [Metastore threads starter thread] metastore.HiveMetaStore: This HMS instance will act as a Compactor Worker with 1 threads 2020-04-15T20:53:24,393 INFO [hive-ptest-slaves-b43.c.gcp-hive-upstream.internal-171] compactor.Worker: Starting Worker thread 2020-04-15T20:53:24,397 INFO [cmclearer-1] metastore.ReplChangeManager: CMClearer started 2020-04-15T20:53:24,402 INFO [pool-23-thread-3] txn.TxnHandler: Hacking in canned values for transaction manager 2020-04-15T20:53:24,446 INFO [pool-23-thread-3] txn.TxnDbUtil: Txn tables already exist, returning 2020-04-15T20:53:29,820 INFO [main] metastore.MetastoreHousekeepingLeaderTestBase: Name: IPC Server idle connection scanner for port 49729 State: TIMED_WAITING Class name: java.util.TimerThread Name: qtp1353756631-85 State: TIMED_WAITING Class name: java.lang.Thread Name: pool-5-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: pool-23-thread-3 State: RUNNABLE Class name: java.lang.Thread Name: qtp1353756631-82 State: RUNNABLE Class name: java.lang.Thread Name: IPC Server handler 2 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@f0a66bd State: RUNNABLE Class name: org.apache.hadoop.util.Daemon Name: StorageLocationChecker thread 0 State: TIMED_WAITING Class name: java.lang.Thread Name: qtp527939020-29-acceptor-0@5d168fc5-ServerConnector@5484117b{HTTP/1.1,[http/1.1]}{localhost:60465} State: RUNNABLE Class name: java.lang.Thread Name: IPC Server handler 7 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: main State: RUNNABLE Class name: java.lang.Thread Name: qtp1353756631-83 State: RUNNABLE Class name: java.lang.Thread Name: pool-25-thread-3 State: WAITING Class name: java.lang.Thread Name: VolumeScannerThread(/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data2) State: TIMED_WAITING Class name: org.apache.hadoop.hdfs.server.datanode.VolumeScanner Name: IPC Parameter Sending Thread #0 State: TIMED_WAITING Class name: java.lang.Thread Name: org.apache.hadoop.util.JvmPauseMonitor$Monitor@e9474f State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: qtp1353756631-86 State: TIMED_WAITING Class name: java.lang.Thread Name: qtp527939020-30 State: TIMED_WAITING Class name: java.lang.Thread Name: qtp1353756631-87 State: TIMED_WAITING Class name: java.lang.Thread Name: org.eclipse.jetty.server.session.HashSessionManager@6cbe68e9Timer State: TIMED_WAITING Class name: java.lang.Thread Name: qtp1353756631-80 State: RUNNABLE Class name: java.lang.Thread Name: org.apache.hadoop.util.JvmPauseMonitor$Monitor@5aaaa446 State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: IPC Server handler 5 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: process reaper State: TIMED_WAITING Class name: java.lang.Thread Name: pool-12-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: org.eclipse.jetty.server.session.HashSessionManager@49e2b3c5Timer State: TIMED_WAITING Class name: java.lang.Thread Name: org.apache.hadoop.hdfs.server.namenode.LeaseManager$Monitor@e95595b State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: org.apache.hadoop.hdfs.server.blockmanagement.PendingReconstructionBlocks$PendingReconstructionMonitor@39ee94de State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: pool-11-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: org.eclipse.jetty.server.session.HashSessionManager@3f0d6038Timer State: TIMED_WAITING Class name: java.lang.Thread Name: derby.rawStoreDaemon State: TIMED_WAITING Class name: java.lang.Thread Name: hive-ptest-slaves-b43.c.gcp-hive-upstream.internal-171 State: TIMED_WAITING Class name: org.apache.hadoop.hive.ql.txn.compactor.Worker Name: IPC Server handler 4 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: MetaStoreThread-57893 State: RUNNABLE Class name: java.lang.Thread Name: RedundancyMonitor State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: Thread-113 State: TIMED_WAITING Class name: org.apache.hadoop.hive.ql.stats.StatsUpdaterThread Name: org.apache.hadoop.hive.metastore.metrics.JvmPauseMonitor$Monitor@28708229 State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: qtp527939020-26 State: RUNNABLE Class name: java.lang.Thread Name: Timer for 'NameNode' metrics system State: TIMED_WAITING Class name: java.util.TimerThread Name: qtp1353756631-84-acceptor-0@373cb4b5-ServerConnector@1200458e{HTTP/1.1,[http/1.1]}{localhost:60676} State: RUNNABLE Class name: java.lang.Thread Name: Socket Reader #1 for port 49729 State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Listener.Reader Name: qtp527939020-32 State: TIMED_WAITING Class name: java.lang.Thread Name: pool-3-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: pool-23-thread-1 State: WAITING Class name: java.lang.Thread Name: IPC Server handler 4 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: IPC Server handler 3 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: Socket Reader #1 for port 52650 State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Listener.Reader Name: surefire-forkedjvm-command-thread State: RUNNABLE Class name: java.lang.Thread Name: Reference Handler State: WAITING Class name: java.lang.ref.Reference.ReferenceHandler Name: IPC Server handler 5 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: DatanodeAdminMonitor-0 State: TIMED_WAITING Class name: java.lang.Thread Name: BP-638667272-10.128.0.72-1587009186993 heartbeating to localhost/127.0.0.1:52650 State: TIMED_WAITING Class name: java.lang.Thread Name: org.apache.hadoop.hdfs.server.namenode.FSNamesystem$NameNodeResourceMonitor@796f632b State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: StorageLocationChecker thread 1 State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server handler 0 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: org.apache.hadoop.hdfs.server.blockmanagement.HeartbeatManager$Monitor@2d3ef181 State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: HikariPool-2 housekeeper State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server Responder State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Responder Name: IPC Server Responder State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Responder Name: IPC Client (999864329) connection to localhost/127.0.0.1:52650 from hiveptest State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Client.Connection Name: refreshUsed-/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data1/current/BP-638667272-10.128.0.72-1587009186993 State: TIMED_WAITING Class name: java.lang.Thread Name: qtp527939020-25 State: RUNNABLE Class name: java.lang.Thread Name: Stats updater worker 0 State: WAITING Class name: java.lang.Thread Name: Timer-0 State: WAITING Class name: java.util.TimerThread Name: refreshUsed-/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data2/current/BP-638667272-10.128.0.72-1587009186993 State: TIMED_WAITING Class name: java.lang.Thread Name: org.eclipse.jetty.server.session.HashSessionManager@30db5536Timer State: TIMED_WAITING Class name: java.lang.Thread Name: org.eclipse.jetty.server.session.HashSessionManager@17143b3bTimer State: TIMED_WAITING Class name: java.lang.Thread Name: qtp1353756631-81 State: RUNNABLE Class name: java.lang.Thread Name: Finalizer State: WAITING Class name: java.lang.ref.Finalizer.FinalizerThread Name: qtp527939020-27 State: RUNNABLE Class name: java.lang.Thread Name: IPC Server handler 0 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: org.apache.hadoop.fs.FileSystem$Statistics$StatisticsDataReferenceCleaner State: WAITING Class name: java.lang.Thread Name: IPC Server listener on 52650 State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Listener Name: org.apache.hadoop.hdfs.server.namenode.FSNamesystem$LazyPersistFileScrubber@40c2ce52 State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: IPC Server handler 2 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: IPC Server handler 6 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: process reaper State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server handler 1 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: IPC Server handler 8 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: Thread-105 State: TIMED_WAITING Class name: org.apache.hadoop.hive.ql.txn.compactor.Cleaner Name: qtp527939020-28 State: RUNNABLE Class name: java.lang.Thread Name: pool-25-thread-2 State: WAITING Class name: java.lang.Thread Name: Signal Dispatcher State: RUNNABLE Class name: java.lang.Thread Name: HikariPool-1 housekeeper State: TIMED_WAITING Class name: java.lang.Thread Name: surefire-forkedjvm-ping-30s State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server handler 1 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: Block report processor State: WAITING Class name: org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.BlockReportProcessingThread Name: pool-9-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: FSEditLogAsync State: WAITING Class name: java.lang.Thread Name: CacheReplicationMonitor(256006028) State: TIMED_WAITING Class name: org.apache.hadoop.hdfs.server.blockmanagement.CacheReplicationMonitor Name: cmclearer-1 State: TIMED_WAITING Class name: java.lang.Thread Name: org.apache.hadoop.hdfs.server.namenode.FSNamesystem$NameNodeEditLogRoller@59b32539 State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: IPC Server listener on 49729 State: RUNNABLE Class name: org.apache.hadoop.ipc.Server.Listener Name: nioEventLoopGroup-2-1 State: RUNNABLE Class name: io.netty.util.concurrent.FastThreadLocalThread Name: IPC Server handler 9 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: VolumeScannerThread(/home/hiveptest/35.224.55.83-hiveptest-2/apache-github-source-source/itests/hive-unit/target/tmp/dfs/data/data1) State: TIMED_WAITING Class name: org.apache.hadoop.hdfs.server.datanode.VolumeScanner Name: IPC Server handler 7 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: IPC Server handler 9 on 49729 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: IPC Server idle connection scanner for port 52650 State: TIMED_WAITING Class name: java.util.TimerThread Name: pool-23-thread-2 State: RUNNABLE Class name: java.lang.Thread Name: java.util.concurrent.ThreadPoolExecutor$Worker@73c234d1[State = -1, empty queue] State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: DataNode DiskChecker thread 0 State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server handler 3 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: HikariPool-3 housekeeper State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Client (999864329) connection to localhost/127.0.0.1:52650 from hiveptest State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Client.Connection Name: StorageInfoMonitor State: TIMED_WAITING Class name: org.apache.hadoop.util.Daemon Name: qtp527939020-31 State: TIMED_WAITING Class name: java.lang.Thread Name: pool-25-thread-1 State: TIMED_WAITING Class name: java.lang.Thread Name: IPC Server handler 8 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: Thread-101 State: TIMED_WAITING Class name: org.apache.hadoop.hive.ql.txn.compactor.Initiator Name: IPC Server handler 6 on 52650 State: TIMED_WAITING Class name: org.apache.hadoop.ipc.Server.Handler Name: org.eclipse.jetty.server.session.HashSessionManager@3204e238Timer State: TIMED_WAITING Class name: java.lang.Thread 2020-04-15T20:53:29,823 INFO [main] metastore.TestMetastoreHousekeepingLeaderEmptyConfig: Found thread with name cmclearer- {code} ) > Ignore flaky test testHouseKeepingThreadExistence in > TestMetastoreHousekeepingLeaderEmptyConfig and TestMetastoreHousekeepingLeader > ----------------------------------------------------------------------------------------------------------------------------------- > > Key: HIVE-23221 > URL: https://issues.apache.org/jira/browse/HIVE-23221 > Project: Hive > Issue Type: Bug > Reporter: Miklos Gergely > Assignee: Miklos Gergely > Priority: Major > Attachments: HIVE-23221.01.patch > > > These tests are failing in an indeterministic fashion with such error > messages as the one attached. -- This message was sent by Atlassian Jira (v8.3.4#803005)