[
https://issues.apache.org/jira/browse/OOZIE-2459?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15191005#comment-15191005
]
abhishek bafna commented on OOZIE-2459:
---------------------------------------
The reasoning you have provided looks good. It must have updated the state of
the coordinator job between the completion of first case and start of the
second case. But what surprises me that, I have seen the same test cases
failing and did not find the logs which tells when the first time
StatusTransitService executed. I do see it running it second time.
Adding the more logs.
{noformat}
15:45:49,476 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j configuration file
[oozie-log4j.properties]
15:45:49,476 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j configuration file loaded
from [CLASSPATH]
15:45:49,477 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j reload interval [disabled]
15:45:49,477 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozie log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,477 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozieError log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,477 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozieaudit log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,477 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie home dir
[/grid/0/jenkins/workspace/UT-oozie-2.4-maint/hdp-BUILDS/oozie-4.2.0.2.4.1.0/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone]
15:45:49,478 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie conf dir
[/grid/0/jenkins/workspace/UT-oozie-2.4-maint/hdp-BUILDS/oozie-4.2.0.2.4.1.0/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf]
15:45:49,478 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie conf file [oozie-site.xml]
15:45:49,482 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.url]
15:45:49,482 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.driver]
15:45:49,482 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.create.db.schema]
15:45:49,482 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.services]
15:45:49,483 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration change via System
Property, [oozie.service.HadoopAccessorService.supported.filesystems]=[*]
15:45:49,483 WARN ConfigurationService:523 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System property
[oozie.action.newId] no defined in Oozie configuration, ignored
15:45:49,483 WARN ConfigurationService:523 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System property
[oozie.action.conf.xml] no defined in Oozie configuration, ignored
15:45:49,484 WARN Services:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System ID [oozie-jenk] exceeds
maximum length [10], trimming
15:45:49,484 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Exiting null Entering NORMAL
15:45:49,484 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initialized runtime directory
[/tmp/oozie-jenk7256241226430388045.dir]
15:45:49,484 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Programmatic configuration
change,
property[oozie.services]=[org.apache.oozie.service.HadoopAccessorService,org.apache.oozie.service.JPAService,org.apache.oozie.service.StoreService]
15:45:49,484 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Services list obtained from
property 'oozie.services'
15:45:49,485 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Services list obtained from
property 'oozie.services.ext'
15:45:49,485 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.HadoopAccessorService] implementation [class
org.apache.oozie.service.HadoopAccessorService]
15:45:49,485 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JPAService] implementation [class
org.apache.oozie.service.JPAService]
15:45:49,485 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StoreService] implementation [class
org.apache.oozie.service.StoreService]
15:45:49,485 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] JOB_TRACKER_WHITELIST :[], Total
entries :0
15:45:49,485 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] NAME_NODE_WHITELIST :[], Total
entries :0
15:45:49,486 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie Kerberos Authentication
[disabled]
15:45:49,508 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] password key is
oozie.service.JPAService.jdbc.password
15:45:49,508 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] credential provider path is null
15:45:49,508 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] password is null
15:45:49,659 INFO JPAService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] All entities initialized
15:45:49,660 INFO JPAService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] JPA configuration:
DriverClassName=org.hsqldb.jdbcDriver,Url=jdbc:hsqldb:mem:oozie-db;create=true,Username=sa,Password=***,MaxActive=10,TestOnBorrow=false,TestOnReturn=false,TestWhileIdle=false,
15:45:49,660 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initialized
15:45:49,660 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Running with JARs for Hadoop
version [2.7.1.2.4.1.0-306]
15:45:49,660 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie System ID [oozie-jenk]
started!
15:45:49,672 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in WF_JOBS removed
from DB!
15:45:49,672 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in WF_ACTIONS removed
from DB!
15:45:49,672 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 1 entries in COORD_JOBS removed
from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 5 entries in COORD_ACTIONS
removed from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in BUNDLE_JOBS removed
from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in BUNDLE_ACTIONS
removed from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in SLA_EVENTS removed
from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in SLA_REGISTRATION
removed from DB!
15:45:49,673 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] 0 entries in SLA_SUMMARY removed
from DB!
15:45:49,674 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Shutdown
Setting FS testcase work
dir[hdfs://localhost:33973/user/test/e13cd411-818c-4986-85b5-eb3c63fa52f6]
15:45:49,724 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5]
*******************************************************************************
STARTUP MSG: Oozie BUILD_VERSION [4.2.0.2.4.1.0-306] compiled by [jenkins] on
[2016.03.02-13:46:41GMT]
STARTUP MSG: revision
[(HEAD@detached]@[g...@github.com:hortonworks/oozie.git]
*******************************************************************************
15:45:49,725 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j configuration file
[oozie-log4j.properties]
15:45:49,725 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j configuration file loaded
from [CLASSPATH]
15:45:49,725 INFO XLogService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Log4j reload interval [disabled]
15:45:49,726 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozie log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,726 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozieError log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,726 WARN XLogUtil:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie WS oozieaudit log will be
disabled, missing property 'log4j.appender.oozie.File' for 'oozie' appender
15:45:49,726 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie home dir
[/grid/0/jenkins/workspace/UT-oozie-2.4-maint/hdp-BUILDS/oozie-4.2.0.2.4.1.0/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone]
15:45:49,726 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie conf dir
[/grid/0/jenkins/workspace/UT-oozie-2.4-maint/hdp-BUILDS/oozie-4.2.0.2.4.1.0/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf]
15:45:49,726 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie conf file [oozie-site.xml]
15:45:49,731 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.url]
15:45:49,731 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.driver]
15:45:49,731 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.create.db.schema]
15:45:49,731 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.services]
15:45:49,731 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration change via System
Property, [oozie.service.HadoopAccessorService.supported.filesystems]=[*]
15:45:49,732 INFO ConfigurationService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration change via System
Property, [oozie.processing.timezone]=[UTC]
15:45:49,732 WARN ConfigurationService:523 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System property
[oozie.action.newId] no defined in Oozie configuration, ignored
15:45:49,732 WARN ConfigurationService:523 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System property
[oozie.action.conf.xml] no defined in Oozie configuration, ignored
15:45:49,732 WARN Services:523 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] System ID [oozie-jenk] exceeds
maximum length [10], trimming
15:45:49,733 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Exiting null Entering NORMAL
15:45:49,733 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initialized runtime directory
[/tmp/oozie-jenk2030223080399852703.dir]
15:45:49,733 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Services list obtained from
property 'oozie.services'
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Services list obtained from
property 'oozie.services.ext'
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SchedulerService] implementation [class
org.apache.oozie.service.SchedulerService]
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.InstrumentationService] implementation [class
org.apache.oozie.service.InstrumentationService]
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.MemoryLocksService] implementation [class
org.apache.oozie.service.MemoryLocksService]
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.UUIDService] implementation [class
org.apache.oozie.service.UUIDService]
15:45:49,734 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ELService] implementation [class
org.apache.oozie.service.ELService]
15:45:49,735 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.AuthorizationService] implementation [class
org.apache.oozie.service.AuthorizationService]
15:45:49,735 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.UserGroupInformationService] implementation [class
org.apache.oozie.service.UserGroupInformationService]
15:45:49,735 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.HadoopAccessorService] implementation [class
org.apache.oozie.service.HadoopAccessorService]
15:45:49,735 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JobsConcurrencyService] implementation [class
org.apache.oozie.service.JobsConcurrencyService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.URIHandlerService] implementation [class
org.apache.oozie.service.URIHandlerService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.DagXLogInfoService] implementation [class
org.apache.oozie.service.DagXLogInfoService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SchemaService] implementation [class
org.apache.oozie.service.SchemaService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.WorkflowAppService] implementation [class
org.apache.oozie.service.LiteWorkflowAppService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JPAService] implementation [class
org.apache.oozie.service.JPAService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StoreService] implementation [class
org.apache.oozie.service.StoreService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SLAStoreService] implementation [class
org.apache.oozie.service.SLAStoreService]
15:45:49,736 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.WorkflowStoreService] implementation [class
org.apache.oozie.service.DBLiteWorkflowStoreService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CallbackService] implementation [class
org.apache.oozie.service.CallbackService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ActionService] implementation [class
org.apache.oozie.service.ActionService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CallableQueueService] implementation [class
org.apache.oozie.service.CallableQueueService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ActionCheckerService] implementation [class
org.apache.oozie.service.ActionCheckerService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.RecoveryService] implementation [class
org.apache.oozie.service.RecoveryService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.PurgeService] implementation [class
org.apache.oozie.service.PurgeService]
15:45:49,737 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CoordinatorEngineService] implementation [class
org.apache.oozie.service.CoordinatorEngineService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.BundleEngineService] implementation [class
org.apache.oozie.service.BundleEngineService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.DagEngineService] implementation [class
org.apache.oozie.service.DagEngineService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CoordMaterializeTriggerService] implementation [class
org.apache.oozie.service.CoordMaterializeTriggerService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StatusTransitService] implementation [class
org.apache.oozie.service.StatusTransitService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.PauseTransitService] implementation [class
org.apache.oozie.service.PauseTransitService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.GroupsService] implementation [class
org.apache.oozie.service.GroupsService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ProxyUserService] implementation [class
org.apache.oozie.service.ProxyUserService]
15:45:49,738 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.XLogStreamingService] implementation [class
org.apache.oozie.service.XLogStreamingService]
15:45:49,739 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JvmPauseMonitorService] implementation [class
org.apache.oozie.service.JvmPauseMonitorService]
15:45:49,739 DEBUG Services:526 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SparkConfigurationService] implementation [class
org.apache.oozie.service.SparkConfigurationService]
15:45:49,742 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration property
[oozie.service.AuthorizationService.security.enabled] not found, use given
value [null]
15:45:49,742 WARN AuthorizationService:523 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie running with authorization
disabled
15:45:49,743 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] JOB_TRACKER_WHITELIST :[], Total
entries :0
15:45:49,743 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] NAME_NODE_WHITELIST :[], Total
entries :0
15:45:49,743 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie Kerberos Authentication
[disabled]
15:45:49,774 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration property
[oozie.service.URIHandlerService.uri.handler.default] not found, use given
value [null]
15:45:49,774 INFO URIHandlerService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loaded urihandlers
[org.apache.oozie.dependency.FSURIHandler]
15:45:49,774 INFO URIHandlerService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Loaded default urihandler
org.apache.oozie.dependency.FSURIHandler
15:45:49,808 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration property
[oozie.subworkflow.classpath.inheritance] not found, use given value [false]
15:45:49,808 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] password key is
oozie.service.JPAService.jdbc.password
15:45:49,808 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] credential provider path is null
15:45:49,808 INFO HadoopAccessorService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] password is null
15:45:49,976 INFO JPAService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] All entities initialized
15:45:49,977 INFO JPAService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] JPA configuration:
DriverClassName=org.hsqldb.jdbcDriver,Url=jdbc:hsqldb:mem:oozie-db;create=true,Username=sa,Password=***,MaxActive=10,TestOnBorrow=false,TestOnReturn=false,TestWhileIdle=false,
15:45:49,978 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [hive]
class [class org.apache.oozie.action.hadoop.HiveActionExecutor]
15:45:49,978 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [shell]
class [class org.apache.oozie.action.hadoop.ShellActionExecutor]
15:45:49,979 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[:START:] class [class org.apache.oozie.action.control.StartActionExecutor]
15:45:49,979 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[:FORK:] class [class org.apache.oozie.action.control.ForkActionExecutor]
15:45:49,979 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[switch] class [class org.apache.oozie.action.decision.DecisionActionExecutor]
15:45:49,979 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [spark]
class [class org.apache.oozie.action.hadoop.SparkActionExecutor]
15:45:49,979 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [ssh]
class [class org.apache.oozie.action.ssh.SshActionExecutor]
15:45:49,980 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [hive2]
class [class org.apache.oozie.action.hadoop.Hive2ActionExecutor]
15:45:49,981 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [pig]
class [class org.apache.oozie.action.hadoop.PigActionExecutor]
15:45:49,981 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [:END:]
class [class org.apache.oozie.action.control.EndActionExecutor]
15:45:49,981 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [email]
class [class org.apache.oozie.action.email.EmailActionExecutor]
15:45:49,981 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[distcp] class [class org.apache.oozie.action.hadoop.DistcpActionExecutor]
15:45:49,981 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[:KILL:] class [class org.apache.oozie.action.control.KillActionExecutor]
15:45:49,982 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[sub-workflow] class [class
org.apache.oozie.action.oozie.SubWorkflowActionExecutor]
15:45:49,982 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [fs]
class [class org.apache.oozie.action.hadoop.FsActionExecutor]
15:45:49,982 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [java]
class [class org.apache.oozie.action.hadoop.JavaActionExecutor]
15:45:49,982 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[:JOIN:] class [class org.apache.oozie.action.control.JoinActionExecutor]
15:45:49,982 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type [sqoop]
class [class org.apache.oozie.action.hadoop.SqoopActionExecutor]
15:45:49,983 DEBUG ActionService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initializing action type
[map-reduce] class [class
org.apache.oozie.action.hadoop.MapReduceActionExecutor]
15:45:49,983 INFO ActionService:520 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initialized action types: [hive,
shell, :START:, :FORK:, switch, spark, ssh, hive2, pig, :END:, email, distcp,
:KILL:, sub-workflow, fs, java, :JOIN:, sqoop, map-reduce]
15:45:49,983 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type [kill]
15:45:49,983 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type [resume]
15:45:49,983 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type [suspend]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[bundle_kill]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[bundle_resume]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[bundle_suspend]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[coord_kill]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[coord_change]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[coord_resume]
15:45:49,984 DEBUG CallableQueueService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Adding interrupt type
[coord_suspend]
15:45:49,987 DEBUG ConfigurationService:526 - USER[jenkins] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Configuration property
[oozie.service.CoordMaterializeTriggerService.scheduling.interval] not found,
use given value [300]
15:45:49,989 WARN SparkConfigurationService:523 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Spark Configuration could not be
loaded for *:
/grid/0/jenkins/workspace/UT-oozie-2.4-maint/hdp-BUILDS/oozie-4.2.0.2.4.1.0/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf/spark-conf
does not exist
15:45:49,989 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Initialized
15:45:49,989 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Running with JARs for Hadoop
version [2.7.1.2.4.1.0-306]
15:45:49,989 INFO Services:520 - USER[jenkins] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-160302154546912-oozie-jenk-C]
ACTION[0000000-160302154546912-oozie-jenk-C@5] Oozie System ID [oozie-jenk]
started!
15:45:50,053 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Acquired lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:50,076 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Execute command
[coord_action_input] key [0000000-160302154549740-oozie-jenk-C]
15:45:50,076 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1]
[0000000-160302154549740-oozie-jenk-C@1]::ActionInputCheck:: Action is in
WAITING state.
15:45:50,078 INFO CoordActionInputCheckXCommand:520 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] NONE execution: Preparing to
skip action [0000000-160302154549740-oozie-jenk-C@1] because the current time
[2016-03-02T15:45Z] is later than the nominal time [2016-03-02T14:46Z] of the
current action]
15:45:50,079 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Queuing [1] commands with delay
[0]ms
15:45:50,079 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Released lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:50,079 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Waiting up to [50,000] msec
15:45:50,080 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Waiting up to [49,999] msec
15:45:50,085 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Acquired lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_timeout]
15:45:50,098 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Execute command
[coord_action_timeout] key [0000000-160302154549740-oozie-jenk-C]
15:45:50,098 INFO CoordActionSkipXCommand:520 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Setting action
[0000000-160302154549740-oozie-jenk-C@1] status to SKIPPED
15:45:50,101 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Queuing [1] commands with delay
[100]ms
15:45:50,103 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Released lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_timeout]
15:45:50,206 DEBUG CoordActionNotificationXCommand:526 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Notification URL :null
15:45:50,206 DEBUG CoordActionNotificationXCommand:526 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] Execute command
[coord_action_notification] key [0000000-160302154549740-oozie-jenk-C@1]
15:45:50,206 INFO CoordActionNotificationXCommand:520 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@1] No Notification URL is defined.
Therefore nothing to notify for job 0000000-160302154549740-oozie-jenk-C@1
15:45:51,790 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@2] Acquired lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:51,794 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@2] Released lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:51,794 WARN CoordActionInputCheckXCommand:523 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@2] E1100: Command precondition does
not hold before execution,
[[0000000-160302154549740-oozie-jenk-C@2]::CoordActionInputCheck:: Ignoring
action. Coordinator job is not in
RUNNING/RUNNINGWITHERROR/PAUSED/PAUSEDWITHERROR state, but state=SUCCEEDED],
Error Code: E1100
15:45:51,794 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@2] Waiting up to [50,000] msec
15:45:51,819 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Acquired lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:51,821 DEBUG CoordActionInputCheckXCommand:526 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Released lock for
[0000000-160302154549740-oozie-jenk-C] in [coord_action_input]
15:45:51,821 WARN CoordActionInputCheckXCommand:523 - USER[jenkins] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] E1100: Command precondition does
not hold before execution,
[[0000000-160302154549740-oozie-jenk-C@3]::CoordActionInputCheck:: Ignoring
action. Coordinator job is not in
RUNNING/RUNNINGWITHERROR/PAUSED/PAUSEDWITHERROR state, but state=SUCCEEDED],
Error Code: E1100
15:45:51,821 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [50,000] msec
15:45:51,823 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [49,998] msec
15:45:52,824 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [48,997] msec
15:45:53,827 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [47,995] msec
15:45:54,829 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [46,993] msec
15:45:55,831 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [45,990] msec
15:45:56,833 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [44,988] msec
15:45:57,835 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [43,987] msec
15:45:58,837 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [42,985] msec
15:45:59,838 INFO TestCoordActionInputCheckXCommand:520 - USER[jenkins]
GROUP[-] TOKEN[] APP[no-op-wf] JOB[0000000-160302154549740-oozie-jenk-C]
ACTION[0000000-160302154549740-oozie-jenk-C@3] Waiting up to [41,983] msec
15:45:59,988 INFO StatusTransitService$StatusTransitRunnable:520 - Acquired
lock for [org.apache.oozie.service.StatusTransitService]
15:45:59,988 INFO PauseTransitService:520 - Acquired lock for
[org.apache.oozie.service.PauseTransitService]
15:45:59,990 DEBUG RecoveryService$RecoveryRunnable:526 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[-] ACTION[-] QUEUING [ WF_ACTIONS 0, COORD_ACTIONS : 0,
COORD_READY_JOBS : 0, BUNDLE_ACTIONS : 0] for potential recovery
15:45:59,988 DEBUG ActionCheckerService$ActionCheckRunnable:526 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] QUEUING [] for potential checking
15:45:59,988 INFO StatusTransitService$StatusTransitRunnable:520 - Running
coordinator status service from last instance time = 2016-03-02T15:45Z
15:45:59,991 INFO StatusTransitService$StatusTransitRunnable:520 - Running
bundle status service from last instance time = 2016-03-02T15:45Z
15:45:59,992 INFO StatusTransitService$StatusTransitRunnable:520 - Released
lock for [org.apache.oozie.service.StatusTransitService]
15:46:00,024 INFO
CoordMaterializeTriggerService$CoordMaterializeTriggerRunnable:520 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] CoordMaterializeTriggerService - Curr
Date= 2016-03-02T15:50Z, Num jobs to materialize = 0
15:46:00,024 DEBUG PurgeXCommand:526 - USER[-] GROUP[-] TOKEN[-] APP[-] JOB[-]
ACTION[-] Execute command [purge] key [null]
15:46:00,024 INFO
CoordMaterializeTriggerService$CoordMaterializeTriggerRunnable:520 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] Released lock for
[org.apache.oozie.service.CoordMaterializeTriggerService]
15:46:00,024 INFO PurgeXCommand:520 - USER[-] GROUP[-] TOKEN[-] APP[-] JOB[-]
ACTION[-] STARTED Purge to purge Workflow Jobs older than [30] days,
Coordinator Jobs older than [7] days, and Bundlejobs older than [7] days.
15:46:00,024 INFO PurgeXCommand:520 - USER[-] GROUP[-] TOKEN[-] APP[-] JOB[-]
ACTION[-] ENDED Purge deleted [0] workflows, [0] coordinatorActions, [0]
coordinators, [0] bundles
15:46:00,025 INFO PauseTransitService:520 - Released lock for
[org.apache.oozie.service.PauseTransitService]
{noformat}
> TestCoordActionInputCheckXCommand.testNone is flakey
> ----------------------------------------------------
>
> Key: OOZIE-2459
> URL: https://issues.apache.org/jira/browse/OOZIE-2459
> Project: Oozie
> Issue Type: Bug
> Components: action, tests
> Affects Versions: 4.1.0
> Reporter: Ferenc Denes
> Assignee: Ferenc Denes
> Priority: Minor
> Fix For: trunk
>
> Attachments: OOZIE-2459-1.patch
>
>
> TestCoordActionInputCheckXCommand.testNone is flakey.
> Also the TestCoordActionInputCheckXCommandNonUTC.testNone which is very
> similar.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
