Build #15

Builds and runs unit tests for CDAP Apps repository

Build: #15 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

05-Mar-2017 02:03:20 Build CDAP Apps - Build and Unit Test - release-cdap-4.0-compatible - MovieRecommender #15 (CA-BUT9-MR-15) started building on agent bamboo-agent19.prod.continuuity.net
05-Mar-2017 02:03:20 Remote Agent
05-Mar-2017 02:03:20 Build working directory is /var/bamboo/xml-data/build-dir/CA-BUT9-MR
05-Mar-2017 02:03:20 Executing build CDAP Apps - Build and Unit Test - release-cdap-4.0-compatible - MovieRecommender #15 (CA-BUT9-MR-15)
05-Mar-2017 02:03:20 Starting task 'Checkout Default Repository' of type 'com.atlassian.bamboo.plugins.vcs:task.vcs.checkout'
05-Mar-2017 02:03:20 Build always requires a clean checkout
05-Mar-2017 02:03:20 Cleaning build directory '/var/bamboo/xml-data/build-dir/CA-BUT9-MR'
05-Mar-2017 02:03:20 Updating source code to revision: 06e0a20f35bb3be07121b3f3a46b2aaff2c71271
05-Mar-2017 02:03:21 Fetching 'refs/heads/release/cdap-4.0-compatible' from 'git@github.com:caskdata/cdap-apps.git'.
05-Mar-2017 02:03:21 Warning: Permanently added '[127.0.0.1]:33178' (RSA) to the list of known hosts.
05-Mar-2017 02:03:22 From ssh://127.0.0.1:33178/caskdata/cdap-apps
05-Mar-2017 02:03:22 * [new branch]      release/cdap-4.0-compatible -> release/cdap-4.0-compatible
05-Mar-2017 02:03:22 * [new tag]         cdap-4.0.0-compatible -> cdap-4.0.0-compatible
05-Mar-2017 02:03:22 Checking out revision 06e0a20f35bb3be07121b3f3a46b2aaff2c71271.
05-Mar-2017 02:03:22 Creating local git repository in '/var/bamboo/xml-data/build-dir/CA-BUT9-MR/.git'.
05-Mar-2017 02:03:22 Cloning into '/var/bamboo/xml-data/build-dir/CA-BUT9-MR'...
05-Mar-2017 02:03:22 Switched to a new branch 'release/cdap-4.0-compatible'
05-Mar-2017 02:03:22 Branch release/cdap-4.0-compatible set up to track remote branch release/cdap-4.0-compatible from origin.
05-Mar-2017 02:03:23 Updated source code to revision: 06e0a20f35bb3be07121b3f3a46b2aaff2c71271
05-Mar-2017 02:03:23 Finished task 'Checkout Default Repository' with result: Success
05-Mar-2017 02:03:23 Running pre-build action: VCS Version Collector
05-Mar-2017 02:03:23 Running pre-build action: Hung Build Killer PreBuildAction
05-Mar-2017 02:03:23 Starting task 'MovieRecommender' of type 'com.atlassian.bamboo.plugins.maven:task.builder.mvn3'
05-Mar-2017 02:03:23
Beginning to execute external process for build 'CDAP Apps - Build and Unit Test - release-cdap-4.0-compatible - MovieRecommender #15 (CA-BUT9-MR-15)'
... running command line:
/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/CA-BUT9-MR clean package
... in: /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender
... using extra environment variables:
bamboo_planRepository_1_branch=release/cdap-4.0-compatible
bamboo_docs_upload_host=docs1.cask.co
bamboo_repository_revision_number=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
MAVEN2_HOME=/opt/maven
bamboo_repository_58687847_git_repositoryUrl=git@github.com:caskdata/cdap-apps.git
bamboo_docs_s3_bucket=docs.cask.co
bamboo_capability_system_builder_command_s3cmd=/usr/bin/s3cmd
bamboo_buildKey=CA-BUT9-MR
bamboo_gpg_password=********
bamboo_ops_gpg_password=********
bamboo_shortJobName=MovieRecommender
bamboo_buildResultsUrl=http://builds.cask.co/browse/CA-BUT9-MR-15
bamboo_repository_s3_bucket=repository.cask.co
bamboo_planRepository_repositoryUrl=git@github.com:caskdata/cdap-apps.git
bamboo_capability_system_builder_mvn3_Maven_3_1=/opt/maven
bamboo_aws_secret_access_key_password=********
bamboo_agentId=32243727
bamboo_capability_Swapfile=true
bamboo_planRepository_revision=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_remote_docs_base=/var/www/docs
bamboo_planRepository_previousRevision=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_capability_RHEL=rhel
bamboo_capability_freight=true
bamboo_repository_branch_name=release/cdap-4.0-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_downloads_s3_bucket=downloads.cask.co
bamboo_planRepository_branch=release/cdap-4.0-compatible
bamboo_docs_server_list=docs1.cask.co,docs2.cask.co
bamboo_market_s3_access_key_password=********
bamboo_repository_58687847_revision_number=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_planRepository_1_type=git
bamboo_planRepository_branchName=release/cdap-4.0-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=git
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-BUT9
bamboo_repository_58687847_branch_name=release/cdap-4.0-compatible
bamboo_planRepository_username=
bamboo_planRepository_1_branchName=release/cdap-4.0-compatible
bamboo_capability_s3cmd=true
bamboo_resultsUrl=http://builds.cask.co/browse/CA-BUT9-MR-15
bamboo_capability_Sphinx=true
bamboo_repository_58687847_git_branch=release/cdap-4.0-compatible
bamboo_planRepository_1_name=CDAP Apps
bamboo_build_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT9-MR
bamboo_shortPlanName=release-cdap-4.0-compatible
bamboo_planRepository_name=CDAP Apps
bamboo_capability_git_lfs=true
bamboo_buildNumber=15
bamboo_docs_server1=docs1.cask.co
bamboo_planName=CDAP Apps - Build and Unit Test - release-cdap-4.0-compatible
bamboo_shortPlanKey=BUT9
bamboo_docs_server2=docs2.cask.co
bamboo_shortJobKey=MR
bamboo_repository_previous_revision_number=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_buildTimeStamp=2017-03-05T02:00:10.180Z
M2_HOME=/opt/maven
bamboo_s3_bucket=repository.cask.co
bamboo_buildResultKey=CA-BUT9-MR-15
bamboo_capability_system_builder_command_Docker=/usr/bin/docker
bamboo_repository_58687847_previous_revision_number=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_repository_git_branch=release/cdap-4.0-compatible
bamboo_market_s3_secret_key_password=********
bamboo_buildPlanName=CDAP Apps - Build and Unit Test - release-cdap-4.0-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=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_capability_Platform=rhel
bamboo_repository_name=CDAP Apps
bamboo_repository_58687847_git_username=
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=06e0a20f35bb3be07121b3f3a46b2aaff2c71271
bamboo_repository_git_username=
bamboo_capability_system_builder_command_Ruby=/opt/rbenv/shims/ruby
bamboo_aws_access_key_password=********
bamboo_repository_58687847_name=CDAP Apps
bamboo_capability_system_builder_mvn3_Maven_3_x=/opt/maven
bamboo_repository_git_repositoryUrl=git@github.com:caskdata/cdap-apps.git
bamboo_market_cloudfront_access_key_password=********
bamboo_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT9-MR
bamboo_planRepository_1_repositoryUrl=git@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:/bin:/bin:/sbin:/usr/sbin:/bin:/usr/bin
05-Mar-2017 02:03:24 [INFO] Scanning for projects...
05-Mar-2017 02:03:25 [INFO]                                                                         
05-Mar-2017 02:03:25 [INFO] ------------------------------------------------------------------------
05-Mar-2017 02:03:25 [INFO] Building Movie Recommender Application 0.8.0
05-Mar-2017 02:03:25 [INFO] ------------------------------------------------------------------------
05-Mar-2017 02:03:29 [INFO]
05-Mar-2017 02:03:29 [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ MovieRecommender ---
05-Mar-2017 02:03:29 [INFO]
05-Mar-2017 02:03:29 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ MovieRecommender ---
05-Mar-2017 02:03:29 [INFO] Using 'UTF-8' encoding to copy filtered resources.
05-Mar-2017 02:03:29 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/main/resources
05-Mar-2017 02:03:29 [INFO]
05-Mar-2017 02:03:29 [INFO] --- maven-scala-plugin:2.15.2:add-source (scala-compile-first) @ MovieRecommender ---
05-Mar-2017 02:03:29 [INFO] Add Source directory: /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/main/scala
05-Mar-2017 02:03:29 [INFO] Add Test Source directory: /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/test/scala
05-Mar-2017 02:03:29 [INFO]
05-Mar-2017 02:03:29 [INFO] --- maven-scala-plugin:2.15.2:compile (scala-compile-first) @ MovieRecommender ---
05-Mar-2017 02:03:29 [INFO] Checking for multiple versions of scala
05-Mar-2017 02:03:34 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
05-Mar-2017 02:03:34 [WARNING]  com.typesafe.akka:akka-remote_2.10:2.3.11 requires scala version: 2.10.4
05-Mar-2017 02:03:34 [WARNING] Multiple versions of scala libraries detected!
05-Mar-2017 02:03:34 [INFO] includes = [**/*.scala,**/*.java,]
05-Mar-2017 02:03:34 [INFO] excludes = []
05-Mar-2017 02:03:34 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/main/java:-1: info: compiling
05-Mar-2017 02:03:34 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/main/scala:-1: info: compiling
05-Mar-2017 02:03:34 [INFO] Compiling 7 source files to /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/classes at 1488679414396
05-Mar-2017 02:03:44 [INFO] prepare-compile in 0 s
05-Mar-2017 02:03:44 [INFO] compile in 10 s
05-Mar-2017 02:03:44 [INFO]
05-Mar-2017 02:03:44 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ MovieRecommender ---
05-Mar-2017 02:03:44 [INFO] Changes detected - recompiling the module!
05-Mar-2017 02:03:44 [INFO] Compiling 6 source files to /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/classes
05-Mar-2017 02:03:45 [INFO]
05-Mar-2017 02:03:45 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ MovieRecommender ---
05-Mar-2017 02:03:45 [INFO] Using 'UTF-8' encoding to copy filtered resources.
05-Mar-2017 02:03:45 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/test/resources
05-Mar-2017 02:03:45 [INFO]
05-Mar-2017 02:03:45 [INFO] --- maven-scala-plugin:2.15.2:testCompile (scala-test-compile) @ MovieRecommender ---
05-Mar-2017 02:03:45 [INFO] Checking for multiple versions of scala
05-Mar-2017 02:03:46 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
05-Mar-2017 02:03:46 [WARNING]  com.typesafe.akka:akka-remote_2.10:2.3.11 requires scala version: 2.10.4
05-Mar-2017 02:03:46 [WARNING] Multiple versions of scala libraries detected!
05-Mar-2017 02:03:46 [INFO] includes = [**/*.scala,**/*.java,]
05-Mar-2017 02:03:46 [INFO] excludes = []
05-Mar-2017 02:03:46 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/src/test/java:-1: info: compiling
05-Mar-2017 02:03:46 [INFO] Compiling 1 source files to /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/test-classes at 1488679426293
05-Mar-2017 02:03:49 [INFO] prepare-compile in 0 s
05-Mar-2017 02:03:49 [INFO] compile in 3 s
05-Mar-2017 02:03:49 [INFO]
05-Mar-2017 02:03:49 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ MovieRecommender ---
05-Mar-2017 02:03:49 [INFO] Changes detected - recompiling the module!
05-Mar-2017 02:03:49 [INFO] Compiling 1 source file to /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/test-classes
05-Mar-2017 02:03:50 [INFO]
05-Mar-2017 02:03:50 [INFO] --- maven-checkstyle-plugin:2.12.1:check (validate) @ MovieRecommender ---
05-Mar-2017 02:03:51 [INFO] Starting audit...
05-Mar-2017 02:03:51 Audit done.
05-Mar-2017 02:03:51
05-Mar-2017 02:03:51 [INFO]
05-Mar-2017 02:03:51 [INFO] --- maven-surefire-plugin:2.14.1:test (default-test) @ MovieRecommender ---
05-Mar-2017 02:03:51 [INFO] Surefire report directory: /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/surefire-reports
05-Mar-2017 02:03:51
05-Mar-2017 02:03:51 -------------------------------------------------------
05-Mar-2017 02:03:51 T E S T S
05-Mar-2017 02:03:51 -------------------------------------------------------
05-Mar-2017 02:03:52 Running co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest
05-Mar-2017 02:03:52 02:03:52,439 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
05-Mar-2017 02:03:52 02:03:52,439 |-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/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:52 02:03:52,441 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
05-Mar-2017 02:03:52 02:03:52,441 |-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/4.0.0/cdap-common-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:52 02:03:52,441 |-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/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:52 02:03:52,479 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@62526d1d - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml] is not of type file
05-Mar-2017 02:03:52 02:03:52,560 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
05-Mar-2017 02:03:52 02:03:52,576 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
05-Mar-2017 02:03:52 02:03:52,577 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
05-Mar-2017 02:03:52 02:03:52,577 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
05-Mar-2017 02:03:52 02:03:52,582 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
05-Mar-2017 02:03:52 02:03:52,610 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05-Mar-2017 02:03:52 02:03:52,694 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
05-Mar-2017 02:03:52 02:03:52,694 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
05-Mar-2017 02:03:52 02:03:52,694 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
05-Mar-2017 02:03:52 02:03:52,695 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
05-Mar-2017 02:03:52 02:03:52,697 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1179bd59 - Registering current configuration as safe fallback point
05-Mar-2017 02:03:52
05-Mar-2017 02:03:53 02:03:53,181 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
05-Mar-2017 02:03:53 02:03:53,181 |-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/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:53 02:03:53,182 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
05-Mar-2017 02:03:53 02:03:53,183 |-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/4.0.0/cdap-common-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:53 02:03:53,183 |-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/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml]
05-Mar-2017 02:03:53 02:03:53,221 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@6cf49909 - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/4.0.0/cdap-app-fabric-4.0.0-tests.jar!/logback-test.xml] is not of type file
05-Mar-2017 02:03:53 02:03:53,272 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
05-Mar-2017 02:03:53 02:03:53,286 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
05-Mar-2017 02:03:53 02:03:53,286 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
05-Mar-2017 02:03:53 02:03:53,286 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
05-Mar-2017 02:03:53 02:03:53,293 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
05-Mar-2017 02:03:53 02:03:53,335 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
05-Mar-2017 02:03:53 02:03:53,424 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
05-Mar-2017 02:03:53 02:03:53,425 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
05-Mar-2017 02:03:53 02:03:53,425 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
05-Mar-2017 02:03:53 02:03:53,426 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
05-Mar-2017 02:03:53 02:03:53,428 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2e6b55c1 - Registering current configuration as safe fallback point
05-Mar-2017 02:03:53
05-Mar-2017 02:03:53 2017-03-05 02:03:53,622 - INFO  [main:c.c.c.t.TestBase@402] - Custom configuration set: cdap.unit.test.explore.enabled = false
05-Mar-2017 02:03:57 2017-03-05 02:03:57,169 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.l.LevelDBTableFactory@81] - Messaging metadata table created at /tmp/CA-BUT9-MR/junit3916106133568709337/junit712911573086993363/messaging/system.tms.meta
05-Mar-2017 02:03:57 2017-03-05 02:03:57,195 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@295] - System topic created: topic:system.notifications
05-Mar-2017 02:03:57 2017-03-05 02:03:57,198 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@295] - System topic created: topic:system.audit
05-Mar-2017 02:03:57 2017-03-05 02:03:57,199 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@247] - Core Messaging Service started
05-Mar-2017 02:03:57 2017-03-05 02:03:57,412 - WARN  [main:o.a.h.u.NativeCodeLoader@62] - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
05-Mar-2017 02:03:57 2017-03-05 02:03:57,619 - INFO  [authorization-enforcement-service:c.c.c.s.a.AbstractAuthorizationService@102] - Started authorization enforcement service...
05-Mar-2017 02:03:57 2017-03-05 02:03:57,790 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@81] - Starting DatasetOpExecutorService...
05-Mar-2017 02:03:57 2017-03-05 02:03:57,933 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@86] - DatasetOpExecutorService started successfully on /127.0.0.1:42884
05-Mar-2017 02:03:58 2017-03-05 02:03:58,102 - WARN  [main:c.c.c.e.c.ExploreFacade@55] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
05-Mar-2017 02:03:58 2017-03-05 02:03:58,126 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@112] - Starting DatasetService...
05-Mar-2017 02:03:58 2017-03-05 02:03:58,140 - INFO  [DatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@253] - Created dataset dataset:system.datasets.type of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetTypeMDS
05-Mar-2017 02:03:58 2017-03-05 02:03:58,140 - INFO  [DatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@253] - Created dataset dataset:system.datasets.instance of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetInstanceMDS
05-Mar-2017 02:03:58 2017-03-05 02:03:58,412 - INFO  [NettyHttpService STARTING:c.c.c.d.d.d.s.DatasetTypeHandler@58] - Starting DatasetTypeHandler
05-Mar-2017 02:03:58 2017-03-05 02:03:58,420 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService$1@126] - Discovered dataset.executor service
05-Mar-2017 02:03:58 2017-03-05 02:03:58,420 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@170] - Waiting for dataset.executor service to be discoverable
05-Mar-2017 02:03:58 2017-03-05 02:03:58,421 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@152] - Announcing DatasetService for discovery...
05-Mar-2017 02:03:58 2017-03-05 02:03:58,421 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@157] - DatasetService started successfully on localhost/127.0.0.1:42562
05-Mar-2017 02:03:58 2017-03-05 02:03:58,774 - INFO  [main:c.c.c.m.q.MetricsQueryService@79] - Configuring MetricsService , address: 127.0.0.1, backlog connections: 20000, execthreads: 20, bossthreads: 1, workerthreads: 10
05-Mar-2017 02:03:58 2017-03-05 02:03:58,775 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@93] - Starting Metrics Service...
05-Mar-2017 02:03:58 2017-03-05 02:03:58,794 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@95] - Started Metrics HTTP Service...
05-Mar-2017 02:03:58 2017-03-05 02:03:58,794 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@99] - Metrics Service started successfully on /127.0.0.1:43789
05-Mar-2017 02:03:58 2017-03-05 02:03:58,937 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:03:59 2017-03-05 02:03:59,081 - INFO  [LocalSchedulerService STARTING:o.q.s.RAMJobStore@155] - RAMJobStore initialized.
05-Mar-2017 02:03:59 2017-03-05 02:03:59,298 - INFO  [dataset.service-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.schedulestore, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,322 - INFO  [dataset.executor-executor-2:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={}}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,345 - WARN  [dataset.service-executor-9:c.c.c.d.a.AuditPublishers@120] - Audit publisher is null, audit information will not be published
05-Mar-2017 02:03:59 2017-03-05 02:03:59,401 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@69] - Started time scheduler
05-Mar-2017 02:03:59 2017-03-05 02:03:59,416 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@78] - Started stream size scheduler
05-Mar-2017 02:03:59 2017-03-05 02:03:59,439 - WARN  [main:c.c.c.e.c.ExploreFacade@55] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
05-Mar-2017 02:03:59 2017-03-05 02:03:59,440 - WARN  [main:c.c.c.e.c.ExploreFacade@55] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
05-Mar-2017 02:03:59 2017-03-05 02:03:59,443 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:03:59 2017-03-05 02:03:59,546 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:03:59 2017-03-05 02:03:59,857 - INFO  [dataset.service-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,868 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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}}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,884 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.1
05-Mar-2017 02:03:59 2017-03-05 02:03:59,898 - INFO  [dataset.service-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.metrics.v2.entity, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,902 - INFO  [dataset.executor-executor-5:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={}}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,935 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,939 - INFO  [dataset.executor-executor-1:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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}}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,956 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.60
05-Mar-2017 02:03:59 2017-03-05 02:03:59,971 - INFO  [dataset.service-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,975 - INFO  [dataset.executor-executor-7:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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}}
05-Mar-2017 02:03:59 2017-03-05 02:03:59,994 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.3600
05-Mar-2017 02:04:00 2017-03-05 02:04:00,006 - INFO  [dataset.service-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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}
05-Mar-2017 02:04:00 2017-03-05 02:04:00,011 - INFO  [dataset.executor-executor-4:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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}}
05-Mar-2017 02:04:00 2017-03-05 02:04:00,033 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.2147483647
05-Mar-2017 02:04:00 2017-03-05 02:04:00,850 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.app.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
05-Mar-2017 02:04:00 2017-03-05 02:04:00,853 - INFO  [dataset.executor-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@100] - Creating dataset instance dataset:system.app.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={}}
05-Mar-2017 02:04:00 2017-03-05 02:04:00,873 - WARN  [main:c.c.c.e.c.ExploreFacade@55] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
05-Mar-2017 02:04:03 2017-03-05 02:04:03,210 - INFO  [dataset.service-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.artifact.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {conflict.level=COLUMN}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,212 - INFO  [dataset.executor-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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}}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,247 - INFO  [dataset.service-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.system.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {scope=SYSTEM}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,252 - INFO  [dataset.executor-executor-2:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={scope=SYSTEM}}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,357 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:04:03 2017-03-05 02:04:03,418 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:04:03 2017-03-05 02:04:03,844 - 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.
05-Mar-2017 02:04:03 2017-03-05 02:04:03,848 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: movies
05-Mar-2017 02:04:03 2017-03-05 02:04:03,856 - INFO  [dataset.service-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset default.movies, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema="string", type={"isClass":true,"rawType":"java.lang.String"}}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,860 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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"}}}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,978 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: recommendations
05-Mar-2017 02:04:03 2017-03-05 02:04:03,982 - INFO  [dataset.service-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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"}}
05-Mar-2017 02:04:03 2017-03-05 02:04:03,985 - INFO  [dataset.executor-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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"}}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,070 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding dataset instance: ratings
05-Mar-2017 02:04:04 2017-03-05 02:04:04,075 - INFO  [dataset.service-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@253] - 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"}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,078 - INFO  [dataset.executor-executor-2:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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"}}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,249 - INFO  [dataset.service-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.usage.registry, type name: UsageDataset, properties: {}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,252 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,544 - INFO  [dataset.service-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.config.store.table, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,546 - INFO  [dataset.executor-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@100] - Creating dataset instance dataset:system.config.store.table, 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={}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,834 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@254] - Announced HTTP Service for Service program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService at /127.0.0.1:40106
05-Mar-2017 02:04:04 2017-03-05 02:04:04,858 - INFO  [dataset.service-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.lineage, type name: co.cask.cdap.data2.metadata.lineage.LineageDataset, properties: {}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,861 - INFO  [dataset.executor-executor-3:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={}}
05-Mar-2017 02:04:04 2017-03-05 02:04:04,982 - INFO  [main:c.c.c.d.s.s.ConcurrentStreamWriter$StreamFileFactory@370] - Create stream writer for stream:default.ratingsStream with generation 0
05-Mar-2017 02:04:05 2017-03-05 02:04:05,219 - INFO  [main:c.c.c.a.r.s.SparkProgramRunner@201] - Starting Spark Job: SparkRuntimeContext{id=program:default.MovieRecommender.-SNAPSHOT.spark.RecommendationBuilder, runId=02934b13-0148-11e7-b990-ea2f5ac8597b}
05-Mar-2017 02:04:06 2017-03-05 02:04:06,159 - WARN  [spark-submitter-RecommendationBuilder-02934b13-0148-11e7-b990-ea2f5ac8597b: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).
05-Mar-2017 02:04:08 2017-03-05 02:04:08,830 - INFO  [spark-submitter-RecommendationBuilder-02934b13-0148-11e7-b990-ea2f5ac8597b:c.c.c.a.m.RecommendationBuilder@55] - Running with arguments null
05-Mar-2017 02:04:08 2017-03-05 02:04:08,870 - INFO  [spark-submitter-RecommendationBuilder-02934b13-0148-11e7-b990-ea2f5ac8597b:c.c.c.a.m.RecommendationBuilder@57] - Processing ratings data with parameters Params(10,1.0,10,-1,-1,false)
05-Mar-2017 02:04:10
05-Mar-2017 02:04:10 [Stage 0:>                                                          (0 + 0) / 2]
05-Mar-2017 02:04:10                                                                                
05-Mar-2017 02:04:11 Got 4 ratings from 2 users on 3 movies out of 4
05-Mar-2017 02:04:11 2017-03-05 02:04:11,337 - INFO  [spark-submitter-RecommendationBuilder-02934b13-0148-11e7-b990-ea2f5ac8597b:c.c.c.a.m.RecommendationBuilder@98] - Calculating model
05-Mar-2017 02:04:12 2017-03-05 02:04:12,667 - WARN  [Executor task launch worker-1:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemBLAS
05-Mar-2017 02:04:12 2017-03-05 02:04:12,669 - WARN  [Executor task launch worker-1:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefBLAS
05-Mar-2017 02:04:12
05-Mar-2017 02:04:12 2017-03-05 02:04:12,886 - WARN  [Executor task launch worker-0:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemLAPACK
05-Mar-2017 02:04:12 2017-03-05 02:04:12,887 - WARN  [Executor task launch worker-0:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefLAPACK
05-Mar-2017 02:04:13 [Stage 19:>                                                         (0 + 0) / 2]
05-Mar-2017 02:04:13 [Stage 31:>                                                         (0 + 0) / 2]
05-Mar-2017 02:04:13                                                                                
05-Mar-2017 02:04:15 2017-03-05 02:04:15,494 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@254] - Announced HTTP Service for Service program:default.MovieRecommender.-SNAPSHOT.service.MovieRecommenderService at /127.0.0.1:41124
05-Mar-2017 02:04:15 2017-03-05 02:04:15,650 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService-02602d22-0148-11e7-aedf-ea2f5ac8597b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@187] - Stopping Program: MovieDictionaryService
05-Mar-2017 02:04:15 2017-03-05 02:04:15,661 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService-02602d22-0148-11e7-aedf-ea2f5ac8597b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@200] - Program stopped: MovieDictionaryService
05-Mar-2017 02:04:15 2017-03-05 02:04:15,667 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieRecommenderService-08c809d5-0148-11e7-bea9-ea2f5ac8597b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@187] - Stopping Program: MovieRecommenderService
05-Mar-2017 02:04:15 2017-03-05 02:04:15,677 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieRecommenderService-08c809d5-0148-11e7-bea9-ea2f5ac8597b:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@200] - Program stopped: MovieRecommenderService
05-Mar-2017 02:04:15 2017-03-05 02:04:15,683 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@279] - Deleting namespace 'namespace:default'.
05-Mar-2017 02:04:15 2017-03-05 02:04:15,685 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:04:15 2017-03-05 02:04:15,686 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@112] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
05-Mar-2017 02:04:15 2017-03-05 02:04:15,686 - WARN  [main:c.c.c.e.c.ExploreFacade@55] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
05-Mar-2017 02:04:15 2017-03-05 02:04:15,723 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.1
05-Mar-2017 02:04:15 2017-03-05 02:04:15,730 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.60
05-Mar-2017 02:04:15 2017-03-05 02:04:15,738 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.3600
05-Mar-2017 02:04:15 2017-03-05 02:04:15,744 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.2147483647
05-Mar-2017 02:04:15 2017-03-05 02:04:15,767 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.business.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {scope=USER}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,770 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@100] - 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={scope=USER}}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,871 - INFO  [dataset.service-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.routestore, type name: co.cask.cdap.api.dataset.lib.KeyValueTable, properties: {}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,874 - INFO  [dataset.executor-executor-1:c.c.c.d.d.d.s.e.DatasetAdminService@100] - Creating dataset instance dataset:system.routestore, type meta: DatasetTypeMeta{name='co.cask.cdap.api.dataset.lib.KeyValueTable', 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={}}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,897 - INFO  [dataset.service-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@253] - Creating dataset system.workflow.stats, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,899 - INFO  [dataset.executor-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@100] - Creating dataset instance dataset:system.workflow.stats, 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={}}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,941 - INFO  [dataset.service-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.movies
05-Mar-2017 02:04:15 2017-03-05 02:04:15,951 - INFO  [dataset.executor-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@200] - 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]}]}
05-Mar-2017 02:04:15 2017-03-05 02:04:15,977 - INFO  [dataset.service-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.ratings
05-Mar-2017 02:04:15 2017-03-05 02:04:15,981 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@200] - 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]}]}
05-Mar-2017 02:04:16 2017-03-05 02:04:16,015 - INFO  [dataset.service-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@450] - Deleting dataset default.recommendations
05-Mar-2017 02:04:16 2017-03-05 02:04:16,021 - INFO  [dataset.executor-executor-5:c.c.c.d.d.d.s.e.DatasetAdminService@200] - 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]}]}
05-Mar-2017 02:04:16 2017-03-05 02:04:16,053 - WARN  [dataset.service-executor-7:c.c.c.d.d.d.t.DatasetTypeManager@410] - Deleting all modules from namespace namespace:default
05-Mar-2017 02:04:16 2017-03-05 02:04:16,106 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.1
05-Mar-2017 02:04:16 2017-03-05 02:04:16,120 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.60
05-Mar-2017 02:04:16 2017-03-05 02:04:16,130 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.3600
05-Mar-2017 02:04:16 2017-03-05 02:04:16,136 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@100] - FactTable created: metrics.v2.table.ts.2147483647
05-Mar-2017 02:04:16 2017-03-05 02:04:16,191 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceResourceDeleter@119] - All data for namespace 'namespace:default' deleted.
05-Mar-2017 02:04:16 2017-03-05 02:04:16,192 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@298] - Keeping the 'namespace:default' namespace after removing all data.
05-Mar-2017 02:04:16 2017-03-05 02:04:16,193 - INFO  [MetricsQueryService STOPPING:c.c.c.m.q.MetricsQueryService@104] - Stopping Metrics Service...
05-Mar-2017 02:04:16 2017-03-05 02:04:16,215 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@91] - Stopped stream size scheduler
05-Mar-2017 02:04:16 2017-03-05 02:04:16,223 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@99] - Stopped time scheduler
05-Mar-2017 02:04:16 2017-03-05 02:04:16,423 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@200] - Stopping DatasetService...
05-Mar-2017 02:04:19 2017-03-05 02:04:19,426 - INFO  [NettyHttpService STOPPING:c.c.c.d.d.d.s.DatasetTypeHandler@63] - Stopping DatasetTypeHandler
05-Mar-2017 02:04:19 2017-03-05 02:04:19,429 - INFO  [DatasetOpExecutorService STOPPING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@91] - Stopping DatasetOpExecutorService...
05-Mar-2017 02:04:19 2017-03-05 02:04:19,436 - INFO  [authorization-enforcement-service:c.c.c.s.a.AbstractAuthorizationService@139] - Shutdown authorization enforcement service successfully.
05-Mar-2017 02:04:19 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.161 sec
05-Mar-2017 02:04:19
05-Mar-2017 02:04:19 Results :
05-Mar-2017 02:04:19
05-Mar-2017 02:04:19 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
05-Mar-2017 02:04:19
05-Mar-2017 02:04:19 [INFO]
05-Mar-2017 02:04:19 [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ MovieRecommender ---
05-Mar-2017 02:04:19 [INFO] Building jar: /var/bamboo/xml-data/build-dir/CA-BUT9-MR/MovieRecommender/target/MovieRecommender-0.8.0.jar
05-Mar-2017 02:04:19 [INFO]
05-Mar-2017 02:04:19 [INFO] --- maven-bundle-plugin:2.3.7:bundle (default) @ MovieRecommender ---
05-Mar-2017 02:04:27 [INFO] ------------------------------------------------------------------------
05-Mar-2017 02:04:27 [INFO] BUILD SUCCESS
05-Mar-2017 02:04:27 [INFO] ------------------------------------------------------------------------
05-Mar-2017 02:04:27 [INFO] Total time: 1:03.138s
05-Mar-2017 02:04:27 [INFO] Finished at: Sun Mar 05 02:04:27 UTC 2017
05-Mar-2017 02:04:27 [INFO] Final Memory: 75M/896M
05-Mar-2017 02:04:27 [INFO] ------------------------------------------------------------------------
05-Mar-2017 02:04:28 Parsing test results under /var/bamboo/xml-data/build-dir/CA-BUT9-MR...
05-Mar-2017 02:04:28 Finished task 'MovieRecommender' with result: Success
05-Mar-2017 02:04:28 Running post build plugin 'Docker Container Cleanup'
05-Mar-2017 02:04:28 Running post build plugin 'NCover Results Collector'
05-Mar-2017 02:04:28 Running post build plugin 'Clover Results Collector'
05-Mar-2017 02:04:28 Running post build plugin 'npm Cache Cleanup'
05-Mar-2017 02:04:28 Running post build plugin 'Artifact Copier'
05-Mar-2017 02:04:28 Finalising the build...
05-Mar-2017 02:04:28 Stopping timer.
05-Mar-2017 02:04:28 Build CA-BUT9-MR-15 completed.
05-Mar-2017 02:04:28 Running on server: post build plugin 'NCover Results Collector'
05-Mar-2017 02:04:28 Running on server: post build plugin 'Build Hanging Detection Configuration'
05-Mar-2017 02:04:28 Running on server: post build plugin 'Clover Delta Calculator'
05-Mar-2017 02:04:28 Running on server: post build plugin 'Maven Dependencies Postprocessor'
05-Mar-2017 02:04:28 All post build plugins have finished
05-Mar-2017 02:04:28 Generating build results summary...
05-Mar-2017 02:04:28 Saving build results to disk...
05-Mar-2017 02:04:28 Logging substituted variables...
05-Mar-2017 02:04:28 Indexing build results...
05-Mar-2017 02:04:28 Finished building CA-BUT9-MR-15.