See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/358/changes
Changes:
[stack] HADOOP-2240 Truncate for hbase
[stack] HADOOP-2240 Truncate for hbase
[stack] HADOOP-2530 Missing type in new hbase custom RPC serializer
------------------------------------------
[...truncated 39180 lines...]
[junit] 2008-01-06 12:38:10,316 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:10,410 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:10,524 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:10,525 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:10,527 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:57505:Finishing DataNode in:
FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] Shutting down DataNode 1
[junit] 2008-01-06 12:38:10,528 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:57381
[junit] 2008-01-06 12:38:10,529 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:10,607 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:10,691 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:10,769 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:10,770 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:10,771 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:57376: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-06 12:38:10,773 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:57248
[junit] 2008-01-06 12:38:10,773 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:10,859 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:10,943 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:10,943 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=57248]
[junit] 2008-01-06 12:38:11,028 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:11,029 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:11,030 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:57243: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-06 12:38:11,032 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:57122
[junit] 2008-01-06 12:38:11,033 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:11,114 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:11,195 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:11,273 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:11,274 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:11,275 INFO fs.FSNamesystem
(FSEditLog.java:printStatistics(772)) - Number of transactions: 8 Total time
for transactions(ms): 1 Number of syncs: 3 SyncTimes(ms): 800 895
[junit] 2008-01-06 12:38:11,620 INFO ipc.Server (Server.java:stop(986)) -
Stopping server on 57100
[junit] 2008-01-06 12:38:11,621 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 0 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 6 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 1 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 3 on 57100: exiting
[junit] 2008-01-06 12:38:11,623 INFO ipc.Server (Server.java:run(522)) -
Stopping IPC Server Responder
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 2 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 5 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 9 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 4 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 7 on 57100: exiting
[junit] 2008-01-06 12:38:11,622 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 8 on 57100: exiting
[junit] 2008-01-06 12:38:11,621 INFO ipc.Server (Server.java:run(350)) -
Stopping IPC Server listener on 57100
[junit] 2008-01-06 12:38:13,394 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(310)) - fsOwner=hudson,other
[junit] 2008-01-06 12:38:13,395 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(314)) - supergroup=supergroup
[junit] 2008-01-06 12:38:13,395 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(315)) - isPermissionEnabled=true
[junit] 2008-01-06 12:38:16,282 INFO dfs.Storage
(FSImage.java:format(821)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/name1
has been successfully formatted.
[junit] 2008-01-06 12:38:19,374 INFO dfs.Storage
(FSImage.java:format(821)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/name2
has been successfully formatted.
[junit] 2008-01-06 12:38:19,376 INFO dfs.NameNode
(NameNode.java:initialize(125)) - Namenode up at: localhost/127.0.0.1:57778
[junit] 2008-01-06 12:38:19,376 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=NameNode, sessionId=null - already initialized
[junit] 2008-01-06 12:38:19,404 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(310)) - fsOwner=hudson,other
[junit] 2008-01-06 12:38:19,404 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(314)) - supergroup=supergroup
[junit] 2008-01-06 12:38:19,405 INFO fs.FSNamesystem
(FSNamesystem.java:setConfigurationParameters(315)) - isPermissionEnabled=true
[junit] 2008-01-06 12:38:19,410 INFO dfs.StateChange
(FSNamesystem.java:leave(3493)) - STATE* Network topology has 0 racks and 0
datanodes
[junit] 2008-01-06 12:38:19,411 INFO dfs.StateChange
(FSNamesystem.java:leave(3496)) - STATE* UnderReplicatedBlocks has 0 blocks
[junit] 2008-01-06 12:38:19,415 INFO http.HttpServer
(HttpServer.java:doStart(729)) - Version Jetty/5.1.4
[junit] 2008-01-06 12:38:19,519 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:19,524 INFO util.Container
(Container.java:start(74)) - Started WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:19,525 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:19,526 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/static,/static]
[junit] 2008-01-06 12:38:19,527 INFO http.SocketListener
(SocketListener.java:start(204)) - Started SocketListener on 0.0.0.0:57783
[junit] 2008-01-06 12:38:19,527 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:19,528 INFO fs.FSNamesystem
(FSNamesystem.java:initialize(276)) - Web-server up at: 0.0.0.0:57783
[junit] 2008-01-06 12:38:19,531 INFO ipc.Server (Server.java:run(467)) -
IPC Server Responder: starting
[junit] 2008-01-06 12:38:19,532 INFO ipc.Server (Server.java:run(314)) -
IPC Server listener on 57778: starting
[junit] 2008-01-06 12:38:19,533 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 1 on 57778: starting
[junit] 2008-01-06 12:38:19,535 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 3 on 57778: starting
[junit] 2008-01-06 12:38:19,536 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 4 on 57778: starting
[junit] 2008-01-06 12:38:19,536 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 5 on 57778: starting
[junit] 2008-01-06 12:38:19,536 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 8 on 57778: starting
[junit] 2008-01-06 12:38:19,540 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 9 on 57778: starting
[junit] 2008-01-06 12:38:19,540 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 7 on 57778: starting
[junit] 2008-01-06 12:38:19,603 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 2 on 57778: starting
[junit] 2008-01-06 12:38:19,602 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 0 on 57778: starting
[junit] 2008-01-06 12:38:19,601 INFO ipc.Server (Server.java:run(869)) -
IPC Server handler 6 on 57778: starting
[junit] Starting DataNode 0 with dfs.data.dir:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2
[junit] 2008-01-06 12:38:19,913 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2008-01-06 12:38:19,919 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1
is not formatted.
[junit] 2008-01-06 12:38:19,919 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:22,687 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data2
is not formatted.
[junit] 2008-01-06 12:38:22,688 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:26,840 INFO dfs.DataNode
(DataNode.java:startDataNode(282)) - Opened server at 57902
[junit] 2008-01-06 12:38:26,840 INFO dfs.DataNode
(DataNode.java:startDataNode(302)) - Balancing bandwith is 1048576 bytes/s
[junit] 2008-01-06 12:38:26,843 INFO http.HttpServer
(HttpServer.java:doStart(729)) - Version Jetty/5.1.4
[junit] 2008-01-06 12:38:26,931 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:27,048 INFO util.Container
(Container.java:start(74)) - Started WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:27,049 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:27,049 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/static,/static]
[junit] 2008-01-06 12:38:27,051 INFO http.SocketListener
(SocketListener.java:start(204)) - Started SocketListener on 0.0.0.0:57905
[junit] 2008-01-06 12:38:27,051 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:27,253 INFO dfs.StateChange
(FSNamesystem.java:registerDatanode(1796)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:57902 storage
DS-1140953341-140.211.11.75-57902-1199623107052
[junit] 2008-01-06 12:38:27,254 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node:
/default-rack/127.0.0.1:57902
[junit] 2008-01-06 12:38:28,262 INFO dfs.DataNode
(DataNode.java:register(440)) - New storage id
DS-1140953341-140.211.11.75-57902-1199623107052 is assigned to data-node
127.0.0.1:57902
[junit] 2008-01-06 12:38:28,263 INFO dfs.DataNode
(DataNode.java:run(1715)) - 127.0.0.1:57902In DataNode.run, data =
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] Starting DataNode 1 with dfs.data.dir:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4
[junit] 2008-01-06 12:38:28,264 INFO dfs.DataNode
(DataNode.java:offerService(532)) - using BLOCKREPORT_INTERVAL of 3415100msec
Initial delay: 0msec
[junit] 2008-01-06 12:38:28,264 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2008-01-06 12:38:28,515 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3
is not formatted.
[junit] 2008-01-06 12:38:28,519 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:33,354 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4
is not formatted.
[junit] 2008-01-06 12:38:33,354 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:36,842 INFO dfs.DataNode
(DataNode.java:startDataNode(282)) - Opened server at 58024
[junit] 2008-01-06 12:38:36,842 INFO dfs.DataNode
(DataNode.java:startDataNode(302)) - Balancing bandwith is 1048576 bytes/s
[junit] 2008-01-06 12:38:36,845 INFO http.HttpServer
(HttpServer.java:doStart(729)) - Version Jetty/5.1.4
[junit] 2008-01-06 12:38:36,931 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:37,103 INFO util.Container
(Container.java:start(74)) - Started WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:37,103 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:37,104 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/static,/static]
[junit] 2008-01-06 12:38:37,105 INFO http.SocketListener
(SocketListener.java:start(204)) - Started SocketListener on 0.0.0.0:58029
[junit] 2008-01-06 12:38:37,105 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:37,112 INFO dfs.StateChange
(FSNamesystem.java:registerDatanode(1796)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:58024 storage
DS-1136496162-140.211.11.75-58024-1199623117106
[junit] 2008-01-06 12:38:37,113 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node:
/default-rack/127.0.0.1:58024
[junit] 2008-01-06 12:38:37,694 INFO dfs.DataNode
(DataNode.java:register(440)) - New storage id
DS-1136496162-140.211.11.75-58024-1199623117106 is assigned to data-node
127.0.0.1:58024
[junit] 2008-01-06 12:38:37,695 INFO dfs.DataNode
(DataNode.java:run(1715)) - 127.0.0.1:58024In DataNode.run, data =
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] 2008-01-06 12:38:37,696 INFO dfs.DataNode
(DataNode.java:offerService(532)) - using BLOCKREPORT_INTERVAL of 3558619msec
Initial delay: 0msec
[junit] Starting DataNode 2 with dfs.data.dir:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6
[junit] 2008-01-06 12:38:37,735 INFO jvm.JvmMetrics
(JvmMetrics.java:init(51)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2008-01-06 12:38:37,743 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5
is not formatted.
[junit] 2008-01-06 12:38:37,743 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:40,135 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(113)) - Storage directory
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data6
is not formatted.
[junit] 2008-01-06 12:38:40,135 INFO dfs.Storage
(DataStorage.java:recoverTransitionRead(114)) - Formatting ...
[junit] 2008-01-06 12:38:42,084 INFO dfs.DataNode
(DataNode.java:startDataNode(282)) - Opened server at 58124
[junit] 2008-01-06 12:38:42,085 INFO dfs.DataNode
(DataNode.java:startDataNode(302)) - Balancing bandwith is 1048576 bytes/s
[junit] 2008-01-06 12:38:42,088 INFO http.HttpServer
(HttpServer.java:doStart(729)) - Version Jetty/5.1.4
[junit] 2008-01-06 12:38:42,276 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:42,278 INFO util.Container
(Container.java:start(74)) - Started WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:42,279 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:42,279 INFO util.Container
(Container.java:start(74)) - Started HttpContext[/static,/static]
[junit] 2008-01-06 12:38:42,281 INFO http.SocketListener
(SocketListener.java:start(204)) - Started SocketListener on 0.0.0.0:58132
[junit] 2008-01-06 12:38:42,281 INFO util.Container
(Container.java:start(74)) - Started [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:42,283 INFO dfs.StateChange
(FSNamesystem.java:registerDatanode(1796)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:58124 storage
DS-255925282-140.211.11.75-58124-1199623122283
[junit] 2008-01-06 12:38:42,326 INFO net.NetworkTopology
(NetworkTopology.java:add(320)) - Adding a new node:
/default-rack/127.0.0.1:58124
[junit] 2008-01-06 12:38:42,690 INFO dfs.DataNode
(DataNode.java:register(440)) - New storage id
DS-255925282-140.211.11.75-58124-1199623122283 is assigned to data-node
127.0.0.1:58124
[junit] 2008-01-06 12:38:42,691 INFO dfs.DataNode
(DataNode.java:run(1715)) - 127.0.0.1:58124In DataNode.run, data =
FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-01-06 12:38:42,723 INFO dfs.DataNode
(DataNode.java:offerService(532)) - using BLOCKREPORT_INTERVAL of 3340542msec
Initial delay: 0msec
[junit] 2008-01-06 12:38:45,247 INFO dfs.StateChange
(FSNamesystem.java:allocateBlock(1239)) - BLOCK* NameSystem.allocateBlock:
/data/file1. blk_9096708675833796573
[junit] 2008-01-06 12:38:45,560 INFO dfs.StateChange
(FSNamesystem.java:addStoredBlock(2410)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:58124 is added to blk_9096708675833796573
[junit] 2008-01-06 12:38:45,567 INFO dfs.DataNode
(DataNode.java:writeBlock(1017)) - Received block blk_9096708675833796573 from
/127.0.0.1
[junit] 2008-01-06 12:38:45,568 INFO dfs.DataNode
(DataNode.java:writeBlock(1017)) - Received block blk_9096708675833796573 from
/127.0.0.1
[junit] 2008-01-06 12:38:45,569 INFO dfs.StateChange
(FSNamesystem.java:addStoredBlock(2410)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:57902 is added to blk_9096708675833796573
[junit] 2008-01-06 12:38:45,645 INFO dfs.DataNode
(DataNode.java:writeBlock(1017)) - Received block blk_9096708675833796573 from
/127.0.0.1
[junit] 2008-01-06 12:38:45,646 INFO dfs.StateChange
(FSNamesystem.java:addStoredBlock(2410)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:58024 is added to blk_9096708675833796573
[junit] 2008-01-06 12:38:46,904 INFO dfs.DataNode
(DataNode.java:readBlock(911)) - 127.0.0.1:58124Served block
blk_9096708675833796573 to /127.0.0.1
[junit] 2008-01-06 12:38:47,956 INFO ipc.Server (Server.java:run(905)) -
IPC Server handler 2 on 57778, call mkdirs(/data/web2, rwxr-xr-x) from
127.0.0.1:58254: error: org.apache.hadoop.fs.permission.AccessControlException:
Permission denied: user=Who, access=WRITE,
inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=Who, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at
org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:156)
[junit] at
org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3877)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:3857)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.mkdirsInternal(FSNamesystem.java:1497)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.mkdirs(FSNamesystem.java:1481)
[junit] at org.apache.hadoop.dfs.NameNode.mkdirs(NameNode.java:413)
[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:585)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:401)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:903)
[junit] 2008-01-06 12:38:47,963 INFO ipc.Server (Server.java:run(905)) -
IPC Server handler 0 on 57778, call create(/data/file2, rwxr-xr-x,
DFSClient_-1192996402, true, 3, 67108864) from 127.0.0.1:58254: error:
org.apache.hadoop.fs.permission.AccessControlException: Permission denied:
user=Who, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=Who, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at
org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:156)
[junit] at
org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3877)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:3857)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.startFileInternal(FSNamesystem.java:910)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.startFile(FSNamesystem.java:875)
[junit] at org.apache.hadoop.dfs.NameNode.create(NameNode.java:273)
[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:585)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:401)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:903)
[junit] 2008-01-06 12:38:47,965 INFO ipc.Server (Server.java:run(905)) -
IPC Server handler 6 on 57778, call open(/data/file1, 0, 671088640) from
127.0.0.1:58254: error: org.apache.hadoop.fs.permission.AccessControlException:
Permission denied: user=Who, access=READ,
inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=Who, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at
org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3877)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.checkPathAccess(FSNamesystem.java:3847)
[junit] at
org.apache.hadoop.dfs.FSNamesystem.getBlockLocations(FSNamesystem.java:705)
[junit] at
org.apache.hadoop.dfs.NameNode.getBlockLocations(NameNode.java:246)
[junit] at org.apache.hadoop.dfs.NameNode.open(NameNode.java:233)
[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:585)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:401)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:903)
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-01-06 12:38:48,907 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=58132]
[junit] 2008-01-06 12:38:48,908 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:58132
[junit] 2008-01-06 12:38:48,909 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,009 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:49,111 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:49,199 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:49,199 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,201 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:58124:Finishing DataNode in:
FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] Shutting down DataNode 1
[junit] 2008-01-06 12:38:49,203 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:58029
[junit] 2008-01-06 12:38:49,204 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,293 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:49,379 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:49,467 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:49,468 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,469 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:58024: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-06 12:38:49,471 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:57905
[junit] 2008-01-06 12:38:49,472 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,554 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:49,639 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:49,726 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:49,727 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,729 INFO dfs.DataNode
(DataNode.java:run(1741)) - 127.0.0.1:57902: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-06 12:38:49,730 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:57783
[junit] 2008-01-06 12:38:49,731 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,813 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-01-06 12:38:49,890 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-01-06 12:38:49,968 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-01-06 12:38:49,969 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-01-06 12:38:49,970 INFO fs.FSNamesystem
(FSEditLog.java:printStatistics(772)) - Number of transactions: 6 Total time
for transactions(ms): 1 Number of syncs: 4 SyncTimes(ms): 1220 1003
[junit] 2008-01-06 12:38:50,320 INFO ipc.Server (Server.java:stop(986)) -
Stopping server on 57778
[junit] 2008-01-06 12:38:50,321 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 0 on 57778: exiting
[junit] 2008-01-06 12:38:50,321 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 1 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 4 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 5 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 9 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 7 on 57778: exiting
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 83.645 sec
[junit] 2008-01-06 12:38:50,324 INFO ipc.Server (Server.java:run(522)) -
Stopping IPC Server Responder
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 6 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 8 on 57778: exiting
[junit] 2008-01-06 12:38:50,322 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 3 on 57778: exiting
[junit] 2008-01-06 12:38:50,321 INFO ipc.Server (Server.java:run(934)) -
IPC Server handler 2 on 57778: exiting
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.576 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.878 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:511: Tests failed!
Total time: 66 minutes 27 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2240
Updating HADOOP-2530
