Build #50

Builds and runs unit tests for CDAP Apps repository

Build: #50 failed

Job: MovieRecommender was successful

Stages & jobs

  1. Build Stage

Build log

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

09-Apr-2017 02:00:11 Build CDAP Apps - Build and Unit Test - release-cdap-3.3-compatible - MovieRecommender #50 (CA-BUT4-MR-50) started building on agent bamboo-agent12.prod.continuuity.net
09-Apr-2017 02:00:11 Remote Agent
09-Apr-2017 02:00:11 Build working directory is /var/bamboo/xml-data/build-dir/CA-BUT4-MR
09-Apr-2017 02:00:11 Executing build CDAP Apps - Build and Unit Test - release-cdap-3.3-compatible - MovieRecommender #50 (CA-BUT4-MR-50)
09-Apr-2017 02:00:11 Starting task 'Checkout Default Repository' of type 'com.atlassian.bamboo.plugins.vcs:task.vcs.checkout'
09-Apr-2017 02:00:11 Build always requires a clean checkout
09-Apr-2017 02:00:11 Cleaning build directory '/var/bamboo/xml-data/build-dir/CA-BUT4-MR'
09-Apr-2017 02:00:11 Updating source code to revision: a56a49baabb1125e784041c5ce7e9047ebfec0c4
09-Apr-2017 02:00:11 Fetching 'refs/heads/release/cdap-3.3-compatible' from 'https://github.com/caskdata/cdap-apps.git'.
09-Apr-2017 02:00:11 Checking out revision a56a49baabb1125e784041c5ce7e9047ebfec0c4.
09-Apr-2017 02:00:11 Creating local git repository in '/var/bamboo/xml-data/build-dir/CA-BUT4-MR/.git'.
09-Apr-2017 02:00:11 Cloning into '/var/bamboo/xml-data/build-dir/CA-BUT4-MR'...
09-Apr-2017 02:00:11 done.
09-Apr-2017 02:00:11 Switched to a new branch 'release/cdap-3.3-compatible'
09-Apr-2017 02:00:11 Branch release/cdap-3.3-compatible set up to track remote branch release/cdap-3.3-compatible from origin.
09-Apr-2017 02:00:11 Updated source code to revision: a56a49baabb1125e784041c5ce7e9047ebfec0c4
09-Apr-2017 02:00:11 Finished task 'Checkout Default Repository' with result: Success
09-Apr-2017 02:00:11 Running pre-build action: VCS Version Collector
09-Apr-2017 02:00:11 Running pre-build action: Hung Build Killer PreBuildAction
09-Apr-2017 02:00:11 Starting task 'MovieRecommender' of type 'com.atlassian.bamboo.plugins.maven:task.builder.mvn3'
09-Apr-2017 02:00:11
Beginning to execute external process for build 'CDAP Apps - Build and Unit Test - release-cdap-3.3-compatible - MovieRecommender #50 (CA-BUT4-MR-50)'
... running command line:
/opt/maven/bin/mvn --batch-mode -Djava.io.tmpdir=/tmp/CA-BUT4-MR clean package
... in: /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender
... using extra environment variables:
bamboo_planRepository_1_branch=release/cdap-3.3-compatible
bamboo_docs_upload_host=docs1.cask.co
bamboo_planRepository_1_branchDisplayName=release/cdap-3.3-compatible
bamboo_repository_revision_number=a56a49baabb1125e784041c5ce7e9047ebfec0c4
MAVEN2_HOME=/opt/maven
bamboo_docs_s3_bucket=docs.cask.co
bamboo_capability_system_builder_command_s3cmd=/usr/bin/s3cmd
bamboo_buildKey=CA-BUT4-MR
bamboo_gpg_password=********
bamboo_ops_gpg_password=********
bamboo_shortJobName=MovieRecommender
bamboo_buildResultsUrl=http://builds.cask.co/browse/CA-BUT4-MR-50
bamboo_repository_s3_bucket=repository.cask.co
bamboo_planRepository_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_capability_system_builder_mvn3_Maven_3_1=/opt/maven
bamboo_aws_secret_access_key_password=********
bamboo_agentId=50921473
bamboo_capability_Swapfile=true
bamboo_planRepository_revision=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_remote_docs_base=/var/www/docs
bamboo_planRepository_previousRevision=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_capability_RHEL=rhel
bamboo_capability_freight=true
bamboo_repository_branch_name=release/cdap-3.3-compatible
bamboo_docs_upload_user=bamboo
bamboo_kdc_address_password=********
bamboo_capability_GPG=GPG
bamboo_capability_system_jdk_Oracle_JDK_8=/usr/lib/jvm/jdk8
JAVA_HOME=/usr/lib/jvm/jdk1.7.0_75
bamboo_capability_system_jdk_Oracle_JDK_7=/usr/lib/jvm/jdk7
bamboo_repository_41353655_git_branch=release/cdap-3.3-compatible
bamboo_downloads_s3_bucket=downloads.cask.co
bamboo_planRepository_branch=release/cdap-3.3-compatible
bamboo_docs_server_list=docs1.cask.co,docs2.cask.co
bamboo_market_s3_access_key_password=********
bamboo_planRepository_1_type=gitv2
bamboo_planRepository_branchName=release/cdap-3.3-compatible
bamboo_capability_SPHINX=Sphinx
bamboo_capability_rsync=true
bamboo_market_cloudfront_secret_key_password=********
bamboo_repository_41353655_git_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_capability_system_jdk_JDK=/usr/lib/jvm/jdk1.7.0_75
bamboo_planRepository_type=gitv2
bamboo_planRepository_1_username=
bamboo_capability_expect=true
bamboo_capability_RSYNC=rsync
useMavenReturnCode=false
bamboo_capability_system_builder_node_Node_js=/usr/bin/node
bamboo_docs_upload_target=/data/s3stg/incoming
bamboo_capability_system_builder_mvn3_Maven_3=/opt/maven
bamboo_planKey=CA-BUT4
bamboo_planRepository_username=
bamboo_planRepository_1_branchName=release/cdap-3.3-compatible
bamboo_capability_s3cmd=true
bamboo_resultsUrl=http://builds.cask.co/browse/CA-BUT4-MR-50
bamboo_capability_Sphinx=true
bamboo_planRepository_1_name=CDAP Apps
bamboo_build_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT4-MR
bamboo_shortPlanName=release-cdap-3.3-compatible
bamboo_planRepository_name=CDAP Apps
bamboo_repository_41353655_revision_number=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_capability_git_lfs=true
bamboo_buildNumber=50
bamboo_docs_server1=docs1.cask.co
bamboo_planName=CDAP Apps - Build and Unit Test - release-cdap-3.3-compatible
bamboo_shortPlanKey=BUT4
bamboo_docs_server2=docs2.cask.co
bamboo_shortJobKey=MR
bamboo_repository_41353655_name=CDAP Apps
bamboo_repository_previous_revision_number=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_buildTimeStamp=2017-04-09T02:00:01.195Z
M2_HOME=/opt/maven
bamboo_s3_bucket=repository.cask.co
bamboo_buildResultKey=CA-BUT4-MR-50
bamboo_capability_system_builder_command_Docker=/usr/bin/docker
bamboo_repository_git_branch=release/cdap-3.3-compatible
bamboo_market_s3_secret_key_password=********
bamboo_buildPlanName=CDAP Apps - Build and Unit Test - release-cdap-3.3-compatible - MovieRecommender
bamboo_capability_system_builder_command_fpm=/opt/rbenv/shims/fpm
bamboo_capability_system_jdk_JDK_1_8_0_101=/usr/lib/jvm/jdk1.8.0_101
bamboo_planRepository_1_revision=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_capability_Platform=rhel
bamboo_repository_name=CDAP Apps
bamboo_repository_41353655_git_username=
bamboo_capability_system_docker_executable=/usr/bin/docker
MAVEN_OPTS=-Xmx2048m
bamboo_agentWorkingDirectory=/var/bamboo/xml-data/build-dir
bamboo_capability_system_git_executable=/usr/local/bin/git
bamboo_planRepository_1_previousRevision=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_repository_git_username=
bamboo_capability_system_builder_command_Ruby=/opt/rbenv/shims/ruby
bamboo_planRepository_branchDisplayName=release/cdap-3.3-compatible
bamboo_aws_access_key_password=********
bamboo_repository_41353655_previous_revision_number=a56a49baabb1125e784041c5ce7e9047ebfec0c4
bamboo_capability_system_builder_mvn3_Maven_3_x=/opt/maven
bamboo_plan_storageTag=CA-BUT4
bamboo_repository_git_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_repository_41353655_branch_name=release/cdap-3.3-compatible
bamboo_market_cloudfront_access_key_password=********
bamboo_working_directory=/var/bamboo/xml-data/build-dir/CA-BUT4-MR
bamboo_planRepository_1_repositoryUrl=https://github.com/caskdata/cdap-apps.git
bamboo_capability_createrepo=true
bamboo_capability_system_jdk_JDK_1_8=/usr/lib/jvm/jdk1.8.0_101
bamboo_capability_rpm_build=true
bamboo_capability_system_jdk_JDK_1_7=/usr/lib/jvm/jdk1.7.0_75
PATH=/usr/lib/jvm/jdk1.7.0_75/bin:/usr/lib/jvm/java/bin:/opt/rbenv/shims:/opt/rbenv/bin:/opt/rbenv/plugins/ruby_build/bin:/usr/local/maven-3.1.1/bin:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/bamboo/bin
09-Apr-2017 02:00:13 [INFO] Scanning for projects...
09-Apr-2017 02:00:14 [INFO]                                                                         
09-Apr-2017 02:00:14 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:00:14 [INFO] Building Movie Recommender Application 0.7.0
09-Apr-2017 02:00:14 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:00:18 [INFO]
09-Apr-2017 02:00:18 [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ MovieRecommender ---
09-Apr-2017 02:00:18 [INFO]
09-Apr-2017 02:00:18 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ MovieRecommender ---
09-Apr-2017 02:00:18 [INFO] Using 'UTF-8' encoding to copy filtered resources.
09-Apr-2017 02:00:18 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/main/resources
09-Apr-2017 02:00:18 [INFO]
09-Apr-2017 02:00:18 [INFO] --- maven-scala-plugin:2.15.2:add-source (scala-compile-first) @ MovieRecommender ---
09-Apr-2017 02:00:19 [INFO] Add Source directory: /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/main/scala
09-Apr-2017 02:00:19 [INFO] Add Test Source directory: /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/test/scala
09-Apr-2017 02:00:19 [INFO]
09-Apr-2017 02:00:19 [INFO] --- maven-scala-plugin:2.15.2:compile (scala-compile-first) @ MovieRecommender ---
09-Apr-2017 02:00:19 [INFO] Checking for multiple versions of scala
09-Apr-2017 02:00:23 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
09-Apr-2017 02:00:23 [WARNING]  com.twitter:chill_2.10:0.5.0 requires scala version: 2.10.4
09-Apr-2017 02:00:23 [WARNING] Multiple versions of scala libraries detected!
09-Apr-2017 02:00:23 [INFO] includes = [**/*.scala,**/*.java,]
09-Apr-2017 02:00:23 [INFO] excludes = []
09-Apr-2017 02:00:23 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/main/java:-1: info: compiling
09-Apr-2017 02:00:23 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/main/scala:-1: info: compiling
09-Apr-2017 02:00:23 [INFO] Compiling 7 source files to /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/classes at 1491703223640
09-Apr-2017 02:00:35 [INFO] prepare-compile in 0 s
09-Apr-2017 02:00:35 [INFO] compile in 11 s
09-Apr-2017 02:00:35 [INFO]
09-Apr-2017 02:00:35 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ MovieRecommender ---
09-Apr-2017 02:00:35 [INFO] Changes detected - recompiling the module!
09-Apr-2017 02:00:35 [INFO] Compiling 6 source files to /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/classes
09-Apr-2017 02:00:36 [INFO]
09-Apr-2017 02:00:36 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ MovieRecommender ---
09-Apr-2017 02:00:36 [INFO] Using 'UTF-8' encoding to copy filtered resources.
09-Apr-2017 02:00:36 [INFO] skip non existing resourceDirectory /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/test/resources
09-Apr-2017 02:00:36 [INFO]
09-Apr-2017 02:00:36 [INFO] --- maven-scala-plugin:2.15.2:testCompile (scala-test-compile) @ MovieRecommender ---
09-Apr-2017 02:00:36 [INFO] Checking for multiple versions of scala
09-Apr-2017 02:00:37 [WARNING]  Expected all dependencies to require Scala version: 2.10.3
09-Apr-2017 02:00:37 [WARNING]  com.twitter:chill_2.10:0.5.0 requires scala version: 2.10.4
09-Apr-2017 02:00:37 [WARNING] Multiple versions of scala libraries detected!
09-Apr-2017 02:00:37 [INFO] includes = [**/*.scala,**/*.java,]
09-Apr-2017 02:00:37 [INFO] excludes = []
09-Apr-2017 02:00:37 [INFO] /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/src/test/java:-1: info: compiling
09-Apr-2017 02:00:37 [INFO] Compiling 1 source files to /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/test-classes at 1491703237393
09-Apr-2017 02:00:40 [INFO] prepare-compile in 0 s
09-Apr-2017 02:00:40 [INFO] compile in 3 s
09-Apr-2017 02:00:40 [INFO]
09-Apr-2017 02:00:40 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ MovieRecommender ---
09-Apr-2017 02:00:40 [INFO] Changes detected - recompiling the module!
09-Apr-2017 02:00:40 [INFO] Compiling 1 source file to /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/test-classes
09-Apr-2017 02:00:41 [INFO]
09-Apr-2017 02:00:41 [INFO] --- maven-checkstyle-plugin:2.12.1:check (validate) @ MovieRecommender ---
09-Apr-2017 02:00:43 [INFO] Starting audit...
09-Apr-2017 02:00:43 Audit done.
09-Apr-2017 02:00:43
09-Apr-2017 02:00:43 [INFO]
09-Apr-2017 02:00:43 [INFO] --- maven-surefire-plugin:2.14.1:test (default-test) @ MovieRecommender ---
09-Apr-2017 02:00:43 [INFO] Surefire report directory: /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/surefire-reports
09-Apr-2017 02:00:43
09-Apr-2017 02:00:43 -------------------------------------------------------
09-Apr-2017 02:00:43 T E S T S
09-Apr-2017 02:00:43 -------------------------------------------------------
09-Apr-2017 02:00:44 Running co.cask.cdap.apps.movierecommender.MovieRecommenderAppTest
09-Apr-2017 02:00:47 2017-04-09 02:00:47,610 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@81] - Starting DatasetOpExecutorService...
09-Apr-2017 02:00:47 2017-04-09 02:00:47,722 - INFO  [DatasetOpExecutorService STARTING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@96] - DatasetOpExecutorService started successfully on /127.0.0.1:39909
09-Apr-2017 02:00:47 2017-04-09 02:00:47,845 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@123] - Starting DatasetService...
09-Apr-2017 02:00:47 2017-04-09 02:00:47,855 - INFO  [DatasetTypeManager STARTING:c.c.c.d.d.InMemoryDatasetFramework@225] - Created dataset dataset:system.datasets.instance of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetInstanceMDS
09-Apr-2017 02:00:47 2017-04-09 02:00:47,880 - INFO  [DatasetTypeManager STARTING:c.c.c.d.d.InMemoryDatasetFramework@225] - Created dataset dataset:system.datasets.type of type co.cask.cdap.data2.datafabric.dataset.service.mds.DatasetTypeMDS
09-Apr-2017 02:00:47 2017-04-09 02:00:47,964 - INFO  [NettyHttpService STARTING:c.c.c.d.d.d.s.DatasetTypeHandler@83] - Starting DatasetTypeHandler
09-Apr-2017 02:00:47 2017-04-09 02:00:47,970 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService$1@138] - Discovered dataset.executor service
09-Apr-2017 02:00:47 2017-04-09 02:00:47,973 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@185] - Waiting for dataset.executor service to be discoverable
09-Apr-2017 02:00:47 2017-04-09 02:00:47,974 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@158] - Announcing DatasetService for discovery...
09-Apr-2017 02:00:47 2017-04-09 02:00:47,975 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@172] - DatasetService started successfully on /127.0.0.1:33316
09-Apr-2017 02:00:48 2017-04-09 02:00:48,220 - INFO  [main:c.c.c.m.q.MetricsQueryService@80] - Configuring MetricsService , address: null, backlog connections: 20000, execthreads: 20, bossthreads: 1, workerthreads: 10
09-Apr-2017 02:00:48 2017-04-09 02:00:48,221 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@94] - Starting Metrics Service...
09-Apr-2017 02:00:48 2017-04-09 02:00:48,227 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@96] - Started Metrics HTTP Service...
09-Apr-2017 02:00:48 2017-04-09 02:00:48,228 - INFO  [MetricsQueryService STARTING:c.c.c.m.q.MetricsQueryService@110] - Metrics Service started successfully on /127.0.0.1:33478
09-Apr-2017 02:00:48 2017-04-09 02:00:48,244 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:00:48 2017-04-09 02:00:48,283 - INFO  [LocalSchedulerService STARTING:o.q.s.RAMJobStore@155] - RAMJobStore initialized.
09-Apr-2017 02:00:48 2017-04-09 02:00:48,470 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.schedulestore, type name: co.cask.cdap.api.dataset.table.Table, properties: {}
09-Apr-2017 02:00:48 2017-04-09 02:00:48,480 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:00:48 2017-04-09 02:00:48,529 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@67] - Started time scheduler
09-Apr-2017 02:00:48 2017-04-09 02:00:48,550 - INFO  [LocalSchedulerService STARTING:c.c.c.i.a.r.s.AbstractSchedulerService@76] - Started stream size scheduler
09-Apr-2017 02:00:48 2017-04-09 02:00:48,562 - INFO  [main:c.c.c.e.s.h.BaseHiveExploreService@210] - Active handle timeout = 86400 secs
09-Apr-2017 02:00:48 2017-04-09 02:00:48,562 - INFO  [main:c.c.c.e.s.h.BaseHiveExploreService@211] - Inactive handle timeout = 3600 secs
09-Apr-2017 02:00:48 2017-04-09 02:00:48,562 - INFO  [main:c.c.c.e.s.h.BaseHiveExploreService@212] - Cleanup job schedule = 60 secs
09-Apr-2017 02:00:48 2017-04-09 02:00:48,659 - INFO  [ExploreExecutorService STARTING:c.c.c.e.e.ExploreExecutorService@88] - Starting ExploreExecutorService...
09-Apr-2017 02:00:48 2017-04-09 02:00:48,668 - INFO  [ExploreExecutorService STARTING:c.c.c.e.s.h.BaseHiveExploreService@1025] - Checking for tables that need upgrade...
09-Apr-2017 02:00:48 2017-04-09 02:00:48,668 - INFO  [Hive14ExploreService STARTING:c.c.c.e.s.h.BaseHiveExploreService@274] - Starting BaseHiveExploreService...
09-Apr-2017 02:00:49 2017-04-09 02:00:49,263 - INFO  [netty-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.metrics.v2.table.ts.1, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12],[0,0,0,13]], dataset.table.ttl=7200, dataset.table.readless.increment=true}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,269 - INFO  [netty-executor-8:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.metrics.v2.table.ts.1, type meta: DatasetTypeMeta{name=co.cask.cdap.data2.dataset2.lib.table.MetricsTable, modules=DatasetModuleMeta{name=metricsTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule, jarLocation=null, usesModules=, usedByModules=cube}}, props: DatasetProperties{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],[0,0,0,13]], dataset.table.ttl=7200, dataset.table.readless.increment=true}}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,294 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:00:49 2017-04-09 02:00:49,312 - INFO  [netty-executor-0:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.metrics.v2.entity, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,322 - INFO  [netty-executor-4:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=cube}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,351 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.metrics.v2.table.ts.60, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12],[0,0,0,13]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,357 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.metrics.v2.table.ts.60, type meta: DatasetTypeMeta{name=co.cask.cdap.data2.dataset2.lib.table.MetricsTable, modules=DatasetModuleMeta{name=metricsTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule, jarLocation=null, usesModules=, usedByModules=cube}}, props: DatasetProperties{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],[0,0,0,13]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,371 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:00:49 2017-04-09 02:00:49,383 - INFO  [netty-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.metrics.v2.table.ts.3600, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12],[0,0,0,13]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,387 - INFO  [netty-executor-4:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.metrics.v2.table.ts.3600, type meta: DatasetTypeMeta{name=co.cask.cdap.data2.dataset2.lib.table.MetricsTable, modules=DatasetModuleMeta{name=metricsTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule, jarLocation=null, usesModules=, usedByModules=cube}}, props: DatasetProperties{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],[0,0,0,13]], dataset.table.ttl=2592000, dataset.table.readless.increment=true}}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,402 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:00:49 2017-04-09 02:00:49,423 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.metrics.v2.table.ts.2147483647, type name: co.cask.cdap.data2.dataset2.lib.table.MetricsTable, properties: {hbase.splits=[[0,0,0,2],[0,0,0,3],[0,0,0,4],[0,0,0,5],[0,0,0,6],[0,0,0,7],[0,0,0,8],[0,0,0,9],[0,0,0,10],[0,0,0,11],[0,0,0,12],[0,0,0,13]], dataset.table.readless.increment=true}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,429 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.metrics.v2.table.ts.2147483647, type meta: DatasetTypeMeta{name=co.cask.cdap.data2.dataset2.lib.table.MetricsTable, modules=DatasetModuleMeta{name=metricsTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryMetricsTableModule, jarLocation=null, usesModules=, usedByModules=cube}}, props: DatasetProperties{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],[0,0,0,13]], dataset.table.readless.increment=true}}
09-Apr-2017 02:00:49 2017-04-09 02:00:49,445 - INFO  [metrics-cleanup:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:00:49 2017-04-09 02:00:49,474 - WARN  [Hive14ExploreService STARTING:o.a.h.u.NativeCodeLoader@62] - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
09-Apr-2017 02:00:49 2017-04-09 02:00:49,661 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 0: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:00:49 2017-04-09 02:00:49,691 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:00:49 log4j:WARN No appenders could be found for logger (DataNucleus.General).
09-Apr-2017 02:00:49 log4j:WARN Please initialize the log4j system properly.
09-Apr-2017 02:00:49 log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
09-Apr-2017 02:00:52 2017-04-09 02:00:52,489 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@350] - Setting MetaStore object pin classes with hive.metastore.cache.pinobjtypes="Table,StorageDescriptor,SerDeInfo,Partition,Database,Type,FieldSchema,Order"
09-Apr-2017 02:00:56 2017-04-09 02:00:56,521 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:00:56 2017-04-09 02:00:56,521 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:00:56 2017-04-09 02:00:56,671 - WARN  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@6599] - Version information not found in metastore. hive.metastore.schema.verification is not enabled so recording the schema version 1.1.0
09-Apr-2017 02:00:56 2017-04-09 02:00:56,840 - WARN  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@548] - Failed to get database default, returning NoSuchObjectException
09-Apr-2017 02:00:57 2017-04-09 02:00:57,240 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@649] - Added admin role in metastore
09-Apr-2017 02:00:57 2017-04-09 02:00:57,246 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@658] - Added public role in metastore
09-Apr-2017 02:00:57 2017-04-09 02:00:57,364 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@698] - No user is added in admin role, since config is empty
09-Apr-2017 02:00:57 2017-04-09 02:00:57,566 - INFO  [Hive14ExploreService STARTING:o.a.h.c.Configuration@996] - dfs.umaskmode is deprecated. Instead, use fs.permissions.umask-mode
09-Apr-2017 02:00:57 2017-04-09 02:00:57,589 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo
09-Apr-2017 02:00:57 2017-04-09 02:00:57,593 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/bamboo
09-Apr-2017 02:00:57 2017-04-09 02:00:57,598 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/f1b05b99-593d-4387-881c-358c25228f1d_resources
09-Apr-2017 02:00:57 2017-04-09 02:00:57,602 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/f1b05b99-593d-4387-881c-358c25228f1d
09-Apr-2017 02:00:57 2017-04-09 02:00:57,609 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/bamboo/f1b05b99-593d-4387-881c-358c25228f1d
09-Apr-2017 02:00:57 2017-04-09 02:00:57,614 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/f1b05b99-593d-4387-881c-358c25228f1d/_tmp_space.db
09-Apr-2017 02:00:57 2017-04-09 02:00:57,616 - INFO  [Hive14ExploreService STARTING:o.a.h.h.q.s.SessionState@488] - No Tez session required at this point. hive.execution.engine=mr.
09-Apr-2017 02:00:57 2017-04-09 02:00:57,913 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:00:57 2017-04-09 02:00:57,929 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:00:57 2017-04-09 02:00:57,929 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:00:57 2017-04-09 02:00:57,930 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 0: get_databases: default
09-Apr-2017 02:00:57 2017-04-09 02:00:57,930 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_databases: default        
09-Apr-2017 02:00:57 2017-04-09 02:00:57,950 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 0: Shutting down the object store...
09-Apr-2017 02:00:57 2017-04-09 02:00:57,951 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Shutting down the object store...        
09-Apr-2017 02:00:57 2017-04-09 02:00:57,951 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 0: Metastore shutdown complete.
09-Apr-2017 02:00:57 2017-04-09 02:00:57,951 - INFO  [Hive14ExploreService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Metastore shutdown complete.        
09-Apr-2017 02:00:58 2017-04-09 02:00:58,052 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 1: get_all_tables: db=default
09-Apr-2017 02:00:58 2017-04-09 02:00:58,053 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_all_tables: db=default        
09-Apr-2017 02:00:58 2017-04-09 02:00:58,053 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 1: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:00:58 2017-04-09 02:00:58,056 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:00:58 2017-04-09 02:00:58,067 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:00:58 2017-04-09 02:00:58,067 - INFO  [ExploreExecutorService STARTING:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:00:58 2017-04-09 02:00:58,103 - INFO  [ExploreExecutorService STARTING:c.c.c.e.e.ExploreExecutorService@109] - ExploreExecutorService started successfully on /127.0.0.1:34965
09-Apr-2017 02:00:58 2017-04-09 02:00:58,117 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:00:58 2017-04-09 02:00:58,118 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:00:58 2017-04-09 02:00:58,119 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:00:58 2017-04-09 02:00:58,223 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:00:58 2017-04-09 02:00:58,248 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.app.meta, type name: table, properties: {}
09-Apr-2017 02:00:58 2017-04-09 02:00:58,252 - INFO  [netty-executor-3:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.app.meta, type meta: DatasetTypeMeta{name=table, modules=DatasetModuleMeta{name=orderedTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule, jarLocation=null, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:07 2017-04-09 02:01:07,389 - INFO  [main:c.c.c.i.AppFabricClient@361] - Created deployedJar at /tmp/CA-BUT4-MR/junit121641612177161762/junit4404979611743967187/co.cask.cdap.apps.movierecommender.MovieRecommenderApp.e9baacc6-a096-415a-9d4e-30b592b99513.jar
09-Apr-2017 02:01:07 2017-04-09 02:01:07,520 - INFO  [netty-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.system.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {}
09-Apr-2017 02:01:07 2017-04-09 02:01:07,526 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata},DatasetModuleMeta{name=metadata, className=co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule, jarLocation=null, usesModules=orderedTable-memory,core, usedByModules=}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:07 2017-04-09 02:01:07,609 - INFO  [netty-executor-2:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.artifact.meta, type name: co.cask.cdap.api.dataset.table.Table, properties: {conflict.level=COLUMN}
09-Apr-2017 02:01:07 2017-04-09 02:01:07,612 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage}}, props: DatasetProperties{properties={conflict.level=COLUMN}}
09-Apr-2017 02:01:07 2017-04-09 02:01:07,703 - WARN  [main:c.c.c.i.a.r.a.ArtifactRepository@94] - Ignoring /opt/cdap/master/artifacts because it is not a directory.
09-Apr-2017 02:01:07 2017-04-09 02:01:07,997 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding instance: movies
09-Apr-2017 02:01:08 2017-04-09 02:01:08,013 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset default.movies, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema="string", type={"isClass":true,"rawType":"java.lang.String"}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,017 - INFO  [netty-executor-6:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}, props: DatasetProperties{properties={schema="string", type={"isClass":true,"rawType":"java.lang.String"}}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,171 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding instance: recommendations
09-Apr-2017 02:01:08 2017-04-09 02:01:08,187 - INFO  [netty-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset default.recommendations, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,191 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}, props: DatasetProperties{properties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,334 - INFO  [main:c.c.c.i.a.d.p.DatasetInstanceCreator@60] - Adding instance: ratings
09-Apr-2017 02:01:08 2017-04-09 02:01:08,341 - INFO  [netty-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset default.ratings, type name: co.cask.cdap.api.dataset.lib.ObjectStore, properties: {schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,344 - INFO  [netty-executor-1:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}, props: DatasetProperties{properties={schema={"type":"record","name":"co.cask.cdap.apps.movierecommender.UserScore","fields":[{"name":"movieID","type":"int"},{"name":"rating","type":"int"},{"name":"userID","type":"int"}]}, type={"isClass":true,"rawType":"co.cask.cdap.apps.movierecommender.UserScore"}}}
09-Apr-2017 02:01:08 2017-04-09 02:01:08,617 - INFO  [netty-executor-5:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/3809f8a9-3f77-4db7-801e-727280f9183c_resources
09-Apr-2017 02:01:08 2017-04-09 02:01:08,620 - INFO  [netty-executor-5:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/3809f8a9-3f77-4db7-801e-727280f9183c
09-Apr-2017 02:01:08 2017-04-09 02:01:08,625 - INFO  [netty-executor-5:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/bamboo/3809f8a9-3f77-4db7-801e-727280f9183c
09-Apr-2017 02:01:08 2017-04-09 02:01:08,633 - INFO  [netty-executor-5:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/3809f8a9-3f77-4db7-801e-727280f9183c/_tmp_space.db
09-Apr-2017 02:01:08 2017-04-09 02:01:08,633 - INFO  [netty-executor-5:o.a.h.h.q.s.SessionState@488] - No Tez session required at this point. hive.execution.engine=mr.
09-Apr-2017 02:01:08 2017-04-09 02:01:08,665 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=compile from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:08 2017-04-09 02:01:08,701 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=parse from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,029 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=parse start=1491703268701 end=1491703269029 duration=328 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,034 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=semanticAnalyze from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,106 - INFO  [netty-executor-5:o.a.h.h.q.p.SemanticAnalyzer@10146] - Starting Semantic Analysis
09-Apr-2017 02:01:09 2017-04-09 02:01:09,148 - INFO  [netty-executor-5:o.a.h.h.q.p.SemanticAnalyzer@10773] - Creating table default.stream_ratingsstream position=36
09-Apr-2017 02:01:09 2017-04-09 02:01:09,162 - INFO  [netty-executor-5:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 2: get_table : db=default tbl=stream_ratingsstream
09-Apr-2017 02:01:09 2017-04-09 02:01:09,163 - INFO  [netty-executor-5:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=stream_ratingsstream        
09-Apr-2017 02:01:09 2017-04-09 02:01:09,163 - INFO  [netty-executor-5:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 2: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:01:09 2017-04-09 02:01:09,167 - INFO  [netty-executor-5:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:09 2017-04-09 02:01:09,175 - INFO  [netty-executor-5:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:09 2017-04-09 02:01:09,176 - INFO  [netty-executor-5:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:09 2017-04-09 02:01:09,204 - INFO  [netty-executor-5:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 2: get_database: default
09-Apr-2017 02:01:09 2017-04-09 02:01:09,205 - INFO  [netty-executor-5:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_database: default        
09-Apr-2017 02:01:09 2017-04-09 02:01:09,264 - INFO  [netty-executor-5:o.a.h.h.q.Driver@433] - Semantic Analysis Completed
09-Apr-2017 02:01:09 2017-04-09 02:01:09,266 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=semanticAnalyze start=1491703269034 end=1491703269266 duration=232 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,275 - INFO  [netty-executor-5:o.a.h.h.q.Driver@239] - Returning Hive schema: Schema(fieldSchemas:null, properties:null)
09-Apr-2017 02:01:09 2017-04-09 02:01:09,276 - INFO  [netty-executor-5:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=compile start=1491703268665 end=1491703269276 duration=611 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,284 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=Driver.run from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,285 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=TimeToSubmit from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,285 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.Driver@159] - Concurrency mode is disabled, not creating a lock manager
09-Apr-2017 02:01:09 2017-04-09 02:01:09,285 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=Driver.execute from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,286 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.Driver@1317] - Starting command: CREATE EXTERNAL TABLE IF NOT EXISTS stream_ratingsstream (ts bigint, headers map<string,string>, body string) COMMENT 'CDAP Stream' STORED BY 'co.cask.cdap.hive.stream.StreamStorageHandler' WITH SERDEPROPERTIES ('explore.stream.name'='ratingsStream', 'explore.stream.namespace'='default', 'explore.format.specification'='{"name":"text","schema":{"type":"record","name":"stringBody","fields":[{"name":"body","type":"string"}]},"settings":{}}') TBLPROPERTIES ('cdap.name'='ratingsStream', 'cdap.version'='3.3.1-1455910352324')
09-Apr-2017 02:01:09 2017-04-09 02:01:09,293 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=TimeToSubmit start=1491703269285 end=1491703269293 duration=8 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,293 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=runTasks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,294 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=task.DDL.Stage-0 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,299 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.Driver@1636] - Starting task [Stage-0:DDL] in serial mode
09-Apr-2017 02:01:09 2017-04-09 02:01:09,338 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 3: create_table: Table(tableName:stream_ratingsstream, dbName:default, owner:bamboo, createTime:1491703269, lastAccessTime:0, retention:0, sd:StorageDescriptor(cols:[FieldSchema(name:ts, type:bigint, comment:null), FieldSchema(name:headers, type:map<string,string>, comment:null), FieldSchema(name:body, type:string, comment:null)], location:null, inputFormat:null, outputFormat:null, compressed:false, numBuckets:-1, serdeInfo:SerDeInfo(name:null, serializationLib:co.cask.cdap.hive.stream.StreamSerDe, parameters:{explore.stream.name=ratingsStream, explore.stream.namespace=default, explore.format.specification={"name":"text","schema":{"type":"record","name":"stringBody","fields":[{"name":"body","type":"string"}]},"settings":{}}, serialization.format=1}), bucketCols:[], sortCols:[], parameters:{}, skewedInfo:SkewedInfo(skewedColNames:[], skewedColValues:[], skewedColValueLocationMaps:{}), storedAsSubDirectories:false), partitionKeys:[], parameters:{cdap.name=ratingsStream, EXTERNAL=TRUE, cdap.version=3.3.1-1455910352324, comment=CDAP Stream, storage_handler=co.cask.cdap.hive.stream.StreamStorageHandler}, viewOriginalText:null, viewExpandedText:null, tableType:EXTERNAL_TABLE, privileges:PrincipalPrivilegeSet(userPrivileges:{}, groupPrivileges:null, rolePrivileges:null), temporary:false)
09-Apr-2017 02:01:09 2017-04-09 02:01:09,338 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=create_table: Table(tableName:stream_ratingsstream, dbName:default, owner:bamboo, createTime:1491703269, lastAccessTime:0, retention:0, sd:StorageDescriptor(cols:[FieldSchema(name:ts, type:bigint, comment:null), FieldSchema(name:headers, type:map<string,string>, comment:null), FieldSchema(name:body, type:string, comment:null)], location:null, inputFormat:null, outputFormat:null, compressed:false, numBuckets:-1, serdeInfo:SerDeInfo(name:null, serializationLib:co.cask.cdap.hive.stream.StreamSerDe, parameters:{explore.stream.name=ratingsStream, explore.stream.namespace=default, explore.format.specification={"name":"text","schema":{"type":"record","name":"stringBody","fields":[{"name":"body","type":"string"}]},"settings":{}}, serialization.format=1}), bucketCols:[], sortCols:[], parameters:{}, skewedInfo:SkewedInfo(skewedColNames:[], skewedColValues:[], skewedColValueLocationMaps:{}), storedAsSubDirectories:false), partitionKeys:[], parameters:{cdap.name=ratingsStream, EXTERNAL=TRUE, cdap.version=3.3.1-1455910352324, comment=CDAP Stream, storage_handler=co.cask.cdap.hive.stream.StreamStorageHandler}, viewOriginalText:null, viewExpandedText:null, tableType:EXTERNAL_TABLE, privileges:PrincipalPrivilegeSet(userPrivileges:{}, groupPrivileges:null, rolePrivileges:null), temporary:false)        
09-Apr-2017 02:01:09 2017-04-09 02:01:09,339 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 3: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:01:09 2017-04-09 02:01:09,348 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:09 2017-04-09 02:01:09,360 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:09 2017-04-09 02:01:09,360 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:09 2017-04-09 02:01:09,378 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.c.FileUtils@501] - Creating directory if it doesn't exist: file:/tmp/CA-BUT4-MR/junit121641612177161762/hive/warehouse/1491703248642/stream_ratingsstream
09-Apr-2017 02:01:09 2017-04-09 02:01:09,644 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=runTasks start=1491703269293 end=1491703269644 duration=351 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,645 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=Driver.execute start=1491703269285 end=1491703269645 duration=360 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,646 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.s.SessionState$LogHelper@852] - OK
09-Apr-2017 02:01:09 2017-04-09 02:01:09,646 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,648 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=releaseLocks start=1491703269646 end=1491703269648 duration=2 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,648 - INFO  [HiveServer2-Background-Pool: Thread-143:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=Driver.run start=1491703269284 end=1491703269648 duration=364 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,655 - INFO  [explore-handle-timeout:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,655 - INFO  [explore-handle-timeout:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=releaseLocks start=1491703269655 end=1491703269655 duration=0 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:09 2017-04-09 02:01:09,809 - INFO  [netty-executor-6:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.usage.registry, type name: UsageDataset, properties: {}
09-Apr-2017 02:01:09 2017-04-09 02:01:09,811 - INFO  [netty-executor-5:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=usage, className=co.cask.cdap.data2.registry.UsageDatasetModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:09 2017-04-09 02:01:09,952 - INFO  [netty-executor-7:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.config.store.table, type name: table, properties: {}
09-Apr-2017 02:01:09 2017-04-09 02:01:09,955 - INFO  [netty-executor-9:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.config.store.table, type meta: DatasetTypeMeta{name=table, modules=DatasetModuleMeta{name=orderedTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule, jarLocation=null, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:10 2017-04-09 02:01:10,152 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@239] - Announced HTTP Service for Service program:default.MovieRecommender.service.MovieDictionaryService at /0:0:0:0:0:0:0:0:41034
09-Apr-2017 02:01:10 2017-04-09 02:01:10,223 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.lineage, type name: co.cask.cdap.data2.metadata.lineage.LineageDataset, properties: {}
09-Apr-2017 02:01:10 2017-04-09 02:01:10,225 - INFO  [netty-executor-0:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance system.lineage, type meta: DatasetTypeMeta{name=co.cask.cdap.data2.metadata.lineage.LineageDataset, modules=DatasetModuleMeta{name=orderedTable-memory, className=co.cask.cdap.data2.dataset2.module.lib.inmemory.InMemoryTableModule, jarLocation=null, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=lineage, className=co.cask.cdap.data2.metadata.lineage.LineageDatasetModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:10 2017-04-09 02:01:10,329 - INFO  [main:c.c.c.d.s.s.ConcurrentStreamWriter$StreamFileFactory@314] - Create stream writer for stream:default.ratingsStream with generation 0
09-Apr-2017 02:01:11 2017-04-09 02:01:11,005 - INFO  [main:c.c.c.i.a.r.s.SparkProgramRunner@174] - Starting Spark Job: SparkContext{id=program:default.MovieRecommender.spark.RecommendationBuilder, runId=66edd8f3-1cc8-11e7-b3df-000000dff345}
09-Apr-2017 02:01:11 2017-04-09 02:01:11,369 - WARN  [spark-submitter-RecommendationBuilder-66edd8f3-1cc8-11e7-b3df-000000dff345:o.a.s.Logging$class@71] - In Spark 1.0 and later spark.local.dir will be overridden by the value set by the cluster manager (via SPARK_LOCAL_DIRS in mesos/standalone and LOCAL_DIRS in YARN).
09-Apr-2017 02:01:14 2017-04-09 02:01:14,358 - INFO  [spark-submitter-RecommendationBuilder-66edd8f3-1cc8-11e7-b3df-000000dff345:c.c.c.a.m.RecommendationBuilder@53] - Running with arguments null
09-Apr-2017 02:01:14 2017-04-09 02:01:14,379 - INFO  [spark-submitter-RecommendationBuilder-66edd8f3-1cc8-11e7-b3df-000000dff345:c.c.c.a.m.RecommendationBuilder@55] - Processing ratings data with parameters Params(20,1.0,10,-1,-1,false)
09-Apr-2017 02:01:17 Got 4 ratings from 2 users on 3 movies out of 4
09-Apr-2017 02:01:17 2017-04-09 02:01:17,268 - INFO  [spark-submitter-RecommendationBuilder-66edd8f3-1cc8-11e7-b3df-000000dff345:c.c.c.a.m.RecommendationBuilder@99] - Calculating model
09-Apr-2017 02:01:19 2017-04-09 02:01:19,707 - WARN  [Executor task launch worker-0:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemBLAS
09-Apr-2017 02:01:19 2017-04-09 02:01:19,708 - WARN  [Executor task launch worker-0:c.g.f.n.BLAS@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefBLAS
09-Apr-2017 02:01:20 2017-04-09 02:01:20,182 - WARN  [Executor task launch worker-0:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeSystemLAPACK
09-Apr-2017 02:01:20 2017-04-09 02:01:20,183 - WARN  [Executor task launch worker-0:c.g.f.n.LAPACK@61] - Failed to load implementation from: com.github.fommil.netlib.NativeRefLAPACK
09-Apr-2017 02:01:25 2017-04-09 02:01:25,220 - INFO  [ServiceHttpServer STARTING:c.c.c.i.a.s.ServiceHttpServer@239] - Announced HTTP Service for Service program:default.MovieRecommender.service.MovieRecommenderService at /0:0:0:0:0:0:0:0:44085
09-Apr-2017 02:01:25 2017-04-09 02:01:25,335 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieRecommenderService-6fb9bd55-1cc8-11e7-923a-0000001eecd1:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@179] - Stopping Program : MovieRecommenderService
09-Apr-2017 02:01:25 2017-04-09 02:01:25,351 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieRecommenderService-6fb9bd55-1cc8-11e7-923a-0000001eecd1:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@194] - Program stopped: MovieRecommenderService
09-Apr-2017 02:01:25 2017-04-09 02:01:25,355 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieDictionaryService-66b9d0a2-1cc8-11e7-b495-00000096a4f2:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@179] - Stopping Program : MovieDictionaryService
09-Apr-2017 02:01:25 2017-04-09 02:01:25,379 - INFO  [pcontroller-program:default.MovieRecommender.service.MovieDictionaryService-66b9d0a2-1cc8-11e7-b495-00000096a4f2:c.c.c.i.a.AbstractInMemoryProgramRunner$InMemoryProgramController@194] - Program stopped: MovieDictionaryService
09-Apr-2017 02:01:25 2017-04-09 02:01:25,390 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@180] - Deleting namespace 'namespace:default'.
09-Apr-2017 02:01:25 2017-04-09 02:01:25,426 - INFO  [netty-executor-5:c.c.c.d.d.d.s.DatasetInstanceService@179] - Creating dataset system.business.metadata, type name: co.cask.cdap.data2.metadata.dataset.MetadataDataset, properties: {}
09-Apr-2017 02:01:25 2017-04-09 02:01:25,429 - INFO  [netty-executor-2:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@108] - Creating dataset instance 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata},DatasetModuleMeta{name=metadata, className=co.cask.cdap.data2.metadata.dataset.MetadataDatasetModule, jarLocation=null, usesModules=orderedTable-memory,core, usedByModules=}}, props: DatasetProperties{properties={}}
09-Apr-2017 02:01:25 2017-04-09 02:01:25,534 - INFO  [netty-executor-3:c.c.c.d.d.d.s.DatasetInstanceService@265] - Deleting dataset default.movies
09-Apr-2017 02:01:25 2017-04-09 02:01:25,591 - INFO  [netty-executor-9:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 4: get_table : db=default tbl=dataset_movies
09-Apr-2017 02:01:25 2017-04-09 02:01:25,591 - INFO  [netty-executor-9:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=dataset_movies        
09-Apr-2017 02:01:25 2017-04-09 02:01:25,591 - INFO  [netty-executor-9:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 4: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:01:25 2017-04-09 02:01:25,592 - INFO  [netty-executor-9:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:25 2017-04-09 02:01:25,599 - INFO  [netty-executor-9:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:25 2017-04-09 02:01:25,600 - INFO  [netty-executor-9:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:25 2017-04-09 02:01:25,618 - INFO  [netty-executor-1:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@151] - Dropping dataset with spec: DatasetSpecification{datasetSpecs={objects=DatasetSpecification{datasetSpecs={kv=DatasetSpecification{datasetSpecs={}, name='movies.objects.kv', type='table', properties={}}}, name='movies.objects', type='keyValueTable', properties={}}}, name='movies', type='co.cask.cdap.api.dataset.lib.ObjectStore', properties={schema="string", type={"isClass":true,"rawType":"java.lang.String"}}}, 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}
09-Apr-2017 02:01:25 2017-04-09 02:01:25,627 - INFO  [netty-executor-4:c.c.c.d.d.d.s.DatasetInstanceService@265] - Deleting dataset default.ratings
09-Apr-2017 02:01:25 2017-04-09 02:01:25,681 - INFO  [netty-executor-4:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 5: get_table : db=default tbl=dataset_ratings
09-Apr-2017 02:01:25 2017-04-09 02:01:25,681 - INFO  [netty-executor-4:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=dataset_ratings        
09-Apr-2017 02:01:25 2017-04-09 02:01:25,681 - INFO  [netty-executor-4:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 5: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:01:25 2017-04-09 02:01:25,683 - INFO  [netty-executor-4:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:25 2017-04-09 02:01:25,691 - INFO  [netty-executor-4:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:25 2017-04-09 02:01:25,691 - INFO  [netty-executor-4:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:25 2017-04-09 02:01:25,705 - INFO  [netty-executor-5:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@151] - Dropping dataset with spec: DatasetSpecification{datasetSpecs={objects=DatasetSpecification{datasetSpecs={kv=DatasetSpecification{datasetSpecs={}, name='ratings.objects.kv', type='table', properties={}}}, name='ratings.objects', type='keyValueTable', properties={}}}, name='ratings', type='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"}}}, 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}
09-Apr-2017 02:01:25 2017-04-09 02:01:25,710 - INFO  [netty-executor-9:c.c.c.d.d.d.s.DatasetInstanceService@265] - Deleting dataset default.recommendations
09-Apr-2017 02:01:25 2017-04-09 02:01:25,715 - INFO  [netty-executor-9:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 4: get_table : db=default tbl=dataset_recommendations
09-Apr-2017 02:01:25 2017-04-09 02:01:25,715 - INFO  [netty-executor-9:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=dataset_recommendations        
09-Apr-2017 02:01:25 2017-04-09 02:01:25,726 - INFO  [netty-executor-8:c.c.c.d.d.d.s.e.DatasetAdminOpHTTPHandler@151] - Dropping dataset with spec: DatasetSpecification{datasetSpecs={objects=DatasetSpecification{datasetSpecs={kv=DatasetSpecification{datasetSpecs={}, name='recommendations.objects.kv', type='table', properties={}}}, name='recommendations.objects', type='keyValueTable', properties={}}}, name='recommendations', type='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"}}}, 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, usesModules=, usedByModules=core,objectMappedTable,cube,usage,lineage},DatasetModuleMeta{name=core, className=co.cask.cdap.data2.dataset2.lib.table.CoreDatasetsModule, jarLocation=null, usesModules=orderedTable-memory, usedByModules=timePartitionedFileSet,partitionedFileSet,metadata}}
09-Apr-2017 02:01:25 2017-04-09 02:01:25,736 - WARN  [netty-executor-0:c.c.c.d.d.d.t.DatasetTypeManager@361] - Deleting all modules from namespace namespace:default
09-Apr-2017 02:01:25 2017-04-09 02:01:25,773 - INFO  [netty-executor-4:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:25 2017-04-09 02:01:25,783 - INFO  [netty-executor-4:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:25 2017-04-09 02:01:25,783 - INFO  [netty-executor-4:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:25 2017-04-09 02:01:25,794 - INFO  [netty-executor-4:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/5deb34b1-e335-47ee-bbe0-f1f8530e0fd2_resources
09-Apr-2017 02:01:25 2017-04-09 02:01:25,798 - INFO  [netty-executor-4:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/5deb34b1-e335-47ee-bbe0-f1f8530e0fd2
09-Apr-2017 02:01:25 2017-04-09 02:01:25,801 - INFO  [netty-executor-4:o.a.h.h.q.s.SessionState@586] - Created local directory: /tmp/CA-BUT4-MR/bamboo/5deb34b1-e335-47ee-bbe0-f1f8530e0fd2
09-Apr-2017 02:01:25 2017-04-09 02:01:25,805 - INFO  [netty-executor-4:o.a.h.h.q.s.SessionState@586] - Created HDFS directory: /tmp/CA-BUT4-MR/junit121641612177161762/hive/tmp/bamboo/5deb34b1-e335-47ee-bbe0-f1f8530e0fd2/_tmp_space.db
09-Apr-2017 02:01:25 2017-04-09 02:01:25,806 - INFO  [netty-executor-4:o.a.h.h.q.s.SessionState@488] - No Tez session required at this point. hive.execution.engine=mr.
09-Apr-2017 02:01:25 2017-04-09 02:01:25,807 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=compile from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:25 2017-04-09 02:01:25,808 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=parse from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:25 2017-04-09 02:01:25,810 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=parse start=1491703285808 end=1491703285810 duration=2 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:25 2017-04-09 02:01:25,810 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=semanticAnalyze from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:25 2017-04-09 02:01:25,814 - INFO  [netty-executor-4:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 5: get_table : db=default tbl=stream_ratingsstream
09-Apr-2017 02:01:25 2017-04-09 02:01:25,814 - INFO  [netty-executor-4:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=stream_ratingsstream        
09-Apr-2017 02:01:26 2017-04-09 02:01:26,057 - INFO  [netty-executor-4:o.a.h.h.q.Driver@433] - Semantic Analysis Completed
09-Apr-2017 02:01:26 2017-04-09 02:01:26,058 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=semanticAnalyze start=1491703285810 end=1491703286058 duration=248 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,058 - INFO  [netty-executor-4:o.a.h.h.q.Driver@239] - Returning Hive schema: Schema(fieldSchemas:null, properties:null)
09-Apr-2017 02:01:26 2017-04-09 02:01:26,059 - INFO  [netty-executor-4:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=compile start=1491703285807 end=1491703286059 duration=252 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,061 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=Driver.run from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,062 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=TimeToSubmit from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,062 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.Driver@159] - Concurrency mode is disabled, not creating a lock manager
09-Apr-2017 02:01:26 2017-04-09 02:01:26,063 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=Driver.execute from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,063 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.Driver@1317] - Starting command: DROP TABLE IF EXISTS stream_ratingsstream
09-Apr-2017 02:01:26 2017-04-09 02:01:26,064 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=TimeToSubmit start=1491703286062 end=1491703286064 duration=2 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,064 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=runTasks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,064 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=task.DDL.Stage-0 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:26 2017-04-09 02:01:26,065 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.Driver@1636] - Starting task [Stage-0:DDL] in serial mode
09-Apr-2017 02:01:26 2017-04-09 02:01:26,066 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 6: get_table : db=default tbl=stream_ratingsstream
09-Apr-2017 02:01:26 2017-04-09 02:01:26,066 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=stream_ratingsstream        
09-Apr-2017 02:01:26 2017-04-09 02:01:26,067 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@575] - 6: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
09-Apr-2017 02:01:26 2017-04-09 02:01:26,068 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.ObjectStore@269] - ObjectStore, initialize called
09-Apr-2017 02:01:26 2017-04-09 02:01:26,075 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.MetaStoreDirectSql@132] - Using direct SQL, underlying DB is DERBY
09-Apr-2017 02:01:26 2017-04-09 02:01:26,076 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.ObjectStore@252] - Initialized ObjectStore
09-Apr-2017 02:01:26 2017-04-09 02:01:26,103 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 6: get_table : db=default tbl=stream_ratingsstream
09-Apr-2017 02:01:26 2017-04-09 02:01:26,104 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=get_table : db=default tbl=stream_ratingsstream        
09-Apr-2017 02:01:26 2017-04-09 02:01:26,127 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 6: drop_table : db=default tbl=stream_ratingsstream
09-Apr-2017 02:01:26 2017-04-09 02:01:26,128 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=drop_table : db=default tbl=stream_ratingsstream        
09-Apr-2017 02:01:27 2017-04-09 02:01:27,316 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=runTasks start=1491703286064 end=1491703287315 duration=1251 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,316 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=Driver.execute start=1491703286063 end=1491703287316 duration=1253 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,318 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.s.SessionState$LogHelper@852] - OK
09-Apr-2017 02:01:27 2017-04-09 02:01:27,318 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,319 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=releaseLocks start=1491703287318 end=1491703287319 duration=1 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,319 - INFO  [HiveServer2-Background-Pool: Thread-303:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=Driver.run start=1491703286061 end=1491703287319 duration=1258 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,480 - INFO  [explore-handle-timeout:o.a.h.h.q.l.PerfLogger@121] - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,481 - INFO  [explore-handle-timeout:o.a.h.h.q.l.PerfLogger@148] - </PERFLOG method=releaseLocks start=1491703287480 end=1491703287481 duration=1 from=org.apache.hadoop.hive.ql.Driver>
09-Apr-2017 02:01:27 2017-04-09 02:01:27,591 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:01:27 2017-04-09 02:01:27,601 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:01:27 2017-04-09 02:01:27,613 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:01:27 2017-04-09 02:01:27,625 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:01:27 2017-04-09 02:01:27,777 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.1
09-Apr-2017 02:01:27 2017-04-09 02:01:27,784 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.60
09-Apr-2017 02:01:27 2017-04-09 02:01:27,791 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.3600
09-Apr-2017 02:01:27 2017-04-09 02:01:27,798 - INFO  [main:c.c.c.m.s.DefaultMetricDatasetFactory@99] - FactTable created: metrics.v2.table.ts.2147483647
09-Apr-2017 02:01:27 2017-04-09 02:01:27,838 - INFO  [main:c.c.c.i.a.n.DefaultNamespaceAdmin@217] - All data for namespace 'namespace:default' deleted.
09-Apr-2017 02:01:27 2017-04-09 02:01:27,839 - INFO  [MetricsQueryService STOPPING:c.c.c.m.q.MetricsQueryService@115] - Stopping Metrics Service...
09-Apr-2017 02:01:27 2017-04-09 02:01:27,842 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@98] - Stopped stream size scheduler
09-Apr-2017 02:01:27 2017-04-09 02:01:27,845 - INFO  [LocalSchedulerService STOPPING:c.c.c.i.a.r.s.AbstractSchedulerService@106] - Stopped time scheduler
09-Apr-2017 02:01:27 2017-04-09 02:01:27,845 - INFO  [ExploreExecutorService STOPPING:c.c.c.e.e.ExploreExecutorService@115] - Stopping ExploreExecutorService...
09-Apr-2017 02:01:27 2017-04-09 02:01:27,849 - INFO  [Hive14ExploreService STOPPING:c.c.c.e.s.h.BaseHiveExploreService@307] - Stopping BaseHiveExploreService...
09-Apr-2017 02:01:37 2017-04-09 02:01:37,851 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Shutting down the object store...
09-Apr-2017 02:01:37 2017-04-09 02:01:37,852 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Shutting down the object store...        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,852 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Metastore shutdown complete.
09-Apr-2017 02:01:37 2017-04-09 02:01:37,852 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Metastore shutdown complete.        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,853 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Shutting down the object store...
09-Apr-2017 02:01:37 2017-04-09 02:01:37,853 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Shutting down the object store...        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,853 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Metastore shutdown complete.
09-Apr-2017 02:01:37 2017-04-09 02:01:37,853 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Metastore shutdown complete.        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,853 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Shutting down the object store...
09-Apr-2017 02:01:37 2017-04-09 02:01:37,854 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Shutting down the object store...        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,854 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@732] - 7: Metastore shutdown complete.
09-Apr-2017 02:01:37 2017-04-09 02:01:37,854 - INFO  [Hive14ExploreService STOPPING:o.a.h.h.m.HiveMetaStore$HMSHandler@358] - ugi=bamboo        ip=unknown-ip-addr        cmd=Metastore shutdown complete.        
09-Apr-2017 02:01:37 2017-04-09 02:01:37,982 - INFO  [DatasetService:c.c.c.d.d.d.s.DatasetService@215] - Stopping DatasetService...
09-Apr-2017 02:01:40 2017-04-09 02:01:40,985 - INFO  [NettyHttpService STOPPING:c.c.c.d.d.d.s.DatasetTypeHandler@88] - Stopping DatasetTypeHandler
09-Apr-2017 02:01:40 2017-04-09 02:01:40,987 - INFO  [DatasetOpExecutorService STOPPING:c.c.c.d.d.d.s.e.DatasetOpExecutorService@101] - Stopping DatasetOpExecutorService...
09-Apr-2017 02:01:41 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 56.773 sec
09-Apr-2017 02:01:41
09-Apr-2017 02:01:41 Results :
09-Apr-2017 02:01:41
09-Apr-2017 02:01:41 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
09-Apr-2017 02:01:41
09-Apr-2017 02:01:41 [INFO]
09-Apr-2017 02:01:41 [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ MovieRecommender ---
09-Apr-2017 02:01:41 [INFO] Building jar: /var/bamboo/xml-data/build-dir/CA-BUT4-MR/MovieRecommender/target/MovieRecommender-0.7.0.jar
09-Apr-2017 02:01:41 [INFO]
09-Apr-2017 02:01:41 [INFO] --- maven-bundle-plugin:2.3.7:bundle (default) @ MovieRecommender ---
09-Apr-2017 02:01:49 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:01:49 [INFO] BUILD SUCCESS
09-Apr-2017 02:01:49 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:01:49 [INFO] Total time: 1:35.642s
09-Apr-2017 02:01:49 [INFO] Finished at: Sun Apr 09 02:01:49 UTC 2017
09-Apr-2017 02:01:49 [INFO] Final Memory: 70M/858M
09-Apr-2017 02:01:49 [INFO] ------------------------------------------------------------------------
09-Apr-2017 02:01:49 Parsing test results under /var/bamboo/xml-data/build-dir/CA-BUT4-MR...
09-Apr-2017 02:01:49 Finished task 'MovieRecommender' with result: Success
09-Apr-2017 02:01:49 Running post build plugin 'Docker Container Cleanup'
09-Apr-2017 02:01:49 Running post build plugin 'NCover Results Collector'
09-Apr-2017 02:01:49 Running post build plugin 'Clover Results Collector'
09-Apr-2017 02:01:49 Running post build plugin 'npm Cache Cleanup'
09-Apr-2017 02:01:49 Running post build plugin 'Artifact Copier'
09-Apr-2017 02:01:49 Finalising the build...
09-Apr-2017 02:01:49 Stopping timer.
09-Apr-2017 02:01:49 Build CA-BUT4-MR-50 completed.
09-Apr-2017 02:01:49 Running on server: post build plugin 'NCover Results Collector'
09-Apr-2017 02:01:49 Running on server: post build plugin 'Build Hanging Detection Configuration'
09-Apr-2017 02:01:49 Running on server: post build plugin 'Clover Delta Calculator'
09-Apr-2017 02:01:49 Running on server: post build plugin 'Maven Dependencies Postprocessor'
09-Apr-2017 02:01:49 All post build plugins have finished
09-Apr-2017 02:01:49 Generating build results summary...
09-Apr-2017 02:01:49 Saving build results to disk...
09-Apr-2017 02:01:49 Logging substituted variables...
09-Apr-2017 02:01:49 Indexing build results...
09-Apr-2017 02:01:49 Finished building CA-BUT4-MR-50.