[
https://issues.apache.org/jira/browse/OOZIE-2459?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15204521#comment-15204521
]
Ferenc Denes commented on OOZIE-2459:
-------------------------------------
[~abhishekbafna] I could not find the logs I based my research on, probably
they were purged already. The logs we have do not contain the first run of the
StatusTransitService's relevant entries.
The logs I have found now are:
{noformat}
Setting testcase work
dir[/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone]
18:37:31,362 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5]
*******************************************************************************
STARTUP MSG: Oozie BUILD_VERSION [4.1.0-cdh5.6.0-SNAPSHOT] compiled by
[jenkins] on [${build.time}]
STARTUP MSG: revision [${vc.revision}]@[${vc.url}]
*******************************************************************************
18:37:31,362 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j configuration file
[oozie-log4j.properties]
18:37:31,362 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j configuration file loaded
from [CLASSPATH]
18:37:31,362 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j reload interval [disabled]
18:37:31,363 WARN XLogService:523 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie WS log will be disabled,
missing property 'log4j.appender.oozie.File' for 'oozie' appender
18:37:31,363 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie home dir
[/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone]
18:37:31,363 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie conf dir
[/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf]
18:37:31,363 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie conf file [oozie-site.xml]
18:37:31,365 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.url]
18:37:31,365 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.create.db.schema]
18:37:31,365 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.driver]
18:37:31,365 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.services]
18:37:31,366 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration change via System
Property, [oozie.service.HadoopAccessorService.supported.filesystems]=[*]
18:37:31,366 WARN ConfigurationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System property
[oozie.action.newId] no defined in Oozie configuration, ignored
18:37:31,366 WARN ConfigurationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System property
[oozie.action.conf.xml] no defined in Oozie configuration, ignored
18:37:31,366 WARN Services:523 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System ID [oozie-jenk] exceeds
maximum length [10], trimming
18:37:31,366 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Exiting null Entering NORMAL
18:37:31,366 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initialized runtime directory
[/tmp/oozie-jenk2587469029651284218.dir]
18:37:31,367 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Programmatic configuration
change,
property[oozie.services]=[org.apache.oozie.service.JPAService,org.apache.oozie.service.StoreService]
18:37:31,367 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Services list obtained from
property 'oozie.services'
18:37:31,367 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Services list obtained from
property 'oozie.services.ext'
18:37:31,367 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JPAService] implementation [class
org.apache.oozie.service.JPAService]
18:37:31,367 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StoreService] implementation [class
org.apache.oozie.service.StoreService]
18:37:31,367 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[hadoop.security.credential.clear-text-fallback] not found, use given value
[true]
18:37:31,431 INFO JPAService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] All entities initialized
18:37:31,431 INFO JPAService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-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,
18:37:31,431 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initialized
18:37:31,431 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Running with JARs for Hadoop
version [2.6.0-cdh5.6.0-SNAPSHOT]
18:37:31,432 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie System ID [oozie-jenk]
started!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in WF_JOBS removed
from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in WF_ACTIONS removed
from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 1 entries in COORD_JOBS removed
from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 5 entries in COORD_ACTIONS
removed from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in BUNDLE_JOBS removed
from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in BUNDLE_ACTIONS
removed from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in SLA_EVENTS removed
from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in SLA_REGISTRATION
removed from DB!
18:37:31,441 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] 0 entries in SLA_SUMMARY removed
from DB!
18:37:31,441 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Shutdown
Setting FS testcase work
dir[hdfs://localhost:35660/user/test/1bcd4dbb-a184-474e-84e2-fb5975085456]
18:37:31,496 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5]
*******************************************************************************
STARTUP MSG: Oozie BUILD_VERSION [4.1.0-cdh5.6.0-SNAPSHOT] compiled by
[jenkins] on [${build.time}]
STARTUP MSG: revision [${vc.revision}]@[${vc.url}]
*******************************************************************************
18:37:31,496 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j configuration file
[oozie-log4j.properties]
18:37:31,496 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j configuration file loaded
from [CLASSPATH]
18:37:31,496 INFO XLogService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Log4j reload interval [disabled]
18:37:31,497 WARN XLogService:523 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie WS log will be disabled,
missing property 'log4j.appender.oozie.File' for 'oozie' appender
18:37:31,497 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie home dir
[/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone]
18:37:31,497 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie conf dir
[/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf]
18:37:31,497 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie conf file [oozie-site.xml]
18:37:31,499 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.url]
18:37:31,499 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.create.db.schema]
18:37:31,499 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.service.JPAService.jdbc.driver]
18:37:31,499 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Overriding configuration with
oozie-site, [oozie.services]
18:37:31,500 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration change via System
Property, [oozie.processing.timezone]=[UTC]
18:37:31,500 INFO ConfigurationService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration change via System
Property, [oozie.service.HadoopAccessorService.supported.filesystems]=[*]
18:37:31,500 WARN ConfigurationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System property
[oozie.action.newId] no defined in Oozie configuration, ignored
18:37:31,500 WARN ConfigurationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System property
[oozie.action.conf.xml] no defined in Oozie configuration, ignored
18:37:31,500 WARN Services:523 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] System ID [oozie-jenk] exceeds
maximum length [10], trimming
18:37:31,500 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Exiting null Entering NORMAL
18:37:31,500 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initialized runtime directory
[/tmp/oozie-jenk1417170731200600248.dir]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Services list obtained from
property 'oozie.services'
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Services list obtained from
property 'oozie.services.ext'
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SchedulerService] implementation [class
org.apache.oozie.service.SchedulerService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.InstrumentationService] implementation [class
org.apache.oozie.service.InstrumentationService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.MemoryLocksService] implementation [class
org.apache.oozie.service.MemoryLocksService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.UUIDService] implementation [class
org.apache.oozie.service.UUIDService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ELService] implementation [class
org.apache.oozie.service.ELService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.AuthorizationService] implementation [class
org.apache.oozie.service.AuthorizationService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.UserGroupInformationService] implementation [class
org.apache.oozie.service.UserGroupInformationService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.HadoopAccessorService] implementation [class
org.apache.oozie.service.HadoopAccessorService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JobsConcurrencyService] implementation [class
org.apache.oozie.service.JobsConcurrencyService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.URIHandlerService] implementation [class
org.apache.oozie.service.URIHandlerService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.DagXLogInfoService] implementation [class
org.apache.oozie.service.DagXLogInfoService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SchemaService] implementation [class
org.apache.oozie.service.SchemaService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.WorkflowAppService] implementation [class
org.apache.oozie.service.LiteWorkflowAppService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JPAService] implementation [class
org.apache.oozie.service.JPAService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StoreService] implementation [class
org.apache.oozie.service.StoreService]
18:37:31,501 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SLAStoreService] implementation [class
org.apache.oozie.service.SLAStoreService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.WorkflowStoreService] implementation [class
org.apache.oozie.service.DBLiteWorkflowStoreService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CallbackService] implementation [class
org.apache.oozie.service.CallbackService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ActionService] implementation [class
org.apache.oozie.service.ActionService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CallableQueueService] implementation [class
org.apache.oozie.service.CallableQueueService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ActionCheckerService] implementation [class
org.apache.oozie.service.ActionCheckerService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.RecoveryService] implementation [class
org.apache.oozie.service.RecoveryService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.PurgeService] implementation [class
org.apache.oozie.service.PurgeService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CoordinatorEngineService] implementation [class
org.apache.oozie.service.CoordinatorEngineService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.BundleEngineService] implementation [class
org.apache.oozie.service.BundleEngineService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.DagEngineService] implementation [class
org.apache.oozie.service.DagEngineService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.CoordMaterializeTriggerService] implementation [class
org.apache.oozie.service.CoordMaterializeTriggerService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.StatusTransitService] implementation [class
org.apache.oozie.service.StatusTransitService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.PauseTransitService] implementation [class
org.apache.oozie.service.PauseTransitService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.GroupsService] implementation [class
org.apache.oozie.service.GroupsService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.ProxyUserService] implementation [class
org.apache.oozie.service.ProxyUserService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.XLogStreamingService] implementation [class
org.apache.oozie.service.XLogStreamingService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.JvmPauseMonitorService] implementation [class
org.apache.oozie.service.JvmPauseMonitorService]
18:37:31,502 DEBUG Services:526 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loading service [class
org.apache.oozie.service.SparkConfigurationService] implementation [class
org.apache.oozie.service.SparkConfigurationService]
18:37:31,504 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[oozie.service.AuthorizationService.security.enabled] not found, use given
value [null]
18:37:31,504 WARN AuthorizationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie running with authorization
disabled
18:37:31,504 INFO HadoopAccessorService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] JOB_TRACKER_WHITELIST :[], Total
entries :0
18:37:31,504 INFO HadoopAccessorService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] NAME_NODE_WHITELIST :[], Total
entries :0
18:37:31,504 INFO HadoopAccessorService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie Kerberos Authentication
[disabled]
18:37:31,518 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[oozie.service.URIHandlerService.uri.handler.default] not found, use given
value [null]
18:37:31,518 INFO URIHandlerService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loaded urihandlers
[org.apache.oozie.dependency.FSURIHandler]
18:37:31,518 INFO URIHandlerService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Loaded default urihandler
org.apache.oozie.dependency.FSURIHandler
18:37:31,535 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[oozie.subworkflow.classpath.inheritance] not found, use given value [false]
18:37:31,535 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[hadoop.security.credential.clear-text-fallback] not found, use given value
[true]
18:37:31,601 INFO JPAService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] All entities initialized
18:37:31,601 INFO JPAService:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-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,
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [hive]
class [class org.apache.oozie.action.hadoop.HiveActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[map-reduce] class [class
org.apache.oozie.action.hadoop.MapReduceActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[:FORK:] class [class org.apache.oozie.action.control.ForkActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[:JOIN:] class [class org.apache.oozie.action.control.JoinActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [:END:]
class [class org.apache.oozie.action.control.EndActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [hive2]
class [class org.apache.oozie.action.hadoop.Hive2ActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [ssh]
class [class org.apache.oozie.action.ssh.SshActionExecutor]
18:37:31,602 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [fs]
class [class org.apache.oozie.action.hadoop.FsActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[switch] class [class org.apache.oozie.action.decision.DecisionActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [pig]
class [class org.apache.oozie.action.hadoop.PigActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[distcp] class [class org.apache.oozie.action.hadoop.DistcpActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [java]
class [class org.apache.oozie.action.hadoop.JavaActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [shell]
class [class org.apache.oozie.action.hadoop.ShellActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [spark]
class [class org.apache.oozie.action.hadoop.SparkActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[:START:] class [class org.apache.oozie.action.control.StartActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [sqoop]
class [class org.apache.oozie.action.hadoop.SqoopActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[:KILL:] class [class org.apache.oozie.action.control.KillActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type [email]
class [class org.apache.oozie.action.email.EmailActionExecutor]
18:37:31,603 DEBUG ActionService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initializing action type
[sub-workflow] class [class
org.apache.oozie.action.oozie.SubWorkflowActionExecutor]
18:37:31,603 INFO ActionService:520 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initialized action types: [hive,
map-reduce, :FORK:, :JOIN:, :END:, hive2, ssh, fs, switch, pig, distcp, java,
shell, spark, :START:, sqoop, :KILL:, email, sub-workflow]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type [kill]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type [resume]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type [suspend]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[bundle_kill]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[bundle_resume]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[bundle_suspend]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[coord_kill]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[coord_change]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[coord_resume]
18:37:31,604 DEBUG CallableQueueService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Adding interrupt type
[coord_suspend]
18:37:31,605 DEBUG ConfigurationService:526 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Configuration property
[oozie.service.CoordMaterializeTriggerService.scheduling.interval] not found,
use given value [300]
18:37:31,606 WARN SparkConfigurationService:523 - USER[test] GROUP[-] TOKEN[]
APP[no-op-wf] JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Spark Configuration could not be
loaded for *:
/data/jenkins/workspace/CDH5.6.x-Oozie-4.1.0-mr2-JDK8/core/target/test-data/oozietests/org.apache.oozie.command.coord.TestCoordActionInputCheckXCommand/testNone/conf/spark-conf
does not exist
18:37:31,606 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Initialized
18:37:31,606 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Running with JARs for Hadoop
version [2.6.0-cdh5.6.0-SNAPSHOT]
18:37:31,606 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183730105-oozie-jenk-C]
ACTION[0000000-151010183730105-oozie-jenk-C@5] Oozie System ID [oozie-jenk]
started!
18:37:31,630 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Acquired lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:31,647 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Execute command
[coord_action_input] key [0000000-151010183731503-oozie-jenk-C]
18:37:31,647 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1]
[0000000-151010183731503-oozie-jenk-C@1]::ActionInputCheck:: Action is in
WAITING state.
18:37:31,648 INFO CoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] NONE execution: Preparing to
skip action [0000000-151010183731503-oozie-jenk-C@1] because the current time
[2015-10-11T01:37Z] is later than the nominal time [2015-10-11T00:38Z] of the
current action]
18:37:31,648 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Queuing [1] commands with delay
[0]ms
18:37:31,649 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Released lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:31,649 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Waiting up to [10,000] msec
18:37:31,650 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Waiting up to [9,999] msec
18:37:31,704 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Acquired lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_timeout]
18:37:31,715 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Execute command
[coord_action_timeout] key [0000000-151010183731503-oozie-jenk-C]
18:37:31,715 INFO CoordActionSkipXCommand:520 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Setting action
[0000000-151010183731503-oozie-jenk-C@1] status to SKIPPED
18:37:31,717 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Queuing [1] commands with delay
[100]ms
18:37:31,718 DEBUG CoordActionSkipXCommand:526 - USER[-] GROUP[-] TOKEN[-]
APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Released lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_timeout]
18:37:31,820 DEBUG CoordActionNotificationXCommand:526 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Notification URL :null
18:37:31,820 DEBUG CoordActionNotificationXCommand:526 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] Execute command
[coord_action_notification] key [0000000-151010183731503-oozie-jenk-C@1]
18:37:31,820 INFO CoordActionNotificationXCommand:520 - USER[-] GROUP[-]
TOKEN[-] APP[-] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@1] No Notification URL is defined.
Therefore nothing to notify for job 0000000-151010183731503-oozie-jenk-C@1
18:37:32,662 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@2] Acquired lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:32,663 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@2] Released lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:32,663 WARN CoordActionInputCheckXCommand:523 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@2] E1100: Command precondition does
not hold before execution,
[[0000000-151010183731503-oozie-jenk-C@2]::CoordActionInputCheck:: Ignoring
action. Coordinator job is not in
RUNNING/RUNNINGWITHERROR/PAUSED/PAUSEDWITHERROR state, but state=SUCCEEDED],
Error Code: E1100
18:37:32,663 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@2] Waiting up to [10,000] msec
18:37:32,674 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Acquired lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:32,675 DEBUG CoordActionInputCheckXCommand:526 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Released lock for
[0000000-151010183731503-oozie-jenk-C] in [coord_action_input]
18:37:32,675 WARN CoordActionInputCheckXCommand:523 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] E1100: Command precondition does
not hold before execution,
[[0000000-151010183731503-oozie-jenk-C@3]::CoordActionInputCheck:: Ignoring
action. Coordinator job is not in
RUNNING/RUNNINGWITHERROR/PAUSED/PAUSEDWITHERROR state, but state=SUCCEEDED],
Error Code: E1100
18:37:32,675 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [10,000] msec
18:37:32,677 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [9,998] msec
18:37:33,679 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [8,996] msec
18:37:34,680 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [7,995] msec
18:37:35,682 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [6,993] msec
18:37:36,684 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [5,991] msec
18:37:37,686 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [4,989] msec
18:37:38,688 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [3,987] msec
18:37:39,689 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [2,986] msec
18:37:40,691 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [1,984] msec
18:37:41,605 INFO StatusTransitService$StatusTransitRunnable:520 - Acquired
lock for [org.apache.oozie.service.StatusTransitService]
18:37:41,606 DEBUG ActionCheckerService$ActionCheckRunnable:526 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] QUEUING [] for potential checking
18:37:41,606 INFO StatusTransitService$StatusTransitRunnable:520 - Running
coordinator status service from last instance time = 2015-10-11T01:37Z
18:37:41,606 INFO PauseTransitService:520 - Acquired lock for
[org.apache.oozie.service.PauseTransitService]
18:37:41,608 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
18:37:41,608 INFO StatusTransitService$StatusTransitRunnable:520 - Running
bundle status service from last instance time = 2015-10-11T01:37Z
18:37:41,608 INFO StatusTransitService$StatusTransitRunnable:520 - Released
lock for [org.apache.oozie.service.StatusTransitService]
18:37:41,632 INFO
CoordMaterializeTriggerService$CoordMaterializeTriggerRunnable:520 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] CoordMaterializeTriggerService - Curr
Date= 2015-10-11T01:42Z, Num jobs to materialize = 0
18:37:41,632 INFO
CoordMaterializeTriggerService$CoordMaterializeTriggerRunnable:520 - USER[-]
GROUP[-] TOKEN[-] APP[-] JOB[-] ACTION[-] Released lock for
[org.apache.oozie.service.CoordMaterializeTriggerService]
18:37:41,634 DEBUG PurgeXCommand:526 - USER[-] GROUP[-] TOKEN[-] APP[-] JOB[-]
ACTION[-] Execute command [purge] key [null]
18:37:41,634 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.
18:37:41,634 INFO PurgeXCommand:520 - USER[-] GROUP[-] TOKEN[-] APP[-] JOB[-]
ACTION[-] ENDED Purge deleted [0] workflows, [0] coordinatorActions, [0]
coordinators, [0] bundles
18:37:41,636 INFO PauseTransitService:520 - Released lock for
[org.apache.oozie.service.PauseTransitService]
18:37:41,693 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting up to [982] msec
18:37:42,696 INFO TestCoordActionInputCheckXCommand:520 - USER[test] GROUP[-]
TOKEN[] APP[no-op-wf] JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Waiting timed out after [5,000]
msec
18:37:42,698 INFO Services:520 - USER[test] GROUP[-] TOKEN[] APP[no-op-wf]
JOB[0000000-151010183731503-oozie-jenk-C]
ACTION[0000000-151010183731503-oozie-jenk-C@3] Shutdown
{noformat}
What I will try is to run the test on my machine several hundred times with and
without the fix to see if it fails. I know it is experimental, however this is
the best option we have I think.
> 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)
