See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/354/changes
------------------------------------------ [...truncated 38279 lines...] [junit] 2008-01-03 16:56:16,798 INFO dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-8378777297456820170 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4/current/blk_-8378777297456820170 [junit] 2008-01-03 16:56:17,003 INFO dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-2114403456717153875 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current/blk_-2114403456717153875 [junit] 2008-01-03 16:56:17,604 INFO dfs.DataNode (FSDataset.java:invalidate(740)) - Deleting block blk_-8378777297456820170 file http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data2/current/blk_-8378777297456820170 [junit] 2008-01-03 16:56:17,732 INFO mapred.MiniMRCluster (MiniMRCluster.java:waitUntilIdle(207)) - Waiting for task tracker tracker_lucene.zones.apache.org:/127.0.0.1:60203 to be idle. [junit] 2008-01-03 16:56:18,474 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(886)) - Removed completed task 'task_200801031654_0004_m_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:60203' [junit] 2008-01-03 16:56:18,474 INFO mapred.JobTracker (JobTracker.java:removeMarkedTasks(886)) - Removed completed task 'task_200801031654_0004_r_000000_0' from 'tracker_lucene.zones.apache.org:/127.0.0.1:60203' [junit] 2008-01-03 16:56:18,475 INFO mapred.TaskTracker (TaskTracker.java:purgeJob(1116)) - Received 'KillJobAction' for job: job_200801031654_0004 [junit] 2008-01-03 16:56:18,475 INFO mapred.TaskRunner (MapTaskRunner.java:close(41)) - task_200801031654_0004_m_000000_0 done; removing files. [junit] 2008-01-03 16:56:18,677 INFO mapred.TaskRunner (ReduceTaskRunner.java:close(45)) - task_200801031654_0004_r_000000_0 done; removing files. [junit] 2008-01-03 16:56:18,977 INFO ipc.Server (Server.java:stop(976)) - Stopping server on 60199 [junit] 2008-01-03 16:56:18,978 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60199: exiting [junit] 2008-01-03 16:56:18,978 INFO ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60199 [junit] 2008-01-03 16:56:18,978 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60199: exiting [junit] 2008-01-03 16:56:18,979 INFO ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder [junit] 2008-01-03 16:56:19,671 INFO mapred.TaskTracker (TaskTracker.java:shutdown(713)) - Shutting down StatusHttpServer [junit] 2008-01-03 16:56:19,671 INFO mapred.TaskTracker (TaskTracker.java:run(509)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:60199 [junit] 2008-01-03 16:56:19,682 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60198 [junit] 2008-01-03 16:56:19,683 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:19,858 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:20,194 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:20,460 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:20,462 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:20,511 INFO ipc.Client (Client.java:run(305)) - java.net.SocketException: Socket closed [junit] at java.net.SocketInputStream.read(SocketInputStream.java:162) [junit] at java.io.FilterInputStream.read(FilterInputStream.java:111) [junit] at org.apache.hadoop.ipc.Client$Connection$1.read(Client.java:188) [junit] at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) [junit] at java.io.BufferedInputStream.read(BufferedInputStream.java:235) [junit] at java.io.DataInputStream.readInt(DataInputStream.java:353) [junit] at org.apache.hadoop.ipc.Client$Connection.run(Client.java:274) [junit] 2008-01-03 16:56:20,512 ERROR mapred.TaskTracker (TaskTracker.java:offerService(954)) - Caught exception: java.io.InterruptedIOException [junit] at java.net.SocketOutputStream.socketWrite0(Native Method) [junit] at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:92) [junit] at java.net.SocketOutputStream.write(SocketOutputStream.java:136) [junit] at org.apache.hadoop.ipc.Client$Connection$2.write(Client.java:199) [junit] at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65) [junit] at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123) [junit] at java.io.DataOutputStream.flush(DataOutputStream.java:106) [junit] at org.apache.hadoop.ipc.Client$Connection.sendParam(Client.java:344) [junit] at org.apache.hadoop.ipc.Client.call(Client.java:501) [junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198) [junit] at org.apache.hadoop.mapred.$Proxy6.heartbeat(Unknown Source) [junit] at org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1019) [junit] at org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:902) [junit] at org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1315) [junit] at org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:144) [junit] at java.lang.Thread.run(Thread.java:595) [junit] 2008-01-03 16:56:20,573 INFO ipc.Server (Server.java:stop(976)) - Stopping server on 60203 [junit] 2008-01-03 16:56:20,573 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60203: exiting [junit] 2008-01-03 16:56:20,573 INFO ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60203 [junit] 2008-01-03 16:56:20,574 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60203: exiting [junit] 2008-01-03 16:56:20,575 INFO ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder [junit] 2008-01-03 16:56:21,088 INFO mapred.TaskTracker (TaskTracker.java:shutdown(713)) - Shutting down StatusHttpServer [junit] 2008-01-03 16:56:21,089 INFO mapred.TaskTracker (TaskTracker.java:run(540)) - Shutting down: Map-events fetcher for all reduce tasks on tracker_lucene.zones.apache.org:/127.0.0.1:60203 [junit] 2008-01-03 16:56:21,092 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60202 [junit] 2008-01-03 16:56:21,093 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:21,230 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:21,349 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:21,481 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:21,482 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:21,482 INFO mapred.TaskTracker (TaskTracker.java:offerService(934)) - Interrupted. Closing down. [junit] 2008-01-03 16:56:21,483 INFO mapred.JobTracker (JobTracker.java:close(726)) - Stopping infoServer [junit] 2008-01-03 16:56:21,485 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60195 [junit] 2008-01-03 16:56:21,486 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:21,609 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:21,738 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:21,858 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:21,859 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:21,859 INFO mapred.JobTracker (JobTracker.java:close(734)) - Stopping interTrackerServer [junit] 2008-01-03 16:56:21,859 INFO ipc.Server (Server.java:stop(976)) - Stopping server on 60194 [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60194: exiting [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 8 on 60194: exiting [junit] 2008-01-03 16:56:21,861 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 4 on 60194: exiting [junit] 2008-01-03 16:56:21,861 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 3 on 60194: exiting [junit] 2008-01-03 16:56:21,861 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 2 on 60194: exiting [junit] 2008-01-03 16:56:21,861 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 5 on 60194: exiting [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 7 on 60194: exiting [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 6 on 60194: exiting [junit] 2008-01-03 16:56:21,863 INFO ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60194: exiting [junit] 2008-01-03 16:56:21,860 INFO mapred.JobTracker (JobTracker.java:offerService(721)) - Stopped interTrackerServer [junit] 2008-01-03 16:56:21,860 INFO mapred.JobTracker (JobTracker.java:close(738)) - Stopping expireTrackers [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 9 on 60194: exiting [junit] 2008-01-03 16:56:21,860 INFO ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60194 [junit] 2008-01-03 16:56:21,865 INFO mapred.JobTracker (JobTracker.java:close(747)) - Stopping retirer [junit] 2008-01-03 16:56:21,866 INFO mapred.JobTracker (JobTracker.java:close(756)) - Stopping initer [junit] 2008-01-03 16:56:21,866 INFO mapred.JobTracker (JobTracker.java:close(765)) - Stopping expireLaunchingTasks [junit] 2008-01-03 16:56:21,867 INFO mapred.JobTracker (JobTracker.java:close(774)) - Stopping TaskCommit thread [junit] 2008-01-03 16:56:21,868 WARN mapred.JobTracker (JobTracker.java:run(2032)) - Task Commit Thread exiting... [junit] 2008-01-03 16:56:21,868 INFO mapred.JobTracker (JobTracker.java:close(782)) - stopped all jobtracker services [junit] Shutting down the Mini HDFS Cluster [junit] Shutting down DataNode 1 [junit] 2008-01-03 16:56:21,870 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60192 [junit] 2008-01-03 16:56:21,871 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:21,994 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:22,114 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:22,239 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:22,240 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:22,242 INFO dfs.DataNode (DataNode.java:run(1741)) - 127.0.0.1:60191: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] 2008-01-03 16:56:22,243 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60186 [junit] 2008-01-03 16:56:22,244 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:22,365 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:22,481 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:22,595 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:22,596 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:22,597 INFO dfs.DataNode (DataNode.java:run(1741)) - 127.0.0.1:60185: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] 2008-01-03 16:56:22,599 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:60180 [junit] 2008-01-03 16:56:22,600 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:22,712 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/] [junit] 2008-01-03 16:56:22,822 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs] [junit] 2008-01-03 16:56:22,961 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static] [junit] 2008-01-03 16:56:22,961 INFO util.Container (Container.java:stop(156)) - Stopped [EMAIL PROTECTED] [junit] 2008-01-03 16:56:22,962 INFO fs.FSNamesystem (FSEditLog.java:printStatistics(739)) - Number of transactions: 117 Total time for transactions(ms): 7 Number of syncs: 74 SyncTimes(ms): 7687 7737 [junit] 2008-01-03 16:56:23,180 INFO ipc.Server (Server.java:stop(976)) - Stopping server on 60179 [junit] 2008-01-03 16:56:23,181 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 0 on 60179: exiting [junit] 2008-01-03 16:56:23,182 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 5 on 60179: exiting [junit] 2008-01-03 16:56:23,182 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 8 on 60179: exiting [junit] 2008-01-03 16:56:23,182 INFO ipc.Server (Server.java:run(340)) - Stopping IPC Server listener on 60179 [junit] 2008-01-03 16:56:23,184 INFO ipc.Server (Server.java:run(512)) - Stopping IPC Server Responder [junit] 2008-01-03 16:56:23,182 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 2 on 60179: exiting [junit] 2008-01-03 16:56:23,182 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 6 on 60179: exiting [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 146.038 sec [junit] 2008-01-03 16:56:23,185 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 9 on 60179: exiting [junit] 2008-01-03 16:56:23,181 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 3 on 60179: exiting [junit] 2008-01-03 16:56:23,181 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 7 on 60179: exiting [junit] 2008-01-03 16:56:23,195 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 1 on 60179: exiting [junit] 2008-01-03 16:56:23,181 INFO ipc.Server (Server.java:run(924)) - IPC Server handler 4 on 60179: exiting [junit] Running org.apache.hadoop.net.TestNetworkTopology [junit] 2008-01-03 16:56:26,042 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020 [junit] 2008-01-03 16:56:26,048 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020 [junit] 2008-01-03 16:56:26,048 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020 [junit] 2008-01-03 16:56:26,049 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020 [junit] 2008-01-03 16:56:26,050 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020 [junit] 2008-01-03 16:56:26,051 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020 [junit] 2008-01-03 16:56:26,052 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h7:5020 [junit] 2008-01-03 16:56:26,103 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h1:5020 [junit] 2008-01-03 16:56:26,104 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r1/h2:5020 [junit] 2008-01-03 16:56:26,106 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h3:5020 [junit] 2008-01-03 16:56:26,107 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h4:5020 [junit] 2008-01-03 16:56:26,108 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d1/r2/h5:5020 [junit] 2008-01-03 16:56:26,109 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h6:5020 [junit] 2008-01-03 16:56:26,110 INFO net.NetworkTopology (NetworkTopology.java:remove(351)) - Removing a node: /d2/r3/h7:5020 [junit] 2008-01-03 16:56:26,111 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h1:5020 [junit] 2008-01-03 16:56:26,113 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r1/h2:5020 [junit] 2008-01-03 16:56:26,114 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h3:5020 [junit] 2008-01-03 16:56:26,115 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h4:5020 [junit] 2008-01-03 16:56:26,116 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d1/r2/h5:5020 [junit] 2008-01-03 16:56:26,118 INFO net.NetworkTopology (NetworkTopology.java:add(320)) - Adding a new node: /d2/r3/h6:5020 [junit] 2008-01-03 16:56:26,119 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.07 sec [junit] Running org.apache.hadoop.record.TestBuffer [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.16 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.546 sec [junit] Running org.apache.hadoop.record.TestRecordMR [junit] 2008-01-03 16:56:30,267 INFO jvm.JvmMetrics (JvmMetrics.java:init(56)) - Initializing JVM Metrics with processName=JobTracker, sessionId= [junit] 2008-01-03 16:56:30,377 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] 2008-01-03 16:56:30,395 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1 [junit] 2008-01-03 16:56:30,790 WARN conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_1.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring. [junit] 2008-01-03 16:56:30,798 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_1 [junit] 2008-01-03 16:56:30,834 INFO mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1 [junit] 2008-01-03 16:56:31,087 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] 2008-01-03 16:56:31,090 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_1_map_0000' done. [junit] 2008-01-03 16:56:31,253 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce [junit] 2008-01-03 16:56:31,255 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_crezyc' done. [junit] 2008-01-03 16:56:31,279 INFO mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_crezyc' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/genouts [junit] 2008-01-03 16:56:31,802 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_1 [junit] 2008-01-03 16:56:31,805 INFO mapred.JobClient (Counters.java:log(491)) - Counters: 9 [junit] 2008-01-03 16:56:31,806 INFO mapred.JobClient (Counters.java:log(493)) - Map-Reduce Framework [junit] 2008-01-03 16:56:31,809 INFO mapred.JobClient (Counters.java:log(495)) - Map input records=10 [junit] 2008-01-03 16:56:31,811 INFO mapred.JobClient (Counters.java:log(495)) - Map output records=102 [junit] 2008-01-03 16:56:31,813 INFO mapred.JobClient (Counters.java:log(495)) - Map input bytes=100 [junit] 2008-01-03 16:56:31,814 INFO mapred.JobClient (Counters.java:log(495)) - Map output bytes=713 [junit] 2008-01-03 16:56:31,815 INFO mapred.JobClient (Counters.java:log(495)) - Combine input records=0 [junit] 2008-01-03 16:56:31,816 INFO mapred.JobClient (Counters.java:log(495)) - Combine output records=0 [junit] 2008-01-03 16:56:31,817 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input groups=102 [junit] 2008-01-03 16:56:31,818 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input records=102 [junit] 2008-01-03 16:56:31,819 INFO mapred.JobClient (Counters.java:log(495)) - Reduce output records=102 [junit] 2008-01-03 16:56:31,890 INFO jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized [junit] 2008-01-03 16:56:31,894 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] 2008-01-03 16:56:31,896 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1 [junit] 2008-01-03 16:56:32,248 WARN conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_2.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring. [junit] 2008-01-03 16:56:32,251 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_2 [junit] 2008-01-03 16:56:32,260 INFO mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1 [junit] 2008-01-03 16:56:32,346 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+1113 [junit] 2008-01-03 16:56:32,347 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_2_map_0000' done. [junit] 2008-01-03 16:56:32,393 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce [junit] 2008-01-03 16:56:32,394 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_fo40o' done. [junit] 2008-01-03 16:56:32,425 INFO mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_fo40o' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/intermediateouts [junit] 2008-01-03 16:56:33,261 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_2 [junit] 2008-01-03 16:56:33,262 INFO mapred.JobClient (Counters.java:log(491)) - Counters: 9 [junit] 2008-01-03 16:56:33,263 INFO mapred.JobClient (Counters.java:log(493)) - Map-Reduce Framework [junit] 2008-01-03 16:56:33,263 INFO mapred.JobClient (Counters.java:log(495)) - Map input records=102 [junit] 2008-01-03 16:56:33,263 INFO mapred.JobClient (Counters.java:log(495)) - Map output records=102 [junit] 2008-01-03 16:56:33,264 INFO mapred.JobClient (Counters.java:log(495)) - Map input bytes=1020 [junit] 2008-01-03 16:56:33,264 INFO mapred.JobClient (Counters.java:log(495)) - Map output bytes=306 [junit] 2008-01-03 16:56:33,265 INFO mapred.JobClient (Counters.java:log(495)) - Combine input records=0 [junit] 2008-01-03 16:56:33,265 INFO mapred.JobClient (Counters.java:log(495)) - Combine output records=0 [junit] 2008-01-03 16:56:33,266 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input groups=10 [junit] 2008-01-03 16:56:33,266 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input records=102 [junit] 2008-01-03 16:56:33,267 INFO mapred.JobClient (Counters.java:log(495)) - Reduce output records=10 [junit] 2008-01-03 16:56:33,334 INFO jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with processName=JobTracker, sessionId= - already initialized [junit] 2008-01-03 16:56:33,336 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] 2008-01-03 16:56:33,337 INFO mapred.FileInputFormat (FileInputFormat.java:validateInput(157)) - Total input paths to process : 1 [junit] 2008-01-03 16:56:33,640 WARN conf.Configuration (Configuration.java:loadResource(808)) - build/test/mapred/local/localRunner/job_local_3.xml:a attempt to override final parameter: hadoop.tmp.dir; Ignoring. [junit] 2008-01-03 16:56:33,642 INFO mapred.JobClient (JobClient.java:runJob(755)) - Running job: job_local_3 [junit] 2008-01-03 16:56:33,663 INFO mapred.MapTask (MapTask.java:run(171)) - numReduceTasks: 1 [junit] 2008-01-03 16:56:33,681 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+209 [junit] 2008-01-03 16:56:33,682 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'job_local_3_map_0000' done. [junit] 2008-01-03 16:56:33,730 INFO mapred.LocalJobRunner (LocalJobRunner.java:statusUpdate(210)) - reduce > reduce [junit] 2008-01-03 16:56:33,731 INFO mapred.TaskRunner (Task.java:done(381)) - Task 'reduce_eamlf8' done. [junit] 2008-01-03 16:56:33,793 INFO mapred.TaskRunner (Task.java:saveTaskOutput(433)) - Saved output of task 'reduce_eamlf8' to http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/mapred.loadtest/finalouts [junit] 2008-01-03 16:56:34,651 INFO mapred.JobClient (JobClient.java:runJob(833)) - Job complete: job_local_3 [junit] 2008-01-03 16:56:34,652 INFO mapred.JobClient (Counters.java:log(491)) - Counters: 9 [junit] 2008-01-03 16:56:34,653 INFO mapred.JobClient (Counters.java:log(493)) - Map-Reduce Framework [junit] 2008-01-03 16:56:34,654 INFO mapred.JobClient (Counters.java:log(495)) - Map input records=10 [junit] 2008-01-03 16:56:34,655 INFO mapred.JobClient (Counters.java:log(495)) - Map output records=10 [junit] 2008-01-03 16:56:34,655 INFO mapred.JobClient (Counters.java:log(495)) - Map input bytes=116 [junit] 2008-01-03 16:56:34,656 INFO mapred.JobClient (Counters.java:log(495)) - Map output bytes=20 [junit] 2008-01-03 16:56:34,657 INFO mapred.JobClient (Counters.java:log(495)) - Combine input records=0 [junit] 2008-01-03 16:56:34,658 INFO mapred.JobClient (Counters.java:log(495)) - Combine output records=0 [junit] 2008-01-03 16:56:34,659 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input groups=10 [junit] 2008-01-03 16:56:34,660 INFO mapred.JobClient (Counters.java:log(495)) - Reduce input records=10 [junit] 2008-01-03 16:56:34,660 INFO mapred.JobClient (Counters.java:log(495)) - Reduce output records=10 [junit] Original sum: 102 [junit] Recomputed sum: 102 [junit] Success=true [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 5.377 sec [junit] Running org.apache.hadoop.record.TestRecordWritable [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 8.516 sec [junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.474 sec [junit] Running org.apache.hadoop.util.TestReflectionUtils [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.905 sec BUILD FAILED http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :511: Tests failed! Total time: 133 minutes 14 seconds Recording fingerprints Publishing Javadoc Recording test results