Alex Savitsky created LIVY-447:
----------------------------------
Summary: Batch session appears "dead" when launched against YARN
cluster, but the job completes fine.
Key: LIVY-447
URL: https://issues.apache.org/jira/browse/LIVY-447
Project: Livy
Issue Type: Bug
Components: Batch
Affects Versions: 0.5.0
Reporter: Alex Savitsky
Job was launched using the following request:
POST /batches
{{{}}
{{ "file": "/user/alsavits/oats/oats-spark-controls-exec.jar",}}
{{ "className": "com.rbc.rbccm.regops.controls.CompletenessControl",}}
{{ "args": ["ET", "20171229"]}}
{{}}}
Relevant Livy config:
{{livy.spark.master = yarn}}
{{livy.spark.deploy-mode = cluster}}
Livy log:
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Requesting a new application from cluster with 8 NodeManagers}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Verifying our application has not requested more than the maximum
memory capability of the cluster (174080 MB per container)}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Will allocate AM container, with 1408 MB memory including 384 MB
overhead}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Setting up container launch context for our AM}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Setting up the launch environment for our AM container}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
Client: Preparing resources for our AM container}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
YarnSparkHadoopUtil: getting token for namenode:
hdfs://guedlpahdp001.devfg.rbc.com:8020/user/DRLB0SRVCTRLRW/.sparkStaging/application_1520006903702_0110}}
{{18/03/05 12:03:39 INFO LineBufferedStream: stdout: 18/03/05 12:03:39 INFO
DFSClient: Created HDFS_DELEGATION_TOKEN token 73717 for DRLB0SRVCTRLRW on
10.61.34.124:8020}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
metastore: Trying to connect to metastore with URI
thrift://guedlpahdp002.devfg.rbc.com:9083}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
metastore: Connected to metastore.}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
YarnSparkHadoopUtil: HBase class not found java.lang.ClassNotFoundException:
org.apache.hadoop.hbase.HBaseConfiguration}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
Client: Use hdfs cache file as spark.yarn.archive for HDP,
hdfsCacheFile:hdfs:///hdp/apps/2.6.3.0-235/spark2/spark2-hdp-yarn-archive.tar.gz}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
Client: Source and destination file systems are the same. Not copying
hdfs:/hdp/apps/2.6.3.0-235/spark2/spark2-hdp-yarn-archive.tar.gz}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
Client: Source and destination file systems are the same. Not copying
hdfs://guedlpahdp001.devfg.rbc.com:8020/user/alsavits/oats/oats-spark-controls-exec.jar}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
Client: Uploading resource
file:/tmp/spark-91b652aa-704d-4986-9435-cbd369c62f71/__spark_conf__2946776456430567485.zip
->
hdfs://guedlpahdp001.devfg.rbc.com:8020/user/DRLB0SRVCTRLRW/.sparkStaging/application_1520006903702_0110/__spark_conf__.zip}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
SecurityManager: Changing view acls to: drlb0ots,DRLB0SRVCTRLRW}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
SecurityManager: Changing modify acls to: drlb0ots,DRLB0SRVCTRLRW}}
{{18/03/05 12:03:41 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
SecurityManager: Changing view acls groups to:}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:41 INFO
SecurityManager: Changing modify acls groups to:}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
SecurityManager: SecurityManager: authentication disabled; ui acls disabled;
users with view permissions: Set(drlb0ots, DRLB0SRVCTRLRW); groups with view
permissions: Set(); users with modify permissions: Set(drlb0ots,
DRLB0SRVCTRLRW); groups with modify permissions: Set()}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
Client: Submitting application application_1520006903702_0110 to
ResourceManager}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
YarnClientImpl: Submitted application application_1520006903702_0110}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
Client: Application report for application_1520006903702_0110 (state:
ACCEPTED)}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
Client:}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: client token: Token \{
kind: YARN_CLIENT_TOKEN, service: }}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: diagnostics: AM container
is launched, waiting for AM container to Register with RM}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: ApplicationMaster host:
N/A}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: ApplicationMaster RPC
port: -1}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: queue: default}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: start time: 1520269422040}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: final status: UNDEFINED}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: tracking URL:
{color:#FF0000}http://guedlpahdp001.devfg.rbc.com:8088/proxy/application_1520006903702_0110/{color}}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: user: DRLB0SRVCTRLRW}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
ShutdownHookManager: Shutdown hook called}}
{{18/03/05 12:03:42 INFO LineBufferedStream: stdout: 18/03/05 12:03:42 INFO
ShutdownHookManager: Deleting directory
/tmp/spark-91b652aa-704d-4986-9435-cbd369c62f71}}
Later, when the created batch is queried:
GET /batches/2
{{{}}
{{ "id": 2,}}
{{ "state": "dead",}}
{{ "appId": null,}}
{{ "appInfo": {}}
{{ "driverLogUrl": null,}}
{{ "sparkUiUrl": null}}
{{ },}}
{{ "log": [}}
{{ "stdout: ",}}
{{ "ls: cannot access /usr/hdp/2.6.3.0-235/hadoop/lib: No such file or
directory",}}
{{ "\nstderr: ",}}
{{ "\nYARN Diagnostics: ",}}
{{ "java.lang.RuntimeException: java.lang.RuntimeException:
java.lang.ClassNotFoundException: Class
org.apache.hadoop.yarn.client.RequestHedgingRMFailoverProxyProvider not
found",}}
{{ "org.apache.hadoop.conf.Configuration.getClass(Configuration.java:2227)
org.apache.hadoop.yarn.client.RMProxy.createRMFailoverProxyProvider(RMProxy.java:161)
org.apache.hadoop.yarn.client.RMProxy.createRMProxy(RMProxy.java:94)
org.apache.hadoop.yarn.client.ClientRMProxy.createRMProxy(ClientRMProxy.java:72)
org.apache.hadoop.yarn.client.api.impl.YarnClientImpl.serviceStart(YarnClientImpl.java:187)
org.apache.hadoop.service.AbstractService.start(AbstractService.java:193)
org.apache.livy.utils.SparkYarnApp$.yarnClient$lzycompute(SparkYarnApp.scala:51)
org.apache.livy.utils.SparkYarnApp$.yarnClient(SparkYarnApp.scala:48)
org.apache.livy.utils.SparkYarnApp$.$lessinit$greater$default$6(SparkYarnApp.scala:119)
org.apache.livy.utils.SparkApp$$anonfun$create$1.apply(SparkApp.scala:91)
org.apache.livy.utils.SparkApp$$anonfun$create$1.apply(SparkApp.scala:91)
org.apache.livy.utils.SparkYarnApp.org$apache$livy$utils$SparkYarnApp$$getAppIdFromTag(SparkYarnApp.scala:175)
org.apache.livy.utils.SparkYarnApp$$anonfun$1$$anonfun$4.apply(SparkYarnApp.scala:239)
org.apache.livy.utils.SparkYarnApp$$anonfun$1$$anonfun$4.apply(SparkYarnApp.scala:236)
scala.Option.getOrElse(Option.scala:121)
org.apache.livy.utils.SparkYarnApp$$anonfun$1.apply$mcV$sp(SparkYarnApp.scala:236)
org.apache.livy.Utils$$anon$1.run(Utils.scala:94)"}}
{{ ]}}
{{}}}
However, when hitting the tracking URL (shown in Livy log above), the
application status is RUNNING, and it later completes successfully in 2-3
minutes time.
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
