You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by hu...@lucene.zones.apache.org on 2007/10/29 03:15:30 UTC
Build failed in Hudson: Hadoop-Nightly #285
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/285/changes
------------------------------------------
[...truncated 32789 lines...]
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:26,947 INFO mapred.ReduceTask (ReduceTask.java:fetchOutputs(977)) - task_200710290213_0004_r_000001_0 Scheduled 1 of 1 known outputs (0 slow hosts and 0 dup hosts)
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:26,948 INFO mapred.ReduceTask (ReduceTask.java:copyOutput(703)) - task_200710290213_0004_r_000001_0 Copying task_200710290213_0004_m_000000_0 output from lucene.zones.apache.org.
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:26,959 INFO mapred.ReduceTask (ReduceTask.java:copyOutput(751)) - task_200710290213_0004_r_000001_0 done copying task_200710290213_0004_m_000000_0 output from lucene.zones.apache.org.
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:26,980 INFO mapred.ReduceTask (ReduceTask.java:fetchOutputs(1121)) - task_200710290213_0004_r_000001_0 Copying of all map outputs complete. Initiating the last merge on the remaining files in ramfs://mapoutput13480046
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:27,448 INFO mapred.ReduceTask (ReduceTask.java:fetchOutputs(1165)) - task_200710290213_0004_r_000001_0 Merge of the 2 files in InMemoryFileSystem complete. Local file is http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/mapred/local/1_0/task_200710290213_0004_r_000001_0/map_0.out
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:27,502 INFO pipes.PipesReducer (PipesReducer.java:startApplication(68)) - starting application
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:27,634 INFO pipes.PipesReducer (PipesReducer.java:close(102)) - waiting for finish
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:27,639 INFO pipes.PipesReducer (PipesReducer.java:close(104)) - got done
[junit] task_200710290213_0004_r_000001_0: 2007-10-29 02:14:27,647 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'task_200710290213_0004_r_000001_0' done.
[junit] 2007-10-29 02:14:29,295 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_200710290213_0004
[junit] 2007-10-29 02:14:29,296 INFO mapred.JobClient (Counters.java:log(367)) - Counters: 6
[junit] 2007-10-29 02:14:29,297 INFO mapred.JobClient (Counters.java:log(371)) - Job Counters
[junit] 2007-10-29 02:14:29,297 INFO mapred.JobClient (Counters.java:log(373)) - Launched map tasks=2
[junit] 2007-10-29 02:14:29,297 INFO mapred.JobClient (Counters.java:log(373)) - Launched reduce tasks=2
[junit] 2007-10-29 02:14:29,298 INFO mapred.JobClient (Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-10-29 02:14:29,298 INFO mapred.JobClient (Counters.java:log(373)) - Map output records=13
[junit] 2007-10-29 02:14:29,298 INFO mapred.JobClient (Counters.java:log(373)) - Map output bytes=86
[junit] 2007-10-29 02:14:29,298 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input groups=11
[junit] 2007-10-29 02:14:29,299 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input records=13
[junit] 2007-10-29 02:14:29,300 INFO mapred.MiniMRCluster (MiniMRCluster.java:waitUntilIdle(206)) - Waiting for task tracker tracker_lucene.zones.apache.org:/127.0.0.1:62084 to be idle.
[junit] 2007-10-29 02:14:29,895 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(901)) - Removed completed task 'task_200710290213_0004_m_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:62085'
[junit] 2007-10-29 02:14:29,896 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(901)) - Removed completed task 'task_200710290213_0004_r_000001_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:62085'
[junit] 2007-10-29 02:14:29,897 INFO mapred.TaskTracker (TaskTracker.java:purgeJob(994)) - Received 'KillJobAction' for job: job_200710290213_0004
[junit] 2007-10-29 02:14:29,897 INFO mapred.TaskRunner (MapTaskRunner.java:close(41)) - task_200710290213_0004_m_000000_0 done; removing files.
[junit] 2007-10-29 02:14:29,900 INFO mapred.TaskRunner (ReduceTaskRunner.java:close(45)) - task_200710290213_0004_r_000001_0 done; removing files.
[junit] 2007-10-29 02:14:30,187 INFO dfs.DataNode (FSDataset.java:invalidate(709)) - Deleting block blk_2835197432124045512 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current/blk_2835197432124045512
[junit] 2007-10-29 02:14:30,188 INFO dfs.DataNode (FSDataset.java:invalidate(709)) - Deleting block blk_6255534627867866313 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data2/current/blk_6255534627867866313
[junit] 2007-10-29 02:14:30,304 INFO mapred.MiniMRCluster (MiniMRCluster.java:waitUntilIdle(206)) - Waiting for task tracker tracker_lucene.zones.apache.org:/127.0.0.1:62084 to be idle.
[junit] 2007-10-29 02:14:30,516 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(901)) - Removed completed task 'task_200710290213_0004_m_000001_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:62084'
[junit] 2007-10-29 02:14:30,516 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(901)) - Removed completed task 'task_200710290213_0004_r_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:62084'
[junit] 2007-10-29 02:14:30,517 INFO mapred.TaskTracker (TaskTracker.java:purgeJob(994)) - Received 'KillJobAction' for job: job_200710290213_0004
[junit] 2007-10-29 02:14:30,517 INFO mapred.TaskRunner (ReduceTaskRunner.java:close(45)) - task_200710290213_0004_r_000000_0 done; removing files.
[junit] 2007-10-29 02:14:30,518 INFO mapred.TaskRunner (MapTaskRunner.java:close(41)) - task_200710290213_0004_m_000001_0 done; removing files.
[junit] 2007-10-29 02:14:30,956 INFO dfs.DataNode (FSDataset.java:invalidate(709)) - Deleting block blk_2835197432124045512 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current/blk_2835197432124045512
[junit] 2007-10-29 02:14:30,957 INFO dfs.DataNode (FSDataset.java:invalidate(709)) - Deleting block blk_6255534627867866313 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4/current/blk_6255534627867866313
[junit] 2007-10-29 02:14:31,321 INFO ipc.Server (Server.java:stop(677)) - Stopping server on 62084
[junit] 2007-10-29 02:14:31,322 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 0 on 62084: exiting
[junit] 2007-10-29 02:14:31,323 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 1 on 62084: exiting
[junit] 2007-10-29 02:14:31,323 INFO ipc.Server (Server.java:run(316)) - Stopping IPC Server listener on 62084
[junit] 2007-10-29 02:14:31,323 INFO mapred.TaskTracker (TaskTracker.java:run(511)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:62084
[junit] 2007-10-29 02:14:31,323 INFO mapred.TaskTracker (TaskTracker.java:shutdown(649)) - Shutting down StatusHttpServer
[junit] 2007-10-29 02:14:31,327 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:50060
[junit] 2007-10-29 02:14:31,328 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1c3e9ba
[junit] 2007-10-29 02:14:31,511 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:31,689 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:31,814 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:31,815 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@45e228
[junit] 2007-10-29 02:14:31,816 INFO mapred.TaskTracker (TaskTracker.java:offerService(829)) - Interrupted. Closing down.
[junit] 2007-10-29 02:14:31,840 INFO ipc.Server (Server.java:stop(677)) - Stopping server on 62085
[junit] 2007-10-29 02:14:31,840 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 1 on 62085: exiting
[junit] 2007-10-29 02:14:31,840 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 0 on 62085: exiting
[junit] 2007-10-29 02:14:31,842 INFO ipc.Server (Server.java:run(316)) - Stopping IPC Server listener on 62085
[junit] 2007-10-29 02:14:31,844 INFO mapred.TaskTracker (TaskTracker.java:shutdown(649)) - Shutting down StatusHttpServer
[junit] 2007-10-29 02:14:31,844 INFO mapred.TaskTracker (TaskTracker.java:run(496)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:62085
[junit] 2007-10-29 02:14:31,846 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:50061
[junit] 2007-10-29 02:14:31,847 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1bfbfb8
[junit] 2007-10-29 02:14:31,978 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:32,096 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:32,218 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:32,219 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@2b249
[junit] 2007-10-29 02:14:32,220 INFO mapred.TaskTracker (TaskTracker.java:offerService(829)) - Interrupted. Closing down.
[junit] 2007-10-29 02:14:32,221 INFO mapred.JobTracker (JobTracker.java:close(739)) - Stopping infoServer
[junit] 2007-10-29 02:14:32,222 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:62083
[junit] 2007-10-29 02:14:32,223 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1f44ec7
[junit] 2007-10-29 02:14:32,340 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:32,453 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:32,577 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:32,578 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@111ded2
[junit] 2007-10-29 02:14:32,578 INFO mapred.JobTracker (JobTracker.java:close(747)) - Stopping interTrackerServer
[junit] 2007-10-29 02:14:32,578 INFO ipc.Server (Server.java:stop(677)) - Stopping server on 62081
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 2 on 62081: exiting
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 1 on 62081: exiting
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 4 on 62081: exiting
[junit] 2007-10-29 02:14:32,579 INFO mapred.JobTracker (JobTracker.java:close(751)) - Stopping expireTrackers
[junit] 2007-10-29 02:14:32,579 INFO mapred.JobTracker (JobTracker.java:offerService(734)) - Stopped interTrackerServer
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(316)) - Stopping IPC Server listener on 62081
[junit] 2007-10-29 02:14:32,580 ERROR mapred.JobTracker (JobTracker.java:run(308)) - Tracker Expiry Thread got exception: java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.mapred.JobTracker$ExpireTrackers.run(JobTracker.java:263)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 0 on 62081: exiting
[junit] 2007-10-29 02:14:32,581 INFO mapred.JobTracker (JobTracker.java:close(761)) - Stopping retirer
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 8 on 62081: exiting
[junit] 2007-10-29 02:14:32,582 INFO mapred.JobTracker (JobTracker.java:close(770)) - Stopping initer
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 9 on 62081: exiting
[junit] 2007-10-29 02:14:32,582 INFO mapred.JobTracker (JobTracker.java:close(779)) - Stopping expireLaunchingTasks
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 5 on 62081: exiting
[junit] 2007-10-29 02:14:32,583 INFO mapred.JobTracker (JobTracker.java:close(789)) - Stopping TaskCommit thread
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 6 on 62081: exiting
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 3 on 62081: exiting
[junit] 2007-10-29 02:14:32,583 WARN mapred.JobTracker (JobTracker.java:run(2014)) - Task Commit Thread exiting, got interrupted: java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1815)
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1850)
[junit] at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:359)
[junit] at org.apache.hadoop.mapred.JobTracker$TaskCommitQueue.run(JobTracker.java:1916)
[junit] 2007-10-29 02:14:32,579 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 7 on 62081: exiting
[junit] 2007-10-29 02:14:32,584 INFO mapred.JobTracker (JobTracker.java:close(797)) - stopped all jobtracker services
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2007-10-29 02:14:32,586 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:62080
[junit] 2007-10-29 02:14:32,587 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@73305c
[junit] 2007-10-29 02:14:32,714 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:32,841 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:32,968 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:32,968 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@a7dd39
[junit] 2007-10-29 02:14:32,970 INFO dfs.DataNode (DataNode.java:run(1512)) - Finishing DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] Shutting down DataNode 0
[junit] 2007-10-29 02:14:32,971 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=62079]
[junit] 2007-10-29 02:14:32,972 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:62079
[junit] 2007-10-29 02:14:32,973 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@933bcb
[junit] 2007-10-29 02:14:33,092 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:33,208 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:33,328 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:33,329 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@2b323e
[junit] 2007-10-29 02:14:33,330 INFO dfs.DataNode (DataNode.java:run(761)) - Exiting DataXceiveServer due to java.net.SocketException: Socket closed
[junit] 2007-10-29 02:14:33,330 INFO dfs.DataNode (DataNode.java:run(1512)) - Finishing DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2/current'}
[junit] 2007-10-29 02:14:33,331 WARN fs.FSNamesystem (PendingReplicationBlocks.java:run(186)) - PendingReplicationMonitor thread received exception. java.lang.InterruptedException: sleep interrupted
[junit] 2007-10-29 02:14:33,332 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:62077
[junit] 2007-10-29 02:14:33,333 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1eec35
[junit] 2007-10-29 02:14:33,467 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-10-29 02:14:33,580 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-10-29 02:14:33,698 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-10-29 02:14:33,699 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@1e78fc6
[junit] 2007-10-29 02:14:33,700 INFO fs.FSNamesystem (FSEditLog.java:printStatistics(741)) - Number of transactions: 117 Total time for transactions(ms): 4 Number of syncs: 77 SyncTimes(ms): 106 50
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:stop(677)) - Stopping server on 62076
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 1 on 62076: exiting
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 3 on 62076: exiting
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 4 on 62076: exiting
[junit] 2007-10-29 02:14:33,703 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 7 on 62076: exiting
[junit] 2007-10-29 02:14:33,703 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 8 on 62076: exiting
[junit] 2007-10-29 02:14:33,703 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 9 on 62076: exiting
[junit] 2007-10-29 02:14:33,704 INFO ipc.Server (Server.java:run(316)) - Stopping IPC Server listener on 62076
[junit] 2007-10-29 02:14:33,703 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 6 on 62076: exiting
[junit] 2007-10-29 02:14:33,703 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 5 on 62076: exiting
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 0 on 62076: exiting
[junit] 2007-10-29 02:14:33,702 INFO ipc.Server (Server.java:run(633)) - IPC Server handler 2 on 62076: exiting
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 85.003 sec
[junit] Running org.apache.hadoop.net.TestNetworkTopology
[junit] 2007-10-29 02:14:34,341 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
[junit] 2007-10-29 02:14:34,346 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
[junit] 2007-10-29 02:14:34,347 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
[junit] 2007-10-29 02:14:34,348 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
[junit] 2007-10-29 02:14:34,349 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
[junit] 2007-10-29 02:14:34,350 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
[junit] 2007-10-29 02:14:34,351 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020
[junit] 2007-10-29 02:14:34,398 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h1:5020
[junit] 2007-10-29 02:14:34,400 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h2:5020
[junit] 2007-10-29 02:14:34,401 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h3:5020
[junit] 2007-10-29 02:14:34,402 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h4:5020
[junit] 2007-10-29 02:14:34,403 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h5:5020
[junit] 2007-10-29 02:14:34,405 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h6:5020
[junit] 2007-10-29 02:14:34,406 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h7:5020
[junit] 2007-10-29 02:14:34,407 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
[junit] 2007-10-29 02:14:34,408 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
[junit] 2007-10-29 02:14:34,409 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
[junit] 2007-10-29 02:14:34,411 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
[junit] 2007-10-29 02:14:34,412 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
[junit] 2007-10-29 02:14:34,413 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
[junit] 2007-10-29 02:14:34,414 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.061 sec
[junit] Running org.apache.hadoop.record.TestBuffer
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.118 sec
[junit] Running org.apache.hadoop.record.TestRecordIO
[junit] Illustrating toString bugT,102,4567,99344109427290,3.1449999809265137,1.5234,'random text,#000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff,v{},m{},s{'other random text}
[junit] Illustrating toString bugT,102,4567,99344109427290,3.1449999809265137,1.5234,'random text,#000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff,v{},m{},s{'other random text}
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.38 sec
[junit] Running org.apache.hadoop.record.TestRecordMR
[junit] 2007-10-29 02:14:37,142 INFO jvm.JvmMetrics (JvmMetrics.java:init(56)) - Initializing JVM Metrics with processName=JobTracker, sessionId=
[junit] 2007-10-29 02:14:37,328 WARN mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2007-10-29 02:14:37,358 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-10-29 02:14:37,671 WARN conf.Configuration (Configuration.java:loadResource(842)) - build/test/mapred/local/localRunner/job_local_1.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2007-10-29 02:14:37,681 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_1
[junit] 2007-10-29 02:14:37,717 INFO mapred.MapTask (MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-10-29 02:14:37,895 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genins/answer.key :0+190
[junit] 2007-10-29 02:14:37,897 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-10-29 02:14:37,992 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-10-29 02:14:37,994 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'reduce_uy7n4t' done.
[junit] 2007-10-29 02:14:38,003 INFO mapred.TaskRunner (Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_uy7n4t' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts
[junit] 2007-10-29 02:14:38,684 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_1
[junit] 2007-10-29 02:14:38,686 INFO mapred.JobClient (Counters.java:log(367)) - Counters: 7
[junit] 2007-10-29 02:14:38,693 INFO mapred.JobClient (Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-10-29 02:14:38,694 INFO mapred.JobClient (Counters.java:log(373)) - Map input records=10
[junit] 2007-10-29 02:14:38,696 INFO mapred.JobClient (Counters.java:log(373)) - Map output records=105
[junit] 2007-10-29 02:14:38,697 INFO mapred.JobClient (Counters.java:log(373)) - Map input bytes=100
[junit] 2007-10-29 02:14:38,698 INFO mapred.JobClient (Counters.java:log(373)) - Map output bytes=734
[junit] 2007-10-29 02:14:38,699 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input groups=105
[junit] 2007-10-29 02:14:38,700 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input records=105
[junit] 2007-10-29 02:14:38,701 INFO mapred.JobClient (Counters.java:log(373)) - Reduce output records=105
[junit] 2007-10-29 02:14:38,728 INFO jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
[junit] 2007-10-29 02:14:38,732 WARN mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2007-10-29 02:14:38,735 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-10-29 02:14:38,852 WARN conf.Configuration (Configuration.java:loadResource(842)) - build/test/mapred/local/localRunner/job_local_2.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2007-10-29 02:14:38,855 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_2
[junit] 2007-10-29 02:14:38,867 INFO mapred.MapTask (MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-10-29 02:14:38,946 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts/part-00000 :0+1143
[junit] 2007-10-29 02:14:38,947 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-10-29 02:14:38,972 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-10-29 02:14:38,973 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'reduce_7jb7o8' done.
[junit] 2007-10-29 02:14:38,979 INFO mapred.TaskRunner (Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_7jb7o8' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts
[junit] 2007-10-29 02:14:39,864 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_2
[junit] 2007-10-29 02:14:39,865 INFO mapred.JobClient (Counters.java:log(367)) - Counters: 7
[junit] 2007-10-29 02:14:39,866 INFO mapred.JobClient (Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-10-29 02:14:39,866 INFO mapred.JobClient (Counters.java:log(373)) - Map input records=105
[junit] 2007-10-29 02:14:39,867 INFO mapred.JobClient (Counters.java:log(373)) - Map output records=105
[junit] 2007-10-29 02:14:39,867 INFO mapred.JobClient (Counters.java:log(373)) - Map input bytes=1050
[junit] 2007-10-29 02:14:39,868 INFO mapred.JobClient (Counters.java:log(373)) - Map output bytes=315
[junit] 2007-10-29 02:14:39,868 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input groups=10
[junit] 2007-10-29 02:14:39,869 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input records=105
[junit] 2007-10-29 02:14:39,869 INFO mapred.JobClient (Counters.java:log(373)) - Reduce output records=10
[junit] 2007-10-29 02:14:39,876 INFO jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized
[junit] 2007-10-29 02:14:39,878 WARN mapred.JobClient (JobClient.java:submitJob(496)) - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
[junit] 2007-10-29 02:14:39,879 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-10-29 02:14:39,952 WARN conf.Configuration (Configuration.java:loadResource(842)) - build/test/mapred/local/localRunner/job_local_3.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring.
[junit] 2007-10-29 02:14:39,954 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_3
[junit] 2007-10-29 02:14:39,961 INFO mapred.MapTask (MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-10-29 02:14:39,979 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts/part-00000 :0+212
[junit] 2007-10-29 02:14:39,980 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-10-29 02:14:39,997 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-10-29 02:14:39,998 INFO mapred.TaskRunner (Task.java:done(382)) - Task 'reduce_6rkptn' done.
[junit] 2007-10-29 02:14:40,005 INFO mapred.TaskRunner (Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_6rkptn' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/finalouts
[junit] 2007-10-29 02:14:40,964 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_3
[junit] 2007-10-29 02:14:40,965 INFO mapred.JobClient (Counters.java:log(367)) - Counters: 7
[junit] 2007-10-29 02:14:40,965 INFO mapred.JobClient (Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-10-29 02:14:40,967 INFO mapred.JobClient (Counters.java:log(373)) - Map input records=10
[junit] 2007-10-29 02:14:40,968 INFO mapred.JobClient (Counters.java:log(373)) - Map output records=10
[junit] 2007-10-29 02:14:40,969 INFO mapred.JobClient (Counters.java:log(373)) - Map input bytes=119
[junit] 2007-10-29 02:14:40,970 INFO mapred.JobClient (Counters.java:log(373)) - Map output bytes=20
[junit] 2007-10-29 02:14:40,971 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input groups=10
[junit] 2007-10-29 02:14:40,972 INFO mapred.JobClient (Counters.java:log(373)) - Reduce input records=10
[junit] 2007-10-29 02:14:40,973 INFO mapred.JobClient (Counters.java:log(373)) - Reduce output records=10
[junit] Original sum: 105
[junit] Recomputed sum: 105
[junit] Success=true
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.473 sec
[junit] Running org.apache.hadoop.record.TestRecordWritable
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 3.949 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 1.206 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :508: Tests failed!
Total time: 30 minutes 48 seconds
Recording fingerprints
Publishing Javadoc
Recording test results