Build #43

Builds and runs unit tests for CDAP Apps repository

Build: #43 failed

Job: MovieRecommender was successful

Stages & jobs

  1. Build Stage

Build log

The build generated 293 lines of output. Download or view full build log

09-Apr-2017 02:01:49 Build CDAP Apps - Build and Unit Test - release-cdap-3.5-compatible - MovieRecommender #43 (CA-BUT6-MR-43) started building on agent bamboo-agent12.prod.continuuity.net
09-Apr-2017 02:01:49 Remote Agent
09-Apr-2017 02:01:49 Build working directory is /var/bamboo/xml-data/build-dir/CA-BUT6-MR
09-Apr-2017 02:01:49 Executing build CDAP Apps - Build and Unit Test - release-cdap-3.5-compatible - MovieRecommender #43 (CA-BUT6-MR-43)
09-Apr-2017 02:01:49 Starting task 'Checkout Default Repository' of type 'com.atlassian.bamboo.plugins.vcs:task.vcs.checkout'
09-Apr-2017 02:01:49 Build always requires a clean checkout
09-Apr-2017 02:01:49 Cleaning build directory '/var/bamboo/xml-data/build-dir/CA-BUT6-MR'
09-Apr-2017 02:01:50 Updating source code to revision: 41bbb1a6677349b02a00896c092272359ffed55a
09-Apr-2017 02:01:50 Fetching 'refs/heads/release/cdap-3.5-compatible' from 'https://github.com/caskdata/cdap-apps.git'.
09-Apr-2017 02:01:50 Checking out revision 41bbb1a6677349b02a00896c092272359ffed55a.
09-Apr-2017 02:01:50 Creating local git repository in '/var/bamboo/xml-data/build-dir/CA-BUT6-MR/.git'.
09-Apr-2017 02:01:50 Cloning into '/var/bamboo/xml-data/build-dir/CA-BUT6-MR'...
09-Apr-2017 02:01:50 done.
09-Apr-2017 02:01:51 Switched to a new branch 'release/cdap-3.5-compatible'
09-Apr-2017 02:01:51 Branch release/cdap-3.5-compatible set up to track remote branch release/cdap-3.5-compatible from origin.
09-Apr-2017 02:01:51 Updated source code to revision: 41bbb1a6677349b02a00896c092272359ffed55a
09-Apr-2017 02:01:51 Finished task 'Checkout Default Repository' with result: Success
09-Apr-2017 02:01:51 Running pre-build action: VCS Version Collector
09-Apr-2017 02:01:51 Running pre-build action: Hung Build Killer PreBuildAction
09-Apr-2017 02:01:51 Starting task 'MovieRecommender' of type 'com.atlassian.bamboo.plugins.maven:task.builder.mvn3'
09-Apr-2017 02:01:51
Beginning to execute external process for build 'CDAP Apps - Build and Unit Test - release-cdap-3.5-compatible - MovieRecommender #43 (CA-BUT6-MR-43)'
... running command line:
/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/CA-BUT6-MR clean package
... in: /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender
... using extra environment variables:
bamboo_planRepository_1_branch=release/cdap-3.5-compatible
bamboo_docs_upload_host=docs1.cask.co
bamboo_planRepository_1_branchDisplayName=release/cdap-3.5-compatible
bamboo_repository_revision_number=41bbb1a6677349b02a00896c092272359ffed55a
MAVEN2_HOME=/opt/maven
bamboo_docs_s3_bucket=docs.cask.co
bamboo_capability_system_builder_command_s3cmd=/usr/bin/s3cmd
bamboo_buildKey=CA-BUT6-MR
bamboo_gpg_password=********
bamboo_ops_gpg_password=********
bamboo_shortJobName=MovieRecommender
bamboo_buildResultsUrl=http://builds.cask.co/browse/CA-BUT6-MR-43
bamboo_repository_s3_bucket=repository.cask.co
bamboo_planRepository_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_capability_system_builder_mvn3_Maven_3_1=/opt/maven
bamboo_aws_secret_access_key_password=********
bamboo_agentId=50921473
bamboo_capability_Swapfile=true
bamboo_repository_50266308_name=CDAP Apps
bamboo_planRepository_revision=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_remote_docs_base=/var/www/docs
bamboo_planRepository_previousRevision=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_capability_RHEL=rhel
bamboo_capability_freight=true
bamboo_repository_branch_name=release/cdap-3.5-compatible
bamboo_docs_upload_user=bamboo
bamboo_kdc_address_password=********
bamboo_capability_GPG=GPG
bamboo_capability_system_jdk_Oracle_JDK_8=/usr/lib/jvm/jdk8
JAVA_HOME=/usr/lib/jvm/jdk1.7.0_75
bamboo_capability_system_jdk_Oracle_JDK_7=/usr/lib/jvm/jdk7
bamboo_repository_50266308_previous_revision_number=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_downloads_s3_bucket=downloads.cask.co
bamboo_planRepository_branch=release/cdap-3.5-compatible
bamboo_docs_server_list=docs1.cask.co,docs2.cask.co
bamboo_market_s3_access_key_password=********
bamboo_planRepository_1_type=gitv2
bamboo_planRepository_branchName=release/cdap-3.5-compatible
bamboo_capability_SPHINX=Sphinx
bamboo_capability_rsync=true
bamboo_market_cloudfront_secret_key_password=********
bamboo_capability_system_jdk_JDK=/usr/lib/jvm/jdk1.7.0_75
bamboo_planRepository_type=gitv2
bamboo_planRepository_1_username=
bamboo_capability_expect=true
bamboo_capability_RSYNC=rsync
useMavenReturnCode=false
bamboo_capability_system_builder_node_Node_js=/usr/bin/node
bamboo_docs_upload_target=/data/s3stg/incoming
bamboo_capability_system_builder_mvn3_Maven_3=/opt/maven
bamboo_planKey=CA-BUT6
bamboo_planRepository_username=
bamboo_planRepository_1_branchName=release/cdap-3.5-compatible
bamboo_capability_s3cmd=true
bamboo_resultsUrl=http://builds.cask.co/browse/CA-BUT6-MR-43
bamboo_capability_Sphinx=true
bamboo_planRepository_1_name=CDAP Apps
bamboo_build_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT6-MR
bamboo_shortPlanName=release-cdap-3.5-compatible
bamboo_planRepository_name=CDAP Apps
bamboo_capability_git_lfs=true
bamboo_buildNumber=43
bamboo_repository_50266308_git_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_docs_server1=docs1.cask.co
bamboo_planName=CDAP Apps - Build and Unit Test - release-cdap-3.5-compatible
bamboo_shortPlanKey=BUT6
bamboo_docs_server2=docs2.cask.co
bamboo_shortJobKey=MR
bamboo_repository_50266308_revision_number=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_repository_previous_revision_number=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_buildTimeStamp=2017-04-09T02:00:01.704Z
M2_HOME=/opt/maven
bamboo_s3_bucket=repository.cask.co
bamboo_buildResultKey=CA-BUT6-MR-43
bamboo_capability_system_builder_command_Docker=/usr/bin/docker
bamboo_repository_git_branch=release/cdap-3.5-compatible
bamboo_market_s3_secret_key_password=********
bamboo_buildPlanName=CDAP Apps - Build and Unit Test - release-cdap-3.5-compatible - MovieRecommender
bamboo_capability_system_builder_command_fpm=/opt/rbenv/shims/fpm
bamboo_capability_system_jdk_JDK_1_8_0_101=/usr/lib/jvm/jdk1.8.0_101
bamboo_planRepository_1_revision=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_capability_Platform=rhel
bamboo_repository_name=CDAP Apps
bamboo_repository_50266308_git_username=
bamboo_repository_50266308_git_branch=release/cdap-3.5-compatible
bamboo_capability_system_docker_executable=/usr/bin/docker
MAVEN_OPTS=-Xmx2048m
bamboo_agentWorkingDirectory=/var/bamboo/xml-data/build-dir
bamboo_capability_system_git_executable=/usr/local/bin/git
bamboo_planRepository_1_previousRevision=41bbb1a6677349b02a00896c092272359ffed55a
bamboo_repository_git_username=
bamboo_capability_system_builder_command_Ruby=/opt/rbenv/shims/ruby
bamboo_planRepository_branchDisplayName=release/cdap-3.5-compatible
bamboo_repository_50266308_branch_name=release/cdap-3.5-compatible
bamboo_aws_access_key_password=********
bamboo_capability_system_builder_mvn3_Maven_3_x=/opt/maven
bamboo_plan_storageTag=CA-BUT6
bamboo_repository_git_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_market_cloudfront_access_key_password=********
bamboo_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT6-MR
bamboo_planRepository_1_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_capability_createrepo=true
bamboo_capability_system_jdk_JDK_1_8=/usr/lib/jvm/jdk1.8.0_101
bamboo_capability_rpm_build=true
bamboo_capability_system_jdk_JDK_1_7=/usr/lib/jvm/jdk1.7.0_75
PATH=/usr/lib/jvm/jdk1.7.0_75/bin:/usr/lib/jvm/java/bin:/opt/rbenv/shims:/opt/rbenv/bin:/opt/rbenv/plugins/ruby_build/bin:/usr/local/maven-3.1.1/bin:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/bamboo/bin
09-Apr-2017 02:01:53 [INFO] Scanning for projects...
09-Apr-2017 02:01:54 [INFO]                                                                         
09-Apr-2017 02:01:54 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:01:54 [INFO] Building Movie Recommender Application 0.8.0
09-Apr-2017 02:01:54 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:01:58 [INFO]
09-Apr-2017 02:01:58 [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ MovieRecommender ---
09-Apr-2017 02:01:58 [INFO]
09-Apr-2017 02:01:58 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ MovieRecommender ---
09-Apr-2017 02:01:58 [INFO] Using 'UTF-8' encoding to copy filtered resources.
09-Apr-2017 02:01:58 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/main/resources
09-Apr-2017 02:01:58 [INFO]
09-Apr-2017 02:01:58 [INFO] --- maven-scala-plugin:2.15.2:add-source (scala-compile-first) @ MovieRecommender ---
09-Apr-2017 02:01:58 [INFO] Add Source directory: /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/main/scala
09-Apr-2017 02:01:58 [INFO] Add Test Source directory: /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/test/scala
09-Apr-2017 02:01:58 [INFO]
09-Apr-2017 02:01:58 [INFO] --- maven-scala-plugin:2.15.2:compile (scala-compile-first) @ MovieRecommender ---
09-Apr-2017 02:01:58 [INFO] Checking for multiple versions of scala
09-Apr-2017 02:02:03 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
09-Apr-2017 02:02:03 [WARNING]  com.typesafe.akka:akka-remote_2.10:2.3.11 requires scala version: 2.10.4
09-Apr-2017 02:02:03 [WARNING] Multiple versions of scala libraries detected!
09-Apr-2017 02:02:03 [INFO] includes = [**/*.scala,**/*.java,]
09-Apr-2017 02:02:03 [INFO] excludes = []
09-Apr-2017 02:02:03 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/main/java:-1: info: compiling
09-Apr-2017 02:02:03 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/main/scala:-1: info: compiling
09-Apr-2017 02:02:03 [INFO] Compiling 7 source files to /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/classes at 1491703323268
09-Apr-2017 02:02:13 [INFO] prepare-compile in 0 s
09-Apr-2017 02:02:13 [INFO] compile in 10 s
09-Apr-2017 02:02:13 [INFO]
09-Apr-2017 02:02:13 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ MovieRecommender ---
09-Apr-2017 02:02:13 [INFO] Changes detected - recompiling the module!
09-Apr-2017 02:02:13 [INFO] Compiling 6 source files to /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/classes
09-Apr-2017 02:02:14 [INFO]
09-Apr-2017 02:02:14 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ MovieRecommender ---
09-Apr-2017 02:02:14 [INFO] Using 'UTF-8' encoding to copy filtered resources.
09-Apr-2017 02:02:14 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/test/resources
09-Apr-2017 02:02:14 [INFO]
09-Apr-2017 02:02:14 [INFO] --- maven-scala-plugin:2.15.2:testCompile (scala-test-compile) @ MovieRecommender ---
09-Apr-2017 02:02:14 [INFO] Checking for multiple versions of scala
09-Apr-2017 02:02:15 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
09-Apr-2017 02:02:15 [WARNING]  com.typesafe.akka:akka-remote_2.10:2.3.11 requires scala version: 2.10.4
09-Apr-2017 02:02:15 [WARNING] Multiple versions of scala libraries detected!
09-Apr-2017 02:02:15 [INFO] includes = [**/*.scala,**/*.java,]
09-Apr-2017 02:02:15 [INFO] excludes = []
09-Apr-2017 02:02:15 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/src/test/java:-1: info: compiling
09-Apr-2017 02:02:15 [INFO] Compiling 1 source files to /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/test-classes at 1491703335229
09-Apr-2017 02:02:18 [INFO] prepare-compile in 0 s
09-Apr-2017 02:02:18 [INFO] compile in 3 s
09-Apr-2017 02:02:18 [INFO]
09-Apr-2017 02:02:18 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ MovieRecommender ---
09-Apr-2017 02:02:18 [INFO] Changes detected - recompiling the module!
09-Apr-2017 02:02:18 [INFO] Compiling 1 source file to /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/test-classes
09-Apr-2017 02:02:19 [INFO]
09-Apr-2017 02:02:19 [INFO] --- maven-checkstyle-plugin:2.12.1:check (validate) @ MovieRecommender ---
09-Apr-2017 02:02:20 [INFO] Starting audit...
09-Apr-2017 02:02:20 Audit done.
09-Apr-2017 02:02:20
09-Apr-2017 02:02:20 [INFO]
09-Apr-2017 02:02:20 [INFO] --- maven-surefire-plugin:2.14.1:test (default-test) @ MovieRecommender ---
09-Apr-2017 02:02:20 [INFO] Surefire report directory: /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/surefire-reports
09-Apr-2017 02:02:20
09-Apr-2017 02:02:20 -------------------------------------------------------
09-Apr-2017 02:02:20 T E S T S
09-Apr-2017 02:02:20 -------------------------------------------------------
09-Apr-2017 02:02:21 Running co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest
09-Apr-2017 02:02:21 02:02:21,641 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
09-Apr-2017 02:02:21 02:02:21,641 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:21 02:02:21,643 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
09-Apr-2017 02:02:21 02:02:21,644 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:21 02:02:21,644 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-common/3.5.0/cdap-common-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:21 02:02:21,687 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@4459116 - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml] is not of type file
09-Apr-2017 02:02:21 02:02:21,775 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
09-Apr-2017 02:02:21 02:02:21,790 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
09-Apr-2017 02:02:21 02:02:21,791 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
09-Apr-2017 02:02:21 02:02:21,791 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
09-Apr-2017 02:02:21 02:02:21,795 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
09-Apr-2017 02:02:21 02:02:21,824 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
09-Apr-2017 02:02:21 02:02:21,912 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [co.cask.cdap.common.logging.logback.CAppender]
09-Apr-2017 02:02:21 02:02:21,915 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CLOG]
09-Apr-2017 02:02:21 02:02:21,922 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.PatternLayout] for [layout] property
09-Apr-2017 02:02:21 02:02:21,934 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
09-Apr-2017 02:02:21 02:02:21,934 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
09-Apr-2017 02:02:21 02:02:21,934 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CLOG] to Logger[ROOT]
09-Apr-2017 02:02:21 02:02:21,935 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
09-Apr-2017 02:02:21 02:02:21,935 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
09-Apr-2017 02:02:21 02:02:21,937 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@73383d8a - Registering current configuration as safe fallback point
09-Apr-2017 02:02:21
09-Apr-2017 02:02:22 02:02:22,293 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
09-Apr-2017 02:02:22 02:02:22,293 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:22 02:02:22,295 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
09-Apr-2017 02:02:22 02:02:22,295 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:22 02:02:22,295 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-common/3.5.0/cdap-common-3.5.0-tests.jar!/logback-test.xml]
09-Apr-2017 02:02:22 02:02:22,333 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@5ee3be9f - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/3.5.0/cdap-app-fabric-3.5.0-tests.jar!/logback-test.xml] is not of type file
09-Apr-2017 02:02:22 02:02:22,385 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
09-Apr-2017 02:02:22 02:02:22,401 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
09-Apr-2017 02:02:22 02:02:22,401 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
09-Apr-2017 02:02:22 02:02:22,401 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
09-Apr-2017 02:02:22 02:02:22,408 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
09-Apr-2017 02:02:22 02:02:22,437 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
09-Apr-2017 02:02:22 02:02:22,536 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [co.cask.cdap.common.logging.logback.CAppender]
09-Apr-2017 02:02:22 02:02:22,539 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [CLOG]
09-Apr-2017 02:02:22 02:02:22,547 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.PatternLayout] for [layout] property
09-Apr-2017 02:02:22 02:02:22,559 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
09-Apr-2017 02:02:22 02:02:22,559 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
09-Apr-2017 02:02:22 02:02:22,560 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [CLOG] to Logger[ROOT]
09-Apr-2017 02:02:22 02:02:22,561 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
09-Apr-2017 02:02:22 02:02:22,561 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
09-Apr-2017 02:02:22 02:02:22,563 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@39f7e964 - Registering current configuration as safe fallback point
09-Apr-2017 02:02:22
09-Apr-2017 02:02:22 2017-04-09 02:02:22,752 - INFO  [main:c.c.c.t.TestBase@380] - Custom configuration set: cdap.unit.test.explore.enabled = false
09-Apr-2017 02:02:25 2017-04-09 02:02:25,960 - WARN  [main:c.c.c.s.a.AbstractAuthorizationService@213] - Authorization policy caching is enabled (security.authorization.cache.enabled is set to true), however, this setting will have no effect because authorization is disabled (security.authorization.enabled is set to false).
09-Apr-2017 02:02:25 2017-04-09 02:02:25,963 - WARN  [main:c.c.c.s.a.AbstractAuthorizationService@213] - Authorization policy caching is enabled (security.authorization.cache.enabled is set to true), however, this setting will have no effect because authorization is disabled (security.authorization.enabled is set to false).
09-Apr-2017 02:02:26 2017-04-09 02:02:26,190 - WARN  [main:o.a.h.u.NativeCodeLoader@62] - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
09-Apr-2017 02:02:26 2017-04-09 02:02:26,404 - INFO  [authorization-enforcement-service:c.c.c.s.a.AbstractAuthorizationService@102] - Started authorization enforcement service...
09-Apr-2017 02:02:26 2017-04-09 02:02:26,620 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@82] - Starting DatasetOpExecutorService...
09-Apr-2017 02:02:26 2017-04-09 02:02:26,739 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@97] - DatasetOpExecutorService started successfully on /127.0.0.1:46296
09-Apr-2017 02:02:26 2017-04-09 02:02:26,948 - WARN  [main:c.c.c.e.c.ExploreFacade@53] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
09-Apr-2017 02:02:26 2017-04-09 02:02:26,979 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@110] - Starting DatasetService...
09-Apr-2017 02:02:26 2017-04-09 02:02:26,990 - INFO  [DatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@250] - Created dataset dataset:system.datasets.type of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetTypeMDS
09-Apr-2017 02:02:26 2017-04-09 02:02:26,990 - INFO  [DatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@250] - Created dataset dataset:system.datasets.instance of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetInstanceMDS
09-Apr-2017 02:02:27 2017-04-09 02:02:27,310 - INFO  [NettyHttpService STARTING:c.c.c.d.d.d.s.DatasetTypeHandler@58] - Starting DatasetTypeHandler
09-Apr-2017 02:02:27 2017-04-09 02:02:27,325 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService$1@124] - Discovered dataset.executor service
09-Apr-2017 02:02:27 2017-04-09 02:02:27,326 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@171] - Waiting for dataset.executor service to be discoverable
09-Apr-2017 02:02:27 2017-04-09 02:02:27,328 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@144] - Announcing DatasetService for discovery...
09-Apr-2017 02:02:27 2017-04-09 02:02:27,330 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@158] - DatasetService started successfully on /127.0.0.1:41674
09-Apr-2017 02:02:27 2017-04-09 02:02:27,726 - INFO  [main:c.c.c.m.q.MetricsQueryService@80] - Configuring MetricsService , address: 127.0.0.1, backlog connections: 20000, execthreads: 20, bossthreads: 1, workerthreads: 10
09-Apr-2017 02:02:27 2017-04-09 02:02:27,727 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@94] - Starting Metrics Service...
09-Apr-2017 02:02:27 2017-04-09 02:02:27,959 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@96] - Started Metrics HTTP Service...
09-Apr-2017 02:02:27 2017-04-09 02:02:27,962 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@110] - Metrics Service started successfully on /127.0.0.1:38367
09-Apr-2017 02:02:28 2017-04-09 02:02:28,114 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:28 2017-04-09 02:02:28,200 - INFO  [LocalSchedulerService STARTING:o.q.s.RAMJobStore@155] - RAMJobStore initialized.
09-Apr-2017 02:02:28 2017-04-09 02:02:28,404 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.schedulestore, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
09-Apr-2017 02:02:28 2017-04-09 02:02:28,421 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.schedulestore, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.table.Table', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:28 2017-04-09 02:02:28,444 - WARN  [netty-executor-9:c.c.c.d.a.AuditPublishers@120] - Audit publisher is null, audit information will not be published
09-Apr-2017 02:02:28 2017-04-09 02:02:28,501 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@67] - Started time scheduler
09-Apr-2017 02:02:28 2017-04-09 02:02:28,517 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@76] - Started stream size scheduler
09-Apr-2017 02:02:28 2017-04-09 02:02:28,541 - WARN  [main:c.c.c.e.c.ExploreFacade@53] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
09-Apr-2017 02:02:28 2017-04-09 02:02:28,542 - WARN  [main:c.c.c.e.c.ExploreFacade@53] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
09-Apr-2017 02:02:28 2017-04-09 02:02:28,543 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:28 2017-04-09 02:02:28,631 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:29 2017-04-09 02:02:29,023 - INFO  [netty-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.metrics.v2.table.ts.1, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=7200, dataset.table.readless.increment=true}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,045 - INFO  [netty-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v2.table.ts.1, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.dataset2.lib.table.MetricsTable', modules=[DatasetModuleMeta{name='metricsTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule', jarLocation=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}, props: DatasetProperties{description=nullproperties={hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=7200, dataset.table.readless.increment=true}}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,064 - INFO  [metrics-collection:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:02:29 2017-04-09 02:02:29,089 - INFO  [netty-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.metrics.v2.entity, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,093 - INFO  [netty-executor-5:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v2.entity, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.dataset2.lib.table.MetricsTable', modules=[DatasetModuleMeta{name='metricsTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule', jarLocation=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,126 - INFO  [netty-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.metrics.v2.table.ts.60, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,131 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v2.table.ts.60, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.dataset2.lib.table.MetricsTable', modules=[DatasetModuleMeta{name='metricsTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule', jarLocation=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}, props: DatasetProperties{description=nullproperties={hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,151 - INFO  [metrics-collection:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:02:29 2017-04-09 02:02:29,164 - INFO  [netty-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.metrics.v2.table.ts.3600, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,168 - INFO  [netty-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v2.table.ts.3600, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.dataset2.lib.table.MetricsTable', modules=[DatasetModuleMeta{name='metricsTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule', jarLocation=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}, props: DatasetProperties{description=nullproperties={hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,184 - INFO  [metrics-collection:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:02:29 2017-04-09 02:02:29,199 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.metrics.v2.table.ts.2147483647, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.readless.increment=true}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,204 - INFO  [netty-executor-1:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v2.table.ts.2147483647, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.dataset2.lib.table.MetricsTable', modules=[DatasetModuleMeta{name='metricsTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule', jarLocation=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}, props: DatasetProperties{description=nullproperties={hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12]], dataset.table.readless.increment=true}}
09-Apr-2017 02:02:29 2017-04-09 02:02:29,221 - INFO  [metrics-collection:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:02:30 2017-04-09 02:02:30,101 - INFO  [netty-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.app.meta, type name: table, properties: {}
09-Apr-2017 02:02:30 2017-04-09 02:02:30,104 - INFO  [netty-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.app.meta, type meta: DatasetTypeMeta{name='table', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:30 2017-04-09 02:02:30,129 - WARN  [main:c.c.c.e.c.ExploreFacade@53] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
09-Apr-2017 02:02:33 2017-04-09 02:02:33,208 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.artifact.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {conflict.level=COLUMN}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,211 - INFO  [netty-executor-3:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.artifact.meta, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.table.Table', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}]}, props: DatasetProperties{description=nullproperties={conflict.level=COLUMN}}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,252 - INFO  [netty-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.system.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,257 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.system.metadata, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.metadata.dataset.MetadataDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}, DatasetModuleMeta{name='metadata', className='co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule', jarLocation=null, types=[metadataDataset, co.cask.cdap.data2.metadata.dataset.MetadataDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,338 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:33 2017-04-09 02:02:33,399 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:33 2017-04-09 02:02:33,794 - INFO  [main:c.c.c.i.a.d.p.DatasetModulesDeployer@73] - Not adding dataset type 'co.cask.cdap.api.dataset.lib.ObjectStore' because it is defined by the system.
09-Apr-2017 02:02:33 2017-04-09 02:02:33,798 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: movies
09-Apr-2017 02:02:33 2017-04-09 02:02:33,803 - INFO  [netty-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset default.movies, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema="string", type={"isClass":true,"rawType":"java.lang.String"}}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,805 - INFO  [netty-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:default.movies, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}, props: DatasetProperties{description=nullproperties={schema="string", type={"isClass":true,"rawType":"java.lang.String"}}}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,915 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: recommendations
09-Apr-2017 02:02:33 2017-04-09 02:02:33,920 - INFO  [netty-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset default.recommendations, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}
09-Apr-2017 02:02:33 2017-04-09 02:02:33,923 - INFO  [netty-executor-3:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:default.recommendations, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}, props: DatasetProperties{description=nullproperties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,016 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: ratings
09-Apr-2017 02:02:34 2017-04-09 02:02:34,020 - INFO  [netty-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset default.ratings, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,022 - INFO  [netty-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:default.ratings, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}, props: DatasetProperties{description=nullproperties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,208 - INFO  [netty-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.usage.registry, type name: UsageDataset, properties: {}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,210 - INFO  [netty-executor-5:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.usage.registry, type meta: DatasetTypeMeta{name='UsageDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='usage', className='co.cask.cdap.data2.registry.UsageDatasetModule', jarLocation=null, types=[UsageDataset], usesModules=[orderedTable-memory], usedByModules=[]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,405 - INFO  [netty-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.config.store.table, type name: table, properties: {}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,407 - INFO  [netty-executor-1:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.config.store.table, type meta: DatasetTypeMeta{name='table', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,637 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@239] - Announced HTTP Service for Service program:default.MovieRecommender.service.MovieDictionaryService at /127.0.0.1:41683
09-Apr-2017 02:02:34 2017-04-09 02:02:34,670 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.lineage, type name: co.cask.cdap.data2.metadata.lineage.LineageDataset, properties: {}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,672 - INFO  [netty-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.lineage, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.metadata.lineage.LineageDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='lineage', className='co.cask.cdap.data2.metadata.lineage.LineageDatasetModule', jarLocation=null, types=[lineageDataset, co.cask.cdap.data2.metadata.lineage.LineageDataset], usesModules=[orderedTable-memory], usedByModules=[]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:34 2017-04-09 02:02:34,792 - INFO  [main:c.c.c.d.s.s.ConcurrentStreamWriter$StreamFileFactory@370] - Create stream writer for stream:default.ratingsStream with generation 0
09-Apr-2017 02:02:35 2017-04-09 02:02:35,108 - INFO  [main:c.c.c.a.r.s.SparkProgramRunner@196] - Starting Spark Job: SparkRuntimeContext{id=program:default.MovieRecommender.spark.RecommendationBuilder, runId=995179a3-1cc8-11e7-9884-000000d3a28d}
09-Apr-2017 02:02:36 2017-04-09 02:02:36,503 - WARN  [spark-submitter-RecommendationBuilder-995179a3-1cc8-11e7-9884-000000d3a28d:o.a.s.Logging$class@70] - In Spark 1.0 and later spark.local.dir will be overridden by the value set by the cluster manager (via SPARK_LOCAL_DIRS in mesos/standalone and LOCAL_DIRS in YARN).
09-Apr-2017 02:02:39 2017-04-09 02:02:39,730 - INFO  [spark-submitter-RecommendationBuilder-995179a3-1cc8-11e7-9884-000000d3a28d:c.c.c.a.m.RecommendationBuilder@55] - Running with arguments null
09-Apr-2017 02:02:39 2017-04-09 02:02:39,775 - INFO  [spark-submitter-RecommendationBuilder-995179a3-1cc8-11e7-9884-000000d3a28d:c.c.c.a.m.RecommendationBuilder@57] - Processing ratings data with parameters Params(10,1.0,10,-1,-1,false)
09-Apr-2017 02:02:41
09-Apr-2017 02:02:41 [Stage 0:>                                                          (0 + 2) / 2]
09-Apr-2017 02:02:41                                                                                
09-Apr-2017 02:02:42 Got 4 ratings from 2 users on 3 movies out of 4
09-Apr-2017 02:02:42 2017-04-09 02:02:42,678 - INFO  [spark-submitter-RecommendationBuilder-995179a3-1cc8-11e7-9884-000000d3a28d:c.c.c.a.m.RecommendationBuilder@98] - Calculating model
09-Apr-2017 02:02:44 2017-04-09 02:02:44,007 - WARN  [Executor task launch worker-0:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemBLAS
09-Apr-2017 02:02:44 2017-04-09 02:02:44,008 - WARN  [Executor task launch worker-0:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefBLAS
09-Apr-2017 02:02:44 2017-04-09 02:02:44,230 - WARN  [Executor task launch worker-1:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemLAPACK
09-Apr-2017 02:02:44 2017-04-09 02:02:44,232 - WARN  [Executor task launch worker-1:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefLAPACK
09-Apr-2017 02:02:45
09-Apr-2017 02:02:45 [Stage 36:>                                                         (0 + 0) / 2]
09-Apr-2017 02:02:45                                                                                
09-Apr-2017 02:02:47 2017-04-09 02:02:47,410 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@239] - Announced HTTP Service for Service program:default.MovieRecommender.service.MovieRecommenderService at /127.0.0.1:33430
09-Apr-2017 02:02:47 2017-04-09 02:02:47,537 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieDictionaryService-991b0052-1cc8-11e7-8bc7-00000096f02b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@187] - Stopping Program: MovieDictionaryService
09-Apr-2017 02:02:47 2017-04-09 02:02:47,549 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieDictionaryService-991b0052-1cc8-11e7-8bc7-00000096f02b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@200] - Program stopped: MovieDictionaryService
09-Apr-2017 02:02:47 2017-04-09 02:02:47,555 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieRecommenderService-a0bdce05-1cc8-11e7-9240-000000fb4f9a:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@187] - Stopping Program: MovieRecommenderService
09-Apr-2017 02:02:47 2017-04-09 02:02:47,563 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieRecommenderService-a0bdce05-1cc8-11e7-9240-000000fb4f9a:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@200] - Program stopped: MovieRecommenderService
09-Apr-2017 02:02:47 2017-04-09 02:02:47,575 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@269] - Deleting namespace 'namespace:default'.
09-Apr-2017 02:02:47 2017-04-09 02:02:47,578 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:47 2017-04-09 02:02:47,579 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:02:47 2017-04-09 02:02:47,580 - WARN  [main:c.c.c.e.c.ExploreFacade@53] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
09-Apr-2017 02:02:47 2017-04-09 02:02:47,632 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:02:47 2017-04-09 02:02:47,640 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:02:47 2017-04-09 02:02:47,649 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:02:47 2017-04-09 02:02:47,656 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:02:47 2017-04-09 02:02:47,718 - INFO  [netty-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.business.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,753 - INFO  [netty-executor-3:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.business.metadata, type meta: DatasetTypeMeta{name='co.cask.cdap.data2.metadata.dataset.MetadataDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}, DatasetModuleMeta{name='metadata', className='co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule', jarLocation=null, types=[metadataDataset, co.cask.cdap.data2.metadata.dataset.MetadataDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,874 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@256] - Creating dataset system.workflow.stats, type name: table, properties: {}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,877 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.workflow.stats, type meta: DatasetTypeMeta{name='table', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}]}, props: DatasetProperties{description=nullproperties={}}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,920 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.movies
09-Apr-2017 02:02:47 2017-04-09 02:02:47,928 - INFO  [netty-executor-7:c.c.c.d.d.d.s.e.DatasetAdminService@201] - Dropping dataset with spec: DatasetSpecification{name='movies', type='co.cask.cdap.api.dataset.lib.ObjectStore', description='null', originalProperties={schema="string", type={"isClass":true,"rawType":"java.lang.String"}}, properties={schema="string", type={"isClass":true,"rawType":"java.lang.String"}}, datasetSpecs={objects=DatasetSpecification{name='movies.objects', type='keyValueTable', description='null', originalProperties=null, properties={}, datasetSpecs={kv=DatasetSpecification{name='movies.objects.kv', type='table', description='null', originalProperties=null, properties={}, datasetSpecs={}}}}}}, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,950 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.ratings
09-Apr-2017 02:02:47 2017-04-09 02:02:47,954 - INFO  [netty-executor-1:c.c.c.d.d.d.s.e.DatasetAdminService@201] - Dropping dataset with spec: DatasetSpecification{name='ratings', type='co.cask.cdap.api.dataset.lib.ObjectStore', description='null', originalProperties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}, properties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}, datasetSpecs={objects=DatasetSpecification{name='ratings.objects', type='keyValueTable', description='null', originalProperties=null, properties={}, datasetSpecs={kv=DatasetSpecification{name='ratings.objects.kv', type='table', description='null', originalProperties=null, properties={}, datasetSpecs={}}}}}}, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}
09-Apr-2017 02:02:47 2017-04-09 02:02:47,980 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.recommendations
09-Apr-2017 02:02:47 2017-04-09 02:02:47,986 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@201] - Dropping dataset with spec: DatasetSpecification{name='recommendations', type='co.cask.cdap.api.dataset.lib.ObjectStore', description='null', originalProperties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}, properties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}, datasetSpecs={objects=DatasetSpecification{name='recommendations.objects', type='keyValueTable', description='null', originalProperties=null, properties={}, datasetSpecs={kv=DatasetSpecification{name='recommendations.objects.kv', type='table', description='null', originalProperties=null, properties={}, datasetSpecs={}}}}}}, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.ObjectStore', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, types=[keyValueTable, co.cask.cdap.api.dataset.lib.KeyValueTable, objectStore, co.cask.cdap.api.dataset.lib.ObjectStore, indexedObjectStore, co.cask.cdap.api.dataset.lib.IndexedObjectStore, indexedTable, co.cask.cdap.api.dataset.lib.IndexedTable, timeseriesTable, co.cask.cdap.api.dataset.lib.TimeseriesTable, counterTimeseriesTable, co.cask.cdap.api.dataset.lib.CounterTimeseriesTable, memoryTable], usesModules=[orderedTable-memory], usedByModules=[timePartitionedFileSet, partitionedFileSet, metadata]}]}
09-Apr-2017 02:02:48 2017-04-09 02:02:48,018 - WARN  [netty-executor-5:c.c.c.d.d.d.t.DatasetTypeManager@409] - Deleting all modules from namespace namespace:default
09-Apr-2017 02:02:48 2017-04-09 02:02:48,077 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:02:48 2017-04-09 02:02:48,084 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:02:48 2017-04-09 02:02:48,091 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:02:48 2017-04-09 02:02:48,096 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:02:48 2017-04-09 02:02:48,130 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceResourceDeleter@109] - All data for namespace 'namespace:default' deleted.
09-Apr-2017 02:02:48 2017-04-09 02:02:48,130 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@288] - Keeping the 'namespace:default' namespace after removing all data.
09-Apr-2017 02:02:48 2017-04-09 02:02:48,132 - INFO  [MetricsQueryService STOPPING:c.c.c.m.q.MetricsQueryService@115] - Stopping Metrics Service...
09-Apr-2017 02:02:48 2017-04-09 02:02:48,149 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@98] - Stopped stream size scheduler
09-Apr-2017 02:02:48 2017-04-09 02:02:48,152 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@106] - Stopped time scheduler
09-Apr-2017 02:02:48 2017-04-09 02:02:48,493 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@201] - Stopping DatasetService...
09-Apr-2017 02:02:51 2017-04-09 02:02:51,496 - INFO  [NettyHttpService STOPPING:c.c.c.d.d.d.s.DatasetTypeHandler@63] - Stopping DatasetTypeHandler
09-Apr-2017 02:02:51 2017-04-09 02:02:51,498 - INFO  [DatasetOpExecutorService STOPPING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@102] - Stopping DatasetOpExecutorService...
09-Apr-2017 02:02:51 2017-04-09 02:02:51,505 - INFO  [authorization-enforcement-service:c.c.c.s.a.AbstractAuthorizationService@139] - Shutdown authorization enforcement service successfully.
09-Apr-2017 02:02:51 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 30.097 sec
09-Apr-2017 02:02:51
09-Apr-2017 02:02:51 Results :
09-Apr-2017 02:02:51
09-Apr-2017 02:02:51 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
09-Apr-2017 02:02:51
09-Apr-2017 02:02:51 [INFO]
09-Apr-2017 02:02:51 [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ MovieRecommender ---
09-Apr-2017 02:02:52 [INFO] Building jar: /var/bamboo/xml-data/build-dir/CA-BUT6-MR/MovieRecommender/target/MovieRecommender-0.8.0.jar
09-Apr-2017 02:02:52 [INFO]
09-Apr-2017 02:02:52 [INFO] --- maven-bundle-plugin:2.3.7:bundle (default) @ MovieRecommender ---
09-Apr-2017 02:03:00 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:03:00 [INFO] BUILD SUCCESS
09-Apr-2017 02:03:00 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:03:00 [INFO] Total time: 1:07.372s
09-Apr-2017 02:03:00 [INFO] Finished at: Sun Apr 09 02:03:00 UTC 2017
09-Apr-2017 02:03:00 [INFO] Final Memory: 74M/985M
09-Apr-2017 02:03:00 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:03:00 Parsing test results under /var/bamboo/xml-data/build-dir/CA-BUT6-MR...
09-Apr-2017 02:03:01 Finished task 'MovieRecommender' with result: Success
09-Apr-2017 02:03:01 Running post build plugin 'Docker Container Cleanup'
09-Apr-2017 02:03:01 Running post build plugin 'NCover Results Collector'
09-Apr-2017 02:03:01 Running post build plugin 'Clover Results Collector'
09-Apr-2017 02:03:01 Running post build plugin 'npm Cache Cleanup'
09-Apr-2017 02:03:01 Running post build plugin 'Artifact Copier'
09-Apr-2017 02:03:01 Finalising the build...
09-Apr-2017 02:03:01 Stopping timer.
09-Apr-2017 02:03:01 Build CA-BUT6-MR-43 completed.
09-Apr-2017 02:03:01 Running on server: post build plugin 'NCover Results Collector'
09-Apr-2017 02:03:01 Running on server: post build plugin 'Build Hanging Detection Configuration'
09-Apr-2017 02:03:01 Running on server: post build plugin 'Clover Delta Calculator'
09-Apr-2017 02:03:01 Running on server: post build plugin 'Maven Dependencies Postprocessor'
09-Apr-2017 02:03:01 All post build plugins have finished
09-Apr-2017 02:03:01 Generating build results summary...
09-Apr-2017 02:03:01 Saving build results to disk...
09-Apr-2017 02:03:01 Logging substituted variables...
09-Apr-2017 02:03:01 Indexing build results...
09-Apr-2017 02:03:01 Finished building CA-BUT6-MR-43.