org.scalatest.exceptions.TestFailedException: spark-submit returned with exit code 1. Command line: './bin/spark-submit' '--name' 'prepare testing tables' '--master' 'local[2]' '--conf' 'spark.ui.enabled=false' '--conf' 'spark.master.rest.enabled=false' '--conf' 'spark.sql.warehouse.dir=/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b' '--conf' 'spark.sql.test.version.index=4' '--driver-java-options' '-Dderby.system.home=/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b' '/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py' 2018-05-17 17:35:04.304 - stderr> SLF4J: Class path contains multiple SLF4J bindings. 2018-05-17 17:35:04.304 - stderr> SLF4J: Found binding in [jar:file:/tmp/test-spark/spark-2.3.0/jars/slf4j-log4j12-1.7.16.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2018-05-17 17:35:04.304 - stderr> SLF4J: Found binding in [jar:file:/home/sparkivy/per-executor-caches/3/.ivy2/cache/org.slf4j/slf4j-log4j12/jars/slf4j-log4j12-1.7.16.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2018-05-17 17:35:04.304 - stderr> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2018-05-17 17:35:04.304 - stderr> SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory] 2018-05-17 17:35:05.205 - stdout> 2018-05-17 17:35:05 WARN NativeCodeLoader:62 - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable 2018-05-17 17:35:06.173 - stdout> 2018-05-17 17:35:06 INFO SparkContext:54 - Running Spark version 2.3.0 2018-05-17 17:35:06.198 - stdout> 2018-05-17 17:35:06 INFO SparkContext:54 - Submitted application: prepare testing tables 2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing view acls to: jenkins 2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing modify acls to: jenkins 2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing view acls groups to: 2018-05-17 17:35:06.261 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing modify acls groups to: 2018-05-17 17:35:06.261 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(jenkins); groups with view permissions: Set(); users with modify permissions: Set(jenkins); groups with modify permissions: Set() 2018-05-17 17:35:06.582 - stdout> 2018-05-17 17:35:06 INFO Utils:54 - Successfully started service 'sparkDriver' on port 32911. 2018-05-17 17:35:06.61 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering MapOutputTracker 2018-05-17 17:35:06.632 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering BlockManagerMaster 2018-05-17 17:35:06.635 - stdout> 2018-05-17 17:35:06 INFO BlockManagerMasterEndpoint:54 - Using org.apache.spark.storage.DefaultTopologyMapper for getting topology information 2018-05-17 17:35:06.636 - stdout> 2018-05-17 17:35:06 INFO BlockManagerMasterEndpoint:54 - BlockManagerMasterEndpoint up 2018-05-17 17:35:06.65 - stdout> 2018-05-17 17:35:06 INFO DiskBlockManager:54 - Created local directory at /tmp/blockmgr-eb1c7092-13c2-431c-b51d-d9dc3bf84f9a 2018-05-17 17:35:06.674 - stdout> 2018-05-17 17:35:06 INFO MemoryStore:54 - MemoryStore started with capacity 366.3 MB 2018-05-17 17:35:06.694 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering OutputCommitCoordinator 2018-05-17 17:35:07.021 - stdout> 2018-05-17 17:35:07 INFO SparkContext:54 - Added file file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py at file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py with timestamp 1526603707021 2018-05-17 17:35:07.023 - stdout> 2018-05-17 17:35:07 INFO Utils:54 - Copying /home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py to /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef/userFiles-eaa4e5b6-c882-4100-b1ea-ff9cb6430840/test3175560666562982753.py 2018-05-17 17:35:07.089 - stdout> 2018-05-17 17:35:07 INFO Executor:54 - Starting executor ID driver on host localhost 2018-05-17 17:35:07.107 - stdout> 2018-05-17 17:35:07 INFO Utils:54 - Successfully started service 'org.apache.spark.network.netty.NettyBlockTransferService' on port 46692. 2018-05-17 17:35:07.108 - stdout> 2018-05-17 17:35:07 INFO NettyBlockTransferService:54 - Server created on amp-jenkins-worker-07.amp:46692 2018-05-17 17:35:07.109 - stdout> 2018-05-17 17:35:07 INFO BlockManager:54 - Using org.apache.spark.storage.RandomBlockReplicationPolicy for block replication policy 2018-05-17 17:35:07.11 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMaster:54 - Registering BlockManager BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None) 2018-05-17 17:35:07.114 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMasterEndpoint:54 - Registering block manager amp-jenkins-worker-07.amp:46692 with 366.3 MB RAM, BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None) 2018-05-17 17:35:07.117 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMaster:54 - Registered BlockManager BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None) 2018-05-17 17:35:07.117 - stdout> 2018-05-17 17:35:07 INFO BlockManager:54 - Initialized BlockManager: BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None) 2018-05-17 17:35:07.302 - stdout> 2018-05-17 17:35:07 INFO log:192 - Logging initialized @3943ms 2018-05-17 17:35:07.496 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - loading hive config file: file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/sql/hive/target/scala-2.11/test-classes/hive-site.xml 2018-05-17 17:35:07.536 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - Setting hive.metastore.warehouse.dir ('null') to the value of spark.sql.warehouse.dir ('/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b'). 2018-05-17 17:35:07.537 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - Warehouse path is '/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b'. 2018-05-17 17:35:08.073 - stdout> 2018-05-17 17:35:08 INFO StateStoreCoordinatorRef:54 - Registered StateStoreCoordinator endpoint 2018-05-17 17:35:08.532 - stdout> 2018-05-17 17:35:08 INFO HiveUtils:54 - Initializing HiveMetastoreConnection version 1.2.1 using Spark classes. 2018-05-17 17:35:09.167 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore 2018-05-17 17:35:09.192 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called 2018-05-17 17:35:09.219 - stdout> 2018-05-17 17:35:09 ERROR General:115 - ResourceBundle org.datanucleus.api.jdo.Localisation for locale en_US was not found! 2018-05-17 17:35:09.237 - stdout> 2018-05-17 17:35:09 ERROR General:115 - ResourceBundle org.datanucleus.Localisation for locale en_US was not found! 2018-05-17 17:35:09.256 - stdout> 2018-05-17 17:35:09 WARN HiveMetaStore:622 - Retrying creating default database after error: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException" 2018-05-17 17:35:09.256 - stdout> javax.jdo.JDOFatalInternalException: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException" 2018-05-17 17:35:09.256 - stdout> at javax.jdo.spi.I18NHelper.assertBundle(I18NHelper.java:269) 2018-05-17 17:35:09.256 - stdout> at javax.jdo.spi.I18NHelper.msg(I18NHelper.java:147) 2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1193) 2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:808) 2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:701) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPMF(ObjectStore.java:365) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPersistenceManager(ObjectStore.java:394) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.initialize(ObjectStore.java:291) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.setConf(ObjectStore.java:258) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.util.ReflectionUtils.setConf(ReflectionUtils.java:76) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.util.ReflectionUtils.newInstance(ReflectionUtils.java:136) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.<init>(RawStoreProxy.java:57) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.getProxy(RawStoreProxy.java:66) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.newRawStore(HiveMetaStore.java:593) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.getMS(HiveMetaStore.java:571) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.createDefaultDB(HiveMetaStore.java:620) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.init(HiveMetaStore.java:461) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.<init>(RetryingHMSHandler.java:66) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.getProxy(RetryingHMSHandler.java:72) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore.newRetryingHMSHandler(HiveMetaStore.java:5762) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStoreClient.<init>(HiveMetaStoreClient.java:199) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient.<init>(SessionHiveMetaStoreClient.java:74) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.256 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1234) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.reloadFunctions(Hive.java:174) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.<clinit>(Hive.java:166) 2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503) 2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180) 2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.256 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.256 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74) 2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638) 2018-05-17 17:35:09.257 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2018-05-17 17:35:09.257 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2018-05-17 17:35:09.257 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2018-05-17 17:35:09.257 - stdout> at java.lang.reflect.Method.invoke(Method.java:497) 2018-05-17 17:35:09.257 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244) 2018-05-17 17:35:09.257 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357) 2018-05-17 17:35:09.257 - stdout> at py4j.Gateway.invoke(Gateway.java:282) 2018-05-17 17:35:09.257 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132) 2018-05-17 17:35:09.257 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79) 2018-05-17 17:35:09.257 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214) 2018-05-17 17:35:09.257 - stdout> at java.lang.Thread.run(Thread.java:745) 2018-05-17 17:35:09.257 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore 2018-05-17 17:35:09.258 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called 2018-05-17 17:35:09.275 - stdout> 2018-05-17 17:35:09 WARN Hive:168 - Failed to access metastore. This class should not accessed in runtime. 2018-05-17 17:35:09.275 - stdout> org.apache.hadoop.hive.ql.metadata.HiveException: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1236) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.reloadFunctions(Hive.java:174) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.<clinit>(Hive.java:166) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74) 2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Method.invoke(Method.java:497) 2018-05-17 17:35:09.275 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244) 2018-05-17 17:35:09.275 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357) 2018-05-17 17:35:09.275 - stdout> at py4j.Gateway.invoke(Gateway.java:282) 2018-05-17 17:35:09.275 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132) 2018-05-17 17:35:09.275 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79) 2018-05-17 17:35:09.275 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214) 2018-05-17 17:35:09.275 - stdout> at java.lang.Thread.run(Thread.java:745) 2018-05-17 17:35:09.275 - stdout> Caused by: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1523) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1234) 2018-05-17 17:35:09.275 - stdout> ... 46 more 2018-05-17 17:35:09.275 - stdout> Caused by: java.lang.reflect.InvocationTargetException 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521) 2018-05-17 17:35:09.275 - stdout> ... 52 more 2018-05-17 17:35:09.275 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore 2018-05-17 17:35:09.276 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called 2018-05-17 17:35:09.282 - stdout> 2018-05-17 17:35:09 WARN HiveMetaStore:622 - Retrying creating default database after error: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException" 2018-05-17 17:35:09.282 - stdout> javax.jdo.JDOFatalInternalException: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException" 2018-05-17 17:35:09.282 - stdout> at javax.jdo.spi.I18NHelper.assertBundle(I18NHelper.java:269) 2018-05-17 17:35:09.282 - stdout> at javax.jdo.spi.I18NHelper.msg(I18NHelper.java:147) 2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1193) 2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:808) 2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:701) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPMF(ObjectStore.java:365) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPersistenceManager(ObjectStore.java:394) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.initialize(ObjectStore.java:291) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.setConf(ObjectStore.java:258) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.util.ReflectionUtils.setConf(ReflectionUtils.java:76) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.util.ReflectionUtils.newInstance(ReflectionUtils.java:136) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.<init>(RawStoreProxy.java:57) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.getProxy(RawStoreProxy.java:66) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.newRawStore(HiveMetaStore.java:593) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.getMS(HiveMetaStore.java:571) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.createDefaultDB(HiveMetaStore.java:620) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.init(HiveMetaStore.java:461) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.<init>(RetryingHMSHandler.java:66) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.getProxy(RetryingHMSHandler.java:72) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore.newRetryingHMSHandler(HiveMetaStore.java:5762) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStoreClient.<init>(HiveMetaStoreClient.java:199) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient.<init>(SessionHiveMetaStoreClient.java:74) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.282 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024) 2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) 2018-05-17 17:35:09.282 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) 2018-05-17 17:35:09.282 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47) 2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74) 2018-05-17 17:35:09.283 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638) 2018-05-17 17:35:09.283 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2018-05-17 17:35:09.283 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2018-05-17 17:35:09.283 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2018-05-17 17:35:09.283 - stdout> at java.lang.reflect.Method.invoke(Method.java:497) 2018-05-17 17:35:09.283 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244) 2018-05-17 17:35:09.283 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357) 2018-05-17 17:35:09.283 - stdout> at py4j.Gateway.invoke(Gateway.java:282) 2018-05-17 17:35:09.283 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132) 2018-05-17 17:35:09.283 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79) 2018-05-17 17:35:09.283 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214) 2018-05-17 17:35:09.283 - stdout> at java.lang.Thread.run(Thread.java:745) 2018-05-17 17:35:09.283 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore 2018-05-17 17:35:09.283 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called 2018-05-17 17:35:09.304 - stdout> Traceback (most recent call last): 2018-05-17 17:35:09.304 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/utils.py", line 63, in deco 2018-05-17 17:35:09.304 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/protocol.py", line 320, in get_return_value 2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JJavaErrorERROR:root:Exception while sending command. 2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last): 2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1062, in send_command 2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JNetworkError: Answer from Java side is empty 2018-05-17 17:35:09.307 - stdout> 2018-05-17 17:35:09.307 - stdout> During handling of the above exception, another exception occurred: 2018-05-17 17:35:09.307 - stdout> 2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last): 2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 908, in send_command 2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1067, in send_command 2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JNetworkError: Error while receiving 2018-05-17 17:35:09.307 - stdout> 2018-05-17 17:35:09.307 - stdout> During handling of the above exception, another exception occurred: 2018-05-17 17:35:09.307 - stdout> 2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last): 2018-05-17 17:35:09.307 - stdout> File "/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py", line 8, in <module> 2018-05-17 17:35:09.307 - stdout> spark.sql("create table data_source_tbl_{} using json as select 1 i".format(version_index)) 2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/session.py", line 708, in sql 2018-05-17 17:35:09.308 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1160, in __call__ 2018-05-17 17:35:09.308 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/utils.py", line 69, in deco 2018-05-17 17:35:09.308 - stdout> pyspark.sql.utils.AnalysisException: 'java.lang.RuntimeException: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient;' 2018-05-17 17:35:09.363 - stdout> 2018-05-17 17:35:09 INFO SparkContext:54 - Invoking stop() from shutdown hook 2018-05-17 17:35:09.374 - stdout> 2018-05-17 17:35:09 INFO MapOutputTrackerMasterEndpoint:54 - MapOutputTrackerMasterEndpoint stopped! 2018-05-17 17:35:09.381 - stdout> 2018-05-17 17:35:09 INFO MemoryStore:54 - MemoryStore cleared 2018-05-17 17:35:09.381 - stdout> 2018-05-17 17:35:09 INFO BlockManager:54 - BlockManager stopped 2018-05-17 17:35:09.387 - stdout> 2018-05-17 17:35:09 INFO BlockManagerMaster:54 - BlockManagerMaster stopped 2018-05-17 17:35:09.39 - stdout> 2018-05-17 17:35:09 INFO OutputCommitCoordinator$OutputCommitCoordinatorEndpoint:54 - OutputCommitCoordinator stopped! 2018-05-17 17:35:09.392 - stdout> 2018-05-17 17:35:09 INFO SparkContext:54 - Successfully stopped SparkContext 2018-05-17 17:35:09.392 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Shutdown hook called 2018-05-17 17:35:09.393 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef 2018-05-17 17:35:09.394 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c1b782ca-5e12-455d-985e-c278873dc853 2018-05-17 17:35:09.394 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef/pyspark-f626555a-3b3b-40d8-865b-92b3637f5d48
sbt.ForkMain$ForkError: org.scalatest.exceptions.TestFailedException: spark-submit returned with exit code 1.
Command line: './bin/spark-submit' '--name' 'prepare testing tables' '--master' 'local[2]' '--conf' 'spark.ui.enabled=false' '--conf' 'spark.master.rest.enabled=false' '--conf' 'spark.sql.warehouse.dir=/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b' '--conf' 'spark.sql.test.version.index=4' '--driver-java-options' '-Dderby.system.home=/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b' '/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py'
2018-05-17 17:35:04.304 - stderr> SLF4J: Class path contains multiple SLF4J bindings.
2018-05-17 17:35:04.304 - stderr> SLF4J: Found binding in [jar:file:/tmp/test-spark/spark-2.3.0/jars/slf4j-log4j12-1.7.16.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2018-05-17 17:35:04.304 - stderr> SLF4J: Found binding in [jar:file:/home/sparkivy/per-executor-caches/3/.ivy2/cache/org.slf4j/slf4j-log4j12/jars/slf4j-log4j12-1.7.16.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2018-05-17 17:35:04.304 - stderr> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2018-05-17 17:35:04.304 - stderr> SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
2018-05-17 17:35:05.205 - stdout> 2018-05-17 17:35:05 WARN NativeCodeLoader:62 - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2018-05-17 17:35:06.173 - stdout> 2018-05-17 17:35:06 INFO SparkContext:54 - Running Spark version 2.3.0
2018-05-17 17:35:06.198 - stdout> 2018-05-17 17:35:06 INFO SparkContext:54 - Submitted application: prepare testing tables
2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing view acls to: jenkins
2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing modify acls to: jenkins
2018-05-17 17:35:06.26 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing view acls groups to:
2018-05-17 17:35:06.261 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - Changing modify acls groups to:
2018-05-17 17:35:06.261 - stdout> 2018-05-17 17:35:06 INFO SecurityManager:54 - SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(jenkins); groups with view permissions: Set(); users with modify permissions: Set(jenkins); groups with modify permissions: Set()
2018-05-17 17:35:06.582 - stdout> 2018-05-17 17:35:06 INFO Utils:54 - Successfully started service 'sparkDriver' on port 32911.
2018-05-17 17:35:06.61 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering MapOutputTracker
2018-05-17 17:35:06.632 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering BlockManagerMaster
2018-05-17 17:35:06.635 - stdout> 2018-05-17 17:35:06 INFO BlockManagerMasterEndpoint:54 - Using org.apache.spark.storage.DefaultTopologyMapper for getting topology information
2018-05-17 17:35:06.636 - stdout> 2018-05-17 17:35:06 INFO BlockManagerMasterEndpoint:54 - BlockManagerMasterEndpoint up
2018-05-17 17:35:06.65 - stdout> 2018-05-17 17:35:06 INFO DiskBlockManager:54 - Created local directory at /tmp/blockmgr-eb1c7092-13c2-431c-b51d-d9dc3bf84f9a
2018-05-17 17:35:06.674 - stdout> 2018-05-17 17:35:06 INFO MemoryStore:54 - MemoryStore started with capacity 366.3 MB
2018-05-17 17:35:06.694 - stdout> 2018-05-17 17:35:06 INFO SparkEnv:54 - Registering OutputCommitCoordinator
2018-05-17 17:35:07.021 - stdout> 2018-05-17 17:35:07 INFO SparkContext:54 - Added file file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py at file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py with timestamp 1526603707021
2018-05-17 17:35:07.023 - stdout> 2018-05-17 17:35:07 INFO Utils:54 - Copying /home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py to /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef/userFiles-eaa4e5b6-c882-4100-b1ea-ff9cb6430840/test3175560666562982753.py
2018-05-17 17:35:07.089 - stdout> 2018-05-17 17:35:07 INFO Executor:54 - Starting executor ID driver on host localhost
2018-05-17 17:35:07.107 - stdout> 2018-05-17 17:35:07 INFO Utils:54 - Successfully started service 'org.apache.spark.network.netty.NettyBlockTransferService' on port 46692.
2018-05-17 17:35:07.108 - stdout> 2018-05-17 17:35:07 INFO NettyBlockTransferService:54 - Server created on amp-jenkins-worker-07.amp:46692
2018-05-17 17:35:07.109 - stdout> 2018-05-17 17:35:07 INFO BlockManager:54 - Using org.apache.spark.storage.RandomBlockReplicationPolicy for block replication policy
2018-05-17 17:35:07.11 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMaster:54 - Registering BlockManager BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None)
2018-05-17 17:35:07.114 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMasterEndpoint:54 - Registering block manager amp-jenkins-worker-07.amp:46692 with 366.3 MB RAM, BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None)
2018-05-17 17:35:07.117 - stdout> 2018-05-17 17:35:07 INFO BlockManagerMaster:54 - Registered BlockManager BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None)
2018-05-17 17:35:07.117 - stdout> 2018-05-17 17:35:07 INFO BlockManager:54 - Initialized BlockManager: BlockManagerId(driver, amp-jenkins-worker-07.amp, 46692, None)
2018-05-17 17:35:07.302 - stdout> 2018-05-17 17:35:07 INFO log:192 - Logging initialized @3943ms
2018-05-17 17:35:07.496 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - loading hive config file: file:/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/sql/hive/target/scala-2.11/test-classes/hive-site.xml
2018-05-17 17:35:07.536 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - Setting hive.metastore.warehouse.dir ('null') to the value of spark.sql.warehouse.dir ('/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b').
2018-05-17 17:35:07.537 - stdout> 2018-05-17 17:35:07 INFO SharedState:54 - Warehouse path is '/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/warehouse-723c8b23-f6f6-41a0-a688-3ff83692232b'.
2018-05-17 17:35:08.073 - stdout> 2018-05-17 17:35:08 INFO StateStoreCoordinatorRef:54 - Registered StateStoreCoordinator endpoint
2018-05-17 17:35:08.532 - stdout> 2018-05-17 17:35:08 INFO HiveUtils:54 - Initializing HiveMetastoreConnection version 1.2.1 using Spark classes.
2018-05-17 17:35:09.167 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
2018-05-17 17:35:09.192 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called
2018-05-17 17:35:09.219 - stdout> 2018-05-17 17:35:09 ERROR General:115 - ResourceBundle org.datanucleus.api.jdo.Localisation for locale en_US was not found!
2018-05-17 17:35:09.237 - stdout> 2018-05-17 17:35:09 ERROR General:115 - ResourceBundle org.datanucleus.Localisation for locale en_US was not found!
2018-05-17 17:35:09.256 - stdout> 2018-05-17 17:35:09 WARN HiveMetaStore:622 - Retrying creating default database after error: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException"
2018-05-17 17:35:09.256 - stdout> javax.jdo.JDOFatalInternalException: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException"
2018-05-17 17:35:09.256 - stdout> at javax.jdo.spi.I18NHelper.assertBundle(I18NHelper.java:269)
2018-05-17 17:35:09.256 - stdout> at javax.jdo.spi.I18NHelper.msg(I18NHelper.java:147)
2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1193)
2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:808)
2018-05-17 17:35:09.256 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:701)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPMF(ObjectStore.java:365)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPersistenceManager(ObjectStore.java:394)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.initialize(ObjectStore.java:291)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.setConf(ObjectStore.java:258)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.util.ReflectionUtils.setConf(ReflectionUtils.java:76)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.util.ReflectionUtils.newInstance(ReflectionUtils.java:136)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.<init>(RawStoreProxy.java:57)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.getProxy(RawStoreProxy.java:66)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.newRawStore(HiveMetaStore.java:593)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.getMS(HiveMetaStore.java:571)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.createDefaultDB(HiveMetaStore.java:620)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.init(HiveMetaStore.java:461)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.<init>(RetryingHMSHandler.java:66)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.getProxy(RetryingHMSHandler.java:72)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore.newRetryingHMSHandler(HiveMetaStore.java:5762)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStoreClient.<init>(HiveMetaStoreClient.java:199)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient.<init>(SessionHiveMetaStoreClient.java:74)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.256 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1234)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.reloadFunctions(Hive.java:174)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.<clinit>(Hive.java:166)
2018-05-17 17:35:09.256 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503)
2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180)
2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.256 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.256 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.256 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74)
2018-05-17 17:35:09.257 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638)
2018-05-17 17:35:09.257 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2018-05-17 17:35:09.257 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2018-05-17 17:35:09.257 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2018-05-17 17:35:09.257 - stdout> at java.lang.reflect.Method.invoke(Method.java:497)
2018-05-17 17:35:09.257 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244)
2018-05-17 17:35:09.257 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357)
2018-05-17 17:35:09.257 - stdout> at py4j.Gateway.invoke(Gateway.java:282)
2018-05-17 17:35:09.257 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132)
2018-05-17 17:35:09.257 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79)
2018-05-17 17:35:09.257 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214)
2018-05-17 17:35:09.257 - stdout> at java.lang.Thread.run(Thread.java:745)
2018-05-17 17:35:09.257 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
2018-05-17 17:35:09.258 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called
2018-05-17 17:35:09.275 - stdout> 2018-05-17 17:35:09 WARN Hive:168 - Failed to access metastore. This class should not accessed in runtime.
2018-05-17 17:35:09.275 - stdout> org.apache.hadoop.hive.ql.metadata.HiveException: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1236)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.reloadFunctions(Hive.java:174)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.<clinit>(Hive.java:166)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74)
2018-05-17 17:35:09.275 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Method.invoke(Method.java:497)
2018-05-17 17:35:09.275 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244)
2018-05-17 17:35:09.275 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357)
2018-05-17 17:35:09.275 - stdout> at py4j.Gateway.invoke(Gateway.java:282)
2018-05-17 17:35:09.275 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132)
2018-05-17 17:35:09.275 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79)
2018-05-17 17:35:09.275 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214)
2018-05-17 17:35:09.275 - stdout> at java.lang.Thread.run(Thread.java:745)
2018-05-17 17:35:09.275 - stdout> Caused by: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1523)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getAllDatabases(Hive.java:1234)
2018-05-17 17:35:09.275 - stdout> ... 46 more
2018-05-17 17:35:09.275 - stdout> Caused by: java.lang.reflect.InvocationTargetException
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.275 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.275 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.275 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521)
2018-05-17 17:35:09.275 - stdout> ... 52 more
2018-05-17 17:35:09.275 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
2018-05-17 17:35:09.276 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called
2018-05-17 17:35:09.282 - stdout> 2018-05-17 17:35:09 WARN HiveMetaStore:622 - Retrying creating default database after error: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException"
2018-05-17 17:35:09.282 - stdout> javax.jdo.JDOFatalInternalException: No resources could be found to annotate error message key:"EXC_GetPMFUnexpectedException"
2018-05-17 17:35:09.282 - stdout> at javax.jdo.spi.I18NHelper.assertBundle(I18NHelper.java:269)
2018-05-17 17:35:09.282 - stdout> at javax.jdo.spi.I18NHelper.msg(I18NHelper.java:147)
2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1193)
2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:808)
2018-05-17 17:35:09.282 - stdout> at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:701)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPMF(ObjectStore.java:365)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.getPersistenceManager(ObjectStore.java:394)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.initialize(ObjectStore.java:291)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.ObjectStore.setConf(ObjectStore.java:258)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.util.ReflectionUtils.setConf(ReflectionUtils.java:76)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.util.ReflectionUtils.newInstance(ReflectionUtils.java:136)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.<init>(RawStoreProxy.java:57)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RawStoreProxy.getProxy(RawStoreProxy.java:66)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.newRawStore(HiveMetaStore.java:593)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.getMS(HiveMetaStore.java:571)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.createDefaultDB(HiveMetaStore.java:620)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore$HMSHandler.init(HiveMetaStore.java:461)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.<init>(RetryingHMSHandler.java:66)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingHMSHandler.getProxy(RetryingHMSHandler.java:72)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStore.newRetryingHMSHandler(HiveMetaStore.java:5762)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.HiveMetaStoreClient.<init>(HiveMetaStoreClient.java:199)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient.<init>(SessionHiveMetaStoreClient.java:74)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.282 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.MetaStoreUtils.newInstance(MetaStoreUtils.java:1521)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.<init>(RetryingMetaStoreClient.java:86)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:132)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.metastore.RetryingMetaStoreClient.getProxy(RetryingMetaStoreClient.java:104)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.createMetaStoreClient(Hive.java:3005)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.metadata.Hive.getMSC(Hive.java:3024)
2018-05-17 17:35:09.282 - stdout> at org.apache.hadoop.hive.ql.session.SessionState.start(SessionState.java:503)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.newState(HiveClientImpl.scala:180)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.HiveClientImpl.<init>(HiveClientImpl.scala:114)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2018-05-17 17:35:09.282 - stdout> at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2018-05-17 17:35:09.282 - stdout> at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.client.IsolatedClientLoader.createClient(IsolatedClientLoader.scala:264)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:385)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveUtils$.newClientForMetadata(HiveUtils.scala:287)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client$lzycompute(HiveExternalCatalog.scala:66)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.client(HiveExternalCatalog.scala:65)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply$mcZ$sp(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog$$anonfun$databaseExists$1.apply(HiveExternalCatalog.scala:195)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.withClient(HiveExternalCatalog.scala:97)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveExternalCatalog.databaseExists(HiveExternalCatalog.scala:194)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog$lzycompute(SharedState.scala:114)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SharedState.externalCatalog(SharedState.scala:102)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.externalCatalog(HiveSessionStateBuilder.scala:39)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog$lzycompute(HiveSessionStateBuilder.scala:54)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.catalog(HiveSessionStateBuilder.scala:52)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder$$anon$1.<init>(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.hive.HiveSessionStateBuilder.analyzer(HiveSessionStateBuilder.scala:69)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.BaseSessionStateBuilder$$anonfun$build$2.apply(BaseSessionStateBuilder.scala:293)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer$lzycompute(SessionState.scala:79)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.internal.SessionState.analyzer(SessionState.scala:79)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed$lzycompute(QueryExecution.scala:57)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.analyzed(QueryExecution.scala:55)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.execution.QueryExecution.assertAnalyzed(QueryExecution.scala:47)
2018-05-17 17:35:09.282 - stdout> at org.apache.spark.sql.Dataset$.ofRows(Dataset.scala:74)
2018-05-17 17:35:09.283 - stdout> at org.apache.spark.sql.SparkSession.sql(SparkSession.scala:638)
2018-05-17 17:35:09.283 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2018-05-17 17:35:09.283 - stdout> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2018-05-17 17:35:09.283 - stdout> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2018-05-17 17:35:09.283 - stdout> at java.lang.reflect.Method.invoke(Method.java:497)
2018-05-17 17:35:09.283 - stdout> at py4j.reflection.MethodInvoker.invoke(MethodInvoker.java:244)
2018-05-17 17:35:09.283 - stdout> at py4j.reflection.ReflectionEngine.invoke(ReflectionEngine.java:357)
2018-05-17 17:35:09.283 - stdout> at py4j.Gateway.invoke(Gateway.java:282)
2018-05-17 17:35:09.283 - stdout> at py4j.commands.AbstractCommand.invokeMethod(AbstractCommand.java:132)
2018-05-17 17:35:09.283 - stdout> at py4j.commands.CallCommand.execute(CallCommand.java:79)
2018-05-17 17:35:09.283 - stdout> at py4j.GatewayConnection.run(GatewayConnection.java:214)
2018-05-17 17:35:09.283 - stdout> at java.lang.Thread.run(Thread.java:745)
2018-05-17 17:35:09.283 - stdout> 2018-05-17 17:35:09 INFO HiveMetaStore:589 - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
2018-05-17 17:35:09.283 - stdout> 2018-05-17 17:35:09 INFO ObjectStore:289 - ObjectStore, initialize called
2018-05-17 17:35:09.304 - stdout> Traceback (most recent call last):
2018-05-17 17:35:09.304 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/utils.py", line 63, in deco
2018-05-17 17:35:09.304 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/protocol.py", line 320, in get_return_value
2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JJavaErrorERROR:root:Exception while sending command.
2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last):
2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1062, in send_command
2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JNetworkError: Answer from Java side is empty
2018-05-17 17:35:09.307 - stdout>
2018-05-17 17:35:09.307 - stdout> During handling of the above exception, another exception occurred:
2018-05-17 17:35:09.307 - stdout>
2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last):
2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 908, in send_command
2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1067, in send_command
2018-05-17 17:35:09.307 - stdout> py4j.protocol.Py4JNetworkError: Error while receiving
2018-05-17 17:35:09.307 - stdout>
2018-05-17 17:35:09.307 - stdout> During handling of the above exception, another exception occurred:
2018-05-17 17:35:09.307 - stdout>
2018-05-17 17:35:09.307 - stdout> Traceback (most recent call last):
2018-05-17 17:35:09.307 - stdout> File "/home/jenkins/workspace/spark-master-test-sbt-hadoop-2.7/target/tmp/test3175560666562982753.py", line 8, in <module>
2018-05-17 17:35:09.307 - stdout> spark.sql("create table data_source_tbl_{} using json as select 1 i".format(version_index))
2018-05-17 17:35:09.307 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/session.py", line 708, in sql
2018-05-17 17:35:09.308 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/py4j-0.10.6-src.zip/py4j/java_gateway.py", line 1160, in __call__
2018-05-17 17:35:09.308 - stdout> File "/tmp/test-spark/spark-2.3.0/python/lib/pyspark.zip/pyspark/sql/utils.py", line 69, in deco
2018-05-17 17:35:09.308 - stdout> pyspark.sql.utils.AnalysisException: 'java.lang.RuntimeException: java.lang.RuntimeException: Unable to instantiate org.apache.hadoop.hive.ql.metadata.SessionHiveMetaStoreClient;'
2018-05-17 17:35:09.363 - stdout> 2018-05-17 17:35:09 INFO SparkContext:54 - Invoking stop() from shutdown hook
2018-05-17 17:35:09.374 - stdout> 2018-05-17 17:35:09 INFO MapOutputTrackerMasterEndpoint:54 - MapOutputTrackerMasterEndpoint stopped!
2018-05-17 17:35:09.381 - stdout> 2018-05-17 17:35:09 INFO MemoryStore:54 - MemoryStore cleared
2018-05-17 17:35:09.381 - stdout> 2018-05-17 17:35:09 INFO BlockManager:54 - BlockManager stopped
2018-05-17 17:35:09.387 - stdout> 2018-05-17 17:35:09 INFO BlockManagerMaster:54 - BlockManagerMaster stopped
2018-05-17 17:35:09.39 - stdout> 2018-05-17 17:35:09 INFO OutputCommitCoordinator$OutputCommitCoordinatorEndpoint:54 - OutputCommitCoordinator stopped!
2018-05-17 17:35:09.392 - stdout> 2018-05-17 17:35:09 INFO SparkContext:54 - Successfully stopped SparkContext
2018-05-17 17:35:09.392 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Shutdown hook called
2018-05-17 17:35:09.393 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef
2018-05-17 17:35:09.394 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c1b782ca-5e12-455d-985e-c278873dc853
2018-05-17 17:35:09.394 - stdout> 2018-05-17 17:35:09 INFO ShutdownHookManager:54 - Deleting directory /tmp/spark-c0ebfe07-2975-4d6d-9af0-e363bd28f6ef/pyspark-f626555a-3b3b-40d8-865b-92b3637f5d48
at org.scalatest.Assertions$class.newAssertionFailedException(Assertions.scala:528)
at org.scalatest.FunSuite.newAssertionFailedException(FunSuite.scala:1560)
at org.scalatest.Assertions$class.fail(Assertions.scala:1089)
at org.scalatest.FunSuite.fail(FunSuite.scala:1560)
at org.apache.spark.sql.hive.SparkSubmitTestUtils$class.runSparkSubmit(SparkSubmitTestUtils.scala:84)
at org.apache.spark.sql.hive.HiveExternalCatalogVersionsSuite.runSparkSubmit(HiveExternalCatalogVersionsSuite.scala:43)
at org.apache.spark.sql.hive.HiveExternalCatalogVersionsSuite$$anonfun$beforeAll$1.apply(HiveExternalCatalogVersionsSuite.scala:176)
at org.apache.spark.sql.hive.HiveExternalCatalogVersionsSuite$$anonfun$beforeAll$1.apply(HiveExternalCatalogVersionsSuite.scala:161)
at scala.collection.immutable.List.foreach(List.scala:381)
at org.apache.spark.sql.hive.HiveExternalCatalogVersionsSuite.beforeAll(HiveExternalCatalogVersionsSuite.scala:161)
at org.scalatest.BeforeAndAfterAll$class.liftedTree1$1(BeforeAndAfterAll.scala:212)
at org.scalatest.BeforeAndAfterAll$class.run(BeforeAndAfterAll.scala:210)
at org.apache.spark.SparkFunSuite.run(SparkFunSuite.scala:52)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:314)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:480)
at sbt.ForkMain$Run$2.call(ForkMain.java:296)
at sbt.ForkMain$Run$2.call(ForkMain.java:286)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)