See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/292/changes
Changes:
[jimk] HADOOP-2109 Fix another race condition in processing dead servers, Fix
error online meta regions: was using region name and not startKey as key for
map.put
------------------------------------------
[...truncated 33604 lines...]
[junit] 2007-11-04 11:51:12,216 INFO mapred.JobClient
(Counters.java:log(371)) - Job Counters
[junit] 2007-11-04 11:51:12,217 INFO mapred.JobClient
(Counters.java:log(373)) - Launched map tasks=2
[junit] 2007-11-04 11:51:12,218 INFO mapred.JobClient
(Counters.java:log(373)) - Launched reduce tasks=2
[junit] 2007-11-04 11:51:12,219 INFO mapred.JobClient
(Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-11-04 11:51:12,220 INFO mapred.JobClient
(Counters.java:log(373)) - Map output records=13
[junit] 2007-11-04 11:51:12,221 INFO mapred.JobClient
(Counters.java:log(373)) - Map output bytes=86
[junit] 2007-11-04 11:51:12,221 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input groups=11
[junit] 2007-11-04 11:51:12,223 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input records=13
[junit] 2007-11-04 11:51:12,224 INFO mapred.MiniMRCluster
(MiniMRCluster.java:waitUntilIdle(206)) - Waiting for task tracker
tracker_lucene.zones.apache.org:/127.0.0.1:52187 to be idle.
[junit] 2007-11-04 11:51:12,783 INFO mapred.JobTracker
(JobTracker.java:removeMarkedTasks(901)) - Removed completed task
'task_200711041149_0004_m_000000_0' from
'tracker_lucene.zones.apache.org:/127.0.0.1:52187'
[junit] 2007-11-04 11:51:12,784 INFO mapred.JobTracker
(JobTracker.java:removeMarkedTasks(901)) - Removed completed task
'task_200711041149_0004_m_000001_0' from
'tracker_lucene.zones.apache.org:/127.0.0.1:52187'
[junit] 2007-11-04 11:51:12,785 INFO mapred.JobTracker
(JobTracker.java:removeMarkedTasks(901)) - Removed completed task
'task_200711041149_0004_r_000000_0' from
'tracker_lucene.zones.apache.org:/127.0.0.1:52187'
[junit] 2007-11-04 11:51:12,786 INFO mapred.TaskTracker
(TaskTracker.java:purgeJob(994)) - Received 'KillJobAction' for job:
job_200711041149_0004
[junit] 2007-11-04 11:51:12,787 INFO mapred.TaskRunner
(MapTaskRunner.java:close(41)) - task_200711041149_0004_m_000000_0 done;
removing files.
[junit] 2007-11-04 11:51:12,790 INFO mapred.TaskRunner
(MapTaskRunner.java:close(41)) - task_200711041149_0004_m_000001_0 done;
removing files.
[junit] 2007-11-04 11:51:13,003 INFO mapred.JobTracker
(JobTracker.java:removeMarkedTasks(901)) - Removed completed task
'task_200711041149_0004_r_000001_0' from
'tracker_lucene.zones.apache.org:/127.0.0.1:52189'
[junit] 2007-11-04 11:51:13,005 INFO mapred.TaskTracker
(TaskTracker.java:purgeJob(994)) - Received 'KillJobAction' for job:
job_200711041149_0004
[junit] 2007-11-04 11:51:13,006 INFO mapred.TaskRunner
(ReduceTaskRunner.java:close(45)) - task_200711041149_0004_r_000001_0 done;
removing files.
[junit] 2007-11-04 11:51:13,235 INFO ipc.Server (Server.java:stop(677)) -
Stopping server on 52187
[junit] 2007-11-04 11:51:13,236 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 1 on 52187: exiting
[junit] 2007-11-04 11:51:13,236 INFO mapred.TaskTracker
(TaskTracker.java:shutdown(649)) - Shutting down StatusHttpServer
[junit] 2007-11-04 11:51:13,236 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 0 on 52187: exiting
[junit] 2007-11-04 11:51:13,238 INFO ipc.Server (Server.java:run(316)) -
Stopping IPC Server listener on 52187
[junit] 2007-11-04 11:51:13,236 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:52187
[junit] 2007-11-04 11:51:13,241 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:50060
[junit] 2007-11-04 11:51:13,246 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:13,596 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:13,596 INFO dfs.StateChange
(FSNamesystem.java:blocksToInvalidate(2815)) - BLOCK*
NameSystem.blockToInvalidate: ask 127.0.0.1:50010 to delete
blk_-278177298271465829 blk_5284868592679994156
[junit] 2007-11-04 11:51:13,597 INFO dfs.StateChange
(FSNamesystem.java:blocksToInvalidate(2815)) - BLOCK*
NameSystem.blockToInvalidate: ask 127.0.0.1:50011 to delete
blk_-278177298271465829 blk_5284868592679994156
[junit] 2007-11-04 11:51:13,723 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:13,910 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:13,911 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:13,911 INFO mapred.TaskTracker
(TaskTracker.java:offerService(829)) - Interrupted. Closing down.
[junit] 2007-11-04 11:51:13,912 INFO dfs.DataNode
(FSDataset.java:invalidate(709)) - Deleting block blk_-278177298271465829 file
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current/blk_-278177298271465829
[junit] 2007-11-04 11:51:13,912 INFO dfs.DataNode
(FSDataset.java:invalidate(709)) - Deleting block blk_5284868592679994156 file
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4/current/blk_5284868592679994156
[junit] 2007-11-04 11:51:13,913 INFO ipc.Server (Server.java:stop(677)) -
Stopping server on 52189
[junit] 2007-11-04 11:51:13,914 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 0 on 52189: exiting
[junit] 2007-11-04 11:51:13,914 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 1 on 52189: exiting
[junit] 2007-11-04 11:51:13,914 INFO ipc.Server (Server.java:run(316)) -
Stopping IPC Server listener on 52189
[junit] 2007-11-04 11:51:13,915 INFO mapred.TaskTracker
(TaskTracker.java:shutdown(649)) - Shutting down StatusHttpServer
[junit] 2007-11-04 11:51:13,915 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:52189
[junit] 2007-11-04 11:51:13,916 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=50061]
[junit] 2007-11-04 11:51:13,922 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:50061
[junit] 2007-11-04 11:51:13,923 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:14,045 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:14,164 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:14,280 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:14,280 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:14,281 INFO mapred.TaskTracker
(TaskTracker.java:offerService(829)) - Interrupted. Closing down.
[junit] 2007-11-04 11:51:14,282 INFO mapred.JobTracker
(JobTracker.java:close(739)) - Stopping infoServer
[junit] 2007-11-04 11:51:14,283 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:52186
[junit] 2007-11-04 11:51:14,284 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:14,407 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:14,527 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:14,647 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:14,648 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:14,648 INFO mapred.JobTracker
(JobTracker.java:close(747)) - Stopping interTrackerServer
[junit] 2007-11-04 11:51:14,649 INFO ipc.Server (Server.java:stop(677)) -
Stopping server on 52185
[junit] 2007-11-04 11:51:14,649 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 2 on 52185: exiting
[junit] 2007-11-04 11:51:14,649 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 5 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 6 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO mapred.JobTracker
(JobTracker.java:offerService(734)) - Stopped interTrackerServer
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 4 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 0 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 7 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 3 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 9 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 8 on 52185: exiting
[junit] 2007-11-04 11:51:14,650 INFO ipc.Server (Server.java:run(316)) -
Stopping IPC Server listener on 52185
[junit] 2007-11-04 11:51:14,650 INFO mapred.JobTracker
(JobTracker.java:close(751)) - Stopping expireTrackers
[junit] 2007-11-04 11:51:14,649 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 1 on 52185: exiting
[junit] 2007-11-04 11:51:14,654 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-11-04 11:51:14,654 INFO mapred.JobTracker
(JobTracker.java:close(761)) - Stopping retirer
[junit] 2007-11-04 11:51:14,655 INFO mapred.JobTracker
(JobTracker.java:close(770)) - Stopping initer
[junit] 2007-11-04 11:51:14,655 INFO mapred.JobTracker
(JobTracker.java:close(779)) - Stopping expireLaunchingTasks
[junit] 2007-11-04 11:51:14,656 INFO mapred.JobTracker
(JobTracker.java:close(789)) - Stopping TaskCommit thread
[junit] 2007-11-04 11:51:14,656 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-11-04 11:51:14,657 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-11-04 11:51:14,657 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=52184]
[junit] 2007-11-04 11:51:14,658 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:52184
[junit] 2007-11-04 11:51:14,659 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:14,777 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:14,902 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:15,018 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:15,019 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:15,021 INFO dfs.DataNode
(DataNode.java:run(1713)) - 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-11-04 11:51:15,023 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:52183
[junit] 2007-11-04 11:51:15,024 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:15,136 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:15,251 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:15,364 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:15,364 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:15,366 ERROR dfs.DataNode
(DataNode.java:run(1697)) - Exception:
java.lang.reflect.UndeclaredThrowableException
[junit] at org.apache.hadoop.dfs.$Proxy1.sendHeartbeat(Unknown Source)
[junit] at
org.apache.hadoop.dfs.DataNode.offerService(DataNode.java:532)
[junit] at org.apache.hadoop.dfs.DataNode.run(DataNode.java:1695)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] Caused by: java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:477)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:184)
[junit] ... 4 more
[junit] 2007-11-04 11:51:15,366 INFO dfs.DataNode
(DataNode.java:run(1713)) - 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-11-04 11:51:15,367 WARN fs.FSNamesystem
(PendingReplicationBlocks.java:run(186)) - PendingReplicationMonitor thread
received exception. java.lang.InterruptedException: sleep interrupted
[junit] 2007-11-04 11:51:15,368 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=52181]
[junit] 2007-11-04 11:51:15,368 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:52181
[junit] 2007-11-04 11:51:15,369 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:15,480 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2007-11-04 11:51:15,588 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2007-11-04 11:51:15,693 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2007-11-04 11:51:15,694 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2007-11-04 11:51:15,695 INFO fs.FSNamesystem
(FSEditLog.java:printStatistics(741)) - Number of transactions: 117 Total time
for transactions(ms): 5 Number of syncs: 77 SyncTimes(ms): 117 77
[junit] 2007-11-04 11:51:15,696 INFO ipc.Server (Server.java:stop(677)) -
Stopping server on 52180
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 5 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 8 on 52180: exiting
[junit] 2007-11-04 11:51:15,698 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 4 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 0 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 9 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 7 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 2 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 1 on 52180: exiting
[junit] 2007-11-04 11:51:15,699 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 6 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(633)) -
IPC Server handler 3 on 52180: exiting
[junit] 2007-11-04 11:51:15,697 INFO ipc.Server (Server.java:run(316)) -
Stopping IPC Server listener on 52180
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 84.119 sec
[junit] Running org.apache.hadoop.net.TestNetworkTopology
[junit] 2007-11-04 11:51:16,489 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
[junit] 2007-11-04 11:51:16,497 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
[junit] 2007-11-04 11:51:16,497 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
[junit] 2007-11-04 11:51:16,498 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
[junit] 2007-11-04 11:51:16,499 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
[junit] 2007-11-04 11:51:16,500 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
[junit] 2007-11-04 11:51:16,501 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020
[junit] 2007-11-04 11:51:16,585 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h1:5020
[junit] 2007-11-04 11:51:16,587 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h2:5020
[junit] 2007-11-04 11:51:16,588 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h3:5020
[junit] 2007-11-04 11:51:16,589 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h4:5020
[junit] 2007-11-04 11:51:16,599 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h5:5020
[junit] 2007-11-04 11:51:16,601 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h6:5020
[junit] 2007-11-04 11:51:16,602 INFO net.NetworkTopology
(NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h7:5020
[junit] 2007-11-04 11:51:16,603 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020
[junit] 2007-11-04 11:51:16,625 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020
[junit] 2007-11-04 11:51:16,627 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020
[junit] 2007-11-04 11:51:16,628 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020
[junit] 2007-11-04 11:51:16,680 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020
[junit] 2007-11-04 11:51:16,682 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020
[junit] 2007-11-04 11:51:16,683 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.178 sec
[junit] Running org.apache.hadoop.record.TestBuffer
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.127 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.282 sec
[junit] Running org.apache.hadoop.record.TestRecordMR
[junit] 2007-11-04 11:51:19,478 INFO jvm.JvmMetrics
(JvmMetrics.java:init(56)) - Initializing JVM Metrics with
processName=JobTracker, sessionId=
[junit] 2007-11-04 11:51:19,534 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-11-04 11:51:19,547 INFO mapred.FileInputFormat
(FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-11-04 11:51:19,796 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-11-04 11:51:19,805 INFO mapred.JobClient
(JobClient.java:runJob(755)) - Running job: job_local_1
[junit] 2007-11-04 11:51:19,878 INFO mapred.MapTask
(MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-11-04 11:51:20,064 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-11-04 11:51:20,069 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-11-04 11:51:20,149 INFO mapred.LocalJobRunner
(LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-11-04 11:51:20,150 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'reduce_ir8cd7' done.
[junit] 2007-11-04 11:51:20,158 INFO mapred.TaskRunner
(Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_ir8cd7' to
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts
[junit] 2007-11-04 11:51:20,812 INFO mapred.JobClient
(JobClient.java:runJob(833)) - Job complete: job_local_1
[junit] 2007-11-04 11:51:20,814 INFO mapred.JobClient
(Counters.java:log(367)) - Counters: 7
[junit] 2007-11-04 11:51:20,823 INFO mapred.JobClient
(Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-11-04 11:51:20,824 INFO mapred.JobClient
(Counters.java:log(373)) - Map input records=10
[junit] 2007-11-04 11:51:20,825 INFO mapred.JobClient
(Counters.java:log(373)) - Map output records=100
[junit] 2007-11-04 11:51:20,826 INFO mapred.JobClient
(Counters.java:log(373)) - Map input bytes=100
[junit] 2007-11-04 11:51:20,827 INFO mapred.JobClient
(Counters.java:log(373)) - Map output bytes=699
[junit] 2007-11-04 11:51:20,828 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input groups=100
[junit] 2007-11-04 11:51:20,829 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input records=100
[junit] 2007-11-04 11:51:20,830 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce output records=100
[junit] 2007-11-04 11:51:20,838 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=JobTracker, sessionId= - already initialized
[junit] 2007-11-04 11:51:20,843 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-11-04 11:51:20,846 INFO mapred.FileInputFormat
(FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-11-04 11:51:21,066 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-11-04 11:51:21,069 INFO mapred.JobClient
(JobClient.java:runJob(755)) - Running job: job_local_2
[junit] 2007-11-04 11:51:21,082 INFO mapred.MapTask
(MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-11-04 11:51:21,128 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+1093
[junit] 2007-11-04 11:51:21,129 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-11-04 11:51:21,151 INFO mapred.LocalJobRunner
(LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-11-04 11:51:21,152 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'reduce_jg5mvr' done.
[junit] 2007-11-04 11:51:21,158 INFO mapred.TaskRunner
(Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_jg5mvr' to
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts
[junit] 2007-11-04 11:51:22,071 INFO mapred.JobClient
(JobClient.java:runJob(833)) - Job complete: job_local_2
[junit] 2007-11-04 11:51:22,072 INFO mapred.JobClient
(Counters.java:log(367)) - Counters: 7
[junit] 2007-11-04 11:51:22,073 INFO mapred.JobClient
(Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-11-04 11:51:22,074 INFO mapred.JobClient
(Counters.java:log(373)) - Map input records=100
[junit] 2007-11-04 11:51:22,075 INFO mapred.JobClient
(Counters.java:log(373)) - Map output records=100
[junit] 2007-11-04 11:51:22,076 INFO mapred.JobClient
(Counters.java:log(373)) - Map input bytes=1000
[junit] 2007-11-04 11:51:22,076 INFO mapred.JobClient
(Counters.java:log(373)) - Map output bytes=300
[junit] 2007-11-04 11:51:22,077 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input groups=10
[junit] 2007-11-04 11:51:22,077 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input records=100
[junit] 2007-11-04 11:51:22,078 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce output records=10
[junit] 2007-11-04 11:51:22,092 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=JobTracker, sessionId= - already initialized
[junit] 2007-11-04 11:51:22,093 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-11-04 11:51:22,095 INFO mapred.FileInputFormat
(FileInputFormat.java:validateInput(157)) - Total input paths to process : 1
[junit] 2007-11-04 11:51:22,174 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-11-04 11:51:22,176 INFO mapred.JobClient
(JobClient.java:runJob(755)) - Running job: job_local_3
[junit] 2007-11-04 11:51:22,183 INFO mapred.MapTask
(MapTask.java:run(127)) - numReduceTasks: 1
[junit] 2007-11-04 11:51:22,200 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+207
[junit] 2007-11-04 11:51:22,201 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'map_0000' done.
[junit] 2007-11-04 11:51:22,219 INFO mapred.LocalJobRunner
(LocalJobRunner.java:statusUpdate(210)) - reduce > reduce
[junit] 2007-11-04 11:51:22,220 INFO mapred.TaskRunner
(Task.java:done(382)) - Task 'reduce_awe68x' done.
[junit] 2007-11-04 11:51:22,229 INFO mapred.TaskRunner
(Task.java:saveTaskOutput(434)) - Saved output of task 'reduce_awe68x' to
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/finalouts
[junit] 2007-11-04 11:51:23,181 INFO mapred.JobClient
(JobClient.java:runJob(833)) - Job complete: job_local_3
[junit] 2007-11-04 11:51:23,182 INFO mapred.JobClient
(Counters.java:log(367)) - Counters: 7
[junit] 2007-11-04 11:51:23,183 INFO mapred.JobClient
(Counters.java:log(371)) - Map-Reduce Framework
[junit] 2007-11-04 11:51:23,183 INFO mapred.JobClient
(Counters.java:log(373)) - Map input records=10
[junit] 2007-11-04 11:51:23,185 INFO mapred.JobClient
(Counters.java:log(373)) - Map output records=10
[junit] 2007-11-04 11:51:23,185 INFO mapred.JobClient
(Counters.java:log(373)) - Map input bytes=114
[junit] 2007-11-04 11:51:23,186 INFO mapred.JobClient
(Counters.java:log(373)) - Map output bytes=20
[junit] 2007-11-04 11:51:23,187 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input groups=10
[junit] 2007-11-04 11:51:23,188 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce input records=10
[junit] 2007-11-04 11:51:23,189 INFO mapred.JobClient
(Counters.java:log(373)) - Reduce output records=10
[junit] Original sum: 100
[junit] Recomputed sum: 100
[junit] Success=true
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.31 sec
[junit] Running org.apache.hadoop.record.TestRecordWritable
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 3.833 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.995 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:511: Tests failed!
Total time: 43 minutes 21 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2109
