You are viewing a plain text version of this content. The canonical link for it is here.
Posted to mapreduce-dev@hadoop.apache.org by Apache Hudson Server <hu...@hudson.apache.org> on 2010/11/05 05:20:18 UTC
Build failed in Hudson: Hadoop-Mapreduce-trunk-Commit #528
See <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/528/changes>
Changes:
[nigel] HADOOP-7008. Fix bug and missing license header in test-patch files. (nigel)
------------------------------------------
[...truncated 33752 lines...]
[junit] 2010-11-05 04:20:07,169 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@localhost:41011
[junit] 2010-11-05 04:20:07,173 INFO jvm.JvmMetrics (JvmMetrics.java:init(76)) - Initializing JVM Metrics with processName=JobTracker, sessionId=
[junit] 2010-11-05 04:20:07,219 INFO mapred.JobTracker (JobTracker.java:<init>(1518)) - JobTracker up at: 41713
[junit] 2010-11-05 04:20:07,219 INFO mapred.JobTracker (JobTracker.java:<init>(1522)) - JobTracker webserver: 41011
[junit] 2010-11-05 04:20:07,299 INFO mapred.JobTracker (JobTracker.java:<init>(1580)) - Cleaning up the system directory
[junit] 2010-11-05 04:20:07,322 INFO jobhistory.JobHistory (JobHistory.java:initDone(171)) - Inited the done directory to <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/logs/history/done>
[junit] 2010-11-05 04:20:07,325 INFO jobhistory.JobHistory (JobHistory.java:<init>(527)) - Job History Cleaner Thread started. MaxAge is 604800000 ms(7.0 days), Cleanup Frequency is 86400000 ms (1.0 days)
[junit] 2010-11-05 04:20:07,331 INFO mapred.CompletedJobStatusStore (CompletedJobStatusStore.java:<init>(112)) - Completed job store is inactive
[junit] 2010-11-05 04:20:07,365 INFO mapred.JobTracker (JobTracker.java:refreshHosts(4147)) - Refreshing hosts information
[junit] 2010-11-05 04:20:07,403 INFO util.HostsFileReader (HostsFileReader.java:setIncludesFile(109)) - Setting the includes file to
[junit] 2010-11-05 04:20:07,403 INFO util.HostsFileReader (HostsFileReader.java:setExcludesFile(114)) - Setting the excludes file to hosts.exclude
[junit] 2010-11-05 04:20:07,404 INFO util.HostsFileReader (HostsFileReader.java:refresh(85)) - Refreshing hosts (include/exclude) list
[junit] 2010-11-05 04:20:07,404 INFO mapred.JobTracker (JobTracker.java:decommissionNodes(4169)) - Decommissioning 0 nodes
[junit] 2010-11-05 04:20:07,405 INFO ipc.Server (Server.java:run(608)) - IPC Server Responder: starting
[junit] 2010-11-05 04:20:07,422 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 1 on 41713: starting
[junit] 2010-11-05 04:20:07,422 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 0 on 41713: starting
[junit] 2010-11-05 04:20:07,414 INFO ipc.Server (Server.java:run(443)) - IPC Server listener on 41713: starting
[junit] 2010-11-05 04:20:07,423 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 4 on 41713: starting
[junit] 2010-11-05 04:20:07,423 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 3 on 41713: starting
[junit] 2010-11-05 04:20:07,423 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 2 on 41713: starting
[junit] 2010-11-05 04:20:07,424 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 5 on 41713: starting
[junit] 2010-11-05 04:20:07,424 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 6 on 41713: starting
[junit] 2010-11-05 04:20:07,425 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 7 on 41713: starting
[junit] 2010-11-05 04:20:07,425 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 8 on 41713: starting
[junit] 2010-11-05 04:20:07,425 INFO mapred.JobTracker (JobTracker.java:offerService(1757)) - Starting RUNNING
[junit] 2010-11-05 04:20:07,425 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 9 on 41713: starting
[junit] 2010-11-05 04:20:08,104 WARN conf.Configuration (Configuration.java:set(582)) - jobclient.output.filter is deprecated. Instead, use mapreduce.client.output.filter
[junit] 2010-11-05 04:20:08,105 INFO mapred.MiniMRCluster (MiniMRCluster.java:<init>(199)) - mapreduce.cluster.local.dir is /tmp/hadoop-hudson/mapred/local/0_0,/tmp/hadoop-hudson/mapred/local/0_1,/tmp/hadoop-hudson/mapred/local/0_2
[junit] 2010-11-05 04:20:08,123 INFO http.HttpServer (HttpServer.java:addGlobalFilter(406)) - Added global filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
[junit] 2010-11-05 04:20:08,127 INFO http.HttpServer (HttpServer.java:start(576)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2010-11-05 04:20:08,128 INFO http.HttpServer (HttpServer.java:start(581)) - listener.getLocalPort() returned 37796 webServer.getConnectors()[0].getLocalPort() returned 37796
[junit] 2010-11-05 04:20:08,128 INFO http.HttpServer (HttpServer.java:start(614)) - Jetty bound to port 37796
[junit] 2010-11-05 04:20:08,129 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2010-11-05 04:20:08,251 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:37796
[junit] 2010-11-05 04:20:08,259 INFO mapred.TaskTracker (TaskTracker.java:initialize(597)) - Starting tasktracker with owner as hudson and supergroup as supergroup
[junit] 2010-11-05 04:20:08,265 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2010-11-05 04:20:08,269 INFO ipc.Server (Server.java:run(338)) - Starting SocketReader
[junit] 2010-11-05 04:20:08,269 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(63)) - Initializing RPC Metrics with hostName=TaskTracker, port=39181
[junit] 2010-11-05 04:20:08,270 INFO metrics.RpcDetailedMetrics (RpcDetailedMetrics.java:<init>(57)) - Initializing RPC Metrics with hostName=TaskTracker, port=39181
[junit] 2010-11-05 04:20:08,271 INFO ipc.Server (Server.java:run(608)) - IPC Server Responder: starting
[junit] 2010-11-05 04:20:08,271 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 0 on 39181: starting
[junit] 2010-11-05 04:20:08,271 INFO ipc.Server (Server.java:run(443)) - IPC Server listener on 39181: starting
[junit] 2010-11-05 04:20:08,272 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 2 on 39181: starting
[junit] 2010-11-05 04:20:08,272 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 1 on 39181: starting
[junit] 2010-11-05 04:20:08,273 INFO mapred.TaskTracker (TaskTracker.java:initialize(670)) - TaskTracker up at: localhost/127.0.0.1:39181
[junit] 2010-11-05 04:20:08,272 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 3 on 39181: starting
[junit] 2010-11-05 04:20:08,274 INFO mapred.TaskTracker (TaskTracker.java:initialize(673)) - Starting tracker tracker_host0.foo.com:localhost/127.0.0.1:39181
[junit] 2010-11-05 04:20:08,323 INFO mapred.TaskTracker (TaskTracker.java:run(855)) - Starting thread: Map-events fetcher for all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:39181
[junit] 2010-11-05 04:20:08,346 INFO util.ProcessTree (ProcessTree.java:isSetsidSupported(65)) - setsid exited with exit code 0
[junit] 2010-11-05 04:20:08,372 INFO mapred.TaskTracker (TaskTracker.java:initialize(714)) - Using ResourceCalculatorPlugin : org.apache.hadoop.mapreduce.util.LinuxResourceCalculatorPlugin@1c4ff2c
[junit] 2010-11-05 04:20:08,376 WARN mapred.TaskTracker (TaskTracker.java:setTaskMemoryManagerEnabledFlag(4021)) - TaskTracker's totalMemoryAllottedForTasks is -1 and reserved physical memory is not configured. TaskMemoryManager is disabled.
[junit] 2010-11-05 04:20:08,379 INFO mapred.IndexCache (IndexCache.java:<init>(47)) - IndexCache created with max memory = 10485760
[junit] 2010-11-05 04:20:08,383 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040943162_0001 for user-log deletion with retainTimeStamp:1289017208380
[junit] 2010-11-05 04:20:08,384 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040155706_0001 for user-log deletion with retainTimeStamp:1289017208380
[junit] 2010-11-05 04:20:08,385 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040943162_0002 for user-log deletion with retainTimeStamp:1289017208380
[junit] 2010-11-05 04:20:08,386 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105041313122_0001 for user-log deletion with retainTimeStamp:1289017208380
[junit] 2010-11-05 04:20:08,418 WARN conf.Configuration (Configuration.java:set(582)) - jobclient.output.filter is deprecated. Instead, use mapreduce.client.output.filter
[junit] 2010-11-05 04:20:08,419 INFO mapred.MiniMRCluster (MiniMRCluster.java:<init>(199)) - mapreduce.cluster.local.dir is /tmp/hadoop-hudson/mapred/local/1_0,/tmp/hadoop-hudson/mapred/local/1_1,/tmp/hadoop-hudson/mapred/local/1_2
[junit] 2010-11-05 04:20:08,423 INFO net.NetworkTopology (NetworkTopology.java:add(331)) - Adding a new node: /default-rack/host0.foo.com
[junit] 2010-11-05 04:20:08,425 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2268)) - Adding tracker tracker_host0.foo.com:localhost/127.0.0.1:39181 to host host0.foo.com
[junit] 2010-11-05 04:20:08,428 INFO http.HttpServer (HttpServer.java:addGlobalFilter(406)) - Added global filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
[junit] 2010-11-05 04:20:08,429 INFO http.HttpServer (HttpServer.java:start(576)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2010-11-05 04:20:08,430 INFO http.HttpServer (HttpServer.java:start(581)) - listener.getLocalPort() returned 46141 webServer.getConnectors()[0].getLocalPort() returned 46141
[junit] 2010-11-05 04:20:08,430 INFO http.HttpServer (HttpServer.java:start(614)) - Jetty bound to port 46141
[junit] 2010-11-05 04:20:08,431 INFO mortbay.log (?:invoke0(?)) - jetty-6.1.14
[junit] 2010-11-05 04:20:08,585 INFO mortbay.log (?:invoke0(?)) - Started SelectChannelConnector@0.0.0.0:46141
[junit] 2010-11-05 04:20:08,589 INFO mapred.TaskTracker (TaskTracker.java:initialize(597)) - Starting tasktracker with owner as hudson and supergroup as supergroup
[junit] 2010-11-05 04:20:08,594 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=TaskTracker, sessionId= - already initialized
[junit] 2010-11-05 04:20:08,595 INFO ipc.Server (Server.java:run(338)) - Starting SocketReader
[junit] 2010-11-05 04:20:08,595 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(63)) - Initializing RPC Metrics with hostName=TaskTracker, port=46685
[junit] 2010-11-05 04:20:08,597 INFO metrics.RpcDetailedMetrics (RpcDetailedMetrics.java:<init>(57)) - Initializing RPC Metrics with hostName=TaskTracker, port=46685
[junit] 2010-11-05 04:20:08,597 INFO ipc.Server (Server.java:run(608)) - IPC Server Responder: starting
[junit] 2010-11-05 04:20:08,598 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 0 on 46685: starting
[junit] 2010-11-05 04:20:08,598 INFO ipc.Server (Server.java:run(443)) - IPC Server listener on 46685: starting
[junit] 2010-11-05 04:20:08,599 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 1 on 46685: starting
[junit] 2010-11-05 04:20:08,599 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 3 on 46685: starting
[junit] 2010-11-05 04:20:08,599 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 2 on 46685: starting
[junit] 2010-11-05 04:20:08,599 INFO mapred.TaskTracker (TaskTracker.java:initialize(670)) - TaskTracker up at: localhost/127.0.0.1:46685
[junit] 2010-11-05 04:20:08,600 INFO mapred.TaskTracker (TaskTracker.java:initialize(673)) - Starting tracker tracker_host1.foo.com:localhost/127.0.0.1:46685
[junit] 2010-11-05 04:20:08,603 INFO mapred.TaskTracker (TaskTracker.java:run(855)) - Starting thread: Map-events fetcher for all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:46685
[junit] 2010-11-05 04:20:08,603 INFO mapred.TaskTracker (TaskTracker.java:initialize(714)) - Using ResourceCalculatorPlugin : org.apache.hadoop.mapreduce.util.LinuxResourceCalculatorPlugin@f221f6
[junit] 2010-11-05 04:20:08,605 WARN mapred.TaskTracker (TaskTracker.java:setTaskMemoryManagerEnabledFlag(4021)) - TaskTracker's totalMemoryAllottedForTasks is -1 and reserved physical memory is not configured. TaskMemoryManager is disabled.
[junit] 2010-11-05 04:20:08,605 INFO mapred.IndexCache (IndexCache.java:<init>(47)) - IndexCache created with max memory = 10485760
[junit] 2010-11-05 04:20:08,606 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040943162_0001 for user-log deletion with retainTimeStamp:1289017208606
[junit] 2010-11-05 04:20:08,607 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040155706_0001 for user-log deletion with retainTimeStamp:1289017208606
[junit] 2010-11-05 04:20:08,607 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105040943162_0002 for user-log deletion with retainTimeStamp:1289017208606
[junit] 2010-11-05 04:20:08,608 INFO mapred.UserLogCleaner (UserLogCleaner.java:markJobLogsForDeletion(174)) - Adding job_20101105041313122_0001 for user-log deletion with retainTimeStamp:1289017208606
[junit] 2010-11-05 04:20:08,631 INFO net.NetworkTopology (NetworkTopology.java:add(331)) - Adding a new node: /default-rack/host1.foo.com
[junit] 2010-11-05 04:20:08,631 INFO mapred.JobTracker (JobTracker.java:addNewTracker(2268)) - Adding tracker tracker_host1.foo.com:localhost/127.0.0.1:46685 to host host1.foo.com
[junit] 2010-11-05 04:20:08,637 WARN conf.Configuration (Configuration.java:set(582)) - jobclient.output.filter is deprecated. Instead, use mapreduce.client.output.filter
[junit] 2010-11-05 04:20:08,665 WARN conf.Configuration (Configuration.java:set(582)) - jobclient.output.filter is deprecated. Instead, use mapreduce.client.output.filter
[junit] 2010-11-05 04:20:08,747 WARN mapreduce.JobSubmitter (JobSubmitter.java:copyAndConfigureFiles(138)) - Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
[junit] 2010-11-05 04:20:08,762 WARN mapreduce.JobSubmitter (JobSubmitter.java:copyAndConfigureFiles(240)) - No job jar file set. User classes may not be found. See Job or Job#setJar(String).
[junit] 2010-11-05 04:20:08,773 INFO input.FileInputFormat (FileInputFormat.java:listStatus(248)) - Total input paths to process : 2
[junit] 2010-11-05 04:20:08,856 WARN conf.Configuration (Configuration.java:set(582)) - mapred.map.tasks is deprecated. Instead, use mapreduce.job.maps
[junit] 2010-11-05 04:20:08,856 INFO mapreduce.JobSubmitter (JobSubmitter.java:submitJobInternal(359)) - number of splits:2
[junit] 2010-11-05 04:20:09,011 WARN conf.Configuration (Configuration.java:handleDeprecation(313)) - mapred.committer.job.setup.cleanup.needed is deprecated. Instead, use mapreduce.job.committer.setup.cleanup.needed
[junit] 2010-11-05 04:20:09,017 INFO ipc.Server (Server.java:run(1407)) - IPC Server handler 7 on 41713, call submitJob(job_20101105041943122_0001, file:/tmp/hadoop-hudson/mapred/staging/hudson/.staging/job_20101105041943122_0001, org.apache.hadoop.security.Credentials@1e779a1) from 127.0.0.1:55466: error: java.io.IOException: java.lang.NoClassDefFoundError: org/apache/hadoop/hdfs/security/token/delegation/DelegationTokenIdentifier
[junit] java.io.IOException: java.lang.NoClassDefFoundError: org/apache/hadoop/hdfs/security/token/delegation/DelegationTokenIdentifier
[junit] at org.apache.hadoop.mapreduce.security.token.DelegationTokenRenewal.<clinit>(DelegationTokenRenewal.java:116)
[junit] at org.apache.hadoop.mapred.JobInProgress.<init>(JobInProgress.java:480)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:3050)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:3016)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:2998)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.WritableRpcEngine$Server.call(WritableRpcEngine.java:349)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1399)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1395)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1115)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1393)
[junit] 2010-11-05 04:20:09,019 INFO mapreduce.JobSubmitter (JobSubmitter.java:submitJobInternal(385)) - Cleaning up the staging area file:/tmp/hadoop-hudson/mapred/staging/hudson/.staging/job_20101105041943122_0001
[junit] 2010-11-05 04:20:09,022 INFO util.AsyncDiskService (AsyncDiskService.java:shutdown(111)) - Shutting down all AsyncDiskService threads...
[junit] 2010-11-05 04:20:09,022 INFO util.AsyncDiskService (AsyncDiskService.java:awaitTermination(140)) - All AsyncDiskService threads are terminated.
[junit] 2010-11-05 04:20:09,023 INFO mapred.TaskTracker (TaskTracker.java:run(865)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_host0.foo.com:localhost/127.0.0.1:39181
[junit] 2010-11-05 04:20:09,024 ERROR filecache.TrackerDistributedCacheManager (TrackerDistributedCacheManager.java:run(935)) - Exception in DistributedCache CleanupThread.
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.mapreduce.filecache.TrackerDistributedCacheManager$CleanupThread.run(TrackerDistributedCacheManager.java:932)
[junit] 2010-11-05 04:20:09,024 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 39181
[junit] 2010-11-05 04:20:09,027 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 39181: exiting
[junit] 2010-11-05 04:20:09,027 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 3 on 39181: exiting
[junit] 2010-11-05 04:20:09,027 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 39181: exiting
[junit] 2010-11-05 04:20:09,027 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 39181: exiting
[junit] 2010-11-05 04:20:09,033 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-11-05 04:20:09,031 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 39181
[junit] 2010-11-05 04:20:09,034 INFO mapred.TaskTracker (TaskTracker.java:shutdown(1256)) - Shutting down StatusHttpServer
[junit] 2010-11-05 04:20:09,141 INFO mapred.TaskTracker (TaskTracker.java:offerService(1564)) - Interrupted. Closing down.
[junit] 2010-11-05 04:20:09,143 INFO util.AsyncDiskService (AsyncDiskService.java:shutdown(111)) - Shutting down all AsyncDiskService threads...
[junit] 2010-11-05 04:20:09,143 INFO util.AsyncDiskService (AsyncDiskService.java:awaitTermination(140)) - All AsyncDiskService threads are terminated.
[junit] 2010-11-05 04:20:09,145 INFO util.AsyncDiskService (AsyncDiskService.java:shutdown(111)) - Shutting down all AsyncDiskService threads...
[junit] 2010-11-05 04:20:09,145 INFO util.AsyncDiskService (AsyncDiskService.java:awaitTermination(140)) - All AsyncDiskService threads are terminated.
[junit] 2010-11-05 04:20:09,146 INFO mapred.TaskTracker (TaskTracker.java:run(865)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_host1.foo.com:localhost/127.0.0.1:46685
[junit] 2010-11-05 04:20:09,146 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 46685
[junit] 2010-11-05 04:20:09,146 ERROR filecache.TrackerDistributedCacheManager (TrackerDistributedCacheManager.java:run(935)) - Exception in DistributedCache CleanupThread.
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.mapreduce.filecache.TrackerDistributedCacheManager$CleanupThread.run(TrackerDistributedCacheManager.java:932)
[junit] 2010-11-05 04:20:09,147 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 3 on 46685: exiting
[junit] 2010-11-05 04:20:09,147 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 46685: exiting
[junit] 2010-11-05 04:20:09,147 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 46685: exiting
[junit] 2010-11-05 04:20:09,148 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-11-05 04:20:09,148 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 46685
[junit] 2010-11-05 04:20:09,148 INFO mapred.TaskTracker (TaskTracker.java:shutdown(1256)) - Shutting down StatusHttpServer
[junit] 2010-11-05 04:20:09,148 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 46685: exiting
[junit] 2010-11-05 04:20:09,251 INFO mapred.TaskTracker (TaskTracker.java:offerService(1564)) - Interrupted. Closing down.
[junit] 2010-11-05 04:20:09,252 INFO util.AsyncDiskService (AsyncDiskService.java:shutdown(111)) - Shutting down all AsyncDiskService threads...
[junit] 2010-11-05 04:20:09,253 INFO util.AsyncDiskService (AsyncDiskService.java:awaitTermination(140)) - All AsyncDiskService threads are terminated.
[junit] 2010-11-05 04:20:09,253 INFO mapred.JobTracker (JobTracker.java:close(1765)) - Stopping infoServer
[junit] 2010-11-05 04:20:09,355 INFO mapred.JobTracker (JobTracker.java:close(1773)) - Stopping interTrackerServer
[junit] 2010-11-05 04:20:09,356 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 41713
[junit] 2010-11-05 04:20:09,356 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 41713: exiting
[junit] 2010-11-05 04:20:09,356 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 41713
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 41713: exiting
[junit] 2010-11-05 04:20:09,356 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 6 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 9 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 7 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 8 on 41713: exiting
[junit] 2010-11-05 04:20:09,356 INFO mapred.JobTracker (JobTracker.java:stopExpireTrackersThread(1812)) - Stopping expireTrackers
[junit] 2010-11-05 04:20:09,357 INFO mapred.JobTracker (JobTracker.java:offerService(1760)) - Stopped interTrackerServer
[junit] 2010-11-05 04:20:09,360 INFO mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:terminate(108)) - Stopping Job Init Manager thread
[junit] 2010-11-05 04:20:09,358 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 5 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 3 on 41713: exiting
[junit] 2010-11-05 04:20:09,357 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 4 on 41713: exiting
[junit] 2010-11-05 04:20:09,360 INFO mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(61)) - JobInitManagerThread interrupted.
[junit] 2010-11-05 04:20:09,361 INFO mapred.EagerTaskInitializationListener (EagerTaskInitializationListener.java:run(65)) - Shutting down thread pool
[junit] 2010-11-05 04:20:09,362 INFO mapred.JobTracker (JobTracker.java:close(1783)) - Stopping expireLaunchingTasks
[junit] 2010-11-05 04:20:09,362 INFO jobhistory.JobHistory (JobHistory.java:shutDown(195)) - Interrupting History Cleaner
[junit] 2010-11-05 04:20:09,363 INFO jobhistory.JobHistory (JobHistory.java:run(544)) - History Cleaner thread exiting
[junit] 2010-11-05 04:20:09,363 ERROR mapred.MiniMRCluster (MiniMRCluster.java:shutdown(144)) - Problem shutting down job tracker
[junit] java.lang.NoClassDefFoundError: Could not initialize class org.apache.hadoop.mapreduce.security.token.DelegationTokenRenewal
[junit] at org.apache.hadoop.mapred.JobTracker.close(JobTracker.java:1805)
[junit] at org.apache.hadoop.mapred.JobTracker.stopTracker(JobTracker.java:303)
[junit] at org.apache.hadoop.mapred.MiniMRCluster$JobTrackerRunner.shutdown(MiniMRCluster.java:141)
[junit] at org.apache.hadoop.mapred.MiniMRCluster.stopJobTracker(MiniMRCluster.java:674)
[junit] at org.apache.hadoop.mapred.MiniMRCluster.shutdown(MiniMRCluster.java:762)
[junit] at org.apache.hadoop.mapreduce.TestMapReduceLocal.__CLR3_0_2z0hpdm1kp3(TestMapReduceLocal.java:93)
[junit] at org.apache.hadoop.mapreduce.TestMapReduceLocal.testWithLocal(TestMapReduceLocal.java:85)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] at org.apache.hadoop.mapreduce.security.token.DelegationTokenRenewal.<clinit>(DelegationTokenRenewal.java:116)
[junit] at org.apache.hadoop.mapred.JobInProgress.<init>(JobInProgress.java:480)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:3050)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:3016)
[junit] at org.apache.hadoop.mapred.JobTracker.submitJob(JobTracker.java:2998)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.WritableRpcEngine$Server.call(WritableRpcEngine.java:349)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1399)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:1395)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1115)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:1393)
[junit] )
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 26.406 sec
[junit] Test org.apache.hadoop.mapreduce.TestMapReduceLocal FAILED
[junit] Running org.apache.hadoop.mapreduce.lib.input.TestFileInputFormat
[junit] 2010-11-05 04:20:10,341 WARN conf.Configuration (Configuration.java:set(582)) - fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] 2010-11-05 04:20:10,539 WARN conf.Configuration (Configuration.java:set(582)) - fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] defaultfs.getUri() = s3://abc:xyz@hostname
[junit] original = file:/foo
[junit] results = [file:/foo]
[junit] original = file:/bar
[junit] results = [file:/bar]
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.917 sec
[junit] Running org.apache.hadoop.mapreduce.lib.output.TestFileOutputCommitter
[junit] 2010-11-05 04:20:11,970 INFO output.FileOutputCommitter (FileOutputCommitter.java:commitTask(173)) - Saved output of task 'attempt_200707121733_0001_m_000000_0' to <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/data/output>
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.524 sec
checkfailure:
[touch] Creating <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/testsfailed>
BUILD FAILED
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:760: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:723: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:784: Tests failed!
Total time: 19 minutes 22 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Hudson build is back to normal : Hadoop-Mapreduce-trunk-Commit #530
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/530/changes>
Build failed in Hudson: Hadoop-Mapreduce-trunk-Commit #529
Posted by Apache Hudson Server <hu...@hudson.apache.org>.
See <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/529/changes>
Changes:
[nigel] Update hudson patch admin script to fix missing option
------------------------------------------
[...truncated 16849 lines...]
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:963)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:917)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:312)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.registerMBean(JmxMBeanServer.java:482)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.registerMXBean(DataNode.java:528)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.startDataNode(DataNode.java:498)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.<init>(DataNode.java:281)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.<init>(DataNode.java:263)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.makeInstance(DataNode.java:1559)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.instantiateDataNode(DataNode.java:1502)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.instantiateDataNode(DataNode.java:1469)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.startDataNodes(MiniDFSCluster.java:616)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSCluster.java:448)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.<init>(MiniDFSCluster.java:378)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.<init>(MiniDFSCluster.java:281)
[junit] at org.apache.hadoop.mapred.TestFileInputFormat.__CLR3_0_29v537w1c7z(TestFileInputFormat.java:156)
[junit] at org.apache.hadoop.mapred.TestFileInputFormat.testMultiLevelInput(TestFileInputFormat.java:152)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] 2010-11-05 05:43:31,244 INFO datanode.DataNode (DataNode.java:initDataXceiver(467)) - Opened info server at 60268
[junit] 2010-11-05 05:43:31,244 INFO datanode.DataNode (DataXceiverServer.java:<init>(76)) - Balancing bandwith is 1048576 bytes/s
[junit] 2010-11-05 05:43:31,250 INFO common.Storage (DataStorage.java:recoverTransitionRead(127)) - Storage directory <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/data/dfs/data/data1> is not formatted.
[junit] 2010-11-05 05:43:31,251 INFO common.Storage (DataStorage.java:recoverTransitionRead(128)) - Formatting ...
[junit] 2010-11-05 05:43:31,264 INFO common.Storage (DataStorage.java:recoverTransitionRead(127)) - Storage directory <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/data/dfs/data/data2> is not formatted.
[junit] 2010-11-05 05:43:31,264 INFO common.Storage (DataStorage.java:recoverTransitionRead(128)) - Formatting ...
[junit] 2010-11-05 05:43:31,322 INFO datanode.DataNode (FSDataset.java:registerMBean(1773)) - Registered FSDatasetStatusMBean
[junit] 2010-11-05 05:43:31,323 INFO datanode.DirectoryScanner (DirectoryScanner.java:<init>(149)) - scan starts at 1288941446323 with interval 21600000
[junit] 2010-11-05 05:43:31,324 INFO http.HttpServer (HttpServer.java:addGlobalFilter(406)) - Added global filtersafety (class=org.apache.hadoop.http.HttpServer$QuotingInputFilter)
[junit] 2010-11-05 05:43:31,325 INFO http.HttpServer (HttpServer.java:start(576)) - Port returned by webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the listener on 0
[junit] 2010-11-05 05:43:31,325 INFO http.HttpServer (HttpServer.java:start(581)) - listener.getLocalPort() returned 56056 webServer.getConnectors()[0].getLocalPort() returned 56056
[junit] 2010-11-05 05:43:31,325 INFO http.HttpServer (HttpServer.java:start(614)) - Jetty bound to port 56056
[junit] 2010-11-05 05:43:31,326 INFO mortbay.log (?:invoke(?)) - jetty-6.1.14
[junit] 2010-11-05 05:43:31,563 INFO mortbay.log (?:invoke(?)) - Started SelectChannelConnector@localhost:56056
[junit] 2010-11-05 05:43:31,566 INFO jvm.JvmMetrics (JvmMetrics.java:init(71)) - Cannot initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
[junit] 2010-11-05 05:43:31,567 INFO ipc.Server (Server.java:run(338)) - Starting SocketReader
[junit] 2010-11-05 05:43:31,567 INFO metrics.RpcMetrics (RpcMetrics.java:<init>(63)) - Initializing RPC Metrics with hostName=DataNode, port=53080
[junit] 2010-11-05 05:43:31,568 INFO metrics.RpcDetailedMetrics (RpcDetailedMetrics.java:<init>(57)) - Initializing RPC Metrics with hostName=DataNode, port=53080
[junit] 2010-11-05 05:43:31,568 INFO datanode.DataNode (DataNode.java:initIpcServer(427)) - dnRegistration = DatanodeRegistration(host1.rack1.com:60268, storageID=, infoPort=56056, ipcPort=53080)
[junit] Adding node with IP:port : 127.0.0.1:60268 to rack /r1
[junit] 2010-11-05 05:43:31,569 INFO hdfs.StateChange (FSNamesystem.java:registerDatanode(2508)) - BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:60268 storage DS-313588303-127.0.1.1-60268-1288935811569
[junit] 2010-11-05 05:43:31,569 INFO net.NetworkTopology (NetworkTopology.java:add(331)) - Adding a new node: /r1/127.0.0.1:60268
[junit] 2010-11-05 05:43:31,573 INFO datanode.DataNode (DataNode.java:register(697)) - New storage id DS-313588303-127.0.1.1-60268-1288935811569 is assigned to data-node 127.0.0.1:60268
[junit] 2010-11-05 05:43:31,574 INFO datanode.DataNode (DataNode.java:run(1422)) - DatanodeRegistration(127.0.0.1:60268, storageID=DS-313588303-127.0.1.1-60268-1288935811569, infoPort=56056, ipcPort=53080)In DataNode.run, data = FSDataset{dirpath='<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/data/dfs/data/data1/current/finalized,/grid/0/hudson/hudson-slave/workspace/Hadoop-Mapreduce-trunk-Commit/trunk/build/test/data/dfs/data/data2/current/finalized'}>
[junit] 2010-11-05 05:43:31,574 INFO ipc.Server (Server.java:run(608)) - IPC Server Responder: starting
[junit] 2010-11-05 05:43:31,574 INFO ipc.Server (Server.java:run(443)) - IPC Server listener on 53080: starting
[junit] 2010-11-05 05:43:31,576 INFO datanode.DataNode (DataNode.java:offerService(889)) - using BLOCKREPORT_INTERVAL of 21600000msec Initial delay: 0msec
[junit] 2010-11-05 05:43:31,576 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 1 on 53080: starting
[junit] 2010-11-05 05:43:31,576 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 2 on 53080: starting
[junit] 2010-11-05 05:43:31,575 INFO ipc.Server (Server.java:run(1369)) - IPC Server handler 0 on 53080: starting
[junit] 2010-11-05 05:43:31,585 INFO datanode.DataNode (DataNode.java:blockReport(1129)) - BlockReport of 0 blocks got processed in 1 msecs
[junit] 2010-11-05 05:43:31,585 INFO datanode.DataNode (DataNode.java:offerService(932)) - Starting Periodic block scanner.
[junit] 2010-11-05 05:43:31,696 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=mkdirs src=/TestFileInputFormat/dir1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2010-11-05 05:43:31,699 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=create src=/TestFileInputFormat/file1 dst=null perm=hudson:supergroup:rw-r--r--
[junit] 2010-11-05 05:43:31,701 INFO hdfs.StateChange (FSNamesystem.java:allocateBlock(1753)) - BLOCK* NameSystem.allocateBlock: /TestFileInputFormat/file1. blk_-3164059746893134473_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:60268|RBW]]}
[junit] 2010-11-05 05:43:31,702 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(231)) - Receiving block blk_-3164059746893134473_1001 src: /127.0.0.1:53379 dest: /127.0.0.1:60268
[junit] 2010-11-05 05:43:31,704 INFO DataNode.clienttrace (BlockReceiver.java:run(903)) - src: /127.0.0.1:53379, dest: /127.0.0.1:60268, bytes: 1024, op: HDFS_WRITE, cliID: DFSClient_429060621, offset: 0, srvID: DS-313588303-127.0.1.1-60268-1288935811569, blockid: blk_-3164059746893134473_1001, duration: 438142
[junit] 2010-11-05 05:43:31,704 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 0 for block blk_-3164059746893134473_1001 terminating
[junit] 2010-11-05 05:43:31,704 INFO hdfs.StateChange (BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60268 is added to blk_-3164059746893134473_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:60268|RBW]]} size 0
[junit] 2010-11-05 05:43:31,705 INFO hdfs.StateChange (FSNamesystem.java:completeFileInternal(1713)) - DIR* NameSystem.completeFile: file /TestFileInputFormat/file1 is closed by DFSClient_429060621
[junit] 2010-11-05 05:43:31,709 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=open src=/TestFileInputFormat/file1 dst=null perm=null
[junit] 2010-11-05 05:43:31,712 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=create src=/TestFileInputFormat/dir1/file2 dst=null perm=hudson:supergroup:rw-r--r--
[junit] 2010-11-05 05:43:31,713 INFO hdfs.StateChange (FSNamesystem.java:allocateBlock(1753)) - BLOCK* NameSystem.allocateBlock: /TestFileInputFormat/dir1/file2. blk_1409217879314179539_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:60268|RBW]]}
[junit] 2010-11-05 05:43:31,714 INFO datanode.DataNode (DataXceiver.java:opWriteBlock(231)) - Receiving block blk_1409217879314179539_1002 src: /127.0.0.1:53380 dest: /127.0.0.1:60268
[junit] 2010-11-05 05:43:31,716 INFO DataNode.clienttrace (BlockReceiver.java:run(903)) - src: /127.0.0.1:53380, dest: /127.0.0.1:60268, bytes: 1024, op: HDFS_WRITE, cliID: DFSClient_429060621, offset: 0, srvID: DS-313588303-127.0.1.1-60268-1288935811569, blockid: blk_1409217879314179539_1002, duration: 547501
[junit] 2010-11-05 05:43:31,716 INFO hdfs.StateChange (BlockManager.java:addStoredBlock(1126)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60268 is added to blk_1409217879314179539_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[127.0.0.1:60268|RBW]]} size 0
[junit] 2010-11-05 05:43:31,717 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 0 for block blk_1409217879314179539_1002 terminating
[junit] 2010-11-05 05:43:31,718 INFO hdfs.StateChange (FSNamesystem.java:completeFileInternal(1713)) - DIR* NameSystem.completeFile: file /TestFileInputFormat/dir1/file2 is closed by DFSClient_429060621
[junit] 2010-11-05 05:43:31,720 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=open src=/TestFileInputFormat/dir1/file2 dst=null perm=null
[junit] 2010-11-05 05:43:31,722 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=listStatus src=/TestFileInputFormat dst=null perm=null
[junit] 2010-11-05 05:43:31,723 INFO mapred.FileInputFormat (FileInputFormat.java:listStatus(236)) - Total input paths to process : 2
[junit] 2010-11-05 05:43:31,725 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=listStatus src=/TestFileInputFormat dst=null perm=null
[junit] 2010-11-05 05:43:31,726 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=listStatus src=/TestFileInputFormat/dir1 dst=null perm=null
[junit] 2010-11-05 05:43:31,727 INFO mapred.FileInputFormat (FileInputFormat.java:listStatus(236)) - Total input paths to process : 2
[junit] 2010-11-05 05:43:31,728 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=open src=/TestFileInputFormat/dir1/file2 dst=null perm=null
[junit] 2010-11-05 05:43:31,729 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(148)) - ugi=hudson ip=/127.0.0.1 cmd=open src=/TestFileInputFormat/file1 dst=null perm=null
[junit] Shutting down the Mini HDFS Cluster
[junit] 2010-11-05 05:43:31,731 INFO hdfs.MiniDFSCluster (MiniDFSCluster.java:shutdownDataNodes(772)) - Shutting down DataNode 0
[junit] 2010-11-05 05:43:31,836 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 53080
[junit] 2010-11-05 05:43:31,836 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 53080: exiting
[junit] 2010-11-05 05:43:31,837 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 53080: exiting
[junit] 2010-11-05 05:43:31,837 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 53080: exiting
[junit] 2010-11-05 05:43:31,837 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-11-05 05:43:31,837 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 53080
[junit] 2010-11-05 05:43:31,837 WARN datanode.DataNode (DataXceiverServer.java:run(140)) - DatanodeRegistration(127.0.0.1:60268, storageID=DS-313588303-127.0.1.1-60268-1288935811569, infoPort=56056, ipcPort=53080):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:133)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2010-11-05 05:43:31,837 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2010-11-05 05:43:31,838 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(621)) - Exiting DataBlockScanner thread.
[junit] 2010-11-05 05:43:31,838 INFO datanode.DataNode (DataNode.java:run(1443)) - DatanodeRegistration(127.0.0.1:60268, storageID=DS-313588303-127.0.1.1-60268-1288935811569, infoPort=56056, ipcPort=53080):Finishing DataNode in: FSDataset{dirpath='<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/data/dfs/data/data1/current/finalized,/grid/0/hudson/hudson-slave/workspace/Hadoop-Mapreduce-trunk-Commit/trunk/build/test/data/dfs/data/data2/current/finalized'}>
[junit] 2010-11-05 05:43:31,839 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 53080
[junit] 2010-11-05 05:43:31,839 INFO datanode.DataNode (DataNode.java:shutdown(768)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2010-11-05 05:43:31,839 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(133)) - Shutting down all async disk service threads...
[junit] 2010-11-05 05:43:31,839 INFO datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(142)) - All async disk service threads have been shut down.
[junit] 2010-11-05 05:43:31,840 WARN datanode.FSDatasetAsyncDiskService (FSDatasetAsyncDiskService.java:shutdown(130)) - AsyncDiskService has already shut down.
[junit] 2010-11-05 05:43:31,941 WARN namenode.FSNamesystem (FSNamesystem.java:run(2818)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2010-11-05 05:43:31,941 WARN namenode.DecommissionManager (DecommissionManager.java:run(70)) - Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit] 2010-11-05 05:43:31,942 INFO namenode.FSEditLog (FSEditLog.java:printStatistics(630)) - Number of transactions: 8 Total time for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of syncs: 5 SyncTimes(ms): 8 4
[junit] 2010-11-05 05:43:31,943 INFO ipc.Server (Server.java:stop(1601)) - Stopping server on 37882
[junit] 2010-11-05 05:43:31,943 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 0 on 37882: exiting
[junit] 2010-11-05 05:43:31,943 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 2 on 37882: exiting
[junit] 2010-11-05 05:43:31,943 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 5 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(475)) - Stopping IPC Server listener on 37882
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 8 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 1 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 7 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 4 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 9 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(675)) - Stopping IPC Server Responder
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 6 on 37882: exiting
[junit] 2010-11-05 05:43:31,944 INFO ipc.Server (Server.java:run(1444)) - IPC Server handler 3 on 37882: exiting
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 9.48 sec
[junit] Running org.apache.hadoop.mapred.TestFileInputFormatPathFilter
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestFileInputFormatPathFilter FAILED
[junit] Running org.apache.hadoop.mapred.TestFileOutputCommitter
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestFileOutputCommitter FAILED
[junit] Running org.apache.hadoop.mapred.TestFileOutputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestFileOutputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestGetSplitHosts
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0.163 sec
[junit] Test org.apache.hadoop.mapred.TestGetSplitHosts FAILED
[junit] Running org.apache.hadoop.mapred.TestIFileStreams
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestIFileStreams FAILED
[junit] Running org.apache.hadoop.mapred.TestIndexCache
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestIndexCache FAILED
[junit] Running org.apache.hadoop.mapred.TestJavaSerialization
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestJavaSerialization FAILED
[junit] Running org.apache.hadoop.mapred.TestJobHistoryParsing
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestJobHistoryParsing FAILED
[junit] Running org.apache.hadoop.mapred.TestJobInProgress
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestJobInProgress FAILED
[junit] Running org.apache.hadoop.mapred.TestJobQueueTaskScheduler
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestJobQueueTaskScheduler FAILED
[junit] Running org.apache.hadoop.mapred.TestKillCompletedJob
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestKillCompletedJob FAILED
[junit] Running org.apache.hadoop.mapred.TestLostTracker
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestLostTracker FAILED
[junit] Running org.apache.hadoop.mapred.TestMapOutputType
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestMapOutputType FAILED
[junit] Running org.apache.hadoop.mapred.TestMapRed
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestMapRed FAILED
[junit] Running org.apache.hadoop.mapred.TestMiniMRDFSCaching
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestMiniMRDFSCaching FAILED
[junit] Running org.apache.hadoop.mapred.TestQueueAclsForCurrentUser
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestQueueAclsForCurrentUser FAILED
[junit] Running org.apache.hadoop.mapred.TestRackAwareTaskPlacement
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestRackAwareTaskPlacement FAILED
[junit] Running org.apache.hadoop.mapred.TestReduceFetchFromPartialMem
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestReduceFetchFromPartialMem FAILED
[junit] Running org.apache.hadoop.mapred.TestReduceTask
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestReduceTask FAILED
[junit] Running org.apache.hadoop.mapred.TestSequenceFileAsBinaryInputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestSequenceFileAsBinaryInputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestSequenceFileAsBinaryOutputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestSequenceFileAsBinaryOutputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestSequenceFileInputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestSequenceFileInputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestSeveral
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestSeveral FAILED
[junit] Running org.apache.hadoop.mapred.TestSpeculativeExecution
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestSpeculativeExecution FAILED
[junit] Running org.apache.hadoop.mapred.TestTaskLimits
[junit] Tests run: 3, Failures: 0, Errors: 3, Time elapsed: 0.172 sec
[junit] Test org.apache.hadoop.mapred.TestTaskLimits FAILED
[junit] Running org.apache.hadoop.mapred.TestTaskTrackerBlacklisting
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestTaskTrackerBlacklisting FAILED
[junit] Running org.apache.hadoop.mapred.TestTextInputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestTextInputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestTextOutputFormat
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestTextOutputFormat FAILED
[junit] Running org.apache.hadoop.mapred.TestTrackerBlacklistAcrossJobs
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapred.TestTrackerBlacklistAcrossJobs FAILED
[junit] Running org.apache.hadoop.mapreduce.TestCounters
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0.159 sec
[junit] Test org.apache.hadoop.mapreduce.TestCounters FAILED
[junit] Running org.apache.hadoop.mapreduce.TestMapCollection
[junit] Tests run: 11, Failures: 0, Errors: 11, Time elapsed: 0.159 sec
[junit] Test org.apache.hadoop.mapreduce.TestMapCollection FAILED
[junit] Running org.apache.hadoop.mapreduce.TestMapReduceLocal
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapreduce.TestMapReduceLocal FAILED
[junit] Running org.apache.hadoop.mapreduce.lib.input.TestFileInputFormat
[junit] Tests run: 2, Failures: 0, Errors: 2, Time elapsed: 0.16 sec
[junit] Test org.apache.hadoop.mapreduce.lib.input.TestFileInputFormat FAILED
[junit] Running org.apache.hadoop.mapreduce.lib.output.TestFileOutputCommitter
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.hadoop.mapreduce.lib.output.TestFileOutputCommitter FAILED
checkfailure:
[touch] Creating <https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build/test/testsfailed>
BUILD FAILED
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:760: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:723: The following error occurred while executing this line:
<https://hudson.apache.org/hudson/job/Hadoop-Mapreduce-trunk-Commit/ws/trunk/build.xml>:784: Tests failed!
Total time: 3 minutes 27 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure