Build #29

Build: #29 failed

Job: Distro Tests - CDH 5.9 failed

Build log

The build generated 132,600 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log

11-Nov-2018 13:17:47 2018-11-11 13:17:47,862 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:47 2018-11-11 13:17:47,948 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:48 2018-11-11 13:17:48,948 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:49 2018-11-11 13:17:49,033 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:50 2018-11-11 13:17:50,033 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:50 2018-11-11 13:17:50,119 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:51 2018-11-11 13:17:51,120 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:51 2018-11-11 13:17:51,204 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:52 2018-11-11 13:17:52,205 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:52 2018-11-11 13:17:52,293 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:53 2018-11-11 13:17:53,293 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:53 2018-11-11 13:17:53,379 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:54 2018-11-11 13:17:54,380 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:54 2018-11-11 13:17:54,470 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:55 2018-11-11 13:17:55,471 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:55 2018-11-11 13:17:55,562 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:56 2018-11-11 13:17:56,571 - INFO  [main:c.c.c.t.IntegrationTestBase@100] - Beginning setUp.
11-Nov-2018 13:17:56 2018-11-11 13:17:56,572 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/system/services/status
11-Nov-2018 13:17:56 2018-11-11 13:17:56,646 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [401] Response Body: {"auth_uri":["http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token"]}
11-Nov-2018 13:17:56 2018-11-11 13:17:56,647 - DEBUG [main:c.c.c.s.a.c.b.BasicAuthenticationClient@77] - Basic authentication client is configured successfully.
11-Nov-2018 13:17:56 2018-11-11 13:17:56,647 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@158] - Try to get the authentication URI from the gateway server: http://10.210.0.12:11015/ping.
11-Nov-2018 13:17:56 2018-11-11 13:17:56,722 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@161] - Got response 401 - Unauthorized from http://10.210.0.12:11015/ping
11-Nov-2018 13:17:56 2018-11-11 13:17:56,722 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@168] - Response map from gateway server: {auth_uri=[http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token]}
11-Nov-2018 13:17:56 2018-11-11 13:17:56,722 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@212] - Authentication is enabled in the gateway server. Authentication URI http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token.
11-Nov-2018 13:17:56 2018-11-11 13:17:56,802 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@191] - Got response 200 - OK from http://10.210.0.12:11015/ping
11-Nov-2018 13:17:56 2018-11-11 13:17:56,802 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@202] - Response map from auth server: {access_token=Ag5jZGFwaXRuAObK367gWea6koHhWabypusPQLSoAUwU1MRifKqdDZAKcOgQPI0wxHfbDE6AYYI8aIFq, token_type=Bearer, expires_in=86400}
11-Nov-2018 13:17:56 2018-11-11 13:17:56,802 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@108] - Received the access token successfully. Expiration date is Mon Nov 12 13:17:51 UTC 2018.
11-Nov-2018 13:17:56 2018-11-11 13:17:56,803 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/system/services/status
11-Nov-2018 13:17:56 2018-11-11 13:17:56,910 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"messaging.service":"OK","streams":"OK","metrics.processor":"OK","appfabric":"OK","explore.service":"OK","dataset.executor":"OK","metadata.service":"OK","metrics":"OK","remote.system.operation":"OK","transaction":"OK","log.saver":"OK"}
11-Nov-2018 13:17:56 2018-11-11 13:17:56,910 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces
11-Nov-2018 13:17:56 2018-11-11 13:17:56,998 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}]
11-Nov-2018 13:17:57 2018-11-11 13:17:56,999 - INFO  [main:c.c.c.t.IntegrationTestBase@209] - CDAP Services are up and running!
11-Nov-2018 13:17:57 2018-11-11 13:17:57,002 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/config/cdap
11-Nov-2018 13:17:57 2018-11-11 13:17:57,156 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"name":"log.process.pipeline.logger.cache.expiration.ms","value":"300000","source":"cdap-default.xml"},{"name":"http.service.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"flow.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"messaging.twill.java.reserved.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"dashboard.ssl.bind.port","value":"9443","source":"cdap-default.xml"},{"name":"metrics.processor.status.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"hive.version.resolution.strategy","value":"auto.strict","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"app.ssl.bind.port","value":"30443","source":"cdap-default.xml"},{"name":"scheduler.data.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"twill.yarn.am.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.time.secs","value":"7200","source":"cdap-default.xml"},{"name":"security.auth.server.bind.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"system.log.process.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.sync.interval.bytes","value":"10485760","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.cleanup.interval.mins","value":"1440","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.max.lifetime.ms","value":"21600000","source":"cdap-default.xml"},{"name":"workflow.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"master.startup.checks.enabled","value":"true","source":"cdap-default.xml"},{"name":"program.status.record.event.topic","value":"programstatusrecordevent","source":"cdap-default.xml"},{"name":"router.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"router.bind.port","value":"11015","source":"cdap-default.xml"},{"name":"stream.batch.buffer.threshold","value":"1048576","source":"cdap-default.xml"},{"name":"data.tx.snapshot.retain","value":"10","source":"cdap-default.xml"},{"name":"router.client.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"master.collect.app.containers.log.level","value":"ERROR","source":"cdap-default.xml"},{"name":"security.authorization.extension.operation.time.warn.threshold.ms","value":"5000","source":"cdap-default.xml"},{"name":"data.tx.changeset.size.warn.threshold","value":"5000000","source":"cdap-default.xml"},{"name":"metrics.hbase.max.scan.threads","value":"96","source":"cdap-default.xml"},{"name":"router.server.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"http.service.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"app.program.jvm.opts","value":"-XX:MaxPermSize\u003d128M -verbose:gc -Xloggc:\u003cLOG_DIR\u003e/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles\u003d10 -XX:GCLogFileSize\u003d1M","source":"cdap-default.xml"},{"name":"stream.size.event.topic","value":"streamsizeevent","source":"cdap-default.xml"},{"name":"security.enabled","value":"true","source":"cdap-site.xml"},{"name":"flow.retry.policy.max.retries","value":"3","source":"cdap-default.xml"},{"name":"security.authentication.handler.userRdnAttribute","value":"cn","source":"cdap-site.xml"},{"name":"audit.topic","value":"audit","source":"cdap-default.xml"},{"name":"metrics.processor.max.delay.ms","value":"3000","source":"cdap-default.xml"},{"name":"metrics.processor.queue.size","value":"20000","source":"cdap-default.xml"},{"name":"dashboard.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"stream.partition.duration","value":"3600000","source":"cdap-default.xml"},{"name":"metadata.service.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"security.server.maxthreads","value":"100","source":"cdap-default.xml"},{"name":"kerberos.auth.relogin.interval.seconds","value":"300","source":"cdap-default.xml"},{"name":"operational.stats.refresh.interval.secs","value":"60","source":"cdap-default.xml"},{"name":"security.keytab.path","value":"/etc/security/keytabs/${name}.headless.keytab","source":"cdap-site.xml"},{"name":"metrics.dataset.hbase.stats.report.interval","value":"60","source":"cdap-default.xml"},{"name":"explore.executor.container.memory.mb","value":"1100","source":"cdap-site.xml"},{"name":"master.manage.hbase.coprocessors","value":"true","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"router.audit.path.check.enabled","value":"true","source":"cdap-default.xml"},{"name":"log.publish.num.partitions","value":"10","source":"cdap-default.xml"},{"name":"stream.notification.threshold","value":"1024","source":"cdap-default.xml"},{"name":"log.kafka.topic","value":"logs.user-v2","source":"cdap-default.xml"},{"name":"dataset.service.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"app.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"master.services.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"security.realm","value":"cdap","source":"cdap-default.xml"},{"name":"metrics.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"kafka.server.log.flush.interval.messages","value":"10000","source":"cdap-default.xml"},{"name":"security.authentication.handler.userObjectClass","value":"inetorgperson","source":"cdap-site.xml"},{"name":"mapreduce.jobclient.connect.max.retries","value":"2","source":"cdap-default.xml"},{"name":"security.authorization.cache.ttl.secs","value":"300","source":"cdap-default.xml"},{"name":"data.queue.dequeue.tx.percent","value":"30","source":"cdap-default.xml"},{"name":"data.tx.snapshot.local.dir","value":"data/tx.snapshot","source":"cdap-default.xml"},{"name":"app.temp.dir","value":"/tmp","source":"cdap-default.xml"},{"name":"security.auth.server.ssl.bind.port","value":"10010","source":"cdap-default.xml"},{"name":"kafka.seed.brokers","value":"it-itn5-dstc59-29-37215-1000.coopr.sh:9092","source":"cdap-site.xml"},{"name":"explore.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"data.tx.discovery.service.name","value":"transaction","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.permissions","value":"600","source":"cdap-default.xml"},{"name":"app.program.runid.corrector.interval","value":"180","source":"cdap-default.xml"},{"name":"app.program.max.stop.seconds","value":"300","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"messaging.local.data.cleanup.frequency.secs","value":"3600","source":"cdap-default.xml"},{"name":"dashboard.bind.port","value":"11011","source":"cdap-default.xml"},{"name":"data.tx.snapshot.codecs","value":"\n      org.apache.tephra.snapshot.SnapshotCodecV3,\n      org.apache.tephra.snapshot.SnapshotCodecV4\n    ","source":"cdap-default.xml"},{"name":"messaging.http.server.worker.threads","value":"30","source":"cdap-default.xml"},{"name":"security.authentication.handler.port","value":"389","source":"cdap-site.xml"},{"name":"stream.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"app.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"zookeeper.quorum","value":"it-itn5-dstc59-29-37215-1000.coopr.sh:2181/cdap","source":"cdap-site.xml"},{"name":"http.service.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"metrics.data.table.ts.rollTime.60","value":"60","source":"cdap-default.xml"},{"name":"security.store.file.name","value":"securestore","source":"cdap-default.xml"},{"name":"service.retry.policy.max.time.secs","value":"10","source":"cdap-default.xml"},{"name":"explore.executor.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"messaging.table.expiration.seconds","value":"300","source":"cdap-default.xml"},{"name":"messaging.message.table.name","value":"tms.message","source":"cdap-default.xml"},{"name":"explore.start.on.demand","value":"false","source":"cdap-default.xml"},{"name":"dataset.service.output.dir","value":"/datasets","source":"cdap-default.xml"},{"name":"upgrade.thread.pool.size","value":"1","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"router.client.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"app.program.max.start.seconds","value":"300","source":"cdap-default.xml"},{"name":"security.store.file.path","value":"data/store","source":"cdap-default.xml"},{"name":"zookeeper.session.timeout.millis","value":"40000","source":"cdap-default.xml"},{"name":"metrics.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"app.output.dir","value":"/programs","source":"cdap-default.xml"},{"name":"dataset.unchecked.upgrade","value":"false","source":"cdap-default.xml"},{"name":"metrics.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"messaging.message.table.hbase.splits","value":"16","source":"cdap-default.xml"},{"name":"messaging.topic.default.ttl.seconds","value":"604800","source":"cdap-default.xml"},{"name":"messaging.local.data.dir","value":"data/messaging","source":"cdap-default.xml"},{"name":"messaging.system.topics","value":"audit,dataevent,metrics:10,notifications,timeevent,streamsizeevent,programstatusevent,programstatusrecordevent","source":"cdap-default.xml"},{"name":"cdap.http.client.read.timeout.ms","value":"120000","source":"cdap-site.xml"},{"name":"stream.file.cleanup.period","value":"300000","source":"cdap-default.xml"},{"name":"data.tx.max.timeout","value":"600","source":"cdap-default.xml"},{"name":"app.program.runid.corrector.tx.batch.size","value":"1000","source":"cdap-default.xml"},{"name":"messaging.metadata.table.name","value":"tms.meta","source":"cdap-default.xml"},{"name":"stream.event.ttl","value":"9223372036854775807","source":"cdap-default.xml"},{"name":"spark.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"log.saver.container.num.cores","value":"1","source":"cdap-site.xml"},{"name":"spark.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"messaging.http.server.max.request.size.mb","value":"10","source":"cdap-default.xml"},{"name":"zookeeper.client.startup.timeout.millis","value":"60000","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"local.data.dir","value":"data","source":"cdap-default.xml"},{"name":"dataset.executor.max.instances","value":"5","source":"cdap-default.xml"},{"name":"app.artifact.dir","value":"/opt/cdap/master/artifacts","source":"cdap-default.xml"},{"name":"data.tx.snapshot.interval","value":"60","source":"cdap-default.xml"},{"name":"worker.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"data.tx.hdfs.user","value":"cdap","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"security.authorization.enabled","value":"false","source":"cdap-default.xml"},{"name":"log.process.pipeline.lib.dir","value":"/opt/cdap/master/ext/logging/lib","source":"cdap-default.xml"},{"name":"twill.jvm.gc.opts","value":"-verbose:gc -Xloggc:\u003cLOG_DIR\u003e/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles\u003d10 -XX:GCLogFileSize\u003d1M","source":"cdap-default.xml"},{"name":"kerberos.auth.enabled","value":"true","source":"cdap-site.xml"},{"name":"notification.topic","value":"notifications","source":"cdap-default.xml"},{"name":"hbase.client.retries.number","value":"2","source":"cdap-default.xml"},{"name":"messaging.http.server.executor.threads","value":"0","source":"cdap-default.xml"},{"name":"router.ssl.server.port","value":"10443","source":"cdap-default.xml"},{"name":"security.server.extended.token.expiration.ms","value":"604800000","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"metrics.kafka.topic.prefix","value":"metrics","source":"cdap-default.xml"},{"name":"app.program.local.dataset.deleter.interval","value":"3600","source":"cdap-default.xml"},{"name":"data.tx.thrift.max.read.buffer","value":"16777216","source":"cdap-default.xml"},{"name":"app.program.runtime.extensions.dir","value":"/opt/cdap/master/ext/runtimes","source":"cdap-default.xml"},{"name":"security.authentication.loginmodule.className","value":"co.cask.cdap.security.server.LDAPLoginModule","source":"cdap-site.xml"},{"name":"master.service.num.cores","value":"1","source":"cdap-site.xml"},{"name":"messaging.max.instances","value":"5","source":"cdap-default.xml"},{"name":"cdap.ugi.cache.expiration.ms","value":"60000","source":"cdap-site.xml"},{"name":"metrics.data.table.ts.rollTime.3600","value":"24","source":"cdap-default.xml"},{"name":"operational.stats.extensions.dir","value":"/opt/cdap/master/ext/operations","source":"cdap-default.xml"},{"name":"twill.yarn.am.reserved.memory.mb","value":"250","source":"cdap-default.xml"},{"name":"explore.container.yarn.app.classpath.first","value":"false","source":"cdap-default.xml"},{"name":"messaging.container.instances","value":"1","source":"cdap-default.xml"},{"name":"messaging.cache.size.mb","value":"30","source":"cdap-default.xml"},{"name":"cdap.master.kerberos.keytab","value":"/etc/security/keytabs/cdap.service.keytab","source":"cdap-site.xml"},{"name":"spark.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"kafka.server.default.replication.factor","value":"1","source":"cdap-default.xml"},{"name":"data.tx.prune.state.table","value":"cdap_system:tephra.state","source":"cdap-default.xml"},{"name":"hdfs.namespace","value":"/cdap","source":"cdap-site.xml"},{"name":"stream.container.instances","value":"1","source":"cdap-default.xml"},{"name":"messaging.http.server.consume.chunk.size","value":"60000","source":"cdap-default.xml"},{"name":"scheduler.event.poll.delay.millis","value":"2000","source":"cdap-default.xml"},{"name":"messaging.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"metrics.messaging.meta.table","value":"metrics.messaging.meta","source":"cdap-default.xml"},{"name":"http.service.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"program.status.event.topic","value":"programstatusevent","source":"cdap-default.xml"},{"name":"worker.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"stream.container.num.cores","value":"1","source":"cdap-site.xml"},{"name":"log.process.pipeline.checkpoint.interval.ms","value":"10000","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"metadata.max.allowed.chars","value":"50","source":"cdap-default.xml"},{"name":"service.retry.policy.max.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"remote.system.op.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.delay.ms","value":"5000","source":"cdap-default.xml"},{"name":"twill.no.container.timeout","value":"120000","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"log.saver.num.instances","value":"1","source":"cdap-default.xml"},{"name":"dataset.table.prefix","value":"cdap","source":"cdap-default.xml"},{"name":"thrift.max.read.buffer","value":"16777216","source":"cdap-default.xml"},{"name":"metrics.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"root.namespace","value":"cdap","source":"cdap-site.xml"},{"name":"metrics.processor.num.instances","value":"1","source":"cdap-default.xml"},{"name":"twill.zookeeper.namespace","value":"/twill","source":"cdap-default.xml"},{"name":"master.startup.service.timeout.seconds","value":"600","source":"cdap-default.xml"},{"name":"log.saver.status.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"messaging.twill.java.heap.memory.ratio","value":"0.6","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.retention.duration.days","value":"7","source":"cdap-site.xml"},{"name":"explore.http.timeout","value":"20","source":"cdap-default.xml"},{"name":"log.saver.container.memory.mb","value":"768","source":"cdap-site.xml"},{"name":"hdfs.lib.dir","value":"/cdap/lib","source":"cdap-default.xml"},{"name":"app.bind.port","value":"0","source":"cdap-default.xml"},{"name":"data.tx.pruning.plugin.class","value":"co.cask.data2.txprune.DefaultHBaseTransactionPruningPlugin","source":"cdap-default.xml"},{"name":"log.collection.root","value":"data/logs","source":"cdap-default.xml"},{"name":"security.token.digest.keylength","value":"128","source":"cdap-default.xml"},{"name":"kafka.server.port","value":"9092","source":"cdap-default.xml"},{"name":"router.userservice.fallback.strategy","value":"random","source":"cdap-default.xml"},{"name":"dataset.service.exec.threads","value":"10","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"log.process.pipeline.config.dir","value":"/opt/cdap/master/ext/logging/config","source":"cdap-default.xml"},{"name":"security.store.provider","value":"none","source":"cdap-default.xml"},{"name":"app.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"scheduler.max.thread.pool.size","value":"100","source":"cdap-default.xml"},{"name":"data.tx.changeset.size.limit","value":"9223372036854775807","source":"cdap-default.xml"},{"name":"master.service.max.instances","value":"5","source":"cdap-default.xml"},{"name":"data.tx.prune.plugins","value":"data.tx.pruning.plugin","source":"cdap-default.xml"},{"name":"scheduler.misfire.threshold.ms","value":"60000","source":"cdap-default.xml"},{"name":"flow.retry.policy.max.time.secs","value":"10","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"stream.size.schedule.polling.delay","value":"600","source":"cdap-default.xml"},{"name":"hbase.rpc.timeout","value":"15000","source":"cdap-default.xml"},{"name":"metadata.service.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"app.program.local.dataset.deleter.initial.delay","value":"300","source":"cdap-default.xml"},{"name":"flow.retry.policy.type","value":"none","source":"cdap-default.xml"},{"name":"dataset.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"hive.server2.jdbc.url","value":"jdbc:hive2://it-itn5-dstc59-29-37215-1000.coopr.sh:10000/default;principal\u003dhive/_HOST@CONTINUUITY.NET","source":"cdap-site.xml"},{"name":"remote.system.op.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"master.service.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"time.event.topic","value":"timeevent","source":"cdap-default.xml"},{"name":"stream.bind.port","value":"0","source":"cdap-default.xml"},{"name":"twill.java.reserved.memory.mb","value":"250","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"stream.file.prefix","value":"file","source":"cdap-default.xml"},{"name":"data.tx.prune.enable","value":"false","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.cleanup.transaction.timeout","value":"60","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.dir.permissions","value":"700","source":"cdap-default.xml"},{"name":"security.server.token.expiration.ms","value":"86400000","source":"cdap-default.xml"},{"name":"scheduler.stream.size.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"kafka.server.log.retention.hours","value":"24","source":"cdap-default.xml"},{"name":"security.authorization.extension.config.cache.max.entries","value":"100000","source":"cdap-default.xml"},{"name":"router.server.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"twill.location.cache.dir","value":".cache","source":"cdap-default.xml"},{"name":"http.client.read.timeout.ms","value":"60000","source":"cdap-default.xml"},{"name":"dataset.service.worker.threads","value":"4","source":"cdap-default.xml"},{"name":"stream.instance.file.prefix","value":"file.0","source":"cdap-default.xml"},{"name":"monitor.handler.service.discovery.timeout.seconds","value":"1","source":"cdap-default.xml"},{"name":"stream.index.interval","value":"10000","source":"cdap-default.xml"},{"name":"data.tx.num.cores","value":"1","source":"cdap-default.xml"},{"name":"metrics.processor.num.cores","value":"1","source":"cdap-default.xml"},{"name":"spark.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"messaging.container.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"metrics.kafka.partition.size","value":"10","source":"cdap-default.xml"},{"name":"stream.consumer.table.presplits","value":"16","source":"cdap-default.xml"},{"name":"explore.writes.enabled","value":"true","source":"cdap-default.xml"},{"name":"log.process.pipeline.event.delay.ms","value":"2000","source":"cdap-default.xml"},{"name":"metrics.messaging.topic.num","value":"10","source":"cdap-default.xml"},{"name":"data.queue.table.presplits","value":"16","source":"cdap-default.xml"},{"name":"stream.async.queue.size","value":"100","source":"cdap-default.xml"},{"name":"dashboard.ssl.disable.cert.check","value":"false","source":"cdap-default.xml"},{"name":"app.program.status.event.poll.delay.millis","value":"2000","source":"cdap-default.xml"},{"name":"log.publish.partition.key","value":"program","source":"cdap-default.xml"},{"name":"security.token.digest.key.expiration.ms","value":"3600000","source":"cdap-default.xml"},{"name":"explore.local.data.dir","value":"data/explore","source":"cdap-default.xml"},{"name":"stream.container.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"data.tx.client.count","value":"50","source":"cdap-default.xml"},{"name":"security.authentication.handler.userBaseDn","value":"ou\u003dpeople,dc\u003dcontinuuity,dc\u003dcom","source":"cdap-site.xml"},{"name":"instance.name","value":"cdap","source":"cdap-default.xml"},{"name":"workflow.token.max.size.mb","value":"30","source":"cdap-default.xml"},{"name":"router.server.port","value":"11015","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.retries","value":"5000","source":"cdap-default.xml"},{"name":"security.authorization.extension.config.cache.ttl.secs","value":"300","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"dashboard.router.check.timeout.secs","value":"0","source":"cdap-default.xml"},{"name":"security.authentication.handler.debug","value":"true","source":"cdap-site.xml"},{"name":"stream.base.dir","value":"/streams","source":"cdap-default.xml"},{"name":"data.tx.changeset.count.limit","value":"2147483647","source":"cdap-default.xml"},{"name":"master.startup.checks.packages","value":"co.cask.cdap.master.startup,co.cask.cdap.data.startup","source":"cdap-default.xml"},{"name":"data.local.storage.blocksize","value":"1024","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.max.time.secs","value":"1500","source":"cdap-default.xml"},{"name":"security.auth.server.bind.port","value":"10009","source":"cdap-default.xml"},{"name":"kafka.server.zookeeper.connection.timeout.ms","value":"1000000","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.max.retries","value":"1500","source":"cdap-default.xml"},{"name":"security.server.ssl.enabled","value":"false","source":"cdap-site.xml"},{"name":"router.connection.idle.timeout.secs","value":"15","source":"cdap-default.xml"},{"name":"kafka.zookeeper.namespace","value":"kafka","source":"cdap-default.xml"},{"name":"enable.unrecoverable.reset","value":"true","source":"cdap-site.xml"},{"name":"explore.inactive.operation.timeout.secs","value":"3600","source":"cdap-default.xml"},{"name":"kafka.server.num.partitions","value":"10","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"data.tx.retain.client.id","value":"committed","source":"cdap-default.xml"},{"name":"market.base.url","value":"http://market.cask.co/v2","source":"cdap-default.xml"},{"name":"log.process.pipeline.buffer.size","value":"0","source":"cdap-default.xml"},{"name":"messaging.ha.fencing.delay.seconds","value":"5","source":"cdap-default.xml"},{"name":"app.program.status.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"router.webapp.enabled","value":"true","source":"cdap-site.xml"},{"name":"dataset.extensions.dir","value":"/opt/cdap/ext/lib","source":"cdap-default.xml"},{"name":"metrics.max.instances","value":"5","source":"cdap-default.xml"},{"name":"metrics.table.migration.sleep.millis","value":"10","source":"cdap-default.xml"},{"name":"metrics.num.instances","value":"1","source":"cdap-default.xml"},{"name":"log.saver.max.instances","value":"5","source":"cdap-default.xml"},{"name":"data.tx.snapshot.dir","value":"/cdap/tx.snapshot","source":"cdap-default.xml"},{"name":"metrics.query.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"metrics.dataset.leveldb.stats.report.interval","value":"60","source":"cdap-default.xml"},{"name":"data.tx.changeset.count.warn.threshold","value":"50000","source":"cdap-default.xml"},{"name":"messaging.payload.table.name","value":"tms.payload","source":"cdap-default.xml"},{"name":"metadata.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"log.base.dir","value":"/logs/avro","source":"cdap-default.xml"},{"name":"metrics.processor.max.instances","value":"5","source":"cdap-default.xml"},{"name":"service.retry.policy.type","value":"none","source":"cdap-default.xml"},{"name":"service.retry.policy.max.retries","value":"3","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.60.seconds","value":"2592000","source":"cdap-default.xml"},{"name":"worker.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"messaging.hbase.max.scan.threads","value":"96","source":"cdap-default.xml"},{"name":"data.tx.bind.port","value":"0","source":"cdap-default.xml"},{"name":"messaging.coprocessor.metadata.cache.expiration.seconds","value":"120","source":"cdap-default.xml"},{"name":"messaging.payload.table.hbase.splits","value":"16","source":"cdap-default.xml"},{"name":"metrics.table.splits","value":"16","source":"cdap-default.xml"},{"name":"log.process.pipeline.logger.cache.size","value":"1000","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.max.size.bytes","value":"104857600","source":"cdap-default.xml"},{"name":"app.program.spark.yarn.client.rewrite.enabled","value":"true","source":"cdap-default.xml"},{"name":"worker.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"audit.enabled","value":"true","source":"cdap-default.xml"},{"name":"data.tx.grace.period","value":"86400","source":"cdap-default.xml"},{"name":"data.queue.config.update.interval","value":"5","source":"cdap-default.xml"},{"name":"app.meta.upgrade.timeout.secs","value":"60","source":"cdap-default.xml"},{"name":"data.local.storage.cachesize","value":"104857600","source":"cdap-default.xml"},{"name":"mapreduce.include.custom.format.classes","value":"true","source":"cdap-default.xml"},{"name":"app.program.spark.compat","value":"spark1_2.10","source":"cdap-default.xml"},{"name":"mapreduce.status.report.interval.seconds","value":"60","source":"cdap-default.xml"},{"name":"app.program.metrics.enabled","value":"true","source":"cdap-default.xml"},{"name":"dataset.executor.container.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"data.tx.timeout","value":"30","source":"cdap-default.xml"},{"name":"remote.system.op.service.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"app.deploy.update.schedules","value":"true","source":"cdap-default.xml"},{"name":"stream.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"security.authentication.basic.realmfile","value":"/etc/cdap/conf/realmfile","source":"cdap-site.xml"},{"name":"data.tx.memory.mb","value":"350","source":"cdap-site.xml"},{"name":"stream.container.instance.id","value":"0","source":"cdap-default.xml"},{"name":"messaging.table.hbase.split.policy","value":"org.apache.hadoop.hbase.regionserver.DisabledRegionSplitPolicy","source":"cdap-default.xml"},{"name":"metrics.processor.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"explore.enabled","value":"true","source":"cdap-site.xml"},{"name":"dataset.data.dir","value":"data","source":"cdap-default.xml"},{"name":"cdap.master.kerberos.principal","value":"cdap/it-itn5-dstc59-29-37215-1000.coopr.sh@CONTINUUITY.NET","source":"cdap-site.xml"},{"name":"twill.java.heap.memory.ratio","value":"0.6","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"http.client.connection.timeout.ms","value":"60000","source":"cdap-default.xml"},{"name":"hdfs.user","value":"cdap","source":"cdap-site.xml"},{"name":"worker.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"metrics.query.bind.port","value":"45005","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"metrics.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"spark.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"metrics.topic.prefix","value":"metrics","source":"cdap-default.xml"},{"name":"scheduler.time.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"kafka.server.log.dirs","value":"/tmp/kafka-logs","source":"cdap-default.xml"},{"name":"metadata.service.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"data.tx.janitor.enable","value":"true","source":"cdap-default.xml"},{"name":"security.data.keyfile.path","value":"data/security/keyfile","source":"cdap-default.xml"},{"name":"log.process.pipeline.kafka.fetch.size","value":"1048576","source":"cdap-default.xml"},{"name":"security.authentication.handler.hostname","value":"10.240.24.141","source":"cdap-site.xml"},{"name":"master.collect.containers.log","value":"true","source":"cdap-default.xml"},{"name":"dataset.executor.container.instances","value":"1","source":"cdap-default.xml"},{"name":"data.event.topic","value":"dataevent","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.max.retries","value":"600","source":"cdap-default.xml"},{"name":"security.token.digest.algorithm","value":"HmacSHA256","source":"cdap-default.xml"},{"name":"app.program.yarn.attempt.failures.validity.interval","value":"60000","source":"cdap-default.xml"},{"name":"security.token.distributed.parent.znode","value":"/cdap/security/auth","source":"cdap-default.xml"},{"name":"messaging.hbase.scan.cache.rows","value":"1000","source":"cdap-default.xml"},{"name":"router.ssl.bind.port","value":"10443","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.1.seconds","value":"7200","source":"cdap-default.xml"},{"name":"explore.cleanup.job.schedule.secs","value":"60","source":"cdap-default.xml"},{"name":"dataset.service.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.3600.seconds","value":"2592000","source":"cdap-default.xml"},{"name":"workflow.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"hbase.version.resolution.strategy","value":"auto.strict","source":"cdap-default.xml"},{"name":"namespaces.dir","value":"namespaces","source":"cdap-default.xml"},{"name":"dataset.executor.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"security.authentication.handlerClassName","value":"co.cask.cdap.security.server.BasicAuthenticationHandler","source":"cdap-site.xml"},{"name":"kafka.server.host.name","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"data.tx.server.threads","value":"25","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"ssl.external.enabled","value":"false","source":"cdap-site.xml"},{"name":"data.tx.server.io.threads","value":"2","source":"cdap-default.xml"},{"name":"stream.async.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"app.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"metrics.num.cores","value":"1","source":"cdap-default.xml"},{"name":"data.tx.max.instances","value":"5","source":"cdap-default.xml"},{"name":"explore.active.operation.timeout.secs","value":"82800","source":"cdap-default.xml"},{"name":"data.tx.num.instances","value":"1","source":"cdap-default.xml"},{"name":"security.authorization.cache.max.entries","value":"100000","source":"cdap-default.xml"},{"name":"flow.retry.policy.max.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"data.tx.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"metrics.kafka.meta.table","value":"metrics.kafka.meta","source":"cdap-default.xml"},{"name":"audit.publish.timeout.ms","value":"2000","source":"cdap-default.xml"},{"name":"metrics.table.hbase.split.policy","value":"org.apache.hadoop.hbase.regionserver.DisabledRegionSplitPolicy","source":"cdap-default.xml"},{"name":"log.process.pipeline.auto.buffer.ratio","value":"0.7","source":"cdap-default.xml"},{"name":"scheduler.program.status.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"service.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"data.local.storage","value":"data/ldb","source":"cdap-default.xml"},{"name":"router.server.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"data.tx.client.provider","value":"pool","source":"cdap-default.xml"},{"name":"router.bind.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"ssl.internal.enabled","value":"false","source":"cdap-default.xml"},{"name":"dataset.executor.bind.port","value":"0","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.delay.ms","value":"3000","source":"cdap-default.xml"}]
11-Nov-2018 13:17:57 2018-11-11 13:17:57,157 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default
11-Nov-2018 13:17:57 2018-11-11 13:17:57,234 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}
11-Nov-2018 13:17:57 2018-11-11 13:17:57,234 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps
11-Nov-2018 13:17:57 2018-11-11 13:17:57,326 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"type":"App","id":"XMLPatternTest","name":"XMLPatternTest","version":"-SNAPSHOT","description":"Data Pipeline Application","artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}},{"type":"App","id":"UploadFile","name":"UploadFile","version":"-SNAPSHOT","description":"Application to upload file on HDFS using FileSet dataset","artifact":{"name":"UploadFile","version":"1.0.0-SNAPSHOT","scope":"USER"}}]
11-Nov-2018 13:17:57 2018-11-11 13:17:57,326 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT
11-Nov-2018 13:17:57 2018-11-11 13:17:57,452 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"XMLPatternTest","appVersion":"-SNAPSHOT","artifactVersion":"4.3.5-SNAPSHOT","description":"Data Pipeline Application","configuration":"{\"engine\":\"MAPREDUCE\",\"schedule\":\"* * * * *\",\"postActions\":[],\"stages\":[{\"name\":\"XMLProjectionTransform\",\"plugin\":{\"name\":\"Projection\",\"type\":\"transform\",\"properties\":{\"convert\":\"offset:string\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlTransform\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"long\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}},{\"name\":\"XMLReader\",\"plugin\":{\"name\":\"XMLReader\",\"type\":\"batchsource\",\"properties\":{\"referenceName\":\"XMLReaderPatternTest\",\"path\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadersource/*\",\"targetFolder\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadertarget/\",\"pattern\":\"log.xml$\",\"nodePath\":\"/catalog/book/price\",\"reprocessingRequired\":\"No\",\"tableName\":\"XMLPatternTrackingTable\",\"actionAfterProcess\":\"delete\",\"tableExpiryPeriod\":\"30\",\"temporaryFolder\":\"/tmp\"}}},{\"name\":\"XMLTableSink\",\"plugin\":{\"name\":\"Table\",\"type\":\"batchsink\",\"properties\":{\"name\":\"output-batchsink-pattern-test\",\"schema.row.field\":\"offset\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlSink\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}}],\"connections\":[{\"from\":\"XMLReader\",\"to\":\"XMLProjectionTransform\"},{\"from\":\"XMLProjectionTransform\",\"to\":\"XMLTableSink\"}],\"resources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"driverResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"clientResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"stageLoggingEnabled\":true,\"processTimingEnabled\":true,\"numOfRecordsPreview\":0.0,\"properties\":{},\"sinks\":[],\"transforms\":[]}","streams":[],"datasets":[{"name":"XMLPatternTrackingTable","classname":"co.cask.cdap.api.dataset.lib.KeyValueTable"},{"name":"output-batchsink-pattern-test","classname":"co.cask.cdap.api.dataset.table.Table"}],"programs":[{"type":"Mapreduce","app":"XMLPatternTest","id":"phase-1","name":"phase-1","description":"MapReduce phase executor. Sources \u0027XMLReader\u0027 to sinks \u0027XMLTableSink\u0027."},{"type":"Workflow","app":"XMLPatternTest","id":"DataPipelineWorkflow","name":"DataPipelineWorkflow","description":"Data Pipeline Workflow"}],"plugins":[{"id":"XMLProjectionTransform","name":"Projection","type":"transform"},{"id":"XMLTableSink","name":"Table","type":"batchsink"},{"id":"XMLReader","name":"XMLReader","type":"batchsource"}],"artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}}
11-Nov-2018 13:17:57 2018-11-11 13:17:57,453 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:57 2018-11-11 13:17:57,542 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:57 2018-11-11 13:17:57,543 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: POST http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/stop
11-Nov-2018 13:17:57 2018-11-11 13:17:57,633 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [400] Response Body: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:17:57 2018-11-11 13:17:57,636 - WARN  [main:c.c.c.c.ProgramClient@368] - Program program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1 is already stopped, proceeding even though the following exception is raised.
11-Nov-2018 13:17:57 java.io.IOException: 400: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:17:57         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:132) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:17:57         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:74) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:17:57         at co.cask.cdap.client.ProgramClient.stop(ProgramClient.java:284) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:17:57         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:362) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:17:57         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:17:57         at co.cask.cdap.test.IntegrationTestBase.setUp(IntegrationTestBase.java:111) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:17:57         at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) ~[na:na]
11-Nov-2018 13:17:57         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_101]
11-Nov-2018 13:17:57         at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_101]
11-Nov-2018 13:17:57         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.Suite.runChild(Suite.java:127) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.Suite.runChild(Suite.java:26) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:17:57 2018-11-11 13:17:57,636 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:57 2018-11-11 13:17:57,726 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:58 2018-11-11 13:17:58,726 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:58 2018-11-11 13:17:58,814 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:17:59 2018-11-11 13:17:59,815 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:17:59 2018-11-11 13:17:59,902 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:00 2018-11-11 13:18:00,902 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:00 2018-11-11 13:18:00,989 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:01 2018-11-11 13:18:01,989 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:02 2018-11-11 13:18:02,076 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:03 2018-11-11 13:18:03,076 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:03 2018-11-11 13:18:03,163 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:04 2018-11-11 13:18:04,163 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:04 2018-11-11 13:18:04,250 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:05 2018-11-11 13:18:05,250 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:05 2018-11-11 13:18:05,338 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:06 2018-11-11 13:18:06,338 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:06 2018-11-11 13:18:06,424 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:07 2018-11-11 13:18:07,424 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:07 2018-11-11 13:18:07,510 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:08 2018-11-11 13:18:08,511 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:08 2018-11-11 13:18:08,597 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:09 2018-11-11 13:18:09,597 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:09 2018-11-11 13:18:09,684 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:10 2018-11-11 13:18:10,685 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:10 2018-11-11 13:18:10,771 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:11 2018-11-11 13:18:11,771 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:11 2018-11-11 13:18:11,858 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:12 2018-11-11 13:18:12,858 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:12 2018-11-11 13:18:12,944 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:13 2018-11-11 13:18:13,945 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:14 2018-11-11 13:18:14,030 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:15 2018-11-11 13:18:15,031 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:15 2018-11-11 13:18:15,117 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:16 2018-11-11 13:18:16,118 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:16 2018-11-11 13:18:16,203 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:17 2018-11-11 13:18:17,204 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:17 2018-11-11 13:18:17,288 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:18 2018-11-11 13:18:18,289 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:18 2018-11-11 13:18:18,374 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:19 2018-11-11 13:18:19,375 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:19 2018-11-11 13:18:19,460 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:20 2018-11-11 13:18:20,460 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:20 2018-11-11 13:18:20,546 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:21 2018-11-11 13:18:21,547 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:21 2018-11-11 13:18:21,632 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:22 2018-11-11 13:18:22,633 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:22 2018-11-11 13:18:22,717 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:23 2018-11-11 13:18:23,718 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:23 2018-11-11 13:18:23,802 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:24 2018-11-11 13:18:24,803 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:24 2018-11-11 13:18:24,887 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:25 2018-11-11 13:18:25,888 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:25 2018-11-11 13:18:25,973 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:26 2018-11-11 13:18:26,973 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:27 2018-11-11 13:18:27,058 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:28 2018-11-11 13:18:28,059 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:28 2018-11-11 13:18:28,144 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:29 2018-11-11 13:18:29,144 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:29 2018-11-11 13:18:29,230 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:30 2018-11-11 13:18:30,231 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:30 2018-11-11 13:18:30,313 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:31 2018-11-11 13:18:31,314 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:31 2018-11-11 13:18:31,398 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:32 2018-11-11 13:18:32,398 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:32 2018-11-11 13:18:32,484 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:33 2018-11-11 13:18:33,484 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:33 2018-11-11 13:18:33,569 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:34 2018-11-11 13:18:34,570 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:34 2018-11-11 13:18:34,661 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:35 2018-11-11 13:18:35,662 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:35 2018-11-11 13:18:35,744 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:36 2018-11-11 13:18:36,744 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:36 2018-11-11 13:18:36,830 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:37 2018-11-11 13:18:37,830 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:37 2018-11-11 13:18:37,915 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:38 2018-11-11 13:18:38,916 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:39 2018-11-11 13:18:39,001 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:40 2018-11-11 13:18:40,001 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:40 2018-11-11 13:18:40,087 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:41 2018-11-11 13:18:41,088 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:41 2018-11-11 13:18:41,173 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:42 2018-11-11 13:18:42,173 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:42 2018-11-11 13:18:42,259 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:43 2018-11-11 13:18:43,260 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:43 2018-11-11 13:18:43,346 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:44 2018-11-11 13:18:44,346 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:44 2018-11-11 13:18:44,433 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:45 2018-11-11 13:18:45,433 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:45 2018-11-11 13:18:45,519 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:46 2018-11-11 13:18:46,520 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:46 2018-11-11 13:18:46,606 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:47 2018-11-11 13:18:47,607 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:47 2018-11-11 13:18:47,696 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:48 2018-11-11 13:18:48,697 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:48 2018-11-11 13:18:48,787 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:49 2018-11-11 13:18:49,788 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:49 2018-11-11 13:18:49,873 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:50 2018-11-11 13:18:50,873 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:50 2018-11-11 13:18:50,959 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:51 2018-11-11 13:18:51,959 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:52 2018-11-11 13:18:52,045 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:53 2018-11-11 13:18:53,046 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:53 2018-11-11 13:18:53,131 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:54 2018-11-11 13:18:54,132 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:54 2018-11-11 13:18:54,217 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:55 2018-11-11 13:18:55,218 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:55 2018-11-11 13:18:55,306 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:56 2018-11-11 13:18:56,307 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:56 2018-11-11 13:18:56,393 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:57 2018-11-11 13:18:57,394 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:57 2018-11-11 13:18:57,480 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:58 2018-11-11 13:18:58,481 - INFO  [main:c.c.c.t.IntegrationTestBase@119] - Beginning tearDown.
11-Nov-2018 13:18:58 2018-11-11 13:18:58,481 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default
11-Nov-2018 13:18:58 2018-11-11 13:18:58,557 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}
11-Nov-2018 13:18:58 2018-11-11 13:18:58,558 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps
11-Nov-2018 13:18:58 2018-11-11 13:18:58,644 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"type":"App","id":"XMLPatternTest","name":"XMLPatternTest","version":"-SNAPSHOT","description":"Data Pipeline Application","artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}},{"type":"App","id":"UploadFile","name":"UploadFile","version":"-SNAPSHOT","description":"Application to upload file on HDFS using FileSet dataset","artifact":{"name":"UploadFile","version":"1.0.0-SNAPSHOT","scope":"USER"}}]
11-Nov-2018 13:18:58 2018-11-11 13:18:58,645 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT
11-Nov-2018 13:18:58 2018-11-11 13:18:58,767 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"XMLPatternTest","appVersion":"-SNAPSHOT","artifactVersion":"4.3.5-SNAPSHOT","description":"Data Pipeline Application","configuration":"{\"engine\":\"MAPREDUCE\",\"schedule\":\"* * * * *\",\"postActions\":[],\"stages\":[{\"name\":\"XMLProjectionTransform\",\"plugin\":{\"name\":\"Projection\",\"type\":\"transform\",\"properties\":{\"convert\":\"offset:string\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlTransform\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"long\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}},{\"name\":\"XMLReader\",\"plugin\":{\"name\":\"XMLReader\",\"type\":\"batchsource\",\"properties\":{\"referenceName\":\"XMLReaderPatternTest\",\"path\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadersource/*\",\"targetFolder\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadertarget/\",\"pattern\":\"log.xml$\",\"nodePath\":\"/catalog/book/price\",\"reprocessingRequired\":\"No\",\"tableName\":\"XMLPatternTrackingTable\",\"actionAfterProcess\":\"delete\",\"tableExpiryPeriod\":\"30\",\"temporaryFolder\":\"/tmp\"}}},{\"name\":\"XMLTableSink\",\"plugin\":{\"name\":\"Table\",\"type\":\"batchsink\",\"properties\":{\"name\":\"output-batchsink-pattern-test\",\"schema.row.field\":\"offset\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlSink\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}}],\"connections\":[{\"from\":\"XMLReader\",\"to\":\"XMLProjectionTransform\"},{\"from\":\"XMLProjectionTransform\",\"to\":\"XMLTableSink\"}],\"resources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"driverResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"clientResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"stageLoggingEnabled\":true,\"processTimingEnabled\":true,\"numOfRecordsPreview\":0.0,\"properties\":{},\"sinks\":[],\"transforms\":[]}","streams":[],"datasets":[{"name":"XMLPatternTrackingTable","classname":"co.cask.cdap.api.dataset.lib.KeyValueTable"},{"name":"output-batchsink-pattern-test","classname":"co.cask.cdap.api.dataset.table.Table"}],"programs":[{"type":"Mapreduce","app":"XMLPatternTest","id":"phase-1","name":"phase-1","description":"MapReduce phase executor. Sources \u0027XMLReader\u0027 to sinks \u0027XMLTableSink\u0027."},{"type":"Workflow","app":"XMLPatternTest","id":"DataPipelineWorkflow","name":"DataPipelineWorkflow","description":"Data Pipeline Workflow"}],"plugins":[{"id":"XMLProjectionTransform","name":"Projection","type":"transform"},{"id":"XMLTableSink","name":"Table","type":"batchsink"},{"id":"XMLReader","name":"XMLReader","type":"batchsource"}],"artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}}
11-Nov-2018 13:18:58 2018-11-11 13:18:58,767 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:58 2018-11-11 13:18:58,852 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:18:58 2018-11-11 13:18:58,852 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: POST http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/stop
11-Nov-2018 13:18:58 2018-11-11 13:18:58,938 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [400] Response Body: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:18:58 2018-11-11 13:18:58,941 - WARN  [main:c.c.c.c.ProgramClient@368] - Program program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1 is already stopped, proceeding even though the following exception is raised.
11-Nov-2018 13:18:58 java.io.IOException: 400: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:18:58         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:132) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:18:58         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:74) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:18:58         at co.cask.cdap.client.ProgramClient.stop(ProgramClient.java:284) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:18:58         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:362) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:18:58         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:18:58         at co.cask.cdap.test.IntegrationTestBase.tearDown(IntegrationTestBase.java:128) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:18:58         at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) ~[na:na]
11-Nov-2018 13:18:58         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_101]
11-Nov-2018 13:18:58         at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_101]
11-Nov-2018 13:18:58         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.Suite.runChild(Suite.java:127) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.Suite.runChild(Suite.java:26) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:18:58 2018-11-11 13:18:58,941 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:18:59 2018-11-11 13:18:59,027 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:00 2018-11-11 13:19:00,028 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:00 2018-11-11 13:19:00,133 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:01 2018-11-11 13:19:01,134 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:01 2018-11-11 13:19:01,220 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:02 2018-11-11 13:19:02,221 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:02 2018-11-11 13:19:02,305 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:03 2018-11-11 13:19:03,306 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:03 2018-11-11 13:19:03,391 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:04 2018-11-11 13:19:04,391 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:04 2018-11-11 13:19:04,477 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:05 2018-11-11 13:19:05,478 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:05 2018-11-11 13:19:05,563 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:06 2018-11-11 13:19:06,564 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:06 2018-11-11 13:19:06,653 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:07 2018-11-11 13:19:07,653 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:07 2018-11-11 13:19:07,739 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:08 2018-11-11 13:19:08,740 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:08 2018-11-11 13:19:08,824 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:09 2018-11-11 13:19:09,824 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:09 2018-11-11 13:19:09,909 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:10 2018-11-11 13:19:10,910 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:10 2018-11-11 13:19:10,999 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:12 2018-11-11 13:19:11,999 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:12 2018-11-11 13:19:12,084 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:13 2018-11-11 13:19:13,085 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:13 2018-11-11 13:19:13,169 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:14 2018-11-11 13:19:14,170 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:14 2018-11-11 13:19:14,254 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:15 2018-11-11 13:19:15,255 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:15 2018-11-11 13:19:15,340 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:16 2018-11-11 13:19:16,340 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:16 2018-11-11 13:19:16,425 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:17 2018-11-11 13:19:17,426 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:17 2018-11-11 13:19:17,512 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:18 2018-11-11 13:19:18,512 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:18 2018-11-11 13:19:18,598 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:19 2018-11-11 13:19:19,598 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:19 2018-11-11 13:19:19,683 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:20 2018-11-11 13:19:20,683 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:20 2018-11-11 13:19:20,769 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:21 2018-11-11 13:19:21,770 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:21 2018-11-11 13:19:21,855 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:22 2018-11-11 13:19:22,856 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:22 2018-11-11 13:19:22,941 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:23 2018-11-11 13:19:23,942 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:24 2018-11-11 13:19:24,028 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:25 2018-11-11 13:19:25,029 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:25 2018-11-11 13:19:25,119 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:26 2018-11-11 13:19:26,119 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:26 2018-11-11 13:19:26,205 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:27 2018-11-11 13:19:27,206 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:27 2018-11-11 13:19:27,290 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:28 2018-11-11 13:19:28,291 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:28 2018-11-11 13:19:28,376 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:29 2018-11-11 13:19:29,376 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:29 2018-11-11 13:19:29,461 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:30 2018-11-11 13:19:30,462 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:30 2018-11-11 13:19:30,547 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:31 2018-11-11 13:19:31,548 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:31 2018-11-11 13:19:31,632 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:32 2018-11-11 13:19:32,633 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:32 2018-11-11 13:19:32,717 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:33 2018-11-11 13:19:33,718 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:33 2018-11-11 13:19:33,802 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:34 2018-11-11 13:19:34,803 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:34 2018-11-11 13:19:34,887 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:35 2018-11-11 13:19:35,888 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:35 2018-11-11 13:19:35,972 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:36 2018-11-11 13:19:36,972 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:37 2018-11-11 13:19:37,058 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:38 2018-11-11 13:19:38,058 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:38 2018-11-11 13:19:38,144 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:39 2018-11-11 13:19:39,145 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:39 2018-11-11 13:19:39,230 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:40 2018-11-11 13:19:40,230 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:40 2018-11-11 13:19:40,315 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:41 2018-11-11 13:19:41,315 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:41 2018-11-11 13:19:41,400 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:42 2018-11-11 13:19:42,401 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:42 2018-11-11 13:19:42,486 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:43 2018-11-11 13:19:43,487 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:43 2018-11-11 13:19:43,573 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:44 2018-11-11 13:19:44,573 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:44 2018-11-11 13:19:44,668 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:45 2018-11-11 13:19:45,669 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:45 2018-11-11 13:19:45,753 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:46 2018-11-11 13:19:46,754 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:46 2018-11-11 13:19:46,838 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:47 2018-11-11 13:19:47,839 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:47 2018-11-11 13:19:47,923 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:48 2018-11-11 13:19:48,924 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:49 2018-11-11 13:19:49,010 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:50 2018-11-11 13:19:50,010 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:50 2018-11-11 13:19:50,097 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:51 2018-11-11 13:19:51,098 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:51 2018-11-11 13:19:51,183 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:52 2018-11-11 13:19:52,183 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:52 2018-11-11 13:19:52,268 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:53 2018-11-11 13:19:53,269 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:53 2018-11-11 13:19:53,353 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:54 2018-11-11 13:19:54,354 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:54 2018-11-11 13:19:54,438 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:55 2018-11-11 13:19:55,439 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:55 2018-11-11 13:19:55,523 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:56 2018-11-11 13:19:56,524 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:56 2018-11-11 13:19:56,609 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:57 2018-11-11 13:19:57,609 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:57 2018-11-11 13:19:57,693 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:58 2018-11-11 13:19:58,694 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:19:58 2018-11-11 13:19:58,778 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:19:59 2018-11-11 13:19:59,781 - INFO  [main:c.c.c.t.IntegrationTestBase@100] - Beginning setUp.
11-Nov-2018 13:19:59 2018-11-11 13:19:59,781 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/system/services/status
11-Nov-2018 13:19:59 2018-11-11 13:19:59,856 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [401] Response Body: {"auth_uri":["http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token"]}
11-Nov-2018 13:19:59 2018-11-11 13:19:59,856 - DEBUG [main:c.c.c.s.a.c.b.BasicAuthenticationClient@77] - Basic authentication client is configured successfully.
11-Nov-2018 13:19:59 2018-11-11 13:19:59,857 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@158] - Try to get the authentication URI from the gateway server: http://10.210.0.12:11015/ping.
11-Nov-2018 13:19:59 2018-11-11 13:19:59,933 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@161] - Got response 401 - Unauthorized from http://10.210.0.12:11015/ping
11-Nov-2018 13:19:59 2018-11-11 13:19:59,933 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@168] - Response map from gateway server: {auth_uri=[http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token]}
11-Nov-2018 13:19:59 2018-11-11 13:19:59,934 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@212] - Authentication is enabled in the gateway server. Authentication URI http://it-itn5-dstc59-29-37215-1000.coopr.sh:10009/token.
11-Nov-2018 13:20:00 2018-11-11 13:20:00,015 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@191] - Got response 200 - OK from http://10.210.0.12:11015/ping
11-Nov-2018 13:20:00 2018-11-11 13:20:00,015 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@202] - Response map from auth server: {access_token=Ag5jZGFwaXRuAP7P7q7gWf6/oYHhWabypusPQBanrIg7DA6pWiyNjg2iadnXUyG3a7d/tdLK1nP4SCIT, token_type=Bearer, expires_in=86400}
11-Nov-2018 13:20:00 2018-11-11 13:20:00,015 - DEBUG [main:c.c.c.s.a.c.AbstractAuthenticationClient@108] - Received the access token successfully. Expiration date is Mon Nov 12 13:19:54 UTC 2018.
11-Nov-2018 13:20:00 2018-11-11 13:20:00,015 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/system/services/status
11-Nov-2018 13:20:00 2018-11-11 13:20:00,116 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"messaging.service":"OK","streams":"OK","metrics.processor":"OK","appfabric":"OK","explore.service":"OK","dataset.executor":"OK","metadata.service":"OK","metrics":"OK","remote.system.operation":"OK","transaction":"OK","log.saver":"OK"}
11-Nov-2018 13:20:00 2018-11-11 13:20:00,116 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces
11-Nov-2018 13:20:00 2018-11-11 13:20:00,199 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}]
11-Nov-2018 13:20:00 2018-11-11 13:20:00,199 - INFO  [main:c.c.c.t.IntegrationTestBase@209] - CDAP Services are up and running!
11-Nov-2018 13:20:00 2018-11-11 13:20:00,199 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/config/cdap
11-Nov-2018 13:20:00 2018-11-11 13:20:00,351 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"name":"log.process.pipeline.logger.cache.expiration.ms","value":"300000","source":"cdap-default.xml"},{"name":"http.service.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"flow.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"messaging.twill.java.reserved.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"dashboard.ssl.bind.port","value":"9443","source":"cdap-default.xml"},{"name":"metrics.processor.status.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"hive.version.resolution.strategy","value":"auto.strict","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"app.ssl.bind.port","value":"30443","source":"cdap-default.xml"},{"name":"scheduler.data.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"twill.yarn.am.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.time.secs","value":"7200","source":"cdap-default.xml"},{"name":"security.auth.server.bind.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"system.log.process.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.sync.interval.bytes","value":"10485760","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.cleanup.interval.mins","value":"1440","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.max.lifetime.ms","value":"21600000","source":"cdap-default.xml"},{"name":"workflow.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"master.startup.checks.enabled","value":"true","source":"cdap-default.xml"},{"name":"program.status.record.event.topic","value":"programstatusrecordevent","source":"cdap-default.xml"},{"name":"router.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"router.bind.port","value":"11015","source":"cdap-default.xml"},{"name":"stream.batch.buffer.threshold","value":"1048576","source":"cdap-default.xml"},{"name":"data.tx.snapshot.retain","value":"10","source":"cdap-default.xml"},{"name":"router.client.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"master.collect.app.containers.log.level","value":"ERROR","source":"cdap-default.xml"},{"name":"security.authorization.extension.operation.time.warn.threshold.ms","value":"5000","source":"cdap-default.xml"},{"name":"data.tx.changeset.size.warn.threshold","value":"5000000","source":"cdap-default.xml"},{"name":"metrics.hbase.max.scan.threads","value":"96","source":"cdap-default.xml"},{"name":"router.server.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"http.service.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"app.program.jvm.opts","value":"-XX:MaxPermSize\u003d128M -verbose:gc -Xloggc:\u003cLOG_DIR\u003e/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles\u003d10 -XX:GCLogFileSize\u003d1M","source":"cdap-default.xml"},{"name":"stream.size.event.topic","value":"streamsizeevent","source":"cdap-default.xml"},{"name":"security.enabled","value":"true","source":"cdap-site.xml"},{"name":"flow.retry.policy.max.retries","value":"3","source":"cdap-default.xml"},{"name":"security.authentication.handler.userRdnAttribute","value":"cn","source":"cdap-site.xml"},{"name":"audit.topic","value":"audit","source":"cdap-default.xml"},{"name":"metrics.processor.max.delay.ms","value":"3000","source":"cdap-default.xml"},{"name":"metrics.processor.queue.size","value":"20000","source":"cdap-default.xml"},{"name":"dashboard.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"stream.partition.duration","value":"3600000","source":"cdap-default.xml"},{"name":"metadata.service.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"security.server.maxthreads","value":"100","source":"cdap-default.xml"},{"name":"kerberos.auth.relogin.interval.seconds","value":"300","source":"cdap-default.xml"},{"name":"operational.stats.refresh.interval.secs","value":"60","source":"cdap-default.xml"},{"name":"security.keytab.path","value":"/etc/security/keytabs/${name}.headless.keytab","source":"cdap-site.xml"},{"name":"metrics.dataset.hbase.stats.report.interval","value":"60","source":"cdap-default.xml"},{"name":"explore.executor.container.memory.mb","value":"1100","source":"cdap-site.xml"},{"name":"master.manage.hbase.coprocessors","value":"true","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"router.audit.path.check.enabled","value":"true","source":"cdap-default.xml"},{"name":"log.publish.num.partitions","value":"10","source":"cdap-default.xml"},{"name":"stream.notification.threshold","value":"1024","source":"cdap-default.xml"},{"name":"log.kafka.topic","value":"logs.user-v2","source":"cdap-default.xml"},{"name":"dataset.service.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"app.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"master.services.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"security.realm","value":"cdap","source":"cdap-default.xml"},{"name":"metrics.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"kafka.server.log.flush.interval.messages","value":"10000","source":"cdap-default.xml"},{"name":"security.authentication.handler.userObjectClass","value":"inetorgperson","source":"cdap-site.xml"},{"name":"mapreduce.jobclient.connect.max.retries","value":"2","source":"cdap-default.xml"},{"name":"security.authorization.cache.ttl.secs","value":"300","source":"cdap-default.xml"},{"name":"data.queue.dequeue.tx.percent","value":"30","source":"cdap-default.xml"},{"name":"data.tx.snapshot.local.dir","value":"data/tx.snapshot","source":"cdap-default.xml"},{"name":"app.temp.dir","value":"/tmp","source":"cdap-default.xml"},{"name":"security.auth.server.ssl.bind.port","value":"10010","source":"cdap-default.xml"},{"name":"kafka.seed.brokers","value":"it-itn5-dstc59-29-37215-1000.coopr.sh:9092","source":"cdap-site.xml"},{"name":"explore.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"data.tx.discovery.service.name","value":"transaction","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.permissions","value":"600","source":"cdap-default.xml"},{"name":"app.program.runid.corrector.interval","value":"180","source":"cdap-default.xml"},{"name":"app.program.max.stop.seconds","value":"300","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"messaging.local.data.cleanup.frequency.secs","value":"3600","source":"cdap-default.xml"},{"name":"dashboard.bind.port","value":"11011","source":"cdap-default.xml"},{"name":"data.tx.snapshot.codecs","value":"\n      org.apache.tephra.snapshot.SnapshotCodecV3,\n      org.apache.tephra.snapshot.SnapshotCodecV4\n    ","source":"cdap-default.xml"},{"name":"messaging.http.server.worker.threads","value":"30","source":"cdap-default.xml"},{"name":"security.authentication.handler.port","value":"389","source":"cdap-site.xml"},{"name":"stream.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"app.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"zookeeper.quorum","value":"it-itn5-dstc59-29-37215-1000.coopr.sh:2181/cdap","source":"cdap-site.xml"},{"name":"http.service.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"metrics.data.table.ts.rollTime.60","value":"60","source":"cdap-default.xml"},{"name":"security.store.file.name","value":"securestore","source":"cdap-default.xml"},{"name":"service.retry.policy.max.time.secs","value":"10","source":"cdap-default.xml"},{"name":"explore.executor.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"messaging.table.expiration.seconds","value":"300","source":"cdap-default.xml"},{"name":"messaging.message.table.name","value":"tms.message","source":"cdap-default.xml"},{"name":"explore.start.on.demand","value":"false","source":"cdap-default.xml"},{"name":"dataset.service.output.dir","value":"/datasets","source":"cdap-default.xml"},{"name":"upgrade.thread.pool.size","value":"1","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"router.client.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"app.program.max.start.seconds","value":"300","source":"cdap-default.xml"},{"name":"security.store.file.path","value":"data/store","source":"cdap-default.xml"},{"name":"zookeeper.session.timeout.millis","value":"40000","source":"cdap-default.xml"},{"name":"metrics.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"app.output.dir","value":"/programs","source":"cdap-default.xml"},{"name":"dataset.unchecked.upgrade","value":"false","source":"cdap-default.xml"},{"name":"metrics.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"messaging.message.table.hbase.splits","value":"16","source":"cdap-default.xml"},{"name":"messaging.topic.default.ttl.seconds","value":"604800","source":"cdap-default.xml"},{"name":"messaging.local.data.dir","value":"data/messaging","source":"cdap-default.xml"},{"name":"messaging.system.topics","value":"audit,dataevent,metrics:10,notifications,timeevent,streamsizeevent,programstatusevent,programstatusrecordevent","source":"cdap-default.xml"},{"name":"cdap.http.client.read.timeout.ms","value":"120000","source":"cdap-site.xml"},{"name":"stream.file.cleanup.period","value":"300000","source":"cdap-default.xml"},{"name":"data.tx.max.timeout","value":"600","source":"cdap-default.xml"},{"name":"app.program.runid.corrector.tx.batch.size","value":"1000","source":"cdap-default.xml"},{"name":"messaging.metadata.table.name","value":"tms.meta","source":"cdap-default.xml"},{"name":"stream.event.ttl","value":"9223372036854775807","source":"cdap-default.xml"},{"name":"spark.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"log.saver.container.num.cores","value":"1","source":"cdap-site.xml"},{"name":"spark.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"messaging.http.server.max.request.size.mb","value":"10","source":"cdap-default.xml"},{"name":"zookeeper.client.startup.timeout.millis","value":"60000","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"local.data.dir","value":"data","source":"cdap-default.xml"},{"name":"dataset.executor.max.instances","value":"5","source":"cdap-default.xml"},{"name":"app.artifact.dir","value":"/opt/cdap/master/artifacts","source":"cdap-default.xml"},{"name":"data.tx.snapshot.interval","value":"60","source":"cdap-default.xml"},{"name":"worker.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"data.tx.hdfs.user","value":"cdap","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"security.authorization.enabled","value":"false","source":"cdap-default.xml"},{"name":"log.process.pipeline.lib.dir","value":"/opt/cdap/master/ext/logging/lib","source":"cdap-default.xml"},{"name":"twill.jvm.gc.opts","value":"-verbose:gc -Xloggc:\u003cLOG_DIR\u003e/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles\u003d10 -XX:GCLogFileSize\u003d1M","source":"cdap-default.xml"},{"name":"kerberos.auth.enabled","value":"true","source":"cdap-site.xml"},{"name":"notification.topic","value":"notifications","source":"cdap-default.xml"},{"name":"hbase.client.retries.number","value":"2","source":"cdap-default.xml"},{"name":"messaging.http.server.executor.threads","value":"0","source":"cdap-default.xml"},{"name":"router.ssl.server.port","value":"10443","source":"cdap-default.xml"},{"name":"security.server.extended.token.expiration.ms","value":"604800000","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"metrics.kafka.topic.prefix","value":"metrics","source":"cdap-default.xml"},{"name":"app.program.local.dataset.deleter.interval","value":"3600","source":"cdap-default.xml"},{"name":"data.tx.thrift.max.read.buffer","value":"16777216","source":"cdap-default.xml"},{"name":"app.program.runtime.extensions.dir","value":"/opt/cdap/master/ext/runtimes","source":"cdap-default.xml"},{"name":"security.authentication.loginmodule.className","value":"co.cask.cdap.security.server.LDAPLoginModule","source":"cdap-site.xml"},{"name":"master.service.num.cores","value":"1","source":"cdap-site.xml"},{"name":"messaging.max.instances","value":"5","source":"cdap-default.xml"},{"name":"cdap.ugi.cache.expiration.ms","value":"60000","source":"cdap-site.xml"},{"name":"metrics.data.table.ts.rollTime.3600","value":"24","source":"cdap-default.xml"},{"name":"operational.stats.extensions.dir","value":"/opt/cdap/master/ext/operations","source":"cdap-default.xml"},{"name":"twill.yarn.am.reserved.memory.mb","value":"250","source":"cdap-default.xml"},{"name":"explore.container.yarn.app.classpath.first","value":"false","source":"cdap-default.xml"},{"name":"messaging.container.instances","value":"1","source":"cdap-default.xml"},{"name":"messaging.cache.size.mb","value":"30","source":"cdap-default.xml"},{"name":"cdap.master.kerberos.keytab","value":"/etc/security/keytabs/cdap.service.keytab","source":"cdap-site.xml"},{"name":"spark.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"kafka.server.default.replication.factor","value":"1","source":"cdap-default.xml"},{"name":"data.tx.prune.state.table","value":"cdap_system:tephra.state","source":"cdap-default.xml"},{"name":"hdfs.namespace","value":"/cdap","source":"cdap-site.xml"},{"name":"stream.container.instances","value":"1","source":"cdap-default.xml"},{"name":"messaging.http.server.consume.chunk.size","value":"60000","source":"cdap-default.xml"},{"name":"scheduler.event.poll.delay.millis","value":"2000","source":"cdap-default.xml"},{"name":"messaging.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"metrics.messaging.meta.table","value":"metrics.messaging.meta","source":"cdap-default.xml"},{"name":"http.service.connection.backlog","value":"20000","source":"cdap-default.xml"},{"name":"program.status.event.topic","value":"programstatusevent","source":"cdap-default.xml"},{"name":"worker.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"stream.container.num.cores","value":"1","source":"cdap-site.xml"},{"name":"log.process.pipeline.checkpoint.interval.ms","value":"10000","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"metadata.max.allowed.chars","value":"50","source":"cdap-default.xml"},{"name":"service.retry.policy.max.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"remote.system.op.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.delay.ms","value":"5000","source":"cdap-default.xml"},{"name":"twill.no.container.timeout","value":"120000","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"log.saver.num.instances","value":"1","source":"cdap-default.xml"},{"name":"dataset.table.prefix","value":"cdap","source":"cdap-default.xml"},{"name":"thrift.max.read.buffer","value":"16777216","source":"cdap-default.xml"},{"name":"metrics.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"root.namespace","value":"cdap","source":"cdap-site.xml"},{"name":"metrics.processor.num.instances","value":"1","source":"cdap-default.xml"},{"name":"twill.zookeeper.namespace","value":"/twill","source":"cdap-default.xml"},{"name":"master.startup.service.timeout.seconds","value":"600","source":"cdap-default.xml"},{"name":"log.saver.status.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"messaging.twill.java.heap.memory.ratio","value":"0.6","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.retention.duration.days","value":"7","source":"cdap-site.xml"},{"name":"explore.http.timeout","value":"20","source":"cdap-default.xml"},{"name":"log.saver.container.memory.mb","value":"768","source":"cdap-site.xml"},{"name":"hdfs.lib.dir","value":"/cdap/lib","source":"cdap-default.xml"},{"name":"app.bind.port","value":"0","source":"cdap-default.xml"},{"name":"data.tx.pruning.plugin.class","value":"co.cask.data2.txprune.DefaultHBaseTransactionPruningPlugin","source":"cdap-default.xml"},{"name":"log.collection.root","value":"data/logs","source":"cdap-default.xml"},{"name":"security.token.digest.keylength","value":"128","source":"cdap-default.xml"},{"name":"kafka.server.port","value":"9092","source":"cdap-default.xml"},{"name":"router.userservice.fallback.strategy","value":"random","source":"cdap-default.xml"},{"name":"dataset.service.exec.threads","value":"10","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"log.process.pipeline.config.dir","value":"/opt/cdap/master/ext/logging/config","source":"cdap-default.xml"},{"name":"security.store.provider","value":"none","source":"cdap-default.xml"},{"name":"app.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"scheduler.max.thread.pool.size","value":"100","source":"cdap-default.xml"},{"name":"data.tx.changeset.size.limit","value":"9223372036854775807","source":"cdap-default.xml"},{"name":"master.service.max.instances","value":"5","source":"cdap-default.xml"},{"name":"data.tx.prune.plugins","value":"data.tx.pruning.plugin","source":"cdap-default.xml"},{"name":"scheduler.misfire.threshold.ms","value":"60000","source":"cdap-default.xml"},{"name":"flow.retry.policy.max.time.secs","value":"10","source":"cdap-default.xml"},{"name":"custom.action.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"stream.size.schedule.polling.delay","value":"600","source":"cdap-default.xml"},{"name":"hbase.rpc.timeout","value":"15000","source":"cdap-default.xml"},{"name":"metadata.service.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"app.program.local.dataset.deleter.initial.delay","value":"300","source":"cdap-default.xml"},{"name":"flow.retry.policy.type","value":"none","source":"cdap-default.xml"},{"name":"dataset.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"hive.server2.jdbc.url","value":"jdbc:hive2://it-itn5-dstc59-29-37215-1000.coopr.sh:10000/default;principal\u003dhive/_HOST@CONTINUUITY.NET","source":"cdap-site.xml"},{"name":"remote.system.op.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"master.service.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"time.event.topic","value":"timeevent","source":"cdap-default.xml"},{"name":"stream.bind.port","value":"0","source":"cdap-default.xml"},{"name":"twill.java.reserved.memory.mb","value":"250","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"stream.file.prefix","value":"file","source":"cdap-default.xml"},{"name":"data.tx.prune.enable","value":"false","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.cleanup.transaction.timeout","value":"60","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.dir.permissions","value":"700","source":"cdap-default.xml"},{"name":"security.server.token.expiration.ms","value":"86400000","source":"cdap-default.xml"},{"name":"scheduler.stream.size.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"kafka.server.log.retention.hours","value":"24","source":"cdap-default.xml"},{"name":"security.authorization.extension.config.cache.max.entries","value":"100000","source":"cdap-default.xml"},{"name":"router.server.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"twill.location.cache.dir","value":".cache","source":"cdap-default.xml"},{"name":"http.client.read.timeout.ms","value":"60000","source":"cdap-default.xml"},{"name":"dataset.service.worker.threads","value":"4","source":"cdap-default.xml"},{"name":"stream.instance.file.prefix","value":"file.0","source":"cdap-default.xml"},{"name":"monitor.handler.service.discovery.timeout.seconds","value":"1","source":"cdap-default.xml"},{"name":"stream.index.interval","value":"10000","source":"cdap-default.xml"},{"name":"data.tx.num.cores","value":"1","source":"cdap-default.xml"},{"name":"metrics.processor.num.cores","value":"1","source":"cdap-default.xml"},{"name":"spark.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"messaging.container.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"metrics.kafka.partition.size","value":"10","source":"cdap-default.xml"},{"name":"stream.consumer.table.presplits","value":"16","source":"cdap-default.xml"},{"name":"explore.writes.enabled","value":"true","source":"cdap-default.xml"},{"name":"log.process.pipeline.event.delay.ms","value":"2000","source":"cdap-default.xml"},{"name":"metrics.messaging.topic.num","value":"10","source":"cdap-default.xml"},{"name":"data.queue.table.presplits","value":"16","source":"cdap-default.xml"},{"name":"stream.async.queue.size","value":"100","source":"cdap-default.xml"},{"name":"dashboard.ssl.disable.cert.check","value":"false","source":"cdap-default.xml"},{"name":"app.program.status.event.poll.delay.millis","value":"2000","source":"cdap-default.xml"},{"name":"log.publish.partition.key","value":"program","source":"cdap-default.xml"},{"name":"security.token.digest.key.expiration.ms","value":"3600000","source":"cdap-default.xml"},{"name":"explore.local.data.dir","value":"data/explore","source":"cdap-default.xml"},{"name":"stream.container.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"data.tx.client.count","value":"50","source":"cdap-default.xml"},{"name":"security.authentication.handler.userBaseDn","value":"ou\u003dpeople,dc\u003dcontinuuity,dc\u003dcom","source":"cdap-site.xml"},{"name":"instance.name","value":"cdap","source":"cdap-default.xml"},{"name":"workflow.token.max.size.mb","value":"30","source":"cdap-default.xml"},{"name":"router.server.port","value":"11015","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.max.retries","value":"5000","source":"cdap-default.xml"},{"name":"security.authorization.extension.config.cache.ttl.secs","value":"300","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"dashboard.router.check.timeout.secs","value":"0","source":"cdap-default.xml"},{"name":"security.authentication.handler.debug","value":"true","source":"cdap-site.xml"},{"name":"stream.base.dir","value":"/streams","source":"cdap-default.xml"},{"name":"data.tx.changeset.count.limit","value":"2147483647","source":"cdap-default.xml"},{"name":"master.startup.checks.packages","value":"co.cask.cdap.master.startup,co.cask.cdap.data.startup","source":"cdap-default.xml"},{"name":"data.local.storage.blocksize","value":"1024","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.max.time.secs","value":"1500","source":"cdap-default.xml"},{"name":"security.auth.server.bind.port","value":"10009","source":"cdap-default.xml"},{"name":"kafka.server.zookeeper.connection.timeout.ms","value":"1000000","source":"cdap-default.xml"},{"name":"system.log.process.retry.policy.max.retries","value":"1500","source":"cdap-default.xml"},{"name":"security.server.ssl.enabled","value":"false","source":"cdap-site.xml"},{"name":"router.connection.idle.timeout.secs","value":"15","source":"cdap-default.xml"},{"name":"kafka.zookeeper.namespace","value":"kafka","source":"cdap-default.xml"},{"name":"enable.unrecoverable.reset","value":"true","source":"cdap-site.xml"},{"name":"explore.inactive.operation.timeout.secs","value":"3600","source":"cdap-default.xml"},{"name":"kafka.server.num.partitions","value":"10","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"system.notification.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"data.tx.retain.client.id","value":"committed","source":"cdap-default.xml"},{"name":"market.base.url","value":"http://market.cask.co/v2","source":"cdap-default.xml"},{"name":"log.process.pipeline.buffer.size","value":"0","source":"cdap-default.xml"},{"name":"messaging.ha.fencing.delay.seconds","value":"5","source":"cdap-default.xml"},{"name":"app.program.status.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"router.webapp.enabled","value":"true","source":"cdap-site.xml"},{"name":"dataset.extensions.dir","value":"/opt/cdap/ext/lib","source":"cdap-default.xml"},{"name":"metrics.max.instances","value":"5","source":"cdap-default.xml"},{"name":"metrics.table.migration.sleep.millis","value":"10","source":"cdap-default.xml"},{"name":"metrics.num.instances","value":"1","source":"cdap-default.xml"},{"name":"log.saver.max.instances","value":"5","source":"cdap-default.xml"},{"name":"data.tx.snapshot.dir","value":"/cdap/tx.snapshot","source":"cdap-default.xml"},{"name":"metrics.query.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"metrics.dataset.leveldb.stats.report.interval","value":"60","source":"cdap-default.xml"},{"name":"data.tx.changeset.count.warn.threshold","value":"50000","source":"cdap-default.xml"},{"name":"messaging.payload.table.name","value":"tms.payload","source":"cdap-default.xml"},{"name":"metadata.service.bind.port","value":"0","source":"cdap-default.xml"},{"name":"log.base.dir","value":"/logs/avro","source":"cdap-default.xml"},{"name":"metrics.processor.max.instances","value":"5","source":"cdap-default.xml"},{"name":"service.retry.policy.type","value":"none","source":"cdap-default.xml"},{"name":"service.retry.policy.max.retries","value":"3","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.60.seconds","value":"2592000","source":"cdap-default.xml"},{"name":"worker.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"messaging.hbase.max.scan.threads","value":"96","source":"cdap-default.xml"},{"name":"data.tx.bind.port","value":"0","source":"cdap-default.xml"},{"name":"messaging.coprocessor.metadata.cache.expiration.seconds","value":"120","source":"cdap-default.xml"},{"name":"messaging.payload.table.hbase.splits","value":"16","source":"cdap-default.xml"},{"name":"metrics.table.splits","value":"16","source":"cdap-default.xml"},{"name":"log.process.pipeline.logger.cache.size","value":"1000","source":"cdap-default.xml"},{"name":"log.pipeline.cdap.file.max.size.bytes","value":"104857600","source":"cdap-default.xml"},{"name":"app.program.spark.yarn.client.rewrite.enabled","value":"true","source":"cdap-default.xml"},{"name":"worker.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"audit.enabled","value":"true","source":"cdap-default.xml"},{"name":"data.tx.grace.period","value":"86400","source":"cdap-default.xml"},{"name":"data.queue.config.update.interval","value":"5","source":"cdap-default.xml"},{"name":"app.meta.upgrade.timeout.secs","value":"60","source":"cdap-default.xml"},{"name":"data.local.storage.cachesize","value":"104857600","source":"cdap-default.xml"},{"name":"mapreduce.include.custom.format.classes","value":"true","source":"cdap-default.xml"},{"name":"app.program.spark.compat","value":"spark1_2.10","source":"cdap-default.xml"},{"name":"mapreduce.status.report.interval.seconds","value":"60","source":"cdap-default.xml"},{"name":"app.program.metrics.enabled","value":"true","source":"cdap-default.xml"},{"name":"dataset.executor.container.memory.mb","value":"512","source":"cdap-default.xml"},{"name":"data.tx.timeout","value":"30","source":"cdap-default.xml"},{"name":"remote.system.op.service.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"app.deploy.update.schedules","value":"true","source":"cdap-default.xml"},{"name":"stream.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"security.authentication.basic.realmfile","value":"/etc/cdap/conf/realmfile","source":"cdap-site.xml"},{"name":"data.tx.memory.mb","value":"350","source":"cdap-site.xml"},{"name":"stream.container.instance.id","value":"0","source":"cdap-default.xml"},{"name":"messaging.table.hbase.split.policy","value":"org.apache.hadoop.hbase.regionserver.DisabledRegionSplitPolicy","source":"cdap-default.xml"},{"name":"metrics.processor.memory.mb","value":"512","source":"cdap-site.xml"},{"name":"explore.enabled","value":"true","source":"cdap-site.xml"},{"name":"dataset.data.dir","value":"data","source":"cdap-default.xml"},{"name":"cdap.master.kerberos.principal","value":"cdap/it-itn5-dstc59-29-37215-1000.coopr.sh@CONTINUUITY.NET","source":"cdap-site.xml"},{"name":"twill.java.heap.memory.ratio","value":"0.6","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.time.secs","value":"600","source":"cdap-default.xml"},{"name":"http.client.connection.timeout.ms","value":"60000","source":"cdap-default.xml"},{"name":"hdfs.user","value":"cdap","source":"cdap-site.xml"},{"name":"worker.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"metrics.query.bind.port","value":"45005","source":"cdap-default.xml"},{"name":"workflow.retry.policy.max.delay.ms","value":"30000","source":"cdap-default.xml"},{"name":"metrics.exec.threads","value":"20","source":"cdap-default.xml"},{"name":"spark.retry.policy.max.retries","value":"1000","source":"cdap-default.xml"},{"name":"metrics.topic.prefix","value":"metrics","source":"cdap-default.xml"},{"name":"scheduler.time.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"kafka.server.log.dirs","value":"/tmp/kafka-logs","source":"cdap-default.xml"},{"name":"metadata.service.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"data.tx.janitor.enable","value":"true","source":"cdap-default.xml"},{"name":"security.data.keyfile.path","value":"data/security/keyfile","source":"cdap-default.xml"},{"name":"log.process.pipeline.kafka.fetch.size","value":"1048576","source":"cdap-default.xml"},{"name":"security.authentication.handler.hostname","value":"10.240.24.141","source":"cdap-site.xml"},{"name":"master.collect.containers.log","value":"true","source":"cdap-default.xml"},{"name":"dataset.executor.container.instances","value":"1","source":"cdap-default.xml"},{"name":"data.event.topic","value":"dataevent","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.max.retries","value":"600","source":"cdap-default.xml"},{"name":"security.token.digest.algorithm","value":"HmacSHA256","source":"cdap-default.xml"},{"name":"app.program.yarn.attempt.failures.validity.interval","value":"60000","source":"cdap-default.xml"},{"name":"security.token.distributed.parent.znode","value":"/cdap/security/auth","source":"cdap-default.xml"},{"name":"messaging.hbase.scan.cache.rows","value":"1000","source":"cdap-default.xml"},{"name":"router.ssl.bind.port","value":"10443","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.1.seconds","value":"7200","source":"cdap-default.xml"},{"name":"explore.cleanup.job.schedule.secs","value":"60","source":"cdap-default.xml"},{"name":"dataset.service.boss.threads","value":"1","source":"cdap-default.xml"},{"name":"metrics.data.table.retention.resolution.3600.seconds","value":"2592000","source":"cdap-default.xml"},{"name":"workflow.retry.policy.type","value":"exponential.backoff","source":"cdap-default.xml"},{"name":"hbase.version.resolution.strategy","value":"auto.strict","source":"cdap-default.xml"},{"name":"namespaces.dir","value":"namespaces","source":"cdap-default.xml"},{"name":"dataset.executor.container.num.cores","value":"1","source":"cdap-default.xml"},{"name":"security.authentication.handlerClassName","value":"co.cask.cdap.security.server.BasicAuthenticationHandler","source":"cdap-site.xml"},{"name":"kafka.server.host.name","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"data.tx.server.threads","value":"25","source":"cdap-default.xml"},{"name":"system.metrics.retry.policy.type","value":"fixed.delay","source":"cdap-default.xml"},{"name":"mapreduce.retry.policy.base.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"ssl.external.enabled","value":"false","source":"cdap-site.xml"},{"name":"data.tx.server.io.threads","value":"2","source":"cdap-default.xml"},{"name":"stream.async.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"app.worker.threads","value":"10","source":"cdap-default.xml"},{"name":"metrics.num.cores","value":"1","source":"cdap-default.xml"},{"name":"data.tx.max.instances","value":"5","source":"cdap-default.xml"},{"name":"explore.active.operation.timeout.secs","value":"82800","source":"cdap-default.xml"},{"name":"data.tx.num.instances","value":"1","source":"cdap-default.xml"},{"name":"security.authorization.cache.max.entries","value":"100000","source":"cdap-default.xml"},{"name":"flow.retry.policy.max.delay.ms","value":"1000","source":"cdap-default.xml"},{"name":"data.tx.bind.address","value":"0.0.0.0","source":"cdap-default.xml"},{"name":"metrics.kafka.meta.table","value":"metrics.kafka.meta","source":"cdap-default.xml"},{"name":"audit.publish.timeout.ms","value":"2000","source":"cdap-default.xml"},{"name":"metrics.table.hbase.split.policy","value":"org.apache.hadoop.hbase.regionserver.DisabledRegionSplitPolicy","source":"cdap-default.xml"},{"name":"log.process.pipeline.auto.buffer.ratio","value":"0.7","source":"cdap-default.xml"},{"name":"scheduler.program.status.event.fetch.size","value":"100","source":"cdap-default.xml"},{"name":"service.retry.policy.base.delay.ms","value":"100","source":"cdap-default.xml"},{"name":"data.local.storage","value":"data/ldb","source":"cdap-default.xml"},{"name":"router.server.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"data.tx.client.provider","value":"pool","source":"cdap-default.xml"},{"name":"router.bind.address","value":"it-itn5-dstc59-29-37215-1000.coopr.sh","source":"cdap-site.xml"},{"name":"ssl.internal.enabled","value":"false","source":"cdap-default.xml"},{"name":"dataset.executor.bind.port","value":"0","source":"cdap-default.xml"},{"name":"system.program.state.retry.policy.max.delay.ms","value":"3000","source":"cdap-default.xml"}]
11-Nov-2018 13:20:00 2018-11-11 13:20:00,356 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default
11-Nov-2018 13:20:00 2018-11-11 13:20:00,431 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}
11-Nov-2018 13:20:00 2018-11-11 13:20:00,432 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps
11-Nov-2018 13:20:00 2018-11-11 13:20:00,517 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"type":"App","id":"XMLPatternTest","name":"XMLPatternTest","version":"-SNAPSHOT","description":"Data Pipeline Application","artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}},{"type":"App","id":"UploadFile","name":"UploadFile","version":"-SNAPSHOT","description":"Application to upload file on HDFS using FileSet dataset","artifact":{"name":"UploadFile","version":"1.0.0-SNAPSHOT","scope":"USER"}}]
11-Nov-2018 13:20:00 2018-11-11 13:20:00,517 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT
11-Nov-2018 13:20:00 2018-11-11 13:20:00,637 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"XMLPatternTest","appVersion":"-SNAPSHOT","artifactVersion":"4.3.5-SNAPSHOT","description":"Data Pipeline Application","configuration":"{\"engine\":\"MAPREDUCE\",\"schedule\":\"* * * * *\",\"postActions\":[],\"stages\":[{\"name\":\"XMLProjectionTransform\",\"plugin\":{\"name\":\"Projection\",\"type\":\"transform\",\"properties\":{\"convert\":\"offset:string\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlTransform\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"long\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}},{\"name\":\"XMLReader\",\"plugin\":{\"name\":\"XMLReader\",\"type\":\"batchsource\",\"properties\":{\"referenceName\":\"XMLReaderPatternTest\",\"path\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadersource/*\",\"targetFolder\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadertarget/\",\"pattern\":\"log.xml$\",\"nodePath\":\"/catalog/book/price\",\"reprocessingRequired\":\"No\",\"tableName\":\"XMLPatternTrackingTable\",\"actionAfterProcess\":\"delete\",\"tableExpiryPeriod\":\"30\",\"temporaryFolder\":\"/tmp\"}}},{\"name\":\"XMLTableSink\",\"plugin\":{\"name\":\"Table\",\"type\":\"batchsink\",\"properties\":{\"name\":\"output-batchsink-pattern-test\",\"schema.row.field\":\"offset\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlSink\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}}],\"connections\":[{\"from\":\"XMLReader\",\"to\":\"XMLProjectionTransform\"},{\"from\":\"XMLProjectionTransform\",\"to\":\"XMLTableSink\"}],\"resources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"driverResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"clientResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"stageLoggingEnabled\":true,\"processTimingEnabled\":true,\"numOfRecordsPreview\":0.0,\"properties\":{},\"sinks\":[],\"transforms\":[]}","streams":[],"datasets":[{"name":"XMLPatternTrackingTable","classname":"co.cask.cdap.api.dataset.lib.KeyValueTable"},{"name":"output-batchsink-pattern-test","classname":"co.cask.cdap.api.dataset.table.Table"}],"programs":[{"type":"Mapreduce","app":"XMLPatternTest","id":"phase-1","name":"phase-1","description":"MapReduce phase executor. Sources \u0027XMLReader\u0027 to sinks \u0027XMLTableSink\u0027."},{"type":"Workflow","app":"XMLPatternTest","id":"DataPipelineWorkflow","name":"DataPipelineWorkflow","description":"Data Pipeline Workflow"}],"plugins":[{"id":"XMLProjectionTransform","name":"Projection","type":"transform"},{"id":"XMLTableSink","name":"Table","type":"batchsink"},{"id":"XMLReader","name":"XMLReader","type":"batchsource"}],"artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}}
11-Nov-2018 13:20:00 2018-11-11 13:20:00,638 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:00 2018-11-11 13:20:00,723 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:00 2018-11-11 13:20:00,724 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: POST http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/stop
11-Nov-2018 13:20:00 2018-11-11 13:20:00,808 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [400] Response Body: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:20:00 2018-11-11 13:20:00,810 - WARN  [main:c.c.c.c.ProgramClient@368] - Program program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1 is already stopped, proceeding even though the following exception is raised.
11-Nov-2018 13:20:00 java.io.IOException: 400: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:20:00         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:132) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:20:00         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:74) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:20:00         at co.cask.cdap.client.ProgramClient.stop(ProgramClient.java:284) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:20:00         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:362) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:20:00         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:20:00         at co.cask.cdap.test.IntegrationTestBase.setUp(IntegrationTestBase.java:111) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:20:00         at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) ~[na:na]
11-Nov-2018 13:20:00         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_101]
11-Nov-2018 13:20:00         at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_101]
11-Nov-2018 13:20:00         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.Suite.runChild(Suite.java:127) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.Suite.runChild(Suite.java:26) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:20:00 2018-11-11 13:20:00,810 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:00 2018-11-11 13:20:00,894 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:01 2018-11-11 13:20:01,895 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:01 2018-11-11 13:20:01,982 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:02 2018-11-11 13:20:02,982 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:03 2018-11-11 13:20:03,068 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:04 2018-11-11 13:20:04,068 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:04 2018-11-11 13:20:04,154 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:05 2018-11-11 13:20:05,154 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:05 2018-11-11 13:20:05,240 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:06 2018-11-11 13:20:06,241 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:06 2018-11-11 13:20:06,324 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:07 2018-11-11 13:20:07,325 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:07 2018-11-11 13:20:07,410 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:08 2018-11-11 13:20:08,410 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:08 2018-11-11 13:20:08,496 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:09 2018-11-11 13:20:09,497 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:09 2018-11-11 13:20:09,582 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:10 2018-11-11 13:20:10,583 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:10 2018-11-11 13:20:10,668 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:11 2018-11-11 13:20:11,669 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:11 2018-11-11 13:20:11,754 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:12 2018-11-11 13:20:12,754 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:12 2018-11-11 13:20:12,845 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:13 2018-11-11 13:20:13,845 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:13 2018-11-11 13:20:13,930 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:14 2018-11-11 13:20:14,931 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:15 2018-11-11 13:20:15,016 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:16 2018-11-11 13:20:16,017 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:16 2018-11-11 13:20:16,103 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:17 2018-11-11 13:20:17,104 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:17 2018-11-11 13:20:17,189 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:18 2018-11-11 13:20:18,190 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:18 2018-11-11 13:20:18,275 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:19 2018-11-11 13:20:19,276 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:19 2018-11-11 13:20:19,369 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:20 2018-11-11 13:20:20,370 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:20 2018-11-11 13:20:20,456 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:21 2018-11-11 13:20:21,457 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:21 2018-11-11 13:20:21,548 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:22 2018-11-11 13:20:22,549 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:22 2018-11-11 13:20:22,633 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:23 2018-11-11 13:20:23,634 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:23 2018-11-11 13:20:23,718 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:24 2018-11-11 13:20:24,719 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:24 2018-11-11 13:20:24,803 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:25 2018-11-11 13:20:25,803 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:25 2018-11-11 13:20:25,889 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:26 2018-11-11 13:20:26,889 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:26 2018-11-11 13:20:26,974 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:27 2018-11-11 13:20:27,974 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:28 2018-11-11 13:20:28,059 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:29 2018-11-11 13:20:29,060 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:29 2018-11-11 13:20:29,144 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:30 2018-11-11 13:20:30,145 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:30 2018-11-11 13:20:30,228 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:31 2018-11-11 13:20:31,229 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:31 2018-11-11 13:20:31,313 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:32 2018-11-11 13:20:32,313 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:32 2018-11-11 13:20:32,399 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:33 2018-11-11 13:20:33,399 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:33 2018-11-11 13:20:33,485 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:34 2018-11-11 13:20:34,486 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:34 2018-11-11 13:20:34,570 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:35 2018-11-11 13:20:35,570 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:35 2018-11-11 13:20:35,654 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:36 2018-11-11 13:20:36,654 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:36 2018-11-11 13:20:36,739 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:37 2018-11-11 13:20:37,740 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:37 2018-11-11 13:20:37,826 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:38 2018-11-11 13:20:38,827 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:38 2018-11-11 13:20:38,912 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:39 2018-11-11 13:20:39,913 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:39 2018-11-11 13:20:39,998 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:40 2018-11-11 13:20:40,998 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:41 2018-11-11 13:20:41,082 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:42 2018-11-11 13:20:42,083 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:42 2018-11-11 13:20:42,169 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:43 2018-11-11 13:20:43,170 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:43 2018-11-11 13:20:43,256 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:44 2018-11-11 13:20:44,257 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:44 2018-11-11 13:20:44,341 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:45 2018-11-11 13:20:45,342 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:45 2018-11-11 13:20:45,426 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:46 2018-11-11 13:20:46,426 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:46 2018-11-11 13:20:46,512 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:47 2018-11-11 13:20:47,513 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:47 2018-11-11 13:20:47,598 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:48 2018-11-11 13:20:48,599 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:48 2018-11-11 13:20:48,683 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:49 2018-11-11 13:20:49,684 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:49 2018-11-11 13:20:49,768 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:50 2018-11-11 13:20:50,768 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:50 2018-11-11 13:20:50,851 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:51 2018-11-11 13:20:51,852 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:51 2018-11-11 13:20:51,936 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:52 2018-11-11 13:20:52,937 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:53 2018-11-11 13:20:53,023 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:54 2018-11-11 13:20:54,023 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:54 2018-11-11 13:20:54,108 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:55 2018-11-11 13:20:55,109 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:55 2018-11-11 13:20:55,193 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:56 2018-11-11 13:20:56,194 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:56 2018-11-11 13:20:56,278 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:57 2018-11-11 13:20:57,279 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:57 2018-11-11 13:20:57,363 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:58 2018-11-11 13:20:58,363 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:58 2018-11-11 13:20:58,448 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:20:59 2018-11-11 13:20:59,449 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:20:59 2018-11-11 13:20:59,535 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:00 2018-11-11 13:21:00,536 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:00 2018-11-11 13:21:00,621 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:01 2018-11-11 13:21:01,622 - INFO  [main:c.c.c.t.IntegrationTestBase@119] - Beginning tearDown.
11-Nov-2018 13:21:01 2018-11-11 13:21:01,622 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default
11-Nov-2018 13:21:01 2018-11-11 13:21:01,699 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"default","description":"Default Namespace","config":{"scheduler.queue.name":"","explore.as.principal":true}}
11-Nov-2018 13:21:01 2018-11-11 13:21:01,699 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps
11-Nov-2018 13:21:01 2018-11-11 13:21:01,785 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: [{"type":"App","id":"XMLPatternTest","name":"XMLPatternTest","version":"-SNAPSHOT","description":"Data Pipeline Application","artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}},{"type":"App","id":"UploadFile","name":"UploadFile","version":"-SNAPSHOT","description":"Application to upload file on HDFS using FileSet dataset","artifact":{"name":"UploadFile","version":"1.0.0-SNAPSHOT","scope":"USER"}}]
11-Nov-2018 13:21:01 2018-11-11 13:21:01,786 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT
11-Nov-2018 13:21:01 2018-11-11 13:21:01,905 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"name":"XMLPatternTest","appVersion":"-SNAPSHOT","artifactVersion":"4.3.5-SNAPSHOT","description":"Data Pipeline Application","configuration":"{\"engine\":\"MAPREDUCE\",\"schedule\":\"* * * * *\",\"postActions\":[],\"stages\":[{\"name\":\"XMLProjectionTransform\",\"plugin\":{\"name\":\"Projection\",\"type\":\"transform\",\"properties\":{\"convert\":\"offset:string\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlTransform\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"long\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}},{\"name\":\"XMLReader\",\"plugin\":{\"name\":\"XMLReader\",\"type\":\"batchsource\",\"properties\":{\"referenceName\":\"XMLReaderPatternTest\",\"path\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadersource/*\",\"targetFolder\":\"hdfs://10.210.0.12:8020/cdap/namespaces/default/data/xmlreadertarget/\",\"pattern\":\"log.xml$\",\"nodePath\":\"/catalog/book/price\",\"reprocessingRequired\":\"No\",\"tableName\":\"XMLPatternTrackingTable\",\"actionAfterProcess\":\"delete\",\"tableExpiryPeriod\":\"30\",\"temporaryFolder\":\"/tmp\"}}},{\"name\":\"XMLTableSink\",\"plugin\":{\"name\":\"Table\",\"type\":\"batchsink\",\"properties\":{\"name\":\"output-batchsink-pattern-test\",\"schema.row.field\":\"offset\",\"schema\":\"{\\\"type\\\":\\\"record\\\",\\\"name\\\":\\\"xmlSink\\\",\\\"fields\\\":[{\\\"name\\\":\\\"offset\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"filename\\\",\\\"type\\\":\\\"string\\\"},{\\\"name\\\":\\\"record\\\",\\\"type\\\":\\\"string\\\"}]}\"}}}],\"connections\":[{\"from\":\"XMLReader\",\"to\":\"XMLProjectionTransform\"},{\"from\":\"XMLProjectionTransform\",\"to\":\"XMLTableSink\"}],\"resources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"driverResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"clientResources\":{\"virtualCores\":1.0,\"memoryMB\":1024.0},\"stageLoggingEnabled\":true,\"processTimingEnabled\":true,\"numOfRecordsPreview\":0.0,\"properties\":{},\"sinks\":[],\"transforms\":[]}","streams":[],"datasets":[{"name":"XMLPatternTrackingTable","classname":"co.cask.cdap.api.dataset.lib.KeyValueTable"},{"name":"output-batchsink-pattern-test","classname":"co.cask.cdap.api.dataset.table.Table"}],"programs":[{"type":"Mapreduce","app":"XMLPatternTest","id":"phase-1","name":"phase-1","description":"MapReduce phase executor. Sources \u0027XMLReader\u0027 to sinks \u0027XMLTableSink\u0027."},{"type":"Workflow","app":"XMLPatternTest","id":"DataPipelineWorkflow","name":"DataPipelineWorkflow","description":"Data Pipeline Workflow"}],"plugins":[{"id":"XMLProjectionTransform","name":"Projection","type":"transform"},{"id":"XMLTableSink","name":"Table","type":"batchsink"},{"id":"XMLReader","name":"XMLReader","type":"batchsource"}],"artifact":{"name":"cdap-data-pipeline","version":"4.3.5-SNAPSHOT","scope":"SYSTEM"}}
11-Nov-2018 13:21:01 2018-11-11 13:21:01,906 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:01 2018-11-11 13:21:01,990 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:01 2018-11-11 13:21:01,991 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: POST http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/stop
11-Nov-2018 13:21:02 2018-11-11 13:21:02,077 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [400] Response Body: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:21:02 2018-11-11 13:21:02,079 - WARN  [main:c.c.c.c.ProgramClient@368] - Program program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1 is already stopped, proceeding even though the following exception is raised.
11-Nov-2018 13:21:02 java.io.IOException: 400: Program 'program:default.XMLPatternTest.-SNAPSHOT.mapreduce.phase-1' is not running.
11-Nov-2018 13:21:02         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:132) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:21:02         at co.cask.cdap.client.util.RESTClient.execute(RESTClient.java:74) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:21:02         at co.cask.cdap.client.ProgramClient.stop(ProgramClient.java:284) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:21:02         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:362) ~[cdap-cli-4.3.2.jar:na]
11-Nov-2018 13:21:02         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:21:02         at co.cask.cdap.test.IntegrationTestBase.tearDown(IntegrationTestBase.java:128) [cdap-integration-test-4.3.2.jar:na]
11-Nov-2018 13:21:02         at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) ~[na:na]
11-Nov-2018 13:21:02         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_101]
11-Nov-2018 13:21:02         at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_101]
11-Nov-2018 13:21:02         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.Suite.runChild(Suite.java:127) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.Suite.runChild(Suite.java:26) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.junit.runners.ParentRunner.run(ParentRunner.java:309) [junit-4.11.jar:na]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128) [surefire-junit4-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.18.1.jar:2.18.1]
11-Nov-2018 13:21:02 2018-11-11 13:21:02,079 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:02 2018-11-11 13:21:02,165 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:03 2018-11-11 13:21:03,165 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:03 2018-11-11 13:21:03,251 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:04 2018-11-11 13:21:04,252 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:04 2018-11-11 13:21:04,336 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:05 2018-11-11 13:21:05,337 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:05 2018-11-11 13:21:05,423 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:06 2018-11-11 13:21:06,423 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:06 2018-11-11 13:21:06,509 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:07 2018-11-11 13:21:07,509 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:07 2018-11-11 13:21:07,597 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:08 2018-11-11 13:21:08,598 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:08 2018-11-11 13:21:08,685 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:09 2018-11-11 13:21:09,686 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:09 2018-11-11 13:21:09,775 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:10 2018-11-11 13:21:10,776 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:10 2018-11-11 13:21:10,868 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:11 2018-11-11 13:21:11,869 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:11 2018-11-11 13:21:11,960 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:12 2018-11-11 13:21:12,960 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:13 2018-11-11 13:21:13,047 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:14 2018-11-11 13:21:14,048 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:14 2018-11-11 13:21:14,136 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:15 2018-11-11 13:21:15,137 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:15 2018-11-11 13:21:15,227 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:16 2018-11-11 13:21:16,227 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:16 2018-11-11 13:21:16,317 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:17 2018-11-11 13:21:17,317 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:17 2018-11-11 13:21:17,407 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:18 2018-11-11 13:21:18,408 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:18 2018-11-11 13:21:18,492 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:19 2018-11-11 13:21:19,493 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:19 2018-11-11 13:21:19,587 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:20 2018-11-11 13:21:20,587 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:20 2018-11-11 13:21:20,672 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:21 2018-11-11 13:21:21,673 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:21 2018-11-11 13:21:21,759 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:22 2018-11-11 13:21:22,760 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:22 2018-11-11 13:21:22,843 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:23 2018-11-11 13:21:23,844 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:23 2018-11-11 13:21:23,929 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:24 2018-11-11 13:21:24,929 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:25 2018-11-11 13:21:25,013 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:26 2018-11-11 13:21:26,014 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:26 2018-11-11 13:21:26,100 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:27 2018-11-11 13:21:27,101 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:27 2018-11-11 13:21:27,186 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:28 2018-11-11 13:21:28,187 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:28 2018-11-11 13:21:28,284 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:29 2018-11-11 13:21:29,285 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:29 2018-11-11 13:21:29,370 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:30 2018-11-11 13:21:30,371 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:30 2018-11-11 13:21:30,455 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:31 2018-11-11 13:21:31,456 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:31 2018-11-11 13:21:31,541 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:32 2018-11-11 13:21:32,542 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:32 2018-11-11 13:21:32,626 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:33 2018-11-11 13:21:33,627 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:33 2018-11-11 13:21:33,711 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:34 2018-11-11 13:21:34,712 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:34 2018-11-11 13:21:34,797 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:35 2018-11-11 13:21:35,798 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:35 2018-11-11 13:21:35,883 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:36 2018-11-11 13:21:36,884 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:36 2018-11-11 13:21:36,970 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:37 2018-11-11 13:21:37,971 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:38 2018-11-11 13:21:38,057 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:39 2018-11-11 13:21:39,057 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:39 2018-11-11 13:21:39,143 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:40 2018-11-11 13:21:40,143 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:40 2018-11-11 13:21:40,228 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:41 2018-11-11 13:21:41,228 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:41 2018-11-11 13:21:41,316 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:42 2018-11-11 13:21:42,316 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:42 2018-11-11 13:21:42,402 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:43 2018-11-11 13:21:43,402 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:43 2018-11-11 13:21:43,488 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:44 2018-11-11 13:21:44,488 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:44 2018-11-11 13:21:44,574 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:45 2018-11-11 13:21:45,574 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:45 2018-11-11 13:21:45,659 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:46 2018-11-11 13:21:46,660 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:46 2018-11-11 13:21:46,744 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:47 2018-11-11 13:21:47,745 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:47 2018-11-11 13:21:47,830 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:48 2018-11-11 13:21:48,830 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:48 2018-11-11 13:21:48,932 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:49 2018-11-11 13:21:49,932 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:50 2018-11-11 13:21:50,016 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:51 2018-11-11 13:21:51,016 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:51 2018-11-11 13:21:51,103 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:52 2018-11-11 13:21:52,103 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:52 2018-11-11 13:21:52,188 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:53 2018-11-11 13:21:53,189 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:53 2018-11-11 13:21:53,274 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:54 2018-11-11 13:21:54,275 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:54 2018-11-11 13:21:54,359 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:55 2018-11-11 13:21:55,360 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:55 2018-11-11 13:21:55,445 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:56 2018-11-11 13:21:56,446 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:56 2018-11-11 13:21:56,530 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:57 2018-11-11 13:21:57,530 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:57 2018-11-11 13:21:57,615 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:58 2018-11-11 13:21:58,615 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:58 2018-11-11 13:21:58,700 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:21:59 2018-11-11 13:21:59,700 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:21:59 2018-11-11 13:21:59,785 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:22:00 2018-11-11 13:22:00,785 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:22:00 2018-11-11 13:22:00,869 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:22:01 2018-11-11 13:22:01,870 - INFO  [main:c.c.c.t.AudiTestBase$1@125] - Making request: GET http://10.210.0.12:11015/v3/namespaces/default/apps/XMLPatternTest/versions/-SNAPSHOT/mapreduce/phase-1/status
11-Nov-2018 13:22:01 2018-11-11 13:22:01,954 - INFO  [main:c.c.c.t.AudiTestBase$1@134] - Received response: [200] Response Body: {"status":"RUNNING"}
11-Nov-2018 13:22:06 Tests run: 67, Failures: 0, Errors: 6, Skipped: 0, Time elapsed: 14,327.57 sec <<< FAILURE! - in co.cask.cdap.test.suite.AllTests
11-Nov-2018 13:22:06 testPatternWithDeleteAction(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 410.203 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected true but found false.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:130)
11-Nov-2018 13:22:06         at co.cask.cdap.test.AbstractProgramManager.waitForRuns(AbstractProgramManager.java:90)
11-Nov-2018 13:22:06         at co.cask.cdap.test.AbstractProgramManager.waitForRun(AbstractProgramManager.java:84)
11-Nov-2018 13:22:06         at co.cask.cdap.app.etl.batch.XMLReaderTest.startWorkFlow(XMLReaderTest.java:151)
11-Nov-2018 13:22:06         at co.cask.cdap.app.etl.batch.XMLReaderTest.testPatternWithDeleteAction(XMLReaderTest.java:354)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 testPatternWithDeleteAction(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 410.204 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected STOPPED but found RUNNING.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.waitForStatus(ProgramClient.java:490)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:371)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.tearDown(IntegrationTestBase.java:128)
11-Nov-2018 13:22:06         at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
11-Nov-2018 13:22:06         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
11-Nov-2018 13:22:06         at java.lang.reflect.Method.invoke(Method.java:498)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
11-Nov-2018 13:22:06         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
11-Nov-2018 13:22:06         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:127)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:26)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 testPreProcessingRequired(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 123.207 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected STOPPED but found RUNNING.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.waitForStatus(ProgramClient.java:490)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:371)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.setUp(IntegrationTestBase.java:111)
11-Nov-2018 13:22:06         at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
11-Nov-2018 13:22:06         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
11-Nov-2018 13:22:06         at java.lang.reflect.Method.invoke(Method.java:498)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
11-Nov-2018 13:22:06         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
11-Nov-2018 13:22:06         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:127)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:26)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 testPreProcessingRequired(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 123.207 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected STOPPED but found RUNNING.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.waitForStatus(ProgramClient.java:490)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:371)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.tearDown(IntegrationTestBase.java:128)
11-Nov-2018 13:22:06         at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
11-Nov-2018 13:22:06         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
11-Nov-2018 13:22:06         at java.lang.reflect.Method.invoke(Method.java:498)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
11-Nov-2018 13:22:06         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
11-Nov-2018 13:22:06         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:127)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:26)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 testInvalidNodePathWithMoveAction(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 123.175 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected STOPPED but found RUNNING.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.waitForStatus(ProgramClient.java:490)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:371)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.setUp(IntegrationTestBase.java:111)
11-Nov-2018 13:22:06         at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
11-Nov-2018 13:22:06         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
11-Nov-2018 13:22:06         at java.lang.reflect.Method.invoke(Method.java:498)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
11-Nov-2018 13:22:06         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
11-Nov-2018 13:22:06         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:127)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:26)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 testInvalidNodePathWithMoveAction(co.cask.cdap.app.etl.batch.XMLReaderTest)  Time elapsed: 123.176 sec  <<< ERROR!
11-Nov-2018 13:22:06 java.util.concurrent.TimeoutException: Timeout occurred. Expected STOPPED but found RUNNING.
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:70)
11-Nov-2018 13:22:06         at co.cask.cdap.common.utils.Tasks.waitFor(Tasks.java:91)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.waitForStatus(ProgramClient.java:490)
11-Nov-2018 13:22:06         at co.cask.cdap.client.ProgramClient.stopAll(ProgramClient.java:371)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.doClear(IntegrationTestBase.java:413)
11-Nov-2018 13:22:06         at co.cask.cdap.test.IntegrationTestBase.tearDown(IntegrationTestBase.java:128)
11-Nov-2018 13:22:06         at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
11-Nov-2018 13:22:06         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
11-Nov-2018 13:22:06         at java.lang.reflect.Method.invoke(Method.java:498)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
11-Nov-2018 13:22:06         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
11-Nov-2018 13:22:06         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
11-Nov-2018 13:22:06         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
11-Nov-2018 13:22:06         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
11-Nov-2018 13:22:06         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:127)
11-Nov-2018 13:22:06         at org.junit.runners.Suite.runChild(Suite.java:26)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
11-Nov-2018 13:22:06         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
11-Nov-2018 13:22:06         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 Results :
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 Tests in error:
11-Nov-2018 13:22:06 co.cask.cdap.app.etl.batch.XMLReaderTest.testInvalidNodePathWithMoveAction(co.cask.cdap.app.etl.batch.XMLReaderTest)
11-Nov-2018 13:22:06   Run 1: XMLReaderTest>IntegrationTestBase.setUp:111->IntegrationTestBase.doClear:413 » Timeout
11-Nov-2018 13:22:06   Run 2: XMLReaderTest>IntegrationTestBase.tearDown:128->IntegrationTestBase.doClear:413 » Timeout
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 co.cask.cdap.app.etl.batch.XMLReaderTest.testPatternWithDeleteAction(co.cask.cdap.app.etl.batch.XMLReaderTest)
11-Nov-2018 13:22:06   Run 1: XMLReaderTest.testPatternWithDeleteAction:354->startWorkFlow:151 » Timeout Tim...
11-Nov-2018 13:22:06   Run 2: XMLReaderTest>IntegrationTestBase.tearDown:128->IntegrationTestBase.doClear:413 » Timeout
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 co.cask.cdap.app.etl.batch.XMLReaderTest.testPreProcessingRequired(co.cask.cdap.app.etl.batch.XMLReaderTest)
11-Nov-2018 13:22:06   Run 1: XMLReaderTest>IntegrationTestBase.setUp:111->IntegrationTestBase.doClear:413 » Timeout
11-Nov-2018 13:22:06   Run 2: XMLReaderTest>IntegrationTestBase.tearDown:128->IntegrationTestBase.doClear:413 » Timeout
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 Tests run: 64, Failures: 0, Errors: 3, Skipped: 0
11-Nov-2018 13:22:06
11-Nov-2018 13:22:06 [INFO] ------------------------------------------------------------------------
11-Nov-2018 13:22:06 [INFO] Reactor Summary:
11-Nov-2018 13:22:06 [INFO]
11-Nov-2018 13:22:06 [INFO] integration-test .................................. SUCCESS [7.344s]
11-Nov-2018 13:22:06 [INFO] integration-test-core ............................. SUCCESS [4.609s]
11-Nov-2018 13:22:06 [INFO] integration-test-remote ........................... FAILURE [3:58:57.595s]
11-Nov-2018 13:22:06 [INFO] integration-test-standalone ....................... SKIPPED
11-Nov-2018 13:22:06 [INFO] upgrade-test ...................................... SKIPPED
11-Nov-2018 13:22:06 [INFO] long-running-test ................................. SKIPPED
11-Nov-2018 13:22:06 [INFO] ------------------------------------------------------------------------
11-Nov-2018 13:22:06 [INFO] BUILD FAILURE
11-Nov-2018 13:22:06 [INFO] ------------------------------------------------------------------------
11-Nov-2018 13:22:06 [INFO] Total time: 3:59:09.926s
11-Nov-2018 13:22:06 [INFO] Finished at: Sun Nov 11 13:22:06 UTC 2018
11-Nov-2018 13:22:07 [INFO] Final Memory: 87M/915M
11-Nov-2018 13:22:07 [INFO] ------------------------------------------------------------------------
11-Nov-2018 13:22:07 [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (default-test) on project integration-test-remote: There are test failures.
11-Nov-2018 13:22:07 [ERROR]
11-Nov-2018 13:22:07 [ERROR] Please refer to /var/bamboo/xml-data/build-dir/IT-ITN5-DSTC59/cdap-integration-tests/integration-test-remote/target/surefire-reports for the individual test results.
11-Nov-2018 13:22:07 [ERROR] -> [Help 1]
11-Nov-2018 13:22:07 [ERROR]
11-Nov-2018 13:22:07 [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
11-Nov-2018 13:22:07 [ERROR] Re-run Maven using the -X switch to enable full debug logging.
11-Nov-2018 13:22:07 [ERROR]
11-Nov-2018 13:22:07 [ERROR] For more information about the errors and possible solutions, please read the following articles:
11-Nov-2018 13:22:07 [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
11-Nov-2018 13:22:07 [ERROR]
11-Nov-2018 13:22:07 [ERROR] After correcting the problems, you can resume the build with the command
11-Nov-2018 13:22:07 [ERROR]   mvn <goals> -rf :integration-test-remote
11-Nov-2018 13:22:07 Failing task since return code of [/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/IT-ITN5-DSTC59 clean test -U -Dtest=AllTests -Dcdap.username=******** -Dcdap.password=******** -DinstanceUri=http://10.210.0.12] was 1 while expected 0
11-Nov-2018 13:22:07 Parsing test results under /var/bamboo/xml-data/build-dir/IT-ITN5-DSTC59...
11-Nov-2018 13:22:07 Finished task 'Integration test - cluster' with result: Failed
11-Nov-2018 13:22:07 Running post build plugin 'Docker Container Cleanup'
11-Nov-2018 13:22:07 Running post build plugin 'NCover Results Collector'
11-Nov-2018 13:22:07 Running post build plugin 'Clover Results Collector'
11-Nov-2018 13:22:07 Running post build plugin 'npm Cache Cleanup'
11-Nov-2018 13:22:07 Running post build plugin 'Artifact Copier'
11-Nov-2018 13:22:07 Finalising the build...
11-Nov-2018 13:22:07 Stopping timer.
11-Nov-2018 13:22:07 Build IT-ITN5-DSTC59-29 completed.
11-Nov-2018 13:22:07 Running on server: post build plugin 'NCover Results Collector'
11-Nov-2018 13:22:07 Running on server: post build plugin 'Build Hanging Detection Configuration'
11-Nov-2018 13:22:07 Running on server: post build plugin 'Clover Delta Calculator'
11-Nov-2018 13:22:07 Running on server: post build plugin 'Maven Dependencies Postprocessor'
11-Nov-2018 13:22:07 All post build plugins have finished
11-Nov-2018 13:22:08 Generating build results summary...
11-Nov-2018 13:22:08 Saving build results to disk...
11-Nov-2018 13:22:08 Logging substituted variables...
11-Nov-2018 13:22:08 Indexing build results...
11-Nov-2018 13:22:08 Finished building IT-ITN5-DSTC59-29.