Build #69

Builds and runs unit tests for CDAP Apps repository

Build: #69 failed

Job: MovieRecommender failed

Stages & jobs

  1. Build Stage

Build log

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

25-Aug-2017 20:04:02 Build CDAP Apps - Build and Unit Test - MovieRecommender #69 (CA-BUT-MR-69) started building on agent bamboo-agent18.prod.continuuity.net
25-Aug-2017 20:04:02 Remote Agent
25-Aug-2017 20:04:02 Build working directory is /var/bamboo/xml-data/build-dir/CA-BUT-MR
25-Aug-2017 20:04:02 Executing build CDAP Apps - Build and Unit Test - MovieRecommender #69 (CA-BUT-MR-69)
25-Aug-2017 20:04:02 Starting task 'Checkout Default Repository' of type 'com.atlassian.bamboo.plugins.vcs:task.vcs.checkout'
25-Aug-2017 20:04:02 Build always requires a clean checkout
25-Aug-2017 20:04:02 Cleaning build directory '/var/bamboo/xml-data/build-dir/CA-BUT-MR'
25-Aug-2017 20:04:02 Updating source code to revision: a3081f89d0bc181224a7445e184695f327ba21f0
25-Aug-2017 20:04:05 Fetching 'refs/heads/develop' from 'git@github.com:caskdata/cdap-apps.git'.
25-Aug-2017 20:04:06 Warning: Permanently added '[127.0.0.1]:42974' (RSA) to the list of known hosts.
25-Aug-2017 20:04:07 From ssh://127.0.0.1:42974/caskdata/cdap-apps
25-Aug-2017 20:04:07    9ebeb90..a3081f8  develop    -> develop
25-Aug-2017 20:04:07 Checking out revision a3081f89d0bc181224a7445e184695f327ba21f0.
25-Aug-2017 20:04:07 Creating local git repository in '/var/bamboo/xml-data/build-dir/CA-BUT-MR/.git'.
25-Aug-2017 20:04:07 Cloning into '/var/bamboo/xml-data/build-dir/CA-BUT-MR'...
25-Aug-2017 20:04:07 done.
25-Aug-2017 20:04:07 Switched to a new branch 'develop'
25-Aug-2017 20:04:07 Branch develop set up to track remote branch develop from origin.
25-Aug-2017 20:04:07 Updated source code to revision: a3081f89d0bc181224a7445e184695f327ba21f0
25-Aug-2017 20:04:07 Finished task 'Checkout Default Repository' with result: Success
25-Aug-2017 20:04:07 Running pre-build action: VCS Version Collector
25-Aug-2017 20:04:07 Running pre-build action: Hung Build Killer PreBuildAction
25-Aug-2017 20:04:07 Starting task 'MovieRecommender' of type 'com.atlassian.bamboo.plugins.maven:task.builder.mvn3'
25-Aug-2017 20:04:07
Beginning to execute external process for build 'CDAP Apps - Build and Unit Test - MovieRecommender #69 (CA-BUT-MR-69)'
... running command line:
/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/CA-BUT-MR clean package
... in: /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender
... using extra environment variables:
bamboo_planRepository_1_branch=develop
bamboo_planRepository_1_branchDisplayName=develop
bamboo_repository_revision_number=a3081f89d0bc181224a7445e184695f327ba21f0
MAVEN2_HOME=/opt/maven
bamboo_repository_65208385_revision_number=a3081f89d0bc181224a7445e184695f327ba21f0
bamboo_docs_s3_bucket=docs.cask.co
bamboo_capability_system_builder_command_s3cmd=/usr/bin/s3cmd
bamboo_buildKey=CA-BUT-MR
bamboo_gpg_password=********
bamboo_ops_gpg_password=********
bamboo_shortJobName=MovieRecommender
bamboo_buildResultsUrl=http://builds.cask.co/browse/CA-BUT-MR-69
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_repository_65208385_git_username=
bamboo_aws_secret_access_key_password=********
bamboo_agentId=32243726
bamboo_capability_Swapfile=true
bamboo_planRepository_revision=a3081f89d0bc181224a7445e184695f327ba21f0
bamboo_planRepository_previousRevision=9ebeb90f2d17c95d7572ff53613ffee2b25dc91b
bamboo_capability_RHEL=rhel
bamboo_capability_freight=true
bamboo_repository_branch_name=develop
bamboo_kdc_address_password=********
bamboo_repository_65208385_git_repositoryUrl=git@github.com:caskdata/cdap-apps.git
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=develop
bamboo_market_s3_access_key_password=********
bamboo_planRepository_1_type=gitv2
bamboo_planRepository_branchName=develop
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_capability_system_builder_mvn3_Maven_3=/opt/maven
bamboo_planKey=CA-BUT
bamboo_hide_jdk_mirror_password=********
bamboo_planRepository_username=
bamboo_repository_65208385_name=CDAP Apps
bamboo_planRepository_1_branchName=develop
bamboo_capability_s3cmd=true
bamboo_resultsUrl=http://builds.cask.co/browse/CA-BUT-MR-69
bamboo_capability_Sphinx=true
bamboo_planRepository_1_name=CDAP Apps
bamboo_build_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT-MR
bamboo_repository_65208385_git_branch=develop
bamboo_shortPlanName=Build and Unit Test
bamboo_planRepository_name=CDAP Apps
bamboo_capability_git_lfs=true
bamboo_buildNumber=69
bamboo_planName=CDAP Apps - Build and Unit Test
bamboo_shortPlanKey=BUT
bamboo_shortJobKey=MR
bamboo_repository_previous_revision_number=9ebeb90f2d17c95d7572ff53613ffee2b25dc91b
bamboo_buildTimeStamp=2017-08-25T20:04:01.949Z
M2_HOME=/opt/maven
bamboo_s3_bucket=repository.cask.co
bamboo_buildResultKey=CA-BUT-MR-69
bamboo_capability_system_builder_command_Docker=/usr/bin/docker
bamboo_repository_git_branch=develop
bamboo_market_s3_secret_key_password=********
bamboo_buildPlanName=CDAP Apps - Build and Unit Test - 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=a3081f89d0bc181224a7445e184695f327ba21f0
bamboo_capability_Platform=rhel
bamboo_repository_name=CDAP Apps
bamboo_capability_system_docker_executable=/usr/bin/docker
MAVEN_OPTS=-Xmx2048m
bamboo_repository_65208385_previous_revision_number=9ebeb90f2d17c95d7572ff53613ffee2b25dc91b
bamboo_agentWorkingDirectory=/var/bamboo/xml-data/build-dir
bamboo_capability_system_git_executable=/usr/local/bin/git
bamboo_planRepository_1_previousRevision=9ebeb90f2d17c95d7572ff53613ffee2b25dc91b
bamboo_repository_git_username=
bamboo_capability_system_builder_command_Ruby=/opt/rbenv/shims/ruby
bamboo_planRepository_branchDisplayName=develop
bamboo_aws_access_key_password=********
bamboo_capability_system_builder_mvn3_Maven_3_x=/opt/maven
bamboo_repository_65208385_branch_name=develop
bamboo_plan_storageTag=CA-BUT
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-BUT-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:/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
25-Aug-2017 20:04:09 [INFO] Scanning for projects...
25-Aug-2017 20:04:10 [INFO]                                                                         
25-Aug-2017 20:04:10 [INFO] ------------------------------------------------------------------------
25-Aug-2017 20:04:10 [INFO] Building Movie Recommender Application 0.8.0
25-Aug-2017 20:04:10 [INFO] ------------------------------------------------------------------------
25-Aug-2017 20:04:10 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:10 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:11 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 3.7 KB/sec)
25-Aug-2017 20:04:11 [WARNING] Could not transfer metadata co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:11 [WARNING] Failure to transfer co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:11 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/cdap-api-spark-4.3.0-20170825.135538-97.pom
25-Aug-2017 20:04:11 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/cdap-api-spark-4.3.0-20170825.135538-97.pom (10 KB at 82.2 KB/sec)
25-Aug-2017 20:04:12 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-api/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-api/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [WARNING] Could not transfer metadata org.apache.twill:twill-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [WARNING] Failure to transfer org.apache.twill:twill-api:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-parent/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-parent/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [WARNING] Could not transfer metadata org.apache.twill:twill-parent:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-common/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-common/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [WARNING] Could not transfer metadata org.apache.twill:twill-common:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [WARNING] Failure to transfer org.apache.twill:twill-common:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-common:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-discovery-api/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-discovery-api/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [WARNING] Could not transfer metadata org.apache.twill:twill-discovery-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [WARNING] Failure to transfer org.apache.twill:twill-discovery-api:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-discovery-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:12 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:12 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 13.8 KB/sec)
25-Aug-2017 20:04:13 [WARNING] Could not transfer metadata co.cask.cdap:cdap-unit-test:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [WARNING] Failure to transfer co.cask.cdap:cdap-unit-test:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-unit-test:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/cdap-unit-test-4.3.0-20170825.134812-97.pom
25-Aug-2017 20:04:13 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/cdap-unit-test-4.3.0-20170825.134812-97.pom (6 KB at 55.0 KB/sec)
25-Aug-2017 20:04:13 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 18.1 KB/sec)
25-Aug-2017 20:04:13 [WARNING] Could not transfer metadata co.cask.cdap:cdap-test:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [WARNING] Failure to transfer co.cask.cdap:cdap-test:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-test:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/cdap-test-4.3.0-20170825.134706-97.pom
25-Aug-2017 20:04:13 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/cdap-test-4.3.0-20170825.134706-97.pom (2 KB at 16.1 KB/sec)
25-Aug-2017 20:04:13 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-discovery-core/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-discovery-core/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [WARNING] Could not transfer metadata org.apache.twill:twill-discovery-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [WARNING] Failure to transfer org.apache.twill:twill-discovery-core:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-discovery-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:13 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-zookeeper/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:13 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-zookeeper/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [WARNING] Could not transfer metadata org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [WARNING] Failure to transfer org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-yarn/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-yarn/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [WARNING] Could not transfer metadata org.apache.twill:twill-yarn:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [WARNING] Failure to transfer org.apache.twill:twill-yarn:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-yarn:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/org/apache/twill/twill-core/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [INFO] Downloading: http://scala-tools.org/repo-releases/org/apache/twill/twill-core/0.12.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [WARNING] Could not transfer metadata org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [WARNING] Failure to transfer org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:14 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 12.7 KB/sec)
25-Aug-2017 20:04:14 [WARNING] Could not transfer metadata co.cask.cdap:cdap-notifications:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [WARNING] Failure to transfer co.cask.cdap:cdap-notifications:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-notifications:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:14 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/cdap-notifications-4.3.0-20170825.135453-97.pom
25-Aug-2017 20:04:18 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/cdap-notifications-4.3.0-20170825.135453-97.pom (4 KB at 0.8 KB/sec)
25-Aug-2017 20:04:18 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:18 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:18 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 8.6 KB/sec)
25-Aug-2017 20:04:18 [WARNING] Could not transfer metadata co.cask.cdap:cdap-notifications-api:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:18 [WARNING] Failure to transfer co.cask.cdap:cdap-notifications-api:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-notifications-api:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:18 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/cdap-notifications-api-4.3.0-20170825.135008-97.pom
25-Aug-2017 20:04:18 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/cdap-notifications-api-4.3.0-20170825.135008-97.pom (2 KB at 10.7 KB/sec)
25-Aug-2017 20:04:18 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:18 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:18 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 18.0 KB/sec)
25-Aug-2017 20:04:18 [WARNING] Could not transfer metadata co.cask.cdap:cdap-data-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:18 [WARNING] Failure to transfer co.cask.cdap:cdap-data-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-data-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:18 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/cdap-data-fabric-4.3.0-20170825.135358-97.pom
25-Aug-2017 20:04:19 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/cdap-data-fabric-4.3.0-20170825.135358-97.pom (7 KB at 43.2 KB/sec)
25-Aug-2017 20:04:19 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:19 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:19 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 8.6 KB/sec)
25-Aug-2017 20:04:19 [WARNING] Could not transfer metadata co.cask.cdap:cdap-security:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:19 [WARNING] Failure to transfer co.cask.cdap:cdap-security:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-security:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:19 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97.pom
25-Aug-2017 20:04:22 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97.pom (12 KB at 3.6 KB/sec)
25-Aug-2017 20:04:22 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:22 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 6.7 KB/sec)
25-Aug-2017 20:04:23 [WARNING] Could not transfer metadata co.cask.cdap:cdap-tms:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [WARNING] Failure to transfer co.cask.cdap:cdap-tms:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-tms:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/cdap-tms-4.3.0-20170825.134734-97.pom
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/cdap-tms-4.3.0-20170825.134734-97.pom (6 KB at 40.9 KB/sec)
25-Aug-2017 20:04:23 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 23.8 KB/sec)
25-Aug-2017 20:04:23 [WARNING] Could not transfer metadata co.cask.cdap:cdap-hbase-compat-base:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [WARNING] Failure to transfer co.cask.cdap:cdap-hbase-compat-base:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-hbase-compat-base:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/cdap-hbase-compat-base-4.3.0-20170825.134908-97.pom
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/cdap-hbase-compat-base-4.3.0-20170825.134908-97.pom (4 KB at 39.4 KB/sec)
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 17.5 KB/sec)
25-Aug-2017 20:04:23 [WARNING] Could not transfer metadata co.cask.cdap:cdap-hbase-spi:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [WARNING] Failure to transfer co.cask.cdap:cdap-hbase-spi:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-hbase-spi:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/cdap-hbase-spi-4.3.0-20170825.134709-97.pom
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/cdap-hbase-spi-4.3.0-20170825.134709-97.pom (2 KB at 10.7 KB/sec)
25-Aug-2017 20:04:23 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:23 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 18.7 KB/sec)
25-Aug-2017 20:04:24 [WARNING] Could not transfer metadata co.cask.cdap:cdap-explore-client:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:24 [WARNING] Failure to transfer co.cask.cdap:cdap-explore-client:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-explore-client:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:24 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/cdap-explore-client-4.3.0-20170825.135512-97.pom
25-Aug-2017 20:04:24 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/cdap-explore-client-4.3.0-20170825.135512-97.pom (2 KB at 11.6 KB/sec)
25-Aug-2017 20:04:24 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:24 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:24 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/maven-metadata.xml (3 KB at 22.0 KB/sec)
25-Aug-2017 20:04:24 [WARNING] Could not transfer metadata co.cask.cdap:cdap-app-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:24 [WARNING] Failure to transfer co.cask.cdap:cdap-app-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-app-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:24 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97.pom
25-Aug-2017 20:04:24 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97.pom (8 KB at 72.9 KB/sec)
25-Aug-2017 20:04:24 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:24 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:25 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 10.2 KB/sec)
25-Aug-2017 20:04:25 [WARNING] Could not transfer metadata co.cask.cdap:cdap-watchdog:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:25 [WARNING] Failure to transfer co.cask.cdap:cdap-watchdog:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-watchdog:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:25 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/cdap-watchdog-4.3.0-20170825.135529-97.pom
25-Aug-2017 20:04:25 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/cdap-watchdog-4.3.0-20170825.135529-97.pom (4 KB at 38.0 KB/sec)
25-Aug-2017 20:04:25 [WARNING] Failure to transfer co.cask.cdap:cdap-app-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-app-fabric:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:25 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:25 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:25 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 13.0 KB/sec)
25-Aug-2017 20:04:25 [WARNING] Could not transfer metadata co.cask.cdap:cdap-explore:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:25 [WARNING] Failure to transfer co.cask.cdap:cdap-explore:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-explore:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:25 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/cdap-explore-4.3.0-20170825.134617-97.pom
25-Aug-2017 20:04:25 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/cdap-explore-4.3.0-20170825.134617-97.pom (5 KB at 46.1 KB/sec)
25-Aug-2017 20:04:25 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:25 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:26 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 10.9 KB/sec)
25-Aug-2017 20:04:26 [WARNING] Could not transfer metadata co.cask.cdap:cdap-explore-jdbc:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [WARNING] Failure to transfer co.cask.cdap:cdap-explore-jdbc:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-explore-jdbc:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-20170825.135301-97.pom
25-Aug-2017 20:04:26 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-20170825.135301-97.pom (6 KB at 43.5 KB/sec)
25-Aug-2017 20:04:26 [INFO] Downloading: http://scala-tools.org/repo-releases/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:26 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/maven-metadata.xml
25-Aug-2017 20:04:26 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/maven-metadata.xml (2 KB at 15.1 KB/sec)
25-Aug-2017 20:04:26 [WARNING] Could not transfer metadata co.cask.cdap:cdap-spark-core:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [WARNING] Failure to transfer co.cask.cdap:cdap-spark-core:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-spark-core:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/cdap-spark-core-4.3.0-20170825.135814-97.pom
25-Aug-2017 20:04:26 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/cdap-spark-core-4.3.0-20170825.135814-97.pom (16 KB at 106.3 KB/sec)
25-Aug-2017 20:04:26 [WARNING] Failure to transfer co.cask.cdap:cdap-security:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-security:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [WARNING] Failure to transfer org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:26 [WARNING] Failure to transfer org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/cdap-unit-test-4.3.0-20170825.134812-97.jar
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/cdap-api-spark-4.3.0-20170825.135538-97.jar
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/cdap-test-4.3.0-20170825.134706-97.jar
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/cdap-notifications-4.3.0-20170825.135453-97.jar
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/cdap-notifications-api-4.3.0-20170825.135008-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-api-spark/4.3.0-SNAPSHOT/cdap-api-spark-4.3.0-20170825.135538-97.jar (40 KB at 290.0 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/cdap-data-fabric-4.3.0-20170825.135358-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-test/4.3.0-SNAPSHOT/cdap-test-4.3.0-20170825.134706-97.jar (24 KB at 115.6 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications-api/4.3.0-SNAPSHOT/cdap-notifications-api-4.3.0-20170825.135008-97.jar (14 KB at 62.4 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-notifications/4.3.0-SNAPSHOT/cdap-notifications-4.3.0-20170825.135453-97.jar (38 KB at 153.8 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97-tests.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-unit-test/4.3.0-SNAPSHOT/cdap-unit-test-4.3.0-20170825.134812-97.jar (115 KB at 394.1 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/cdap-watchdog-4.3.0-20170825.135529-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97.jar (220 KB at 918.2 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/cdap-explore-4.3.0-20170825.134617-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-watchdog/4.3.0-SNAPSHOT/cdap-watchdog-4.3.0-20170825.135529-97.jar (418 KB at 1906.9 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/cdap-explore-client-4.3.0-20170825.135512-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-data-fabric/4.3.0-SNAPSHOT/cdap-data-fabric-4.3.0-20170825.135358-97.jar (1423 KB at 3762.9 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-20170825.135301-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore/4.3.0-SNAPSHOT/cdap-explore-4.3.0-20170825.134617-97.jar (230 KB at 1365.4 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/cdap-spark-core-4.3.0-20170825.135814-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-client/4.3.0-SNAPSHOT/cdap-explore-client-4.3.0-20170825.135512-97.jar (75 KB at 624.8 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/cdap-tms-4.3.0-20170825.134734-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97-tests.jar (1016 KB at 2445.8 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/cdap-hbase-compat-base-4.3.0-20170825.134908-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-20170825.135326-97.jar (1925 KB at 3401.0 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/cdap-hbase-spi-4.3.0-20170825.134709-97.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-tms/4.3.0-SNAPSHOT/cdap-tms-4.3.0-20170825.134734-97.jar (199 KB at 1270.9 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloading: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97-tests.jar
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-compat-base/4.3.0-SNAPSHOT/cdap-hbase-compat-base-4.3.0-20170825.134908-97.jar (105 KB at 804.7 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-spark-core/4.3.0-SNAPSHOT/cdap-spark-core-4.3.0-20170825.135814-97.jar (557 KB at 2727.8 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-security/4.3.0-SNAPSHOT/cdap-security-4.3.0-20170825.134902-97-tests.jar (115 KB at 1014.8 KB/sec)
25-Aug-2017 20:04:27 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-hbase-spi/4.3.0-SNAPSHOT/cdap-hbase-spi-4.3.0-20170825.134709-97.jar (9 KB at 61.6 KB/sec)
25-Aug-2017 20:04:28 [INFO] Downloaded: https://oss.sonatype.org/content/repositories/snapshots/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-20170825.135301-97.jar (13728 KB at 22249.4 KB/sec)
25-Aug-2017 20:04:28 [INFO]
25-Aug-2017 20:04:28 [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ MovieRecommender ---
25-Aug-2017 20:04:28 [INFO]
25-Aug-2017 20:04:28 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ MovieRecommender ---
25-Aug-2017 20:04:28 [INFO] Using 'UTF-8' encoding to copy filtered resources.
25-Aug-2017 20:04:28 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/main/resources
25-Aug-2017 20:04:28 [INFO]
25-Aug-2017 20:04:28 [INFO] --- maven-scala-plugin:2.15.2:add-source (scala-compile-first) @ MovieRecommender ---
25-Aug-2017 20:04:28 [INFO] Add Source directory: /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/main/scala
25-Aug-2017 20:04:28 [INFO] Add Test Source directory: /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/scala
25-Aug-2017 20:04:28 [INFO]
25-Aug-2017 20:04:28 [INFO] --- maven-scala-plugin:2.15.2:compile (scala-compile-first) @ MovieRecommender ---
25-Aug-2017 20:04:28 [INFO] Checking for multiple versions of scala
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-parent:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-parent:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-discovery-api:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-discovery-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-api:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-api:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-common:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-common:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-discovery-core:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-discovery-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-core:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:30 [WARNING] Failure to transfer org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata org.apache.twill:twill-zookeeper:0.12.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:32 [WARNING] Failure to transfer co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT/maven-metadata.xml from http://scala-tools.org/repo-releases was cached in the local repository, resolution will not be reattempted until the update interval of scala-tools.org has elapsed or updates are forced. Original error: Could not transfer metadata co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT/maven-metadata.xml from/to scala-tools.org (http://scala-tools.org/repo-releases): peer not authenticated
25-Aug-2017 20:04:32 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
25-Aug-2017 20:04:32 [WARNING]  co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT requires scala version: 2.10.4
25-Aug-2017 20:04:32 [WARNING] Multiple versions of scala libraries detected!
25-Aug-2017 20:04:32 [INFO] includes = [**/*.scala,**/*.java,]
25-Aug-2017 20:04:32 [INFO] excludes = []
25-Aug-2017 20:04:32 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/main/java:-1: info: compiling
25-Aug-2017 20:04:32 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/main/scala:-1: info: compiling
25-Aug-2017 20:04:32 [INFO] Compiling 7 source files to /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/classes at 1503691472888
25-Aug-2017 20:04:45 [INFO] prepare-compile in 0 s
25-Aug-2017 20:04:45 [INFO] compile in 12 s
25-Aug-2017 20:04:45 [INFO]
25-Aug-2017 20:04:45 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ MovieRecommender ---
25-Aug-2017 20:04:45 [INFO] Changes detected - recompiling the module!
25-Aug-2017 20:04:45 [INFO] Compiling 6 source files to /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/classes
25-Aug-2017 20:04:46 [INFO]
25-Aug-2017 20:04:46 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ MovieRecommender ---
25-Aug-2017 20:04:46 [INFO] Using 'UTF-8' encoding to copy filtered resources.
25-Aug-2017 20:04:46 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/resources
25-Aug-2017 20:04:46 [INFO]
25-Aug-2017 20:04:46 [INFO] --- maven-scala-plugin:2.15.2:testCompile (scala-test-compile) @ MovieRecommender ---
25-Aug-2017 20:04:46 [INFO] Checking for multiple versions of scala
25-Aug-2017 20:04:46 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
25-Aug-2017 20:04:46 [WARNING]  co.cask.cdap:cdap-api-spark:4.3.0-SNAPSHOT requires scala version: 2.10.4
25-Aug-2017 20:04:46 [WARNING] Multiple versions of scala libraries detected!
25-Aug-2017 20:04:46 [INFO] includes = [**/*.scala,**/*.java,]
25-Aug-2017 20:04:46 [INFO] excludes = []
25-Aug-2017 20:04:46 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/java:-1: info: compiling
25-Aug-2017 20:04:46 [INFO] Compiling 1 source files to /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/test-classes at 1503691486940
25-Aug-2017 20:04:50 [INFO] prepare-compile in 0 s
25-Aug-2017 20:04:50 [INFO] compile in 3 s
25-Aug-2017 20:04:50 [INFO]
25-Aug-2017 20:04:50 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ MovieRecommender ---
25-Aug-2017 20:04:50 [INFO] Changes detected - recompiling the module!
25-Aug-2017 20:04:50 [INFO] Compiling 1 source file to /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/test-classes
25-Aug-2017 20:04:50 [WARNING] /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/java/co/cask/cdap/apps/movierecommender/MovieRecommenderAppTest.java: /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/java/co/cask/cdap/apps/movierecommender/MovieRecommenderAppTest.java uses or overrides a deprecated API.
25-Aug-2017 20:04:50 [WARNING] /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/src/test/java/co/cask/cdap/apps/movierecommender/MovieRecommenderAppTest.java: Recompile with -Xlint:deprecation for details.
25-Aug-2017 20:04:50 [INFO]
25-Aug-2017 20:04:50 [INFO] --- maven-checkstyle-plugin:2.12.1:check (validate) @ MovieRecommender ---
25-Aug-2017 20:04:51 [INFO] Starting audit...
25-Aug-2017 20:04:51 Audit done.
25-Aug-2017 20:04:51
25-Aug-2017 20:04:51 [INFO]
25-Aug-2017 20:04:51 [INFO] --- maven-surefire-plugin:2.14.1:test (default-test) @ MovieRecommender ---
25-Aug-2017 20:04:51 [INFO] Surefire report directory: /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/surefire-reports
25-Aug-2017 20:04:52
25-Aug-2017 20:04:52 -------------------------------------------------------
25-Aug-2017 20:04:52 T E S T S
25-Aug-2017 20:04:52 -------------------------------------------------------
25-Aug-2017 20:04:53 Running co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest
25-Aug-2017 20:04:53 SLF4J: Class path contains multiple SLF4J bindings.
25-Aug-2017 20:04:53 SLF4J: Found binding in [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-SNAPSHOT.jar!/org/slf4j/impl/StaticLoggerBinder.class]
25-Aug-2017 20:04:53 SLF4J: Found binding in [jar:file:/home/bamboo/.m2/repository/ch/qos/logback/logback-classic/1.0.9/logback-classic-1.0.9.jar!/org/slf4j/impl/StaticLoggerBinder.class]
25-Aug-2017 20:04:53 SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
25-Aug-2017 20:04:53 20:04:53,182 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
25-Aug-2017 20:04:53 20:04:53,183 |-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.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:53 20:04:53,184 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
25-Aug-2017 20:04:53 20:04:53,184 |-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.3.0-SNAPSHOT/cdap-common-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:53 20:04:53,184 |-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.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:53 20:04:53,214 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@30646a81 - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml] is not of type file
25-Aug-2017 20:04:53 20:04:53,295 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
25-Aug-2017 20:04:53 20:04:53,316 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
25-Aug-2017 20:04:53 20:04:53,316 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
25-Aug-2017 20:04:53 20:04:53,317 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
25-Aug-2017 20:04:53 20:04:53,322 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
25-Aug-2017 20:04:53 20:04:53,351 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
25-Aug-2017 20:04:53 20:04:53,443 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
25-Aug-2017 20:04:53 20:04:53,443 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
25-Aug-2017 20:04:53 20:04:53,443 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
25-Aug-2017 20:04:53 20:04:53,444 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
25-Aug-2017 20:04:53 20:04:53,446 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@108c53f9 - Registering current configuration as safe fallback point
25-Aug-2017 20:04:53
25-Aug-2017 20:04:53 SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
25-Aug-2017 20:04:53 SLF4J: Class path contains multiple SLF4J bindings.
25-Aug-2017 20:04:53 SLF4J: Found binding in [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-explore-jdbc/4.3.0-SNAPSHOT/cdap-explore-jdbc-4.3.0-SNAPSHOT.jar!/org/slf4j/impl/StaticLoggerBinder.class]
25-Aug-2017 20:04:53 SLF4J: Found binding in [jar:file:/home/bamboo/.m2/repository/ch/qos/logback/logback-classic/1.0.9/logback-classic-1.0.9.jar!/org/slf4j/impl/StaticLoggerBinder.class]
25-Aug-2017 20:04:53 SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
25-Aug-2017 20:04:54 20:04:53,894 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
25-Aug-2017 20:04:54 20:04:53,894 |-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.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:54 20:04:53,896 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
25-Aug-2017 20:04:54 20:04:53,896 |-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.3.0-SNAPSHOT/cdap-common-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:54 20:04:53,896 |-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.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml]
25-Aug-2017 20:04:54 20:04:53,935 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@4fe7ab59 - URL [jar:file:/home/bamboo/.m2/repository/co/cask/cdap/cdap-app-fabric/4.3.0-SNAPSHOT/cdap-app-fabric-4.3.0-SNAPSHOT-tests.jar!/logback-test.xml] is not of type file
25-Aug-2017 20:04:54 20:04:53,989 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
25-Aug-2017 20:04:54 20:04:54,005 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.hadoop] to INFO
25-Aug-2017 20:04:54 20:04:54,005 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mortbay.log] to INFO
25-Aug-2017 20:04:54 20:04:54,005 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
25-Aug-2017 20:04:54 20:04:54,013 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
25-Aug-2017 20:04:54 20:04:54,054 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
25-Aug-2017 20:04:54 20:04:54,150 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [co.cask.cdap] to INFO
25-Aug-2017 20:04:54 20:04:54,150 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
25-Aug-2017 20:04:54 20:04:54,150 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
25-Aug-2017 20:04:54 20:04:54,152 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
25-Aug-2017 20:04:54 20:04:54,154 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@72a2890a - Registering current configuration as safe fallback point
25-Aug-2017 20:04:54
25-Aug-2017 20:04:54 SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
25-Aug-2017 20:04:54 2017-08-25 20:04:54,374 - INFO  [main:c.c.c.t.TestBase@408] - Custom configuration set: cdap.unit.test.explore.enabled = false
25-Aug-2017 20:04:58 2017-08-25 20:04:58,013 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.l.LevelDBTableFactory@83] - Messaging metadata table created at /tmp/CA-BUT-MR/junit2491585572742865872/junit1813849504512578662/messaging/system.tms.meta
25-Aug-2017 20:04:58 2017-08-25 20:04:58,039 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.audit
25-Aug-2017 20:04:58 2017-08-25 20:04:58,043 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.dataevent
25-Aug-2017 20:04:58 2017-08-25 20:04:58,045 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics0
25-Aug-2017 20:04:58 2017-08-25 20:04:58,047 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics1
25-Aug-2017 20:04:58 2017-08-25 20:04:58,049 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics2
25-Aug-2017 20:04:58 2017-08-25 20:04:58,052 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics3
25-Aug-2017 20:04:58 2017-08-25 20:04:58,054 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics4
25-Aug-2017 20:04:58 2017-08-25 20:04:58,057 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics5
25-Aug-2017 20:04:58 2017-08-25 20:04:58,059 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics6
25-Aug-2017 20:04:58 2017-08-25 20:04:58,062 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics7
25-Aug-2017 20:04:58 2017-08-25 20:04:58,064 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics8
25-Aug-2017 20:04:58 2017-08-25 20:04:58,066 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.metrics9
25-Aug-2017 20:04:58 2017-08-25 20:04:58,069 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.notifications
25-Aug-2017 20:04:58 2017-08-25 20:04:58,071 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.timeevent
25-Aug-2017 20:04:58 2017-08-25 20:04:58,074 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.streamsizeevent
25-Aug-2017 20:04:58 2017-08-25 20:04:58,076 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@312] - System topic created: topic:system.programstatusevent
25-Aug-2017 20:04:58 2017-08-25 20:04:58,076 - INFO  [CoreMessagingService STARTING:c.c.c.m.s.CoreMessagingService@240] - Core Messaging Service started
25-Aug-2017 20:04:58 2017-08-25 20:04:58,282 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@81] - Starting DatasetOpExecutorService...
25-Aug-2017 20:04:58 2017-08-25 20:04:58,425 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@86] - DatasetOpExecutorService started successfully on /127.0.0.1:42824
25-Aug-2017 20:04:58 2017-08-25 20:04:58,644 - WARN  [main:c.c.c.e.c.ExploreFacade@57] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
25-Aug-2017 20:04:58 2017-08-25 20:04:58,677 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@110] - Starting DatasetService...
25-Aug-2017 20:04:58 2017-08-25 20:04:58,688 - INFO  [DefaultDatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@254] - Created dataset dataset:system.datasets.type of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetTypeMDS
25-Aug-2017 20:04:58 2017-08-25 20:04:58,688 - INFO  [DefaultDatasetTypeService STARTING:c.c.c.d.d.InMemoryDatasetFramework@254] - Created dataset dataset:system.datasets.instance of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetInstanceMDS
25-Aug-2017 20:04:59 2017-08-25 20:04:59,014 - INFO  [NettyHttpService STARTING:c.c.c.d.d.d.s.DatasetTypeHandler@60] - Starting DatasetTypeHandler
25-Aug-2017 20:04:59 2017-08-25 20:04:59,026 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService$1@124] - Discovered dataset.executor service
25-Aug-2017 20:04:59 2017-08-25 20:04:59,026 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@168] - Waiting for dataset.executor service to be discoverable
25-Aug-2017 20:04:59 2017-08-25 20:04:59,028 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@150] - Announcing DatasetService for discovery...
25-Aug-2017 20:04:59 2017-08-25 20:04:59,028 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@155] - DatasetService started successfully on localhost/127.0.0.1:44288
25-Aug-2017 20:04:59 2017-08-25 20:04:59,053 - 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
25-Aug-2017 20:04:59 2017-08-25 20:04:59,056 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@93] - Starting Metrics Service...
25-Aug-2017 20:04:59 2017-08-25 20:04:59,420 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@95] - Started Metrics HTTP Service...
25-Aug-2017 20:04:59 2017-08-25 20:04:59,421 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@99] - Metrics Service started successfully on /127.0.0.1:42545
25-Aug-2017 20:04:59 2017-08-25 20:04:59,617 - WARN  [main:c.c.c.e.c.ExploreFacade@57] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
25-Aug-2017 20:04:59 2017-08-25 20:04:59,618 - WARN  [main:c.c.c.e.c.ExploreFacade@57] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
25-Aug-2017 20:05:00 2017-08-25 20:05:00,090 - WARN  [dataset.service-executor-2:o.a.h.u.NativeCodeLoader@62] - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
25-Aug-2017 20:05:00 2017-08-25 20:05:00,394 - INFO  [dataset.service-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.schedule.store, type name: ProgramScheduleStoreDataset, properties: {}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,426 - INFO  [dataset.executor-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.schedule.store, type meta: DatasetTypeMeta{name='ProgramScheduleStoreDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}, DatasetModuleMeta{name='app-fabric', className='co.cask.cdap.internal.app.AppFabricDatasetModule', jarLocation=null, jarLocationPath=null, types=[co.cask.cdap.internal.app.runtime.schedule.queue.JobQueueDataset, ProgramScheduleStoreDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,541 - INFO  [dataset.service-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.metrics.v3.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}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,547 - WARN  [dataset.service-executor-9:c.c.c.d.a.AuditPublishers@120] - Audit publisher is null, audit information will not be published
25-Aug-2017 20:05:00 2017-08-25 20:05:00,584 - INFO  [dataset.executor-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v3.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, jarLocationPath=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,661 - INFO  [LocalSchedulerService STARTING:o.q.s.RAMJobStore@155] - RAMJobStore initialized.
25-Aug-2017 20:05:00 2017-08-25 20:05:00,687 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.metrics.v2.entity, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,693 - INFO  [dataset.service-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.schedulestore, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,698 - INFO  [dataset.executor-executor-7: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,700 - INFO  [dataset.executor-executor-8: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, jarLocationPath=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,753 - INFO  [dataset.service-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.metrics.v3.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}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,772 - INFO  [dataset.executor-executor-6:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v3.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, jarLocationPath=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,814 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@69] - Started time scheduler
25-Aug-2017 20:05:00 2017-08-25 20:05:00,822 - INFO  [dataset.service-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.metrics.v3.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}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,834 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v3.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, jarLocationPath=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,884 - INFO  [dataset.service-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.metrics.v3.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}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,894 - INFO  [dataset.executor-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.metrics.v3.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, jarLocationPath=null, types=[co.cask.cdap.data2.dataset2.lib.table.inmemory.InMemoryMetricsTable, co.cask.cdap.data2.dataset2.lib.table.MetricsTable], usesModules=[], usedByModules=[cube]}]}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,901 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@78] - Started stream size scheduler
25-Aug-2017 20:05:00 2017-08-25 20:05:00,950 - INFO  [dataset.service-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.app.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
25-Aug-2017 20:05:00 2017-08-25 20:05:00,954 - INFO  [dataset.executor-executor-4:c.c.c.d.d.d.s.e.DatasetAdminService@101] - 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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:01 2017-08-25 20:05:01,086 - INFO  [ STARTING:c.c.c.s.CoreSchedulerService@215] - Schedule migration is completed for all namespaces.
25-Aug-2017 20:05:01 2017-08-25 20:05:01,109 - INFO  [dataset.service-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.job.queue, type name: co.cask.cdap.internal.app.runtime.schedule.queue.JobQueueDataset, properties: {}
25-Aug-2017 20:05:01 2017-08-25 20:05:01,115 - INFO  [dataset.executor-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.job.queue, type meta: DatasetTypeMeta{name='co.cask.cdap.internal.app.runtime.schedule.queue.JobQueueDataset', modules=[DatasetModuleMeta{name='orderedTable-memory', className='co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule', jarLocation=null, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}, DatasetModuleMeta{name='app-fabric', className='co.cask.cdap.internal.app.AppFabricDatasetModule', jarLocation=null, jarLocationPath=null, types=[co.cask.cdap.internal.app.runtime.schedule.queue.JobQueueDataset, ProgramScheduleStoreDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}
25-Aug-2017 20:05:01 2017-08-25 20:05:01,151 - INFO  [ STARTING:c.c.c.s.CoreSchedulerService$2@144] - Cleaning up jobs in state PENDING_LAUNCH.
25-Aug-2017 20:05:01 2017-08-25 20:05:01,154 - INFO  [ConstraintCheckerService STARTING:c.c.c.s.ConstraintCheckerService@107] - Starting ConstraintCheckerService.
25-Aug-2017 20:05:01 2017-08-25 20:05:01,283 - INFO  [ConstraintCheckerService STARTING:c.c.c.s.ConstraintCheckerService@117] - Started ConstraintCheckerService. state: STARTING
25-Aug-2017 20:05:01 2017-08-25 20:05:01,305 - INFO  [ScheduleNotificationSubscriberService STARTING:c.c.c.s.ScheduleNotificationSubscriberService@81] - Starting ScheduleNotificationSubscriberService
25-Aug-2017 20:05:01 2017-08-25 20:05:01,408 - INFO  [ProgramNotificationSubscriberService STARTING:c.c.c.i.a.s.ProgramNotificationSubscriberService@97] - Starting ProgramNotificationSubscriberService
25-Aug-2017 20:05:01 2017-08-25 20:05:01,454 - INFO  [ STARTING:c.c.c.s.CoreSchedulerService$1$1@118] - Started core scheduler service.
25-Aug-2017 20:05:01 2017-08-25 20:05:01,479 - INFO  [scheduler-subscriber-task-1:c.c.c.m.s.l.LevelDBTableFactory@95] - Messaging message table created at /tmp/CA-BUT-MR/junit2491585572742865872/junit1813849504512578662/messaging/system.tms.message
25-Aug-2017 20:05:01 2017-08-25 20:05:01,875 - WARN  [main:c.c.c.e.c.ExploreFacade@57] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
25-Aug-2017 20:05:01 2017-08-25 20:05:01,878 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@204] - Namespace namespace:default created with meta NamespaceMeta{name='default', description='Default Namespace', config=NamespaceConfig{schedulerQueueName='', rootDirectory='null', hbaseNamespace='null', hiveDatabase='null', principal='null', groupName='null', keytabURI='null', exploreAsPrincipal='true'}}
25-Aug-2017 20:05:06 2017-08-25 20:05:06,433 - INFO  [dataset.service-executor-2:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.artifact.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {conflict.level=COLUMN}
25-Aug-2017 20:05:06 2017-08-25 20:05:06,444 - INFO  [dataset.executor-executor-1: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:06 2017-08-25 20:05:06,482 - INFO  [dataset.service-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.system.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {scope=SYSTEM}
25-Aug-2017 20:05:06 2017-08-25 20:05:06,486 - INFO  [dataset.executor-executor-4: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}, DatasetModuleMeta{name='metadata', className='co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule', jarLocation=null, jarLocationPath=null, types=[metadataDataset, co.cask.cdap.data2.metadata.dataset.MetadataDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,061 - INFO  [main:c.c.c.i.a.d.p.DatasetModulesDeployer@76] - Not adding dataset type 'co.cask.cdap.api.dataset.lib.ObjectStore' because it is defined by the system.
25-Aug-2017 20:05:07 2017-08-25 20:05:07,065 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@74] - Adding dataset instance: movies
25-Aug-2017 20:05:07 2017-08-25 20:05:07,071 - INFO  [dataset.service-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset default.movies, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema="string", type={"isClass":true,"rawType":"java.lang.String"}}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,074 - INFO  [dataset.executor-executor-5: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,168 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@74] - Adding dataset instance: recommendations
25-Aug-2017 20:05:07 2017-08-25 20:05:07,174 - INFO  [dataset.service-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@280] - 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"}}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,178 - INFO  [dataset.executor-executor-2: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,287 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@74] - Adding dataset instance: ratings
25-Aug-2017 20:05:07 2017-08-25 20:05:07,292 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@280] - 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"}}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,296 - INFO  [dataset.executor-executor-3: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,530 - INFO  [main:c.c.c.i.a.d.p.StreamCreator$1@67] - Stream 'default.ratingsStream' created successfully.
25-Aug-2017 20:05:07 2017-08-25 20:05:07,565 - INFO  [dataset.service-executor-2:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.usage.registry, type name: UsageDataset, properties: {}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,567 - INFO  [dataset.executor-executor-6: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='usage', className='co.cask.cdap.data2.registry.UsageDatasetModule', jarLocation=null, jarLocationPath=null, types=[UsageDataset], usesModules=[orderedTable-memory], usedByModules=[]}]}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,905 - INFO  [dataset.service-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.config.store.table, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
25-Aug-2017 20:05:07 2017-08-25 20:05:07,908 - INFO  [dataset.executor-executor-3:c.c.c.d.d.d.s.e.DatasetAdminService@101] - 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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:08 2017-08-25 20:05:08,171 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@259] - Announced HTTP Service for Service program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService at /127.0.0.1:35790
25-Aug-2017 20:05:13 2017-08-25 20:05:13,264 - INFO  [dataset.service-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.owner.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {conflict.level=COLUMN}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,266 - INFO  [dataset.executor-executor-0:c.c.c.d.d.d.s.e.DatasetAdminService@101] - Creating dataset instance dataset:system.owner.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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,296 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService-b152b581-89d0-11e7-aa41-00000099c4b9:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@189] - Stopping Program: MovieDictionaryService
25-Aug-2017 20:05:13 2017-08-25 20:05:13,316 - INFO  [pcontroller-program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService-b152b581-89d0-11e7-aa41-00000099c4b9:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@202] - Program stopped: MovieDictionaryService
25-Aug-2017 20:05:13 2017-08-25 20:05:13,364 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@288] - Deleting namespace 'namespace:default'.
25-Aug-2017 20:05:13 2017-08-25 20:05:13,366 - WARN  [main:c.c.c.e.c.ExploreFacade@57] - Explore functionality for datasets is disabled. All calls to enable explore will be no-ops
25-Aug-2017 20:05:13 2017-08-25 20:05:13,447 - INFO  [dataset.service-executor-8:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.business.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {scope=USER}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,451 - INFO  [dataset.executor-executor-7: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}, DatasetModuleMeta{name='metadata', className='co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule', jarLocation=null, jarLocationPath=null, types=[metadataDataset, co.cask.cdap.data2.metadata.dataset.MetadataDataset], usesModules=[orderedTable-memory, core], usedByModules=[]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,551 - INFO  [dataset.service-executor-1:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.routestore, type name: co.cask.cdap.api.dataset.lib.KeyValueTable, properties: {}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,553 - INFO  [dataset.executor-executor-9:c.c.c.d.d.d.s.e.DatasetAdminService@101] - 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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,570 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@280] - Creating dataset system.workflow.stats, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,572 - INFO  [dataset.executor-executor-8:c.c.c.d.d.d.s.e.DatasetAdminService@101] - 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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,597 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@496] - Deleting dataset default.ratings
25-Aug-2017 20:05:13 2017-08-25 20:05:13,603 - INFO  [dataset.executor-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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,633 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@496] - Deleting dataset default.recommendations
25-Aug-2017 20:05:13 2017-08-25 20:05:13,637 - INFO  [dataset.executor-executor-9: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,664 - INFO  [dataset.service-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@496] - Deleting dataset default.movies
25-Aug-2017 20:05:13 2017-08-25 20:05:13,668 - INFO  [dataset.executor-executor-0: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, jarLocationPath=null, types=[table, co.cask.cdap.api.dataset.table.Table], usesModules=[], usedByModules=[core, objectMappedTable, cube, usage, lineage, app-fabric]}, DatasetModuleMeta{name='core', className='co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule', jarLocation=null, jarLocationPath=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, app-fabric]}]}
25-Aug-2017 20:05:13 2017-08-25 20:05:13,693 - WARN  [dataset.service-executor-1:c.c.c.d.d.d.t.DatasetTypeManager@411] - Deleting all modules from namespace namespace:default
25-Aug-2017 20:05:13 2017-08-25 20:05:13,781 - INFO  [main:c.c.c.i.a.n.AbstractNamespaceResourceDeleter@113] - All data for namespace 'namespace:default' deleted.
25-Aug-2017 20:05:13 2017-08-25 20:05:13,781 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@302] - Keeping the 'namespace:default' namespace after removing all data.
25-Aug-2017 20:05:13 2017-08-25 20:05:13,785 - INFO  [ProgramNotificationSubscriberService STOPPING:c.c.c.i.a.s.ProgramNotificationSubscriberService@119] - Stopped ProgramNotificationSubscriberService
25-Aug-2017 20:05:13 2017-08-25 20:05:13,785 - INFO  [ScheduleNotificationSubscriberService STOPPING:c.c.c.s.ScheduleNotificationSubscriberService@109] - Stopped ScheduleNotificationSubscriberService
25-Aug-2017 20:05:13 2017-08-25 20:05:13,786 - INFO  [ConstraintCheckerService STOPPING:c.c.c.s.ConstraintCheckerService@123] - Stopping ConstraintCheckerService.
25-Aug-2017 20:05:18 2017-08-25 20:05:18,787 - INFO  [ConstraintCheckerService STOPPING:c.c.c.s.ConstraintCheckerService@133] - Stopped ConstraintCheckerService.
25-Aug-2017 20:05:18 2017-08-25 20:05:18,788 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@91] - Stopped stream size scheduler
25-Aug-2017 20:05:18 2017-08-25 20:05:18,791 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@99] - Stopped time scheduler
25-Aug-2017 20:05:18 2017-08-25 20:05:18,791 - INFO  [ STOPPING:c.c.c.s.CoreSchedulerService$1$1@126] - Stopped core scheduler service.
25-Aug-2017 20:05:18 2017-08-25 20:05:18,793 - INFO  [MetricsQueryService STOPPING:c.c.c.m.q.MetricsQueryService@104] - Stopping Metrics Service...
25-Aug-2017 20:05:19 2017-08-25 20:05:19,032 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@198] - Stopping DatasetService...
25-Aug-2017 20:05:22 2017-08-25 20:05:22,035 - INFO  [NettyHttpService STOPPING:c.c.c.d.d.d.s.DatasetTypeHandler@65] - Stopping DatasetTypeHandler
25-Aug-2017 20:05:22 2017-08-25 20:05:22,038 - INFO  [DatasetOpExecutorService STOPPING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@91] - Stopping DatasetOpExecutorService...
25-Aug-2017 20:05:22 2017-08-25 20:05:22,051 - INFO  [CoreMessagingService STOPPING:c.c.c.m.s.CoreMessagingService@263] - Core Messaging Service stopped
25-Aug-2017 20:05:22 Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 29.028 sec <<< FAILURE!
25-Aug-2017 20:05:22 testRecommendation(co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest)  Time elapsed: 11.469 sec  <<< ERROR!
25-Aug-2017 20:05:22 java.lang.IllegalStateException: Program state for 'program:default.MovieRecommender.-SNAPSHOT.service.MovieDictionaryService' not as expected. Expected 'true'.
25-Aug-2017 20:05:22         at co.cask.cdap.test.AbstractProgramManager.waitForStatus(AbstractProgramManager.java:132)
25-Aug-2017 20:05:22         at co.cask.cdap.test.AbstractProgramManager.waitForStatus(AbstractProgramManager.java:101)
25-Aug-2017 20:05:22         at co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest.sendMovieData(MovieRecommenderAppTest.java:106)
25-Aug-2017 20:05:22         at co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest.testRecommendation(MovieRecommenderAppTest.java:60)
25-Aug-2017 20:05:22
25-Aug-2017 20:05:22
25-Aug-2017 20:05:22 Results :
25-Aug-2017 20:05:22
25-Aug-2017 20:05:22 Tests in error:
25-Aug-2017 20:05:22   MovieRecommenderAppTest.testRecommendation:60->sendMovieData:106 » IllegalState
25-Aug-2017 20:05:22
25-Aug-2017 20:05:22 Tests run: 1, Failures: 0, Errors: 1, Skipped: 0
25-Aug-2017 20:05:22
25-Aug-2017 20:05:22 [INFO] ------------------------------------------------------------------------
25-Aug-2017 20:05:22 [INFO] BUILD FAILURE
25-Aug-2017 20:05:22 [INFO] ------------------------------------------------------------------------
25-Aug-2017 20:05:22 [INFO] Total time: 1:12.979s
25-Aug-2017 20:05:22 [INFO] Finished at: Fri Aug 25 20:05:22 UTC 2017
25-Aug-2017 20:05:22 [INFO] Final Memory: 86M/789M
25-Aug-2017 20:05:22 [INFO] ------------------------------------------------------------------------
25-Aug-2017 20:05:22 [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.14.1:test (default-test) on project MovieRecommender: There are test failures.
25-Aug-2017 20:05:22 [ERROR]
25-Aug-2017 20:05:22 [ERROR] Please refer to /var/bamboo/xml-data/build-dir/CA-BUT-MR/MovieRecommender/target/surefire-reports for the individual test results.
25-Aug-2017 20:05:22 [ERROR] -> [Help 1]
25-Aug-2017 20:05:22 [ERROR]
25-Aug-2017 20:05:22 [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
25-Aug-2017 20:05:22 [ERROR] Re-run Maven using the -X switch to enable full debug logging.
25-Aug-2017 20:05:22 [ERROR]
25-Aug-2017 20:05:22 [ERROR] For more information about the errors and possible solutions, please read the following articles:
25-Aug-2017 20:05:22 [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
25-Aug-2017 20:05:22 Failing task since return code of [/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/CA-BUT-MR clean package] was 1 while expected 0
25-Aug-2017 20:05:22 Parsing test results under /var/bamboo/xml-data/build-dir/CA-BUT-MR...
25-Aug-2017 20:05:23 Finished task 'MovieRecommender' with result: Failed
25-Aug-2017 20:05:23 Running post build plugin 'Docker Container Cleanup'
25-Aug-2017 20:05:23 Running post build plugin 'NCover Results Collector'
25-Aug-2017 20:05:23 Running post build plugin 'Clover Results Collector'
25-Aug-2017 20:05:23 Running post build plugin 'npm Cache Cleanup'
25-Aug-2017 20:05:23 Running post build plugin 'Artifact Copier'
25-Aug-2017 20:05:23 Finalising the build...
25-Aug-2017 20:05:23 Stopping timer.
25-Aug-2017 20:05:23 Build CA-BUT-MR-69 completed.
25-Aug-2017 20:05:23 Running on server: post build plugin 'NCover Results Collector'
25-Aug-2017 20:05:23 Running on server: post build plugin 'Build Hanging Detection Configuration'
25-Aug-2017 20:05:23 Running on server: post build plugin 'Clover Delta Calculator'
25-Aug-2017 20:05:23 Running on server: post build plugin 'Maven Dependencies Postprocessor'
25-Aug-2017 20:05:23 All post build plugins have finished
25-Aug-2017 20:05:23 Generating build results summary...
25-Aug-2017 20:05:23 Saving build results to disk...
25-Aug-2017 20:05:23 Logging substituted variables...
25-Aug-2017 20:05:23 Indexing build results...
25-Aug-2017 20:05:23 Finished building CA-BUT-MR-69.