You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hbase.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2009/10/28 01:45:55 UTC
Build failed in Hudson: HBase-Patch #959
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/959/changes>
Changes:
[stack] HBASE-1816 Master rewrite; part 1 -- bit of crud removal and tightening up of access in master package
------------------------------------------
[...truncated 55701 lines...]
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1459)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:383)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:442)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1013)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:921)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit] 09/10/28 00:48:26 INFO wal.HLog: hlog file splitting completed in 6 millis for file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,56518,1256690895847
[junit] 09/10/28 00:48:26 INFO master.HMaster: Found log folder : vesta.apache.org,56864,1256690895777
[junit] 09/10/28 00:48:26 INFO master.HMaster: Log folder doesn't belong to a known region server, splitting
[junit] 09/10/28 00:48:26 INFO wal.HLog: Splitting 1 hlog(s) in file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,56864,1256690895777
[junit] 09/10/28 00:48:26 WARN fs.FSInputChecker: Problem opening checksum file: file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,56864,1256690895777/hlog.dat.1256690895946. Ignoring exception: java.io.EOFException
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:180)
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:152)
[junit] at org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.<init>(ChecksumFileSystem.java:141)
[junit] at org.apache.hadoop.fs.ChecksumFileSystem.open(ChecksumFileSystem.java:290)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.openFile(SequenceFile.java:1474)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.openFile(HLog.java:391)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1453)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:383)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:442)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1013)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:921)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit]
[junit] 09/10/28 00:48:26 WARN wal.HLog: Empty hlog, continuing: org.apache.hadoop.fs.RawLocalFileSystem$RawLocalFileStatus@b7be64ef count=0
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:180)
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:152)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.init(SequenceFile.java:1487)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1459)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:383)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:442)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1013)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:921)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit] 09/10/28 00:48:26 INFO wal.HLog: hlog file splitting completed in 4 millis for file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,56864,1256690895777
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server Responder: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 1 on 60001: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 0 on 60001: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server listener on 60001: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 2 on 60001: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 3 on 60001: starting
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 4 on 60001: starting
[junit] 09/10/28 00:48:26 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124989d737d0002 type:create cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 00:48:26 INFO ipc.HBaseServer: IPC Server handler 1 on 60000, call createTable({NAME => 'table1', FAMILIES => [{NAME => 'family', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}) from 67.195.138.9:48485: error: org.apache.hadoop.hbase.TableExistsException: table1
[junit] org.apache.hadoop.hbase.TableExistsException: table1
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:789)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:754)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:648)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:915)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:789)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:754)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:648)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:915)
[junit] )
[junit] Tests run: 2, Failures: 0, Errors: 2, Time elapsed: 11.501 sec
[junit] 09/10/28 00:48:26 INFO regionserver.HRegionServer: Starting shutdown thread
[junit] 09/10/28 00:48:26 INFO regionserver.HRegionServer: Starting shutdown thread
[junit] 09/10/28 00:48:26 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124989d737d0002 type:create cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 00:48:26 INFO master.ServerManager: Received start message from: vesta.apache.org,55116,1256690906374
[junit] 09/10/28 00:48:26 WARN zookeeper.ZooKeeperWrapper: Failed to create /hbase/rs/1256690906374 znode in ZooKeeper: org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists for /hbase/rs/1256690906374
[junit] 09/10/28 00:48:26 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124989d737d0002 type:create cxid:0x5 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 00:48:26 WARN conf.Configuration: fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] 09/10/28 00:48:26 INFO regionserver.HRegionServer: Master passed us address to use. Was=67.195.138.9:55116, Now=67.195.138.9
[junit] 09/10/28 00:48:26 ERROR regionserver.HRegionServer: Failed init
[junit] java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:739)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:431)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 00:48:26 FATAL regionserver.HRegionServer: Unhandled exception. Aborting...
[junit] java.io.IOException: Region server startup failed
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.convertThrowableToIOE(HRegionServer.java:847)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:757)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:431)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:739)
[junit] ... 3 more
[junit] Exception in thread "RegionServer:0" java.lang.NullPointerException
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.abort(HRegionServer.java:1264)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:598)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 00:48:26 INFO master.ServerManager: Received start message from: vesta.apache.org,50470,1256690906532
[junit] 09/10/28 00:48:26 WARN conf.Configuration: fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] 09/10/28 00:48:26 INFO regionserver.HRegionServer: Master passed us address to use. Was=67.195.138.9:50470, Now=67.195.138.9
[junit] 09/10/28 00:48:26 ERROR regionserver.HRegionServer: Failed init
[junit] java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:739)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:431)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 00:48:26 FATAL regionserver.HRegionServer: Unhandled exception. Aborting...
[junit] java.io.IOException: Region server startup failed
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.convertThrowableToIOE(HRegionServer.java:847)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:757)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:431)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:739)
[junit] ... 3 more
[junit] Exception in thread "RegionServer:1" java.lang.NullPointerException
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.abort(HRegionServer.java:1264)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:598)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 00:48:26 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/10/28 00:48:26 INFO regionserver.LogFlusher: RegionServer:0.logFlusher exiting
[junit] 09/10/28 00:48:26 INFO regionserver.MemStoreFlusher: RegionServer:0.cacheFlusher exiting
[junit] 09/10/28 00:48:26 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/10/28 00:48:26 INFO regionserver.LogFlusher: RegionServer:1.logFlusher exiting
[junit] 09/10/28 00:48:26 INFO regionserver.MemStoreFlusher: RegionServer:1.cacheFlusher exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping server on 56518
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 0 on 56518: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 1 on 56518: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 3 on 56518: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 2 on 56518: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 4 on 56518: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping IPC Server listener on 56518
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:1.majorCompactionChecker exiting
[junit] 09/10/28 00:48:27 INFO transactional.TransactionalRegion: Preparing to close region table1,,1256539771901
[junit] 09/10/28 00:48:27 INFO regionserver.CompactSplitThread: RegionServer:1.compactor exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegion: Closed table1,,1256539771901
[junit] 09/10/28 00:48:27 INFO transactional.TransactionalRegion: Preparing to close region __GLOBAL_TRX_LOG__,,1256539782042
[junit] 09/10/28 00:48:27 INFO regionserver.HRegion: Closed __GLOBAL_TRX_LOG__,,1256539782042
[junit] 09/10/28 00:48:27 INFO transactional.TransactionalRegion: Preparing to close region -ROOT-,,0
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping server on 56864
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping IPC Server listener on 56864
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 2 on 56864: exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:0.majorCompactionChecker exiting
[junit] 09/10/28 00:48:27 INFO transactional.TransactionalRegion: Preparing to close region .META.,,1
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 4 on 56864: exiting
[junit] 09/10/28 00:48:27 INFO regionserver.CompactSplitThread: RegionServer:0.compactor exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegion: Closed .META.,,1
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 3 on 56864: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 1 on 56864: exiting
[junit] 09/10/28 00:48:27 INFO ipc.HBaseServer: IPC Server handler 0 on 56864: exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:56864
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Removing server's info vesta.apache.org,56864,1256690895777
[junit] 09/10/28 00:48:27 INFO master.RegionManager: Offlining META region: {server: 67.195.138.9:56864, regionname: .META.,,1, startKey: <>}
[junit] 09/10/28 00:48:27 INFO master.RegionManager: META region removed from onlineMetaRegions
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Region server vesta.apache.org,56864,1256690895777: MSG_REPORT_EXITING
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: .META.,,1 from vesta.apache.org,56864,1256690895777
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:56864
[junit] 09/10/28 00:48:27 INFO regionserver.HRegion: Closed -ROOT-,,0
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:56518
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Removing server's info vesta.apache.org,56518,1256690895847
[junit] 09/10/28 00:48:27 INFO master.RegionManager: Offlined ROOT server: 67.195.138.9:56518
[junit] 09/10/28 00:48:27 INFO master.RegionManager: -ROOT- region unset (but not set to be reassigned)
[junit] 09/10/28 00:48:27 INFO master.RegionManager: ROOT inserted into regionsInTransition
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Region server vesta.apache.org,56518,1256690895847: MSG_REPORT_EXITING
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: table1,,1256539771901 from vesta.apache.org,56518,1256690895847
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: __GLOBAL_TRX_LOG__,,1256539782042 from vesta.apache.org,56518,1256690895847
[junit] 09/10/28 00:48:27 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: -ROOT-,,0 from vesta.apache.org,56518,1256690895847
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:56518
[junit] 09/10/28 00:48:27 INFO zookeeper.ZooKeeper: Closing session: 0x124989d48e10002
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124989d48e10002
[junit] 09/10/28 00:48:27 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x124989d48e10002
[junit] 09/10/28 00:48:27 INFO server.NIOServerCnxn: closing session:0x124989d48e10002 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:49372]
[junit] 09/10/28 00:48:27 INFO master.ServerManager: vesta.apache.org,56518,1256690895847 znode expired
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x124989d48e10002 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/10/28 00:48:27 INFO zookeeper.ZooKeeper: Closing session: 0x124989d48e10001
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124989d48e10001
[junit] 09/10/28 00:48:27 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x124989d48e10001
[junit] 09/10/28 00:48:27 INFO master.ServerManager: vesta.apache.org,56864,1256690895777 znode expired
[junit] 09/10/28 00:48:27 INFO server.NIOServerCnxn: closing session:0x124989d48e10001 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:49371]
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x124989d48e10001 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124989d48e10002
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/10/28 00:48:27 INFO zookeeper.ZooKeeper: Session: 0x124989d48e10002 closed
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: RegionServer:1 exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124989d48e10001
[junit] 09/10/28 00:48:27 INFO zookeeper.ZooKeeper: Session: 0x124989d48e10001 closed
[junit] 09/10/28 00:48:27 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: RegionServer:0 exiting
[junit] 09/10/28 00:48:27 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] Test org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery FAILED
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:515: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml>:54: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build-contrib.xml>:283: Tests failed!
Total time: 29 minutes 57 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Hudson build is back to normal: HBase-Patch #961
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/961/changes>
Build failed in Hudson: HBase-Patch #960
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/960/changes>
Changes:
[jdcryans] HBASE-1936 HLog group commit
------------------------------------------
[...truncated 105536 lines...]
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1459)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:372)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:431)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1087)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:997)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit] 09/10/28 04:49:29 INFO wal.HLog: hlog file splitting completed in 7 millis for file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,33547,1256705359362
[junit] 09/10/28 04:49:29 INFO master.HMaster: Found log folder : vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:29 INFO master.HMaster: Log folder doesn't belong to a known region server, splitting
[junit] 09/10/28 04:49:29 INFO wal.HLog: Splitting 1 hlog(s) in file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:29 WARN fs.FSInputChecker: Problem opening checksum file: file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,51241,1256705359441/hlog.dat.1256705359556. Ignoring exception: java.io.EOFException
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:180)
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:152)
[junit] at org.apache.hadoop.fs.ChecksumFileSystem$ChecksumFSInputChecker.<init>(ChecksumFileSystem.java:141)
[junit] at org.apache.hadoop.fs.ChecksumFileSystem.open(ChecksumFileSystem.java:290)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.openFile(SequenceFile.java:1474)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.openFile(HLog.java:380)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1453)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:372)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:431)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1087)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:997)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit]
[junit] 09/10/28 04:49:29 WARN wal.HLog: Empty hlog, continuing: org.apache.hadoop.fs.RawLocalFileSystem$RawLocalFileStatus@cdb55652 count=0
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:180)
[junit] at java.io.DataInputStream.readFully(DataInputStream.java:152)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.init(SequenceFile.java:1487)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1459)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1446)
[junit] at org.apache.hadoop.io.SequenceFile$Reader.<init>(SequenceFile.java:1441)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog$WALReader.<init>(HLog.java:372)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.getReader(HLog.java:431)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:1087)
[junit] at org.apache.hadoop.hbase.regionserver.wal.HLog.splitLog(HLog.java:997)
[junit] at org.apache.hadoop.hbase.master.HMaster.splitLogAfterStartup(HMaster.java:626)
[junit] at org.apache.hadoop.hbase.master.HMaster.joinCluster(HMaster.java:550)
[junit] at org.apache.hadoop.hbase.master.HMaster.run(HMaster.java:405)
[junit] 09/10/28 04:49:29 INFO wal.HLog: hlog file splitting completed in 3 millis for file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server Responder: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server listener on 60001: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 0 on 60001: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 1 on 60001: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 2 on 60001: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 3 on 60001: starting
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 4 on 60001: starting
[junit] 09/10/28 04:49:29 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124997a25910001 type:create cxid:0x3 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 04:49:29 INFO ipc.HBaseServer: IPC Server handler 1 on 60000, call createTable({NAME => 'table1', FAMILIES => [{NAME => 'family', VERSIONS => '3', COMPRESSION => 'NONE', TTL => '2147483647', BLOCKSIZE => '65536', IN_MEMORY => 'false', BLOCKCACHE => 'true'}]}) from 67.195.138.9:36215: error: org.apache.hadoop.hbase.TableExistsException: table1
[junit] org.apache.hadoop.hbase.TableExistsException: table1
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:789)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:754)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:648)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:915)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:789)
[junit] at org.apache.hadoop.hbase.master.HMaster.createTable(HMaster.java:754)
[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 org.apache.hadoop.hbase.ipc.HBaseRPC$Server.call(HBaseRPC.java:648)
[junit] at org.apache.hadoop.hbase.ipc.HBaseServer$Handler.run(HBaseServer.java:915)
[junit] )
[junit] Tests run: 2, Failures: 0, Errors: 2, Time elapsed: 11.531 sec
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: Starting shutdown thread
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: Starting shutdown thread
[junit] 09/10/28 04:49:30 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124997a25910001 type:create cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Received start message from: vesta.apache.org,48619,1256705369966
[junit] 09/10/28 04:49:30 WARN zookeeper.ZooKeeperWrapper: Failed to create /hbase/rs/1256705369966 znode in ZooKeeper: org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists for /hbase/rs/1256705369966
[junit] 09/10/28 04:49:30 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124997a25910001 type:create cxid:0x5 zxid:0xfffffffffffffffe txntype:unknown n/a
[junit] org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.pRequest(PrepRequestProcessor.java:245)
[junit] at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:114)
[junit] 09/10/28 04:49:30 WARN conf.Configuration: fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: Master passed us address to use. Was=67.195.138.9:48619, Now=67.195.138.9
[junit] 09/10/28 04:49:30 ERROR regionserver.HRegionServer: Failed init
[junit] java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:730)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:424)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 04:49:30 FATAL regionserver.HRegionServer: Unhandled exception. Aborting...
[junit] java.io.IOException: Region server startup failed
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.convertThrowableToIOE(HRegionServer.java:837)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:747)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:424)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:730)
[junit] ... 3 more
[junit] Exception in thread "RegionServer:1" java.lang.NullPointerException
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.abort(HRegionServer.java:1252)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:590)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Received start message from: vesta.apache.org,52601,1256705370094
[junit] 09/10/28 04:49:30 WARN conf.Configuration: fs.default.name is deprecated. Instead, use fs.defaultFS
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: Master passed us address to use. Was=67.195.138.9:52601, Now=67.195.138.9
[junit] 09/10/28 04:49:30 ERROR regionserver.HRegionServer: Failed init
[junit] java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:730)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:424)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 04:49:30 FATAL regionserver.HRegionServer: Unhandled exception. Aborting...
[junit] java.io.IOException: Region server startup failed
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.convertThrowableToIOE(HRegionServer.java:837)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:747)
[junit] at org.apache.hadoop.hbase.regionserver.transactional.TransactionalRegionServer.init(TransactionalRegionServer.java:95)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:424)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] Caused by: java.lang.IllegalStateException: Shutdown in progress
[junit] at java.lang.ApplicationShutdownHooks.add(ApplicationShutdownHooks.java:39)
[junit] at java.lang.Runtime.addShutdownHook(Runtime.java:192)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.getInternal(FileSystem.java:1782)
[junit] at org.apache.hadoop.fs.FileSystem$Cache.get(FileSystem.java:1768)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:195)
[junit] at org.apache.hadoop.fs.FileSystem.get(FileSystem.java:103)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.init(HRegionServer.java:730)
[junit] ... 3 more
[junit] Exception in thread "RegionServer:0" java.lang.NullPointerException
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.abort(HRegionServer.java:1252)
[junit] at org.apache.hadoop.hbase.regionserver.HRegionServer.run(HRegionServer.java:590)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 09/10/28 04:49:30 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/10/28 04:49:30 INFO regionserver.MemStoreFlusher: RegionServer:1.cacheFlusher exiting
[junit] 09/10/28 04:49:30 INFO regionserver.LogRoller: LogRoller exiting.
[junit] 09/10/28 04:49:30 INFO regionserver.MemStoreFlusher: RegionServer:0.cacheFlusher exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping server on 51241
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 0 on 51241: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 3 on 51241: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping IPC Server listener on 51241
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 1 on 51241: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 2 on 51241: exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:0.majorCompactionChecker exiting
[junit] 09/10/28 04:49:30 INFO transactional.TransactionalRegion: Preparing to close region .META.,,1
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 4 on 51241: exiting
[junit] 09/10/28 04:49:30 INFO regionserver.CompactSplitThread: RegionServer:0.compactor exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegion: Closed .META.,,1
[junit] 09/10/28 04:49:30 INFO transactional.TransactionalRegion: Preparing to close region table1,,1256539771901
[junit] 09/10/28 04:49:30 INFO regionserver.HRegion: Closed table1,,1256539771901
[junit] 09/10/28 04:49:30 INFO transactional.TransactionalRegion: Preparing to close region __GLOBAL_TRX_LOG__,,1256539782042
[junit] 09/10/28 04:49:30 INFO regionserver.HRegion: Closed __GLOBAL_TRX_LOG__,,1256539782042
[junit] 09/10/28 04:49:30 INFO wal.HLog: RegionServer:0.logSyncer exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping server on 33547
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 0 on 33547: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping IPC Server listener on 33547
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: Stopping IPC Server Responder
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 1 on 33547: exiting
[junit] 09/10/28 04:49:30 INFO regionserver.CompactSplitThread: RegionServer:1.compactor exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 4 on 33547: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 2 on 33547: exiting
[junit] 09/10/28 04:49:30 INFO ipc.HBaseServer: IPC Server handler 3 on 33547: exiting
[junit] 09/10/28 04:49:30 INFO transactional.TransactionalRegion: Preparing to close region -ROOT-,,0
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:1.majorCompactionChecker exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:51241
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Removing server's info vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:30 INFO master.RegionManager: Offlining META region: {server: 67.195.138.9:51241, regionname: .META.,,1, startKey: <>}
[junit] 09/10/28 04:49:30 INFO master.RegionManager: META region removed from onlineMetaRegions
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Region server vesta.apache.org,51241,1256705359441: MSG_REPORT_EXITING
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: .META.,,1 from vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: table1,,1256539771901 from vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: __GLOBAL_TRX_LOG__,,1256539782042 from vesta.apache.org,51241,1256705359441
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:51241
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: worker thread exiting
[junit] 09/10/28 04:49:30 INFO zookeeper.ZooKeeper: Closing session: 0x1249979fb460001
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1249979fb460001
[junit] 09/10/28 04:49:30 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1249979fb460001
[junit] 09/10/28 04:49:30 INFO regionserver.HRegion: Closed -ROOT-,,0
[junit] 09/10/28 04:49:30 INFO wal.HLog: RegionServer:1.logSyncer exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:33547
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Removing server's info vesta.apache.org,33547,1256705359362
[junit] 09/10/28 04:49:30 INFO master.RegionManager: Offlined ROOT server: 67.195.138.9:33547
[junit] 09/10/28 04:49:30 INFO master.RegionManager: -ROOT- region unset (but not set to be reassigned)
[junit] 09/10/28 04:49:30 INFO master.RegionManager: ROOT inserted into regionsInTransition
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Region server vesta.apache.org,33547,1256705359362: MSG_REPORT_EXITING
[junit] 09/10/28 04:49:30 INFO master.ServerManager: Processing MSG_REPORT_CLOSE: -ROOT-,,0 from vesta.apache.org,33547,1256705359362
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:33547
[junit] 09/10/28 04:49:30 INFO zookeeper.ZooKeeper: Closing session: 0x1249979fb460002
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x1249979fb460002
[junit] 09/10/28 04:49:30 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x1249979fb460002
[junit] 09/10/28 04:49:30 INFO master.ServerManager: vesta.apache.org,51241,1256705359441 znode expired
[junit] 09/10/28 04:49:30 INFO server.NIOServerCnxn: closing session:0x1249979fb460001 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:53875]
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1249979fb460001 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/10/28 04:49:30 INFO server.NIOServerCnxn: closing session:0x1249979fb460002 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:53876]
[junit] 09/10/28 04:49:30 INFO master.ServerManager: vesta.apache.org,33547,1256705359362 znode expired
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x1249979fb460002 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1249979fb460001
[junit] 09/10/28 04:49:30 INFO zookeeper.ZooKeeper: Session: 0x1249979fb460001 closed
[junit] 09/10/28 04:49:30 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: RegionServer:0 exiting
[junit] 09/10/28 04:49:30 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] 09/10/28 04:49:31 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x1249979fb460002
[junit] 09/10/28 04:49:31 INFO zookeeper.ZooKeeper: Session: 0x1249979fb460002 closed
[junit] 09/10/28 04:49:31 INFO zookeeper.ClientCnxn: EventThread shut down
[junit] 09/10/28 04:49:31 INFO regionserver.HRegionServer: RegionServer:1 exiting
[junit] 09/10/28 04:49:31 INFO regionserver.HRegionServer: Shutdown thread complete
[junit] Test org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery FAILED
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml>:515: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build.xml>:54: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/src/contrib/build-contrib.xml>:283: Tests failed!
Total time: 30 minutes 7 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure