See http://hudson.zones.apache.org/hudson/job/HBase-Patch/539/changes
Changes:
[stack] HBASE-1270 Fix TestInfoServers
------------------------------------------
[...truncated 37287 lines...]
[junit] 2009-03-20 03:13:32,836 DEBUG [main] regionserver.HRegion(2440):
Files for region: TestMergeTool,row_0100,1237518812434
[junit] 2009-03-20 03:13:32,837 DEBUG [main] regionserver.HRegion(2585): d
hdfs://localhost:48090/user/hudson/TestMergeTool/1458130801/contents
[junit] 2009-03-20 03:13:32,838 DEBUG [main] regionserver.HRegion(2588): f
hdfs://localhost:48090/user/hudson/TestMergeTool/1458130801/contents/6587352923206587083
size=718
[junit] 2009-03-20 03:13:32,838 INFO [main] regionserver.HRegion(701):
starting major compaction on region TestMergeTool,,1237518780910
[junit] 2009-03-20 03:13:32,843 DEBUG [main] regionserver.Store(692):
Started compaction of 1 file(s) into
/user/hudson/TestMergeTool/444003835/contents/3720517125982726215
[junit] 2009-03-20 03:13:32,867 DEBUG [main] regionserver.Store(707):
Completed major compaction of 444003835/contents store size is 419.0
[junit] 2009-03-20 03:13:32,868 INFO [main] regionserver.HRegion(716):
compaction completed on region TestMergeTool,,1237518780910 in 0sec
[junit] 2009-03-20 03:13:32,868 DEBUG [main] regionserver.HRegion(2445):
Files for region: TestMergeTool,,1237518780910
[junit] 2009-03-20 03:13:32,869 DEBUG [main] regionserver.HRegion(2585): d
hdfs://localhost:48090/user/hudson/TestMergeTool/444003835/contents
[junit] 2009-03-20 03:13:32,869 DEBUG [main] regionserver.HRegion(2588): f
hdfs://localhost:48090/user/hudson/TestMergeTool/444003835/contents/67637472060244810
size=419
[junit] 2009-03-20 03:13:32,870 INFO [main] regionserver.HRegion(2466):
Creating new region REGION => {NAME => 'TestMergeTool,,1237518812870', STARTKEY
=> '', ENDKEY => '', ENCODED => 2049739120, TABLE => {{NAME => 'TestMergeTool',
IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents',
BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH =>
'2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY => 'false',
BLOCKCACHE => 'false'}], INDEXES => []}}
[junit] 2009-03-20 03:13:32,875 INFO [main] regionserver.HRegion(2475):
starting merge of regions: TestMergeTool,row_0100,1237518812434 and
TestMergeTool,,1237518780910 into new region REGION => {NAME =>
'TestMergeTool,,1237518812870', STARTKEY => '', ENDKEY => '', ENCODED =>
2049739120, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false', IS_META =>
'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false', COMPRESSION
=> 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE =>
'65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES => []}} with
start key <> and end key <>
[junit] 2009-03-20 03:13:32,875 DEBUG [main] regionserver.HRegion(385):
Closing TestMergeTool,row_0100,1237518812434: compactions & flushes disabled
[junit] 2009-03-20 03:13:32,875 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on
TestMergeTool,row_0100,1237518812434
[junit] 2009-03-20 03:13:32,875 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region TestMergeTool,row_0100,1237518812434
[junit] 2009-03-20 03:13:32,876 DEBUG [main] regionserver.Store(434):
closed 1458130801/contents
[junit] 2009-03-20 03:13:32,876 INFO [main] regionserver.HRegion(434):
Closed TestMergeTool,row_0100,1237518812434
[junit] 2009-03-20 03:13:32,876 DEBUG [main] regionserver.HRegion(385):
Closing TestMergeTool,,1237518780910: compactions & flushes disabled
[junit] 2009-03-20 03:13:32,876 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on
TestMergeTool,,1237518780910
[junit] 2009-03-20 03:13:32,876 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region TestMergeTool,,1237518780910
[junit] 2009-03-20 03:13:32,877 DEBUG [main] regionserver.Store(434):
closed 444003835/contents
[junit] 2009-03-20 03:13:32,877 INFO [main] regionserver.HRegion(434):
Closed TestMergeTool,,1237518780910
[junit] 2009-03-20 03:13:32,899 DEBUG [main] regionserver.HRegion(2510):
Files for new region
[junit] 2009-03-20 03:13:32,900 DEBUG [main] regionserver.HRegion(2585): d
hdfs://localhost:48090/user/hudson/TestMergeTool/2049739120/contents
[junit] 2009-03-20 03:13:32,901 DEBUG [main] regionserver.HRegion(2588): f
hdfs://localhost:48090/user/hudson/TestMergeTool/2049739120/contents/1223579493935252277
size=718
[junit] 2009-03-20 03:13:32,901 DEBUG [main] regionserver.HRegion(2588): f
hdfs://localhost:48090/user/hudson/TestMergeTool/2049739120/contents/7107440665587513115
size=419
[junit] 2009-03-20 03:13:32,901 DEBUG [main] regionserver.HRegion(237):
Opening region TestMergeTool,,1237518812870/2049739120
[junit] 2009-03-20 03:13:32,905 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(898): Exception closing session 0x0 to
sun.nio.ch.selectionkeyi...@130c132
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:864)
[junit] 2009-03-20 03:13:32,905 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(932): Ignoring exception during shutdown input
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:638)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:930)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:32,905 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(937): Ignoring exception during shutdown output
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:649)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownOutput(SocketAdaptor.java:368)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:935)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:32,907 DEBUG [main] regionserver.Store(383):
loaded /user/hudson/TestMergeTool/2049739120/contents/1223579493935252277,
isReference=false, sequence id=5, length=718, majorCompaction=true
[junit] 2009-03-20 03:13:32,911 DEBUG [main] regionserver.Store(383):
loaded /user/hudson/TestMergeTool/2049739120/contents/7107440665587513115,
isReference=false, sequence id=6, length=419, majorCompaction=true
[junit] 2009-03-20 03:13:32,911 DEBUG [main] regionserver.Store(213):
Loaded 2 file(s) in Store 2049739120/contents, max sequence id 6
[junit] 2009-03-20 03:13:32,912 DEBUG [main] regionserver.HRegion(297):
Next sequence id for region TestMergeTool,,1237518812870 is 7
[junit] 2009-03-20 03:13:32,913 INFO [main] regionserver.HRegion(314):
region TestMergeTool,,1237518812870/2049739120 available
[junit] 2009-03-20 03:13:32,914 INFO [main] regionserver.HRegion(701):
starting compaction on region TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:32,916 DEBUG [main] regionserver.Store(683):
Compaction size of 2049739120/contents: 1.1k; Skipped 0 file(s), size: 0
[junit] 2009-03-20 03:13:32,954 DEBUG [main] regionserver.Store(692):
Started compaction of 2 file(s) into
/user/hudson/TestMergeTool/2049739120/contents/6386074743477233092
[junit] 2009-03-20 03:13:32,969 DEBUG [main] regionserver.Store(707):
Completed compaction of 2049739120/contents store size is 804.0
[junit] 2009-03-20 03:13:32,969 INFO [main] regionserver.HRegion(716):
compaction completed on region TestMergeTool,,1237518812870 in 0sec
[junit] 2009-03-20 03:13:32,970 DEBUG [main] regionserver.HRegion(2517):
Files for new region
[junit] 2009-03-20 03:13:32,971 DEBUG [main] regionserver.HRegion(2585): d
hdfs://localhost:48090/user/hudson/TestMergeTool/2049739120/contents
[junit] 2009-03-20 03:13:32,971 DEBUG [main] regionserver.HRegion(2588): f
hdfs://localhost:48090/user/hudson/TestMergeTool/2049739120/contents/2323191788684413350
size=804
[junit] 2009-03-20 03:13:32,972 DEBUG [main] regionserver.HRegion(2318):
DELETING region hdfs://localhost:48090/user/hudson/TestMergeTool/1458130801
[junit] 2009-03-20 03:13:32,973 DEBUG [main] regionserver.HRegion(2318):
DELETING region hdfs://localhost:48090/user/hudson/TestMergeTool/444003835
[junit] 2009-03-20 03:13:32,973 INFO [main] regionserver.HRegion(2523):
merge completed. New region is TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:32,974 DEBUG [main] util.Merge(308): Removing
region: REGION => {NAME => 'TestMergeTool,row_0100,1237518812434', STARTKEY =>
'row_0100', ENDKEY => 'row_0600', ENCODED => 1458130801, TABLE => {{NAME =>
'TestMergeTool', IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME =>
'contents', BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3',
LENGTH => '2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY =>
'false', BLOCKCACHE => 'false'}], INDEXES => []}} from .META.,,1
[junit] 2009-03-20 03:13:32,975 DEBUG [main] util.Merge(308): Removing
region: REGION => {NAME => 'TestMergeTool,,1237518780910', STARTKEY => '',
ENDKEY => '', ENCODED => 444003835, TABLE => {{NAME => 'TestMergeTool', IS_ROOT
=> 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER
=> 'false', VERSIONS => '3', COMPRESSION => 'NONE', LENGTH => '2147483647', TTL
=> '-1', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}],
INDEXES => []}} from .META.,,1
[junit] 2009-03-20 03:13:32,978 INFO [main] util.Merge(244): Adding REGION
=> {NAME => 'TestMergeTool,,1237518812870', STARTKEY => '', ENDKEY => '',
ENCODED => 2049739120, TABLE => {{NAME => 'TestMergeTool', IS_ROOT => 'false',
IS_META => 'false', FAMILIES => [{NAME => 'contents', BLOOMFILTER => 'false',
COMPRESSION => 'NONE', VERSIONS => '3', LENGTH => '2147483647', TTL => '-1',
BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'false'}], INDEXES =>
[]}} to REGION => {NAME => '.META.,,1', STARTKEY => '', ENDKEY => '', ENCODED
=> 1028785192, TABLE => {{NAME => '.META.', IS_ROOT => 'false', IS_META =>
'true', FAMILIES => [{NAME => 'info', BLOOMFILTER => 'false', VERSIONS => '10',
COMPRESSION => 'NONE', LENGTH => '2147483647', TTL => '-1', BLOCKSIZE =>
'8192', IN_MEMORY => 'false', BLOCKCACHE => 'true'}, {NAME => 'historian',
BLOOMFILTER => 'false', VERSIONS => '2147483647', COMPRESSION => 'NONE', LENGTH
=> '2147483647', TTL => '604800', BLOCKSIZE => '8192', IN_MEMORY => 'false',
BLOCKCACHE => 'false'}], INDEXES => []}}
[junit] 2009-03-20 03:13:32,979 DEBUG [main] regionserver.HRegion(385):
Closing TestMergeTool,,1237518812870: compactions & flushes disabled
[junit] 2009-03-20 03:13:32,979 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on
TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:32,979 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:32,979 DEBUG [main] regionserver.Store(434):
closed 2049739120/contents
[junit] 2009-03-20 03:13:32,979 INFO [main] regionserver.HRegion(434):
Closed TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:32,980 DEBUG [main] regionserver.HRegion(385):
Closing -ROOT-,,0: compactions & flushes disabled
[junit] 2009-03-20 03:13:32,980 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on -ROOT-,,0
[junit] 2009-03-20 03:13:32,980 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region -ROOT-,,0
[junit] 2009-03-20 03:13:32,980 DEBUG [main] regionserver.Store(434):
closed 70236052/info
[junit] 2009-03-20 03:13:32,980 INFO [main] regionserver.HRegion(434):
Closed -ROOT-,,0
[junit] 2009-03-20 03:13:32,980 DEBUG [main] regionserver.HRegion(385):
Closing .META.,,1: compactions & flushes disabled
[junit] 2009-03-20 03:13:32,981 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on .META.,,1
[junit] 2009-03-20 03:13:32,981 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region .META.,,1
[junit] 2009-03-20 03:13:32,981 DEBUG [main] regionserver.HRegion(826):
Started memcache flush for region .META.,,1. Current region memcache size 928.0
[junit] 2009-03-20 03:13:32,993 DEBUG [main] regionserver.Store(516): Added
hdfs://localhost:48090/user/hudson/.META./1028785192/info/1491889135714065334,
entries=3, sequenceid=21, memsize=928.0, filesize=920.0 to .META.,,1
[junit] 2009-03-20 03:13:32,993 DEBUG [main] regionserver.HRegion(903):
Finished memcache flush of ~928.0 for region .META.,,1 in 12ms, sequence id=21,
compaction requested=true
[junit] 2009-03-20 03:13:32,993 DEBUG [main] regionserver.Store(434):
closed 1028785192/historian
[junit] 2009-03-20 03:13:32,994 DEBUG [main] regionserver.Store(434):
closed 1028785192/info
[junit] 2009-03-20 03:13:32,994 INFO [main] regionserver.HRegion(434):
Closed .META.,,1
[junit] 2009-03-20 03:13:33,010 INFO [main] regionserver.HLog(273): Closed
hdfs://localhost:48090/user/hudson/log_1237518812700/hlog.dat.0, entries=4. New
log writer: /user/hudson/log_1237518812700/hlog.dat.1237518813008
[junit] 2009-03-20 03:13:33,010 DEBUG [main] regionserver.HLog(280): Last
sequence written is empty. Deleting all old hlogs
[junit] 2009-03-20 03:13:33,010 INFO [main] regionserver.HLog(391):
removing old log file /user/hudson/log_1237518812700/hlog.dat.0 whose highest
sequence/edit id is 21
[junit] 2009-03-20 03:13:33,011 DEBUG [main] regionserver.HLog(427):
closing log writer in hdfs://localhost:48090/user/hudson/log_1237518812700
[junit] 2009-03-20 03:13:33,017 DEBUG [main] regionserver.HRegion(2200):
Opening region: REGION => {NAME => 'TestMergeTool,,1237518812870', STARTKEY =>
'', ENDKEY => '', ENCODED => 2049739120, TABLE => {{NAME => 'TestMergeTool',
IS_ROOT => 'false', IS_META => 'false', FAMILIES => [{NAME => 'contents',
BLOOMFILTER => 'false', COMPRESSION => 'NONE', VERSIONS => '3', LENGTH =>
'2147483647', TTL => '-1', BLOCKSIZE => '65536', IN_MEMORY => 'false',
BLOCKCACHE => 'false'}], INDEXES => []}}
[junit] 2009-03-20 03:13:33,018 DEBUG [main] regionserver.HRegion(237):
Opening region TestMergeTool,,1237518812870/2049739120
[junit] 2009-03-20 03:13:33,022 DEBUG [main] regionserver.Store(383):
loaded /user/hudson/TestMergeTool/2049739120/contents/2323191788684413350,
isReference=false, sequence id=6, length=804, majorCompaction=false
[junit] 2009-03-20 03:13:33,023 DEBUG [main] regionserver.Store(213):
Loaded 1 file(s) in Store 2049739120/contents, max sequence id 6
[junit] 2009-03-20 03:13:33,024 DEBUG [main] regionserver.HRegion(297):
Next sequence id for region TestMergeTool,,1237518812870 is 7
[junit] 2009-03-20 03:13:33,025 INFO [main] regionserver.HRegion(314):
region TestMergeTool,,1237518812870/2049739120 available
[junit] 2009-03-20 03:13:33,025 DEBUG [main] regionserver.HLog(217):
changing sequence number from 6 to 7
[junit] 2009-03-20 03:13:33,049 DEBUG [main] regionserver.HRegion(385):
Closing TestMergeTool,,1237518812870: compactions & flushes disabled
[junit] 2009-03-20 03:13:33,049 DEBUG [main] regionserver.HRegion(415):
Updates disabled for region, no outstanding scanners on
TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:33,049 DEBUG [main] regionserver.HRegion(422): No
more row locks outstanding on region TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:33,049 DEBUG [main] regionserver.Store(434):
closed 2049739120/contents
[junit] 2009-03-20 03:13:33,049 INFO [main] regionserver.HRegion(434):
Closed TestMergeTool,,1237518812870
[junit] 2009-03-20 03:13:33,050 INFO [main] util.TestMergeTool(169):
Verified merging regions 0+1+2+3 and 4
[junit] 2009-03-20 03:13:33,050 DEBUG [main] regionserver.HLog(427):
closing log writer in /tmp/log_1237518811298
[junit] 2009-03-20 03:13:33,056 INFO [main] hbase.HBaseTestCase(587):
Shutting down FileSystem
[junit] 2009-03-20 03:13:33,056 INFO [main] hbase.HBaseTestCase(594):
Shutting down Mini DFS
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 1
[junit] 2009-03-20 03:13:33,056 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=35028]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=35028]
[junit] 2009-03-20 03:13:33,061 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:35028
[junit] 2009-03-20 03:13:33,061 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@14627a
[junit] 2009-03-20 03:13:33,136 INFO [main] util.Container(156): Stopped
WebApplicationContext[/static,/static]
[junit] 2009-03-20 03:13:33,136 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@cb42cf
[junit] 2009-03-20 03:13:33,195 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2009-03-20 03:13:33,196 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.ser...@134eb84
[junit] 2009-03-20 03:13:33,198 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@873723]
datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:41646,
storageID=DS-1539882887-67.195.138.9-41646-1237518810621, infoPort=35028,
ipcPort=55678):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:129)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-03-20 03:13:33,582 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(898): Exception closing session 0x0 to
sun.nio.ch.selectionkeyi...@113c817
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:864)
[junit] 2009-03-20 03:13:33,582 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(932): Ignoring exception during shutdown input
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:638)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:930)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:33,582 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(937): Ignoring exception during shutdown output
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:649)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownOutput(SocketAdaptor.java:368)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:935)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:33,905 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(898): Exception closing session 0x0 to
sun.nio.ch.selectionkeyi...@1fe0d66
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:864)
[junit] 2009-03-20 03:13:33,906 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(932): Ignoring exception during shutdown input
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:638)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:930)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:33,906 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(937): Ignoring exception during shutdown output
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:649)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownOutput(SocketAdaptor.java:368)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:935)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:34,004 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(898): Exception closing session 0x0 to
sun.nio.ch.selectionkeyi...@404eb2
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:864)
[junit] 2009-03-20 03:13:34,005 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(932): Ignoring exception during shutdown input
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:638)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:930)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:34,005 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(937): Ignoring exception during shutdown output
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:649)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownOutput(SocketAdaptor.java:368)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:935)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] Shutting down DataNode 0
[junit] 2009-03-20 03:13:34,199 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=47181]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=47181]
[junit] 2009-03-20 03:13:34,199 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:47181
[junit] 2009-03-20 03:13:34,200 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@1000bcf
[junit] 2009-03-20 03:13:34,268 INFO [main] util.Container(156): Stopped
WebApplicationContext[/static,/static]
[junit] 2009-03-20 03:13:34,269 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@e33e18
[junit] 2009-03-20 03:13:34,320 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2009-03-20 03:13:34,321 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.ser...@15e92d7
[junit] 2009-03-20 03:13:34,321 WARN
[org.apache.hadoop.hdfs.server.datanode.dataxceiverser...@457d21]
datanode.DataXceiverServer(134): DatanodeRegistration(127.0.0.1:59208,
storageID=DS-67083093-67.195.138.9-59208-1237518810348, infoPort=47181,
ipcPort=50104):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:129)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-03-20 03:13:34,325 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(898): Exception closing session 0x0 to
sun.nio.ch.selectionkeyi...@76fb1b
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:574)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:864)
[junit] 2009-03-20 03:13:34,325 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(932): Ignoring exception during shutdown input
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownInput(SocketChannelImpl.java:638)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownInput(SocketAdaptor.java:360)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:930)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:34,326 WARN [main-SendThread]
zookeeper.ClientCnxn$SendThread(937): Ignoring exception during shutdown output
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:649)
[junit] at
sun.nio.ch.SocketAdaptor.shutdownOutput(SocketAdaptor.java:368)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.cleanup(ClientCnxn.java:935)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:901)
[junit] 2009-03-20 03:13:35,422 INFO [Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=46483]]
util.ThreadedServer$Acceptor(656): Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=46483]
[junit] 2009-03-20 03:13:35,423 INFO [main] http.SocketListener(212):
Stopped SocketListener on 127.0.0.1:46483
[junit] 2009-03-20 03:13:35,424 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@acdd02
[junit] 2009-03-20 03:13:35,474 INFO [main] util.Container(156): Stopped
WebApplicationContext[/static,/static]
[junit] 2009-03-20 03:13:35,475 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.servlet.webapplicationhand...@130633a
[junit] 2009-03-20 03:13:35,522 INFO [main] util.Container(156): Stopped
WebApplicationContext[/,/]
[junit] 2009-03-20 03:13:35,523 INFO [main] util.Container(156): Stopped
org.mortbay.jetty.ser...@9cfec1
[junit] 2009-03-20 03:13:35,523 WARN
[org.apache.hadoop.hdfs.server.namenode.fsnamesystem$replicationmoni...@b2c6a6]
namenode.FSNamesystem$ReplicationMonitor(2300): ReplicationMonitor thread
received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 34.719 sec
[junit] Running org.apache.hadoop.hbase.util.TestRootPath
[junit] 2009-03-20 03:13:35,870 INFO [main] util.TestRootPath(60): Got
expected exception when checking invalid path:
[junit] java.io.IOException: Root directory does not contain a scheme
[junit] at
org.apache.hadoop.hbase.util.FSUtils.validateRootPath(FSUtils.java:199)
[junit] at
org.apache.hadoop.hbase.util.TestRootPath.testRootPath(TestRootPath.java:56)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
[junit] at
org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.071 sec
[junit] Running org.onelab.test.TestFilter
[junit] 2009-03-20 03:13:36,205 INFO [main] test.TestFilter(244): Checking
for false negatives
[junit] 2009-03-20 03:13:36,210 INFO [main] test.TestFilter(255): Checking
for false positives
[junit] 2009-03-20 03:13:36,210 INFO [main] test.TestFilter(262): Success!
[junit] 2009-03-20 03:13:36,211 INFO [main] test.TestFilter(226): Checking
serialization/deserialization
[junit] 2009-03-20 03:13:36,212 INFO [main] test.TestFilter(244): Checking
for false negatives
[junit] 2009-03-20 03:13:36,215 INFO [main] test.TestFilter(255): Checking
for false positives
[junit] 2009-03-20 03:13:36,216 INFO [main] test.TestFilter(262): Success!
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.086 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :458:
Tests failed!
Total time: 28 minutes 13 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...
