See <http://hudson.zones.apache.org/hudson/job/HBase-Patch/976/changes>
Changes: [stack] HBASE-1954 Transactional scans do not see newest put [stack] HBASE-1954 Transactional scans do not see newest put ------------------------------------------ [...truncated 75264 lines...] [junit] sun.misc.Unsafe.park(Native Method) [junit] java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:198) [junit] java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1963) [junit] java.util.concurrent.DelayQueue.poll(DelayQueue.java:201) [junit] org.apache.hadoop.hbase.Leases.run(Leases.java:81) [junit] Thread 4 (Signal Dispatcher): [junit] State: RUNNABLE [junit] Blocked count: 0 [junit] Waited count: 0 [junit] Stack: [junit] Thread 3 (Finalizer): [junit] State: WAITING [junit] Blocked count: 590 [junit] Waited count: 297 [junit] Waiting on java.lang.ref.referencequeue$l...@14596d5 [junit] Stack: [junit] java.lang.Object.wait(Native Method) [junit] java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116) [junit] java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132) [junit] java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159) [junit] Thread 2 (Reference Handler): [junit] State: WAITING [junit] Blocked count: 10 [junit] Waited count: 10 [junit] Waiting on java.lang.ref.reference$l...@45ce17 [junit] Stack: [junit] java.lang.Object.wait(Native Method) [junit] java.lang.Object.wait(Object.java:485) [junit] java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116) [junit] Thread 1 (main): [junit] State: RUNNABLE [junit] Blocked count: 199 [junit] Waited count: 558 [junit] Stack: [junit] sun.management.ThreadImpl.getThreadInfo0(Native Method) [junit] sun.management.ThreadImpl.getThreadInfo(ThreadImpl.java:147) [junit] sun.management.ThreadImpl.getThreadInfo(ThreadImpl.java:123) [junit] org.apache.hadoop.util.ReflectionUtils.printThreadInfo(ReflectionUtils.java:155) [junit] org.apache.hadoop.hbase.HBaseClusterTestCase.threadDumpingJoin(HBaseClusterTestCase.java:223) [junit] org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery.testWithFlushBeforeCommit(TestTHLogRecovery.java:135) [junit] sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit] sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [junit] sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [junit] java.lang.reflect.Method.invoke(Method.java:597) [junit] junit.framework.TestCase.runTest(TestCase.java:168) [junit] junit.framework.TestCase.runBare(TestCase.java:134) [junit] junit.framework.TestResult$1.protect(TestResult.java:110) [junit] junit.framework.TestResult.runProtected(TestResult.java:128) [junit] junit.framework.TestResult.run(TestResult.java:113) [junit] junit.framework.TestCase.run(TestCase.java:124) [junit] junit.framework.TestSuite.runTest(TestSuite.java:232) [junit] junit.framework.TestSuite.run(TestSuite.java:227) [junit] org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83) [junit] junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39) [junit] 09/11/03 20:56:38 INFO master.ServerManager: 1 region servers, 0 dead, average load 2.0 [junit] 09/11/03 20:56:38 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} [junit] 09/11/03 20:56:38 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} [junit] 09/11/03 20:56:38 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} complete [junit] 09/11/03 20:56:38 INFO master.BaseScanner: RegionManager.metaScanner scan of 0 row(s) of meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} complete [junit] 09/11/03 20:56:38 INFO master.BaseScanner: All 1 .META. region(s) scanned [junit] 09/11/03 20:56:48 INFO master.ServerManager: 1 region servers, 0 dead, average load 2.0 [junit] 09/11/03 20:56:48 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} [junit] 09/11/03 20:56:48 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} [junit] 09/11/03 20:56:48 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} complete [junit] 09/11/03 20:56:48 INFO master.BaseScanner: RegionManager.metaScanner scan of 0 row(s) of meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} complete [junit] 09/11/03 20:56:48 INFO master.BaseScanner: All 1 .META. region(s) scanned [junit] 09/11/03 20:56:58 INFO master.ServerManager: 1 region servers, 0 dead, average load 2.0 [junit] 09/11/03 20:56:58 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} [junit] 09/11/03 20:56:58 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} [junit] 09/11/03 20:56:58 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} complete [junit] 09/11/03 20:56:58 INFO master.BaseScanner: RegionManager.metaScanner scan of 0 row(s) of meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} complete [junit] 09/11/03 20:56:58 INFO master.BaseScanner: All 1 .META. region(s) scanned [junit] 09/11/03 20:57:08 INFO master.ServerManager: 1 region servers, 0 dead, average load 2.0 [junit] 09/11/03 20:57:08 INFO master.BaseScanner: RegionManager.rootScanner scanning meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} [junit] 09/11/03 20:57:08 INFO master.BaseScanner: RegionManager.metaScanner scanning meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} [junit] 09/11/03 20:57:08 INFO master.BaseScanner: RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51635, regionname: -ROOT-,,0, startKey: <>} complete [junit] 09/11/03 20:57:08 INFO master.BaseScanner: RegionManager.metaScanner scan of 0 row(s) of meta region {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} complete [junit] 09/11/03 20:57:08 INFO master.BaseScanner: All 1 .META. region(s) scanned [junit] 09/11/03 20:57:14 FATAL transactional.TestTHLogRecovery: could not re-open meta table because [junit] org.apache.hadoop.hbase.client.RetriesExhaustedException: Trying to contact region server 67.195.138.9:47099 for region table1,,1257281418602, row '', but failed after 10 attempts. [junit] Exceptions: [junit] java.io.IOException: Call to /67.195.138.9:47099 failed on local exception: java.io.EOFException [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] org.apache.hadoop.hbase.TableNotFoundException: table1 [junit] [junit] at org.apache.hadoop.hbase.client.HConnectionManager$TableServers.getRegionServerWithRetries(HConnectionManager.java:998) [junit] at org.apache.hadoop.hbase.client.HTable$ClientScanner.nextScanner(HTable.java:819) [junit] at org.apache.hadoop.hbase.client.HTable$ClientScanner.initialize(HTable.java:739) [junit] at org.apache.hadoop.hbase.client.HTable.getScanner(HTable.java:346) [junit] at org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery$1.run(TestTHLogRecovery.java:270) [junit] at java.lang.Thread.run(Thread.java:619) [junit] Exception in thread "Thread-136" junit.framework.AssertionFailedError: null [junit] at junit.framework.Assert.fail(Assert.java:47) [junit] at junit.framework.Assert.fail(Assert.java:53) [junit] at org.apache.hadoop.hbase.regionserver.transactional.TestTHLogRecovery$1.run(TestTHLogRecovery.java:275) [junit] at java.lang.Thread.run(Thread.java:619) [junit] 09/11/03 20:57:14 DEBUG hbase.LocalHBaseCluster: Shutting down HBase Cluster [junit] 09/11/03 20:57:14 INFO master.HMaster: Cluster shutdown requested. Starting to quiesce servers [junit] 09/11/03 20:57:14 WARN server.PrepRequestProcessor: Got exception when processing sessionid:0x124bbcfcbe50000 type:create cxid:0x10 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/11/03 20:57:14 DEBUG zookeeper.ZooKeeperWrapper: State node deleted in ZooKeeper [junit] 09/11/03 20:57:15 INFO regionserver.HRegionServer: MSG_REGIONSERVER_QUIESCE [junit] 09/11/03 20:57:15 INFO regionserver.HRegionServer: Worker: MSG_REGIONSERVER_QUIESCE [junit] 09/11/03 20:57:15 DEBUG master.RegionManager: telling root scanner to stop [junit] 09/11/03 20:57:15 DEBUG master.RegionManager: telling meta scanner to stop [junit] 09/11/03 20:57:15 DEBUG master.RegionManager: meta and root scanners notified [junit] 09/11/03 20:57:15 INFO master.MetaScanner: RegionManager.metaScanner exiting [junit] 09/11/03 20:57:15 INFO master.RootScanner: RegionManager.rootScanner exiting [junit] 09/11/03 20:57:16 INFO master.ServerManager: Region server vesta.apache.org,51635,1257281408529 quiesced [junit] 09/11/03 20:57:16 INFO master.ServerManager: All user tables quiesced. Proceeding with shutdown [junit] 09/11/03 20:57:16 DEBUG master.RegionManager: telling root scanner to stop [junit] 09/11/03 20:57:16 DEBUG master.RegionManager: telling meta scanner to stop [junit] 09/11/03 20:57:16 DEBUG master.RegionManager: meta and root scanners notified [junit] 09/11/03 20:57:16 INFO regionserver.HRegionServer: MSG_REGIONSERVER_STOP [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping server on 51635 [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 1 on 51635: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 0 on 51635: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 2 on 51635: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping IPC Server listener on 51635 [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 4 on 51635: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 3 on 51635: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping IPC Server Responder [junit] 09/11/03 20:57:16 INFO regionserver.CompactSplitThread: RegionServer:0.compactor exiting [junit] 09/11/03 20:57:16 INFO regionserver.HRegionServer$MajorCompactionChecker: RegionServer:0.majorCompactionChecker exiting [junit] 09/11/03 20:57:16 INFO regionserver.LogRoller: LogRoller exiting. [junit] 09/11/03 20:57:16 INFO regionserver.MemStoreFlusher: RegionServer:0.cacheFlusher exiting [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegionServer: closing region .META.,,1 [junit] 09/11/03 20:57:16 INFO transactional.TransactionalRegion: Preparing to close region .META.,,1 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Closing .META.,,1: compactions & flushes disabled [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Updates disabled for region, no outstanding scanners on .META.,,1 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: No more row locks outstanding on region .META.,,1 [junit] 09/11/03 20:57:16 DEBUG regionserver.Store: closed historian [junit] 09/11/03 20:57:16 DEBUG regionserver.Store: closed info [junit] 09/11/03 20:57:16 INFO regionserver.HRegion: Closed .META.,,1 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegionServer: closing region -ROOT-,,0 [junit] 09/11/03 20:57:16 INFO transactional.TransactionalRegion: Preparing to close region -ROOT-,,0 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Closing -ROOT-,,0: compactions & flushes disabled [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Updates disabled for region, no outstanding scanners on -ROOT-,,0 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: No more row locks outstanding on region -ROOT-,,0 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Started memstore flush for region -ROOT-,,0. Current region memstore size 544 [junit] 09/11/03 20:57:16 DEBUG regionserver.Store: Added file:/tmp/hbase-hudson/hbase/-ROOT-/70236052/info/7729555299280632280, entries=4, sequenceid=8, memsize=544, filesize=574 to -ROOT-,,0 [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegion: Finished memstore flush of ~544 for region -ROOT-,,0 in 23ms, sequence id=8, compaction requested=false [junit] 09/11/03 20:57:16 DEBUG regionserver.Store: closed info [junit] 09/11/03 20:57:16 INFO regionserver.HRegion: Closed -ROOT-,,0 [junit] 09/11/03 20:57:16 DEBUG wal.HLog: RegionServer:0.logSyncerinterrupted while waiting for sync requests [junit] 09/11/03 20:57:16 INFO wal.HLog: RegionServer:0.logSyncer exiting [junit] 09/11/03 20:57:16 DEBUG wal.HLog: closing hlog writer in file:/tmp/hbase-hudson/hbase/.logs/vesta.apache.org,51635,1257281408529 [junit] 09/11/03 20:57:16 INFO regionserver.HRegionServer: telling master that region server is shutting down at: 67.195.138.9:51635 [junit] 09/11/03 20:57:16 INFO master.ServerManager: Removing server's info vesta.apache.org,51635,1257281408529 [junit] 09/11/03 20:57:16 INFO master.RegionManager: Offlined ROOT server: 67.195.138.9:51635 [junit] 09/11/03 20:57:16 INFO master.RegionManager: -ROOT- region unset (but not set to be reassigned) [junit] 09/11/03 20:57:16 INFO master.RegionManager: Offlining META region: {server: 67.195.138.9:51635, regionname: .META.,,1, startKey: <>} [junit] 09/11/03 20:57:16 INFO master.RegionManager: META region removed from onlineMetaRegions [junit] 09/11/03 20:57:16 INFO master.ServerManager: Region server vesta.apache.org,51635,1257281408529: MSG_REPORT_EXITING [junit] 09/11/03 20:57:16 INFO regionserver.HRegionServer: stopping server at: 67.195.138.9:51635 [junit] 09/11/03 20:57:16 INFO hbase.Leases: RegionServer:0.leaseChecker closing leases [junit] 09/11/03 20:57:16 INFO hbase.Leases: RegionServer:0.leaseChecker closed leases [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping server on 60000 [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 3 on 60000: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 0 on 60000: exiting [junit] 09/11/03 20:57:16 DEBUG zookeeper.ZooKeeperWrapper: Deleting node: 1257281408529 [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 2 on 60000: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 1 on 60000: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: IPC Server handler 4 on 60000: exiting [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping IPC Server Responder [junit] 09/11/03 20:57:16 INFO ipc.HBaseServer: Stopping IPC Server listener on 60000 [junit] 09/11/03 20:57:16 INFO master.ServerManager: vesta.apache.org,51635,1257281408529 znode expired [junit] 09/11/03 20:57:16 INFO zookeeper.ZooKeeper: Closing session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 DEBUG master.ZKMasterAddressWatcher: Got event NodeDeleted with path /hbase/master [junit] 09/11/03 20:57:16 INFO regionserver.HRegionServer: Got ZooKeeper event, state: SyncConnected, type: NodeDeleted, path: /hbase/master [junit] 09/11/03 20:57:16 INFO server.NIOServerCnxn: closing session:0x124bbcfcbe50000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36354] [junit] 09/11/03 20:57:16 DEBUG regionserver.HRegionServer: Ignoring ZooKeeper event while shutting down [junit] 09/11/03 20:57:16 DEBUG master.ZKMasterAddressWatcher: Master address ZNode deleted, notifying waiting masters [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x124bbcfcbe50000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO zookeeper.ZooKeeper: Session: 0x124bbcfcbe50000 closed [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: EventThread shut down [junit] 09/11/03 20:57:16 DEBUG zookeeper.ZooKeeperWrapper: Closed connection with ZooKeeper [junit] 09/11/03 20:57:16 INFO zookeeper.ZooKeeper: Closing session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124bbcfcbe50000 [junit] 09/11/03 20:57:16 INFO zookeeper.ZooKeeper: Session: 0x124bbcfcbe50000 closed [junit] 09/11/03 20:57:16 DEBUG zookeeper.ZooKeeperWrapper: Closed connection with ZooKeeper [junit] 09/11/03 20:57:16 INFO master.HMaster: HMaster main thread exiting [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: worker thread exiting [junit] 09/11/03 20:57:17 INFO zookeeper.ZooKeeper: Closing session: 0x124bbcfcbe50001 [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124bbcfcbe50001 [junit] 09/11/03 20:57:17 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x124bbcfcbe50001 [junit] 09/11/03 20:57:17 INFO server.NIOServerCnxn: closing session:0x124bbcfcbe50001 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36355] [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x124bbcfcbe50001 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124bbcfcbe50001 [junit] 09/11/03 20:57:17 INFO zookeeper.ZooKeeper: Session: 0x124bbcfcbe50001 closed [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: EventThread shut down [junit] 09/11/03 20:57:17 DEBUG zookeeper.ZooKeeperWrapper: Closed connection with ZooKeeper [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: RegionServer:0 exiting [junit] 09/11/03 20:57:17 INFO hbase.LocalHBaseCluster: Shutdown 1 region server(s) [junit] 09/11/03 20:57:17 INFO zookeeper.ZooKeeper: Closing session: 0x124bbcfcbe50003 [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Closing ClientCnxn for session: 0x124bbcfcbe50003 [junit] 09/11/03 20:57:17 INFO server.PrepRequestProcessor: Processed session termination request for id: 0x124bbcfcbe50003 [junit] 09/11/03 20:57:17 INFO server.NIOServerCnxn: closing session:0x124bbcfcbe50003 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:21810 remote=/127.0.0.1:36357] [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Exception while closing send thread for session 0x124bbcfcbe50003 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: Disconnecting ClientCnxn for session: 0x124bbcfcbe50003 [junit] 09/11/03 20:57:17 INFO zookeeper.ZooKeeper: Session: 0x124bbcfcbe50003 closed [junit] 09/11/03 20:57:17 INFO zookeeper.ClientCnxn: EventThread shut down [junit] 09/11/03 20:57:17 DEBUG zookeeper.ZooKeeperWrapper: Closed connection with ZooKeeper [junit] 09/11/03 20:57:17 INFO server.NIOServerCnxn: NIOServerCnxn factory exited run method [junit] 09/11/03 20:57:17 INFO server.FinalRequestProcessor: shutdown of request processor complete [junit] 09/11/03 20:57:17 INFO server.PrepRequestProcessor: PrepRequestProcessor exited loop! [junit] 09/11/03 20:57:17 INFO server.SyncRequestProcessor: SyncRequestProcessor exited! [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 471.346 sec [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Starting shutdown thread [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Starting shutdown thread [junit] Exception in thread "Thread-44" java.lang.IllegalStateException: Shutdown in progress [junit] at java.lang.ApplicationShutdownHooks.remove(ApplicationShutdownHooks.java:55) [junit] at java.lang.Runtime.removeShutdownHook(Runtime.java:220) [junit] at org.apache.hadoop.fs.FileSystem$Cache.remove(FileSystem.java:1799) [junit] at org.apache.hadoop.fs.FileSystem$Cache.closeAll(FileSystem.java:1831) [junit] at org.apache.hadoop.fs.FileSystem$Cache.closeAll(FileSystem.java:1808) [junit] at org.apache.hadoop.fs.FileSystem.closeAll(FileSystem.java:246) [junit] at org.apache.hadoop.hbase.regionserver.HRegionServer$ShutdownThread.run(HRegionServer.java:890) [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Shutdown thread complete [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Starting shutdown thread [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Shutdown thread complete [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Starting shutdown thread [junit] 09/11/03 20:57:17 INFO regionserver.HRegionServer: Shutdown thread complete 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: 38 minutes 1 second [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