I ran a MR job on my cluster on 3 nodes which have 128GB and 40 cores each.
I have configured hdfs and alluxio with HA. And both alluxio and hdfs can start successfully without any error in logs.
2016-09-29 21:24:56,125 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Created MRAppMaster for application appattempt_1475155149952_0002_000001 2016-09-29 21:24:56,227 WARN [main] org.apache.hadoop.util.NativeCodeLoader: Unable to load native-hadoop library for your platform... using builtin-java classes where applicable 2016-09-29 21:24:56,252 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Executing with tokens: 2016-09-29 21:24:56,323 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Kind: YARN_AM_RM_TOKEN, Service: , Ident: (appAttemptId { application_id { id: 2 cluster_timestamp: 1475155149952 } attemptId: 1 } keyId: -1453022468) 2016-09-29 21:24:56,333 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Using mapred newApiCommitter. 2016-09-29 21:24:56,738 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: OutputCommitter set in config null 2016-09-29 21:24:56,771 INFO [main] org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter: File Output Committer Algorithm version is 1 2016-09-29 21:24:56,774 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: OutputCommitter is org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter 2016-09-29 21:24:56,787 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.jobhistory.EventType for class org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler 2016-09-29 21:24:56,788 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.job.event.JobEventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$JobEventDispatcher 2016-09-29 21:24:56,788 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.job.event.TaskEventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$TaskEventDispatcher 2016-09-29 21:24:56,788 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.job.event.TaskAttemptEventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$TaskAttemptEventDispatcher 2016-09-29 21:24:56,789 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventType for class org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler 2016-09-29 21:24:56,792 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.speculate.Speculator$EventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$SpeculatorEventDispatcher 2016-09-29 21:24:56,792 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.rm.ContainerAllocator$EventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$ContainerAllocatorRouter 2016-09-29 21:24:56,793 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncher$EventType for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$ContainerLauncherRouter 2016-09-29 21:24:56,818 INFO [main] org.apache.hadoop.mapreduce.v2.jobhistory.JobHistoryUtils: Default file system [hdfs://ns:8020] 2016-09-29 21:24:56,833 INFO [main] org.apache.hadoop.mapreduce.v2.jobhistory.JobHistoryUtils: Default file system [hdfs://ns:8020] 2016-09-29 21:24:56,846 INFO [main] org.apache.hadoop.mapreduce.v2.jobhistory.JobHistoryUtils: Default file system [hdfs://ns:8020] 2016-09-29 21:24:56,859 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Creating intermediate history logDir: [hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate] + based on conf. Should ideally be created by the JobHistoryServer: yarn.app.mapreduce.am.create-intermediate-jh-base-dir 2016-09-29 21:24:56,866 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Perms after creating 493, Expected: 1023 2016-09-29 21:24:56,866 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Explicitly setting permissions to : 1023, rwxrwxrwt 2016-09-29 21:24:56,874 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Perms after creating 488, Expected: 504 2016-09-29 21:24:56,874 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Explicitly setting permissions to : 504, rwxrwx--- 2016-09-29 21:24:56,876 INFO [main] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Emitting job history data to the timeline server is not enabled 2016-09-29 21:24:56,899 INFO [main] org.apache.hadoop.yarn.event.AsyncDispatcher: Registering class org.apache.hadoop.mapreduce.v2.app.job.event.JobFinishEvent$Type for class org.apache.hadoop.mapreduce.v2.app.MRAppMaster$JobFinishEventHandler 2016-09-29 21:24:57,036 INFO [main] org.apache.hadoop.metrics2.impl.MetricsConfig: loaded properties from hadoop-metrics2.properties 2016-09-29 21:24:57,078 INFO [main] org.apache.hadoop.metrics2.impl.MetricsSystemImpl: Scheduled snapshot period at 10 second(s). 2016-09-29 21:24:57,078 INFO [main] org.apache.hadoop.metrics2.impl.MetricsSystemImpl: MRAppMaster metrics system started 2016-09-29 21:24:57,083 INFO [main] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Adding job token for job_1475155149952_0002 to jobTokenSecretManager 2016-09-29 21:24:57,153 INFO [main] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Not uberizing job_1475155149952_0002 because: not enabled; 2016-09-29 21:24:57,163 INFO [main] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Input size for job job_1475155149952_0002 = 12. Number of splits = 1 2016-09-29 21:24:57,163 INFO [main] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Number of reduces for job job_1475155149952_0002 = 1 2016-09-29 21:24:57,164 INFO [main] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: job_1475155149952_0002Job Transitioned from NEW to INITED 2016-09-29 21:24:57,164 INFO [main] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: MRAppMaster launching normal, non-uberized, multi-container job job_1475155149952_0002. 2016-09-29 21:24:57,179 INFO [main] org.apache.hadoop.ipc.CallQueueManager: Using callQueue class java.util.concurrent.LinkedBlockingQueue 2016-09-29 21:24:57,185 INFO [Socket Reader #1 for port 8540] org.apache.hadoop.ipc.Server: Starting Socket Reader #1 for port 8540 2016-09-29 21:24:57,196 INFO [main] org.apache.hadoop.yarn.factories.impl.pb.RpcServerFactoryPBImpl: Adding protocol org.apache.hadoop.mapreduce.v2.api.MRClientProtocolPB to the server 2016-09-29 21:24:57,197 INFO [IPC Server Responder] org.apache.hadoop.ipc.Server: IPC Server Responder: starting 2016-09-29 21:24:57,197 INFO [IPC Server listener on 8540] org.apache.hadoop.ipc.Server: IPC Server listener on 8540: starting 2016-09-29 21:24:57,198 INFO [main] org.apache.hadoop.mapreduce.v2.app.client.MRClientService: Instantiated MRClientService at sq-hbase2.800best.com/10.8.12.17:8540 2016-09-29 21:24:57,246 INFO [main] org.mortbay.log: Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog 2016-09-29 21:24:57,251 INFO [main] org.apache.hadoop.security.authentication.server.AuthenticationFilter: Unable to initialize FileSignerSecretProvider, falling back to use random secrets. 2016-09-29 21:24:57,254 WARN [main] org.apache.hadoop.http.HttpRequestLog: Jetty request log can only be enabled using Log4j 2016-09-29 21:24:57,258 INFO [main] org.apache.hadoop.http.HttpServer2: Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter) 2016-09-29 21:24:57,261 INFO [main] org.apache.hadoop.http.HttpServer2: Added filter AM_PROXY_FILTER (class=org.apache.hadoop.yarn.server.webproxy.amfilter.AmIpFilter) to context mapreduce 2016-09-29 21:24:57,261 INFO [main] org.apache.hadoop.http.HttpServer2: Added filter AM_PROXY_FILTER (class=org.apache.hadoop.yarn.server.webproxy.amfilter.AmIpFilter) to context static 2016-09-29 21:24:57,263 INFO [main] org.apache.hadoop.http.HttpServer2: adding path spec: /mapreduce/* 2016-09-29 21:24:57,263 INFO [main] org.apache.hadoop.http.HttpServer2: adding path spec: /ws/* 2016-09-29 21:24:57,459 INFO [main] org.apache.hadoop.yarn.webapp.WebApps: Registered webapp guice modules 2016-09-29 21:24:57,460 INFO [main] org.apache.hadoop.http.HttpServer2: Jetty bound to port 2252 2016-09-29 21:24:57,461 INFO [main] org.mortbay.log: jetty-6.1.26 2016-09-29 21:24:57,499 INFO [main] org.mortbay.log: Extract jar:file:/home/appadmin/hadoop-2.7.2/share/hadoop/common/lib/alluxio-core-client-1.2.0-jar-with-dependencies.jar!/webapps/mapreduce to /home/appadmin/hadoop-2.7.2/tmp/nm-local-dir/usercache/appadmin/appcache/application_1475155149952_0002/container_1475155149952_0002_01_000001/tmp/Jetty_0_0_0_0_2252_mapreduce____.219gm6/webapp 2016-09-29 21:24:59,124 INFO [main] org.mortbay.log: Started HttpServer2$SelectChannelConne...@0.0.0.0:2252 2016-09-29 21:24:59,124 INFO [main] org.apache.hadoop.yarn.webapp.WebApps: Web app mapreduce started at 2252 2016-09-29 21:24:59,125 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: JOB_CREATE job_1475155149952_0002 2016-09-29 21:24:59,126 INFO [main] org.apache.hadoop.ipc.CallQueueManager: Using callQueue class java.util.concurrent.LinkedBlockingQueue 2016-09-29 21:24:59,126 INFO [Socket Reader #1 for port 26213] org.apache.hadoop.ipc.Server: Starting Socket Reader #1 for port 26213 2016-09-29 21:24:59,128 INFO [IPC Server Responder] org.apache.hadoop.ipc.Server: IPC Server Responder: starting 2016-09-29 21:24:59,128 INFO [IPC Server listener on 26213] org.apache.hadoop.ipc.Server: IPC Server listener on 26213: starting 2016-09-29 21:24:59,142 INFO [main] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: nodeBlacklistingEnabled:true 2016-09-29 21:24:59,142 INFO [main] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: maxTaskFailuresPerNode is 3 2016-09-29 21:24:59,142 INFO [main] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: blacklistDisablePercent is 33 2016-09-29 21:24:59,167 INFO [main] org.apache.hadoop.yarn.client.RMProxy: Connecting to ResourceManager at /10.8.12.16:8030 2016-09-29 21:24:59,217 INFO [main] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: maxContainerCapability: <memory:81408, vCores:32> 2016-09-29 21:24:59,217 INFO [main] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: queue: default 2016-09-29 21:24:59,219 INFO [main] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Upper limit on the thread pool size is 500 2016-09-29 21:24:59,219 INFO [main] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: The thread pool initial size is 10 2016-09-29 21:24:59,221 INFO [main] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: yarn.client.max-cached-nodemanagers-proxies : 0 2016-09-29 21:24:59,224 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: job_1475155149952_0002Job Transitioned from INITED to SETUP 2016-09-29 21:24:59,225 INFO [CommitterEvent Processor #0] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: JOB_SETUP 2016-09-29 21:24:59,230 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: job_1475155149952_0002Job Transitioned from SETUP to RUNNING 2016-09-29 21:24:59,239 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase1.800best.com to /default-rack 2016-09-29 21:24:59,239 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase2.800best.com to /default-rack 2016-09-29 21:24:59,240 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_m_000000 Task Transitioned from NEW to SCHEDULED 2016-09-29 21:24:59,241 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_r_000000 Task Transitioned from NEW to SCHEDULED 2016-09-29 21:24:59,241 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_m_000000_0 TaskAttempt Transitioned from NEW to UNASSIGNED 2016-09-29 21:24:59,242 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from NEW to UNASSIGNED 2016-09-29 21:24:59,242 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: mapResourceRequest:<memory:1024, vCores:1> 2016-09-29 21:24:59,246 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: reduceResourceRequest:<memory:1024, vCores:1> 2016-09-29 21:24:59,274 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Event Writer setup for JobId: job_1475155149952_0002, File: hdfs://ns:8020/tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002/job_1475155149952_0002_1.jhist 2016-09-29 21:25:00,219 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Before Scheduling: PendingReds:1 ScheduledMaps:1 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:0 CompletedReds:0 ContAlloc:0 ContRel:0 HostLocal:0 RackLocal:0 2016-09-29 21:25:00,244 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=4 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:00,244 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:217088, vCores:1> 2016-09-29 21:25:00,245 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Reduce slow start threshold not met. completedMapsForReduceSlowstart 1 2016-09-29 21:25:01,254 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Got allocated containers 1 2016-09-29 21:25:01,255 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned container container_1475155149952_0002_01_000002 to attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:01,256 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:189952, vCores:1> 2016-09-29 21:25:01,256 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Reduce slow start threshold not met. completedMapsForReduceSlowstart 1 2016-09-29 21:25:01,256 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:1 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:1 AssignedReds:0 CompletedMaps:0 CompletedReds:0 ContAlloc:1 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:01,280 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase2.800best.com to /default-rack 2016-09-29 21:25:01,290 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: The job-jar file on the remote FS is hdfs://10.8.12.17:9000/tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002/job.jar 2016-09-29 21:25:01,292 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: The job-conf file on the remote FS is /tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002/job.xml 2016-09-29 21:25:01,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Adding #0 tokens and #1 secret keys for NM use for launching container 2016-09-29 21:25:01,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Size of containertokens_dob is 1 2016-09-29 21:25:01,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Putting shuffle token in serviceData 2016-09-29 21:25:01,307 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_m_000000_0 TaskAttempt Transitioned from UNASSIGNED to ASSIGNED 2016-09-29 21:25:01,310 INFO [ContainerLauncher #0] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_LAUNCH for container container_1475155149952_0002_01_000002 taskAttempt attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:01,311 INFO [ContainerLauncher #0] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Launching attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:01,311 INFO [ContainerLauncher #0] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase2.800best.com:3002 2016-09-29 21:25:01,350 INFO [ContainerLauncher #0] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Shuffle port returned by ContainerManager for attempt_1475155149952_0002_m_000000_0 : 13562 2016-09-29 21:25:01,351 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: TaskAttempt: [attempt_1475155149952_0002_m_000000_0] using containerId: [container_1475155149952_0002_01_000002 on NM: [sq-hbase2.800best.com:3002] 2016-09-29 21:25:01,353 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_m_000000_0 TaskAttempt Transitioned from ASSIGNED to RUNNING 2016-09-29 21:25:01,354 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: ATTEMPT_START task_1475155149952_0002_m_000000 2016-09-29 21:25:01,354 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_m_000000 Task Transitioned from SCHEDULED to RUNNING 2016-09-29 21:25:02,190 INFO [Socket Reader #1 for port 26213] SecurityLogger.org.apache.hadoop.ipc.Server: Auth successful for job_1475155149952_0002 (auth:SIMPLE) 2016-09-29 21:25:02,202 INFO [IPC Server handler 0 on 26213] org.apache.hadoop.mapred.TaskAttemptListenerImpl: JVM with ID : jvm_1475155149952_0002_m_000002 asked for a task 2016-09-29 21:25:02,202 INFO [IPC Server handler 0 on 26213] org.apache.hadoop.mapred.TaskAttemptListenerImpl: JVM with ID: jvm_1475155149952_0002_m_000002 given task: attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:02,258 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=4 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:189952, vCores:1> knownNMs=3 2016-09-29 21:25:02,856 INFO [IPC Server handler 1 on 26213] org.apache.hadoop.mapred.TaskAttemptListenerImpl: Progress of TaskAttempt attempt_1475155149952_0002_m_000000_0 is : 0.0 2016-09-29 21:25:02,963 INFO [IPC Server handler 2 on 26213] org.apache.hadoop.mapred.TaskAttemptListenerImpl: Progress of TaskAttempt attempt_1475155149952_0002_m_000000_0 is : 1.0 2016-09-29 21:25:02,968 INFO [IPC Server handler 4 on 26213] org.apache.hadoop.mapred.TaskAttemptListenerImpl: Done acknowledgement from attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:02,969 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_m_000000_0 TaskAttempt Transitioned from RUNNING to SUCCESS_CONTAINER_CLEANUP 2016-09-29 21:25:02,970 INFO [ContainerLauncher #1] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_CLEANUP for container container_1475155149952_0002_01_000002 taskAttempt attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:02,970 INFO [ContainerLauncher #1] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: KILLING attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:02,970 INFO [ContainerLauncher #1] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase2.800best.com:3002 2016-09-29 21:25:02,990 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_m_000000_0 TaskAttempt Transitioned from SUCCESS_CONTAINER_CLEANUP to SUCCEEDED 2016-09-29 21:25:02,995 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: Task succeeded with attempt attempt_1475155149952_0002_m_000000_0 2016-09-29 21:25:02,995 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_m_000000 Task Transitioned from RUNNING to SUCCEEDED 2016-09-29 21:25:02,997 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Num completed Tasks: 1 2016-09-29 21:25:03,258 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Before Scheduling: PendingReds:1 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:1 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:1 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:03,261 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:189952, vCores:1> 2016-09-29 21:25:03,261 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Reduce slow start threshold reached. Scheduling reduces. 2016-09-29 21:25:03,261 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: All maps assigned. Ramping up all remaining reduces:1 2016-09-29 21:25:03,261 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:1 AssignedMaps:1 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:1 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:04,266 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=1 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:04,266 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Received completed container container_1475155149952_0002_01_000002 2016-09-29 21:25:04,267 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:1 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:1 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:04,267 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Diagnostics report from attempt_1475155149952_0002_m_000000_0: Container killed by the ApplicationMaster. Container killed on request. Exit code is 143 Container exited with a non-zero exit code 143 2016-09-29 21:25:05,273 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Got allocated containers 1 2016-09-29 21:25:05,273 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned to reduce 2016-09-29 21:25:05,273 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned container container_1475155149952_0002_01_000003 to attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:05,273 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:1 CompletedMaps:1 CompletedReds:0 ContAlloc:2 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:05,277 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase3.800best.com to /default-rack 2016-09-29 21:25:05,277 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from UNASSIGNED to ASSIGNED 2016-09-29 21:25:05,278 INFO [ContainerLauncher #2] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_LAUNCH for container container_1475155149952_0002_01_000003 taskAttempt attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:05,278 INFO [ContainerLauncher #2] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Launching attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:05,278 INFO [ContainerLauncher #2] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:05,289 INFO [ContainerLauncher #2] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Shuffle port returned by ContainerManager for attempt_1475155149952_0002_r_000000_0 : 13562 2016-09-29 21:25:05,289 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: TaskAttempt: [attempt_1475155149952_0002_r_000000_0] using containerId: [container_1475155149952_0002_01_000003 on NM: [sq-hbase3.800best.com:63020] 2016-09-29 21:25:05,289 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from ASSIGNED to RUNNING 2016-09-29 21:25:05,290 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: ATTEMPT_START task_1475155149952_0002_r_000000 2016-09-29 21:25:05,290 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_r_000000 Task Transitioned from SCHEDULED to RUNNING 2016-09-29 21:25:06,275 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=1 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:06,275 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Received completed container container_1475155149952_0002_01_000003 2016-09-29 21:25:06,276 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:2 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:06,276 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from RUNNING to FAIL_CONTAINER_CLEANUP 2016-09-29 21:25:06,276 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Diagnostics report from attempt_1475155149952_0002_r_000000_0: org.apache.commons.codec.binary.Base64.encodeBase64String([B)Ljava/lang/String; 2016-09-29 21:25:06,276 INFO [ContainerLauncher #3] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_CLEANUP for container container_1475155149952_0002_01_000003 taskAttempt attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:06,277 INFO [ContainerLauncher #3] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: KILLING attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:06,277 INFO [ContainerLauncher #3] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:06,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from FAIL_CONTAINER_CLEANUP to FAIL_TASK_CLEANUP 2016-09-29 21:25:06,293 INFO [CommitterEvent Processor #1] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: TASK_ABORT 2016-09-29 21:25:06,297 WARN [CommitterEvent Processor #1] org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter: Could not delete hdfs://10.8.12.17:9000/linecount/win_output/_temporary/1/_temporary/attempt_1475155149952_0002_r_000000_0 2016-09-29 21:25:06,298 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_0 TaskAttempt Transitioned from FAIL_TASK_CLEANUP to FAILED 2016-09-29 21:25:06,301 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: 1 failures on node sq-hbase3.800best.com 2016-09-29 21:25:06,301 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from NEW to UNASSIGNED 2016-09-29 21:25:07,276 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Before Scheduling: PendingReds:1 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:2 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:07,277 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:217088, vCores:1> 2016-09-29 21:25:07,277 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: All maps assigned. Ramping up all remaining reduces:1 2016-09-29 21:25:07,277 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:1 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:2 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:08,280 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:09,283 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Got allocated containers 1 2016-09-29 21:25:09,283 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned to reduce 2016-09-29 21:25:09,284 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned container container_1475155149952_0002_01_000004 to attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:09,284 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:1 CompletedMaps:1 CompletedReds:0 ContAlloc:3 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:09,284 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase3.800best.com to /default-rack 2016-09-29 21:25:09,284 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from UNASSIGNED to ASSIGNED 2016-09-29 21:25:09,285 INFO [ContainerLauncher #4] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_LAUNCH for container container_1475155149952_0002_01_000004 taskAttempt attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:09,285 INFO [ContainerLauncher #4] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Launching attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:09,285 INFO [ContainerLauncher #4] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:09,293 INFO [ContainerLauncher #4] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Shuffle port returned by ContainerManager for attempt_1475155149952_0002_r_000000_1 : 13562 2016-09-29 21:25:09,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: TaskAttempt: [attempt_1475155149952_0002_r_000000_1] using containerId: [container_1475155149952_0002_01_000004 on NM: [sq-hbase3.800best.com:63020] 2016-09-29 21:25:09,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from ASSIGNED to RUNNING 2016-09-29 21:25:09,293 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: ATTEMPT_START task_1475155149952_0002_r_000000 2016-09-29 21:25:10,285 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:189952, vCores:1> knownNMs=3 2016-09-29 21:25:11,287 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Received completed container container_1475155149952_0002_01_000004 2016-09-29 21:25:11,287 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:3 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:11,287 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from RUNNING to FAIL_CONTAINER_CLEANUP 2016-09-29 21:25:11,287 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Diagnostics report from attempt_1475155149952_0002_r_000000_1: org.apache.commons.codec.binary.Base64.encodeBase64String([B)Ljava/lang/String; 2016-09-29 21:25:11,288 INFO [ContainerLauncher #5] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_CLEANUP for container container_1475155149952_0002_01_000004 taskAttempt attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:11,288 INFO [ContainerLauncher #5] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: KILLING attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:11,288 INFO [ContainerLauncher #5] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:11,296 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from FAIL_CONTAINER_CLEANUP to FAIL_TASK_CLEANUP 2016-09-29 21:25:11,296 INFO [CommitterEvent Processor #2] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: TASK_ABORT 2016-09-29 21:25:11,298 WARN [CommitterEvent Processor #2] org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter: Could not delete hdfs://10.8.12.17:9000/linecount/win_output/_temporary/1/_temporary/attempt_1475155149952_0002_r_000000_1 2016-09-29 21:25:11,298 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_1 TaskAttempt Transitioned from FAIL_TASK_CLEANUP to FAILED 2016-09-29 21:25:11,299 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: 2 failures on node sq-hbase3.800best.com 2016-09-29 21:25:11,299 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from NEW to UNASSIGNED 2016-09-29 21:25:12,287 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Before Scheduling: PendingReds:1 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:3 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:12,289 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:217088, vCores:1> 2016-09-29 21:25:12,289 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: All maps assigned. Ramping up all remaining reduces:1 2016-09-29 21:25:12,289 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:1 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:3 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:13,290 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:14,294 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Got allocated containers 1 2016-09-29 21:25:14,294 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned to reduce 2016-09-29 21:25:14,294 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned container container_1475155149952_0002_01_000005 to attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:14,294 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:1 CompletedMaps:1 CompletedReds:0 ContAlloc:4 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:14,294 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase3.800best.com to /default-rack 2016-09-29 21:25:14,294 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from UNASSIGNED to ASSIGNED 2016-09-29 21:25:14,295 INFO [ContainerLauncher #6] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_LAUNCH for container container_1475155149952_0002_01_000005 taskAttempt attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:14,295 INFO [ContainerLauncher #6] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Launching attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:14,295 INFO [ContainerLauncher #6] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:14,305 INFO [ContainerLauncher #6] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Shuffle port returned by ContainerManager for attempt_1475155149952_0002_r_000000_2 : 13562 2016-09-29 21:25:14,305 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: TaskAttempt: [attempt_1475155149952_0002_r_000000_2] using containerId: [container_1475155149952_0002_01_000005 on NM: [sq-hbase3.800best.com:63020] 2016-09-29 21:25:14,305 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from ASSIGNED to RUNNING 2016-09-29 21:25:14,305 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: ATTEMPT_START task_1475155149952_0002_r_000000 2016-09-29 21:25:15,296 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:189952, vCores:1> knownNMs=3 2016-09-29 21:25:16,298 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Received completed container container_1475155149952_0002_01_000005 2016-09-29 21:25:16,298 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:4 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:16,298 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from RUNNING to FAIL_CONTAINER_CLEANUP 2016-09-29 21:25:16,298 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Diagnostics report from attempt_1475155149952_0002_r_000000_2: org.apache.commons.codec.binary.Base64.encodeBase64String([B)Ljava/lang/String; 2016-09-29 21:25:16,299 INFO [ContainerLauncher #7] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_CLEANUP for container container_1475155149952_0002_01_000005 taskAttempt attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:16,299 INFO [ContainerLauncher #7] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: KILLING attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:16,299 INFO [ContainerLauncher #7] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:16,305 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from FAIL_CONTAINER_CLEANUP to FAIL_TASK_CLEANUP 2016-09-29 21:25:16,306 INFO [CommitterEvent Processor #3] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: TASK_ABORT 2016-09-29 21:25:16,307 WARN [CommitterEvent Processor #3] org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter: Could not delete hdfs://10.8.12.17:9000/linecount/win_output/_temporary/1/_temporary/attempt_1475155149952_0002_r_000000_2 2016-09-29 21:25:16,307 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_2 TaskAttempt Transitioned from FAIL_TASK_CLEANUP to FAILED 2016-09-29 21:25:16,308 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: 3 failures on node sq-hbase3.800best.com 2016-09-29 21:25:16,308 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from NEW to UNASSIGNED 2016-09-29 21:25:16,308 INFO [Thread-51] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: Blacklisted host sq-hbase3.800best.com 2016-09-29 21:25:17,298 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Before Scheduling: PendingReds:1 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:4 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:17,300 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: Update the blacklist for application_1475155149952_0002: blacklistAdditions=1 blacklistRemovals=0 2016-09-29 21:25:17,300 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: Ignore blacklisting set to true. Known: 3, Blacklisted: 1, 33% 2016-09-29 21:25:17,301 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Recalculating schedule, headroom=<memory:217088, vCores:1> 2016-09-29 21:25:17,301 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: All maps assigned. Ramping up all remaining reduces:1 2016-09-29 21:25:17,301 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:1 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:4 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:18,302 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=0 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:18,302 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: Update the blacklist for application_1475155149952_0002: blacklistAdditions=0 blacklistRemovals=1 2016-09-29 21:25:19,311 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Got allocated containers 1 2016-09-29 21:25:19,311 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned to reduce 2016-09-29 21:25:19,311 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Assigned container container_1475155149952_0002_01_000006 to attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:19,311 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:1 CompletedMaps:1 CompletedReds:0 ContAlloc:5 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:19,311 INFO [AsyncDispatcher event handler] org.apache.hadoop.yarn.util.RackResolver: Resolved sq-hbase3.800best.com to /default-rack 2016-09-29 21:25:19,312 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from UNASSIGNED to ASSIGNED 2016-09-29 21:25:19,313 INFO [ContainerLauncher #8] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_LAUNCH for container container_1475155149952_0002_01_000006 taskAttempt attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:19,313 INFO [ContainerLauncher #8] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Launching attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:19,313 INFO [ContainerLauncher #8] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:19,321 INFO [ContainerLauncher #8] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Shuffle port returned by ContainerManager for attempt_1475155149952_0002_r_000000_3 : 13562 2016-09-29 21:25:19,321 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: TaskAttempt: [attempt_1475155149952_0002_r_000000_3] using containerId: [container_1475155149952_0002_01_000006 on NM: [sq-hbase3.800best.com:63020] 2016-09-29 21:25:19,321 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from ASSIGNED to RUNNING 2016-09-29 21:25:19,321 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.speculate.DefaultSpeculator: ATTEMPT_START task_1475155149952_0002_r_000000 2016-09-29 21:25:20,313 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerRequestor: getResources() for application_1475155149952_0002: ask=1 release= 0 newContainers=0 finishedContainers=1 resourcelimit=<memory:217088, vCores:1> knownNMs=3 2016-09-29 21:25:20,313 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Received completed container container_1475155149952_0002_01_000006 2016-09-29 21:25:20,313 INFO [RMCommunicator Allocator] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: After Scheduling: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:5 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:20,313 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from RUNNING to FAIL_CONTAINER_CLEANUP 2016-09-29 21:25:20,313 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: Diagnostics report from attempt_1475155149952_0002_r_000000_3: org.apache.commons.codec.binary.Base64.encodeBase64String([B)Ljava/lang/String; 2016-09-29 21:25:20,313 INFO [ContainerLauncher #9] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: Processing the event EventType: CONTAINER_REMOTE_CLEANUP for container container_1475155149952_0002_01_000006 taskAttempt attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:20,314 INFO [ContainerLauncher #9] org.apache.hadoop.mapreduce.v2.app.launcher.ContainerLauncherImpl: KILLING attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:20,314 INFO [ContainerLauncher #9] org.apache.hadoop.yarn.client.api.impl.ContainerManagementProtocolProxy: Opening proxy : sq-hbase3.800best.com:63020 2016-09-29 21:25:20,320 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from FAIL_CONTAINER_CLEANUP to FAIL_TASK_CLEANUP 2016-09-29 21:25:20,320 INFO [CommitterEvent Processor #4] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: TASK_ABORT 2016-09-29 21:25:20,322 WARN [CommitterEvent Processor #4] org.apache.hadoop.mapreduce.lib.output.FileOutputCommitter: Could not delete hdfs://10.8.12.17:9000/linecount/win_output/_temporary/1/_temporary/attempt_1475155149952_0002_r_000000_3 2016-09-29 21:25:20,322 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskAttemptImpl: attempt_1475155149952_0002_r_000000_3 TaskAttempt Transitioned from FAIL_TASK_CLEANUP to FAILED 2016-09-29 21:25:20,324 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.TaskImpl: task_1475155149952_0002_r_000000 Task Transitioned from RUNNING to FAILED 2016-09-29 21:25:20,324 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Num completed Tasks: 2 2016-09-29 21:25:20,324 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: Job failed as tasks failed. failedMaps:0 failedReduces:1 2016-09-29 21:25:20,324 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: job_1475155149952_0002Job Transitioned from RUNNING to FAIL_ABORT 2016-09-29 21:25:20,324 INFO [CommitterEvent Processor #0] org.apache.hadoop.mapreduce.v2.app.commit.CommitterEventHandler: Processing the event EventType: JOB_ABORT 2016-09-29 21:25:20,331 INFO [AsyncDispatcher event handler] org.apache.hadoop.mapreduce.v2.app.job.impl.JobImpl: job_1475155149952_0002Job Transitioned from FAIL_ABORT to FAILED 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: We are finishing cleanly so this is the last retry 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Notify RMCommunicator isAMLastRetry: true 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: RMCommunicator notified that shouldUnregistered is: true 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Notify JHEH isAMLastRetry: true 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: JobHistoryEventHandler notified that forceJobCompletion is true 2016-09-29 21:25:20,332 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Calling stop for all the services 2016-09-29 21:25:20,333 INFO [Thread-71] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Stopping JobHistoryEventHandler. Size of the outstanding queue size is 0 2016-09-29 21:25:20,360 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Copying hdfs://ns:8020/tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002/job_1475155149952_0002_1.jhist to hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002-1475155493770-appadmin-linecount-1475155520324-1-0-FAILED-default-1475155499222.jhist_tmp 2016-09-29 21:25:20,382 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Copied to done location: hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002-1475155493770-appadmin-linecount-1475155520324-1-0-FAILED-default-1475155499222.jhist_tmp 2016-09-29 21:25:20,384 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Copying hdfs://ns:8020/tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002/job_1475155149952_0002_1_conf.xml to hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002_conf.xml_tmp 2016-09-29 21:25:20,407 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Copied to done location: hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002_conf.xml_tmp 2016-09-29 21:25:20,418 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Moved tmp to done: hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002.summary_tmp to hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002.summary 2016-09-29 21:25:20,420 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Moved tmp to done: hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002_conf.xml_tmp to hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002_conf.xml 2016-09-29 21:25:20,422 INFO [eventHandlingThread] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Moved tmp to done: hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002-1475155493770-appadmin-linecount-1475155520324-1-0-FAILED-default-1475155499222.jhist_tmp to hdfs://ns:8020/tmp/hadoop-yarn/staging/history/done_intermediate/appadmin/job_1475155149952_0002-1475155493770-appadmin-linecount-1475155520324-1-0-FAILED-default-1475155499222.jhist 2016-09-29 21:25:20,422 INFO [Thread-71] org.apache.hadoop.mapreduce.jobhistory.JobHistoryEventHandler: Stopped JobHistoryEventHandler. super.stop() 2016-09-29 21:25:20,424 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: Setting job diagnostics to Task failed task_1475155149952_0002_r_000000 Job failed as tasks failed. failedMaps:0 failedReduces:1 2016-09-29 21:25:20,425 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: History url is http://sq-hbase1.800best.com:19888/jobhistory/job/job_1475155149952_0002 2016-09-29 21:25:20,434 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.rm.RMCommunicator: Waiting for application to be successfully unregistered. 2016-09-29 21:25:21,436 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.rm.RMContainerAllocator: Final Stats: PendingReds:0 ScheduledMaps:0 ScheduledReds:0 AssignedMaps:0 AssignedReds:0 CompletedMaps:1 CompletedReds:0 ContAlloc:5 ContRel:0 HostLocal:1 RackLocal:0 2016-09-29 21:25:21,436 INFO [Thread-71] org.apache.hadoop.mapreduce.v2.app.MRAppMaster: Deleting staging directory hdfs://10.8.12.17:9000 /tmp/hadoop-yarn/staging/appadmin/.staging/job_1475155149952_0002 2016-09-29 21:25:21,439 INFO [Thread-71] org.apache.hadoop.ipc.Server: Stopping server on 26213 2016-09-29 21:25:21,440 INFO [IPC Server listener on 26213] org.apache.hadoop.ipc.Server: Stopping IPC Server listener on 26213 2016-09-29 21:25:21,440 INFO [TaskHeartbeatHandler PingChecker] org.apache.hadoop.mapreduce.v2.app.TaskHeartbeatHandler: TaskHeartbeatHandler thread interrupted 2016-09-29 21:25:21,441 INFO [IPC Server Responder] org.apache.hadoop.ipc.Server: Stopping IPC Server Responder