2018-02-21 13:24:46,716 DEBUG SubmitXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[-] Workflow record created, Status [PREP]
2018-02-21 13:24:46,730 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [start]
2018-02-21 13:24:46,735 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Execute command [start] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,735 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] STARTED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=null
2018-02-21 13:24:46,737 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Starting job
2018-02-21 13:24:46,737 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Signaling job execution path [/] signal value [:start:] for node [:start:]
2018-02-21 13:24:46,738 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[]  Creating action for node [:start:]
2018-02-21 13:24:46,738 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Setting action for cred: 'null', name: ':start:'
2018-02-21 13:24:46,738 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Setting action for userRetryMax: '0', userRetryInterval: '10', name: ':start:'
2018-02-21 13:24:46,754 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] SignalXCommand: Name: :start:, Id: 0000002-180221105550169-oozie-oozi-W@:start:, Authcode:null
2018-02-21 13:24:46,763 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Updated the workflow status to 0000002-180221105550169-oozie-oozi-W  status =RUNNING
2018-02-21 13:24:46,764 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:24:46,766 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Execute command [action.start] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,767 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] STARTED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,771  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Start action [0000002-180221105550169-oozie-oozi-W@:start:] with user-retry state : userRetryCount [0], userRetryMax [0], userRetryInterval [10]
2018-02-21 13:24:46,771  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] [***0000002-180221105550169-oozie-oozi-W@:start:***]Action status=DONE
2018-02-21 13:24:46,771  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] [***0000002-180221105550169-oozie-oozi-W@:start:***]Action updated in DB!
2018-02-21 13:24:46,790 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:24:46,792 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Execute command [action.end] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,792 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] STARTED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,803 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] End, name [:start:] type [:START:] status[DONE] external status [OK] signal value [null]
2018-02-21 13:24:46,818 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:24:46,825 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Execute command [signal] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,825 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] STARTED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,828 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Signaling job execution path [/] signal value [OK] for node [:start:]
2018-02-21 13:24:46,828 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Exiting node [:start:] with transition[/#sqoop-node]
2018-02-21 13:24:46,829 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Signaling job execution path [/] signal value [::synch::] for node [sqoop-node]
2018-02-21 13:24:46,846 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:]  Creating action for node [sqoop-node]
2018-02-21 13:24:46,847 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Setting action for cred: 'null', name: 'sqoop-node'
2018-02-21 13:24:46,847 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Setting action for userRetryMax: '0', userRetryInterval: '10', name: 'sqoop-node'
2018-02-21 13:24:46,860 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] SignalXCommand: Name: sqoop-node, Id: 0000002-180221105550169-oozie-oozi-W@sqoop-node, Authcode:null
2018-02-21 13:24:46,882 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] ENDED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,882 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Queuing [2] commands with delay [0]ms
2018-02-21 13:24:46,883 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Released lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:24:46,883 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] ENDED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,883 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:24:46,883 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] ENDED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@:start:, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:24:46,883 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:24:46,883 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] ENDED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=null
2018-02-21 13:24:46,883 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Queuing [1] commands with delay [0]ms
2018-02-21 13:24:46,883 DEBUG StartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Released lock for [0000002-180221105550169-oozie-oozi-W] in [start]
2018-02-21 13:24:46,897 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Execute command [job.notification] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,904  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W
2018-02-21 13:24:46,898 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] Execute command [action.notification] key [0000002-180221105550169-oozie-oozi-W@:start:]
2018-02-21 13:24:46,905  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@:start:] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W@:start:
2018-02-21 13:24:46,905 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:24:46,910 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.start] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:24:46,911 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:24:46,934 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Start, name [sqoop-node] type [sqoop] configuration

<sqoop xmlns="uri:oozie:sqoop-action:0.2">
  <job-tracker>sandbox-hdp.hortonworks.com:8032</job-tracker>
  <name-node>hdfs://172.17.0.2:8020</name-node>
  <configuration>
    <property xmlns="">
      <name>yarn.nodemanager.vmem-check-enabled</name>
      <value>false</value>
      <source>programatically</source>
    </property>
    <property xmlns="">
      <name>yarn.nodemanager.vmem-pmem-ratio</name>
      <value>4</value>
      <source>programatically</source>
    </property>
    <property xmlns="">
      <name>mapreduce.job.queuename</name>
      <value>default</value>
      <source>programatically</source>
    </property>
    <property xmlns="">
      <name>oozie.hive.defaults</name>
      <value>/tmp/hive-site.xml</value>
      <source>programatically</source>
    </property>
    <property xmlns="">
      <name>mapred.job.queue.name</name>
      <value>default</value>
      <source>programatically</source>
    </property>
  </configuration>
  <command>import -Dorg.apache.sqoop.splitter.allow_text_splitter=true --connect jdbc:mysql://xxxxxxxx --table assurance --username xxxxxx --password xxxxxx --driver com.mysql.jdbc.Driver  --hive-import --hive-table assurance --check-column lastaccess --incremental lastmodified --map-column-hive firstaccess=TIMESTAMP,lastaccess=TIMESTAMP  -m 1 --merge-key macaddr</command>
  <file>/tmp/hive-site.xml#hive-site.xml</file>
</sqoop>

2018-02-21 13:24:46,936  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Start action [0000002-180221105550169-oozie-oozi-W@sqoop-node] with user-retry state : userRetryCount [0], userRetryMax [0], userRetryInterval [10]
2018-02-21 13:24:46,937 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Starting action 0000002-180221105550169-oozie-oozi-W@sqoop-node getting Action File System
2018-02-21 13:24:46,938 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Preparing action Dir through copying hdfs://172.17.0.2:8020/user/admin/oozie-oozi/0000002-180221105550169-oozie-oozi-W/sqoop-node--sqoop
2018-02-21 13:24:46,959 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Action Dir is ready. Submitting the action 
2018-02-21 13:24:46,977 DEBUG ConfigurationService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Configuration property [oozie.action.sqooprootlogger.log.level] not found, use given value []
2018-02-21 13:24:46,979 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Setting LibFilesArchives 
2018-02-21 13:24:47,298 DEBUG CredentialsProvider:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] About to relogin from keytab
2018-02-21 13:24:47,304 DEBUG CredentialsProvider:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Relogin from keytab successful
2018-02-21 13:24:47,311 DEBUG ConfigurationService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Configuration property [oozie.action.sqoop.launcher.mapreduce.job.ubertask.enable] not found, use given value []
2018-02-21 13:24:48,247 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Creating Job Client for action 0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:24:48,283 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Submitting the job through Job Client for action 0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:24:53,001 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] After submission get the launcherId job_1512099269514_0111
2018-02-21 13:24:53,002 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Action submit completed. Performing check 
2018-02-21 13:24:53,089  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Trying to get job [job_1512099269514_0111], attempt [1]
2018-02-21 13:24:53,150  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] checking action, hadoop job ID [job_1512099269514_0111] status [RUNNING]
2018-02-21 13:24:53,151 DEBUG SqoopActionExecutor:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Action check is done after submission
2018-02-21 13:24:53,157  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] [***0000002-180221105550169-oozie-oozi-W@sqoop-node***]Action status=RUNNING
2018-02-21 13:24:53,158  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] [***0000002-180221105550169-oozie-oozi-W@sqoop-node***]Action updated in DB!
2018-02-21 13:24:53,210 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] ENDED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:24:53,216 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Queuing [1] commands with delay [0]ms
2018-02-21 13:24:53,216 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:24:53,216 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.notification] key [0000002-180221105550169-oozie-oozi-W@sqoop-node]
2018-02-21 13:24:53,216  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:27:35,617 DEBUG CallbackServlet:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Received a CallbackServlet.doGet() with query string id=0000002-180221105550169-oozie-oozi-W@sqoop-node&status=SUCCEEDED
2018-02-21 13:27:35,618  INFO CallbackServlet:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] callback for action [0000002-180221105550169-oozie-oozi-W@sqoop-node]
2018-02-21 13:27:35,620 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [callback] key [null]
2018-02-21 13:27:35,621 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Queuing [1] commands with delay [0]ms
2018-02-21 13:27:35,625 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.check] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:27:35,625 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node priority =2
2018-02-21 13:27:36,038  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Trying to get job [job_1512099269514_0111], attempt [1]
2018-02-21 13:27:36,136  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] checking action, hadoop job ID [job_1512099269514_0111] status [RUNNING]
2018-02-21 13:27:36,142 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] ENDED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:27:36,142 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.check]
2018-02-21 13:28:26,471 DEBUG CallbackServlet:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Received a CallbackServlet.doGet() with query string id=0000002-180221105550169-oozie-oozi-W@sqoop-node&status=SUCCEEDED
2018-02-21 13:28:26,471  INFO CallbackServlet:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] callback for action [0000002-180221105550169-oozie-oozi-W@sqoop-node]
2018-02-21 13:28:26,480 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [callback] key [null]
2018-02-21 13:28:26,481 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Queuing [1] commands with delay [0]ms
2018-02-21 13:28:26,487 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.check] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:26,493 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node priority =2
2018-02-21 13:28:26,582  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Trying to get job [job_1512099269514_0111], attempt [1]
2018-02-21 13:28:26,641  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] checking action, hadoop job ID [job_1512099269514_0111] status [RUNNING]
2018-02-21 13:28:26,647 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] ENDED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:26,647 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.check]
2018-02-21 13:28:33,006 DEBUG CallbackServlet:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Received a CallbackServlet.doGet() with query string id=0000002-180221105550169-oozie-oozi-W@sqoop-node&status=SUCCEEDED
2018-02-21 13:28:33,006  INFO CallbackServlet:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] callback for action [0000002-180221105550169-oozie-oozi-W@sqoop-node]
2018-02-21 13:28:33,008 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [callback] key [null]
2018-02-21 13:28:33,009 DEBUG CompletedActionXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Queuing [1] commands with delay [0]ms
2018-02-21 13:28:33,014 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.check] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,014 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node priority =2
2018-02-21 13:28:33,059  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Trying to get job [job_1512099269514_0111], attempt [1]
2018-02-21 13:28:33,237  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Hadoop Jobs launched : [job_1512099269514_0112,job_1512099269514_0113]
2018-02-21 13:28:33,244  INFO SqoopActionExecutor:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] action completed, external ID [job_1512099269514_0111]
2018-02-21 13:28:33,264  WARN SqoopActionExecutor:523 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Launcher ERROR, reason: Main class [org.apache.oozie.action.hadoop.SqoopMain], exit code [1]
2018-02-21 13:28:33,275 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:28:33,277 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.end] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,277 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:28:33,284 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] End, name [sqoop-node] type [sqoop] status[DONE] external status [FAILED/KILLED] signal value [null]
2018-02-21 13:28:33,302  INFO ActionEndXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] ERROR is considered as FAILED for SLA
2018-02-21 13:28:33,320 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:28:33,325 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [signal] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,325 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] STARTED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:28:33,327 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Signaling job execution path [/] signal value [ERROR] for node [sqoop-node]
2018-02-21 13:28:33,327 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Exiting node [sqoop-node] with transition[/#fail]
2018-02-21 13:28:33,328 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Signaling job execution path [/] signal value [::synch::] for node [fail]
2018-02-21 13:28:33,329 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node]  Creating action for node [fail]
2018-02-21 13:28:33,329 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Setting action for cred: 'null', name: 'fail'
2018-02-21 13:28:33,329 DEBUG LiteWorkflowStoreService:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Setting action for userRetryMax: '0', userRetryInterval: '10', name: 'fail'
2018-02-21 13:28:33,341 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] SignalXCommand: Name: fail, Id: 0000002-180221105550169-oozie-oozi-W@fail, Authcode:null
2018-02-21 13:28:33,356 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:28:33,358 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Execute command [action.start] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,358 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] STARTED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,364  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Start action [0000002-180221105550169-oozie-oozi-W@fail] with user-retry state : userRetryCount [0], userRetryMax [0], userRetryInterval [10]
2018-02-21 13:28:33,364  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] [***0000002-180221105550169-oozie-oozi-W@fail***]Action status=DONE
2018-02-21 13:28:33,365  INFO ActionStartXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] [***0000002-180221105550169-oozie-oozi-W@fail***]Action updated in DB!
2018-02-21 13:28:33,373 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:28:33,375 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Execute command [action.end] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,375 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] STARTED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,382 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] End, name [fail] type [:KILL:] status[DONE] external status [OK] signal value [null]
2018-02-21 13:28:33,397 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Acquired lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:28:33,401 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Execute command [signal] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,402 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] STARTED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,405 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Signaling job execution path [/] signal value [OK] for node [fail]
2018-02-21 13:28:33,406 DEBUG LiteWorkflowInstance:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Completing job, kill node [fail]
2018-02-21 13:28:33,441 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Try to resolve KillNode message for jobid [0000002-180221105550169-oozie-oozi-W], actionId [0000002-180221105550169-oozie-oozi-W@fail], before resolve [Sqoop failed, error message[${wf:errorMessage(wf:lastErrorNode())}]], after resolve [Sqoop failed, error message[Main class [org.apache.oozie.action.hadoop.SqoopMain], exit code [1]]]
2018-02-21 13:28:33,451 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Updated the workflow status to 0000002-180221105550169-oozie-oozi-W  status =KILLED
2018-02-21 13:28:33,452 DEBUG WfEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Execute command [wf_end] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,452 DEBUG WfEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] STARTED WFEndXCommand 0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,453 DEBUG WfEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] WF tmp dir :hdfs://172.17.0.2:8020/user/admin/oozie-oozi/0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,465 DEBUG WfEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED WFEndXCommand 0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,465 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,465 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Queuing [2] commands with delay [0]ms
2018-02-21 13:28:33,465 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:28:33,465 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,465 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:28:33,466 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED ActionStartXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@fail, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,466 DEBUG ActionStartXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.start]
2018-02-21 13:28:33,466 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED SignalCommand for jobid=0000002-180221105550169-oozie-oozi-W, actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:28:33,466 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Queuing [1] commands with delay [0]ms
2018-02-21 13:28:33,466 DEBUG SignalXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [signal]
2018-02-21 13:28:33,466 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED ActionEndXCommand for action 0000002-180221105550169-oozie-oozi-W@sqoop-node
2018-02-21 13:28:33,466 DEBUG ActionEndXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.end]
2018-02-21 13:28:33,466 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] ENDED ActionCheckXCommand for wf actionId=0000002-180221105550169-oozie-oozi-W@sqoop-node, jobId=0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,466 DEBUG ActionCheckXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[admin] GROUP[-] TOKEN[] APP[sqoop-importAssurance] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Released lock for [0000002-180221105550169-oozie-oozi-W] in [action.check]
2018-02-21 13:28:33,466 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] Execute command [action.notification] key [0000002-180221105550169-oozie-oozi-W@fail]
2018-02-21 13:28:33,466  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@fail] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W@fail
2018-02-21 13:28:33,466 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] Execute command [job.notification] key [0000002-180221105550169-oozie-oozi-W]
2018-02-21 13:28:33,466  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W
2018-02-21 13:28:33,466 DEBUG WorkflowNotificationXCommand:526 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] Execute command [action.notification] key [0000002-180221105550169-oozie-oozi-W@sqoop-node]
2018-02-21 13:28:33,466  INFO WorkflowNotificationXCommand:520 - SERVER[sandbox-hdp.hortonworks.com] USER[-] GROUP[-] TOKEN[-] APP[-] JOB[0000002-180221105550169-oozie-oozi-W] ACTION[0000002-180221105550169-oozie-oozi-W@sqoop-node] No Notification URL is defined. Therefore nothing to notify for job 0000002-180221105550169-oozie-oozi-W@sqoop-node