hbase-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: HBase-Patch #651
Date Fri, 19 Jun 2009 01:06:44 GMT
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/651/changes

Changes:

[stack] HBASE-1543 Unnecessary toString during scanning costs us some CPU

[rawson] HBASE-1541, 1488 (thrift server only)

------------------------------------------
[...truncated 26404 lines...]
    [junit] 2009-06-19 01:05:18,442 DEBUG [RegionServer:0.worker] regionserver.HRegion(961):
Finished memcache flush of ~764.0 for region tableA,,1245373452186 in 130ms, sequence id=90,
compaction requested=false
    [junit] 2009-06-19 01:05:18,443 DEBUG [RegionServer:0.worker] regionserver.Store(445):
closed columnA
    [junit] 2009-06-19 01:05:18,443 DEBUG [RegionServer:0.worker] regionserver.Store(445):
closed columnB
    [junit] 2009-06-19 01:05:18,444 INFO  [RegionServer:0.worker] regionserver.HRegion(485):
Closed tableA,,1245373452186
    [junit] 2009-06-19 01:05:19,320 INFO  [IPC Server handler 1 on 60000] master.ServerManager(439):
Received MSG_REPORT_CLOSE: tableA,,1245373452186 from vesta.apache.org,51596,1245373403912;
1 of 1
    [junit] 2009-06-19 01:05:19,320 DEBUG [HMaster] master.HMaster(463): Processing todo:
ProcessRegionClose of tableA,,1245373452186, true, reassign: false
    [junit] 2009-06-19 01:05:19,320 INFO  [HMaster] master.ProcessRegionClose$1(66): region
closed: tableA,,1245373452186
    [junit] 2009-06-19 01:05:23,329 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -2758430222893251127 lease expired
    [junit] 2009-06-19 01:05:23,631 INFO  [Thread-103] master.ServerManager$ServerMonitor(127):
1 region servers, 0 dead, average load 2.0
    [junit] 2009-06-19 01:05:23,897 INFO  [RegionManager.rootScanner] master.BaseScanner(146):
RegionManager.rootScanner scanning meta region {server: 67.195.138.9:51596, regionname: -ROOT-,,0,
startKey: <>}
    [junit] 2009-06-19 01:05:23,898 INFO  [RegionManager.metaScanner] master.BaseScanner(146):
RegionManager.metaScanner scanning meta region {server: 67.195.138.9:51596, regionname: .META.,,1,
startKey: <>}
    [junit] 2009-06-19 01:05:23,901 INFO  [RegionManager.rootScanner] master.BaseScanner(232):
RegionManager.rootScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51596, regionname:
-ROOT-,,0, startKey: <>} complete
    [junit] 2009-06-19 01:05:23,902 WARN  [IPC Server handler 3 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@52744
    [junit] 2009-06-19 01:05:23,903 INFO  [RegionManager.metaScanner] master.BaseScanner(232):
RegionManager.metaScanner scan of 1 row(s) of meta region {server: 67.195.138.9:51596, regionname:
.META.,,1, startKey: <>} complete
    [junit] 2009-06-19 01:05:23,903 INFO  [RegionManager.metaScanner] master.MetaScanner(132):
All 1 .META. region(s) scanned
    [junit] 2009-06-19 01:05:28,336 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -2289244063249532799 lease expired
    [junit] 2009-06-19 01:05:33,342 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -3310396411347608220 lease expired
    [junit] 2009-06-19 01:05:33,362 DEBUG [main] client.HBaseAdmin(403): Wake. Waiting for
all regions to be disabled from tableA
    [junit] 2009-06-19 01:05:33,363 DEBUG [main] client.HConnectionManager$TableServers(667):
Cache hit for row <> in tableName .META.: location server 67.195.138.9:51596, location
region name .META.,,1
    [junit] 2009-06-19 01:05:33,365 WARN  [IPC Server handler 2 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@bb1bc4
    [junit] 2009-06-19 01:05:33,374 WARN  [IPC Server handler 1 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@1ab6c1c
    [junit] 2009-06-19 01:05:33,375 DEBUG [main] client.HConnectionManager$TableServers(667):
Cache hit for row <> in tableName .META.: location server 67.195.138.9:51596, location
region name .META.,,1
    [junit] 2009-06-19 01:05:33,377 WARN  [IPC Server handler 4 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@28bda
    [junit] 2009-06-19 01:05:33,380 WARN  [IPC Server handler 3 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@a6faa9
    [junit] 2009-06-19 01:05:33,380 INFO  [main] client.HBaseAdmin(411): Disabled tableA
    [junit] 2009-06-19 01:05:33,381 DEBUG [main] thrift.ThriftServer$HBaseHandler(419): deleteTable:
table=tableA
    [junit] 2009-06-19 01:05:33,381 DEBUG [main] client.HConnectionManager$TableServers(667):
Cache hit for row <> in tableName .META.: location server 67.195.138.9:51596, location
region name .META.,,1
    [junit] 2009-06-19 01:05:33,383 WARN  [IPC Server handler 1 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@1dd5a3d
    [junit] 2009-06-19 01:05:33,387 WARN  [IPC Server handler 0 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@c02d83
    [junit] 2009-06-19 01:05:33,389 DEBUG [IPC Server handler 1 on 60000] regionserver.HRegion(1950):
DELETING region hdfs://localhost:55283/user/hudson/tableA/853451413
    [junit] 2009-06-19 01:05:33,417 INFO  [IPC Server handler 1 on 60000] master.HMaster(743):
deleted table: tableA
    [junit] 2009-06-19 01:05:33,419 WARN  [IPC Server handler 4 on 51596] regionserver.Store(601):
Not in setorg.apache.hadoop.hbase.regionserver.StoreScanner@1c7eb33
    [junit] 2009-06-19 01:05:33,525 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:05:33,526 INFO  [main] client.HBaseAdmin(303): Deleted tableA
    [junit] 2009-06-19 01:05:33,629 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:05:33,629 DEBUG [main] hbase.LocalHBaseCluster(252): Shutting down
HBase Cluster
    [junit] 2009-06-19 01:05:33,630 INFO  [main] master.HMaster(674): Cluster shutdown requested.
Starting to quiesce servers
    [junit] 2009-06-19 01:05:33,632 INFO  [Thread-103] hbase.Chore(82): Thread-103 exiting
    [junit] 2009-06-19 01:05:33,640 DEBUG [main] zookeeper.ZooKeeperWrapper(313): State node
deleted in ZooKeeper
    [junit] 2009-06-19 01:05:33,897 INFO  [RegionManager.rootScanner] hbase.Chore(82): RegionManager.rootScanner
exiting
    [junit] 2009-06-19 01:05:33,898 INFO  [RegionManager.metaScanner] hbase.Chore(82): RegionManager.metaScanner
exiting
    [junit] 2009-06-19 01:05:34,327 DEBUG [HMaster] master.RegionManager(592): telling root
scanner to stop
    [junit] 2009-06-19 01:05:34,327 DEBUG [HMaster] master.RegionManager(596): telling meta
scanner to stop
    [junit] 2009-06-19 01:05:34,327 DEBUG [HMaster] master.RegionManager(600): meta and root
scanners notified
    [junit] 2009-06-19 01:05:34,345 INFO  [RegionServer:0] regionserver.HRegionServer(489):
MSG_REGIONSERVER_QUIESCE
    [junit] 2009-06-19 01:05:34,345 INFO  [RegionServer:0.worker] regionserver.HRegionServer$Worker(1427):
Worker: MSG_REGIONSERVER_QUIESCE
    [junit] 2009-06-19 01:05:35,327 DEBUG [HMaster] master.RegionManager(592): telling root
scanner to stop
    [junit] 2009-06-19 01:05:35,327 DEBUG [HMaster] master.RegionManager(596): telling meta
scanner to stop
    [junit] 2009-06-19 01:05:35,328 DEBUG [HMaster] master.RegionManager(600): meta and root
scanners notified
    [junit] 2009-06-19 01:05:35,346 INFO  [IPC Server handler 2 on 60000] master.ServerManager(258):
Region server vesta.apache.org,51596,1245373403912 quiesced
    [junit] 2009-06-19 01:05:35,346 INFO  [IPC Server handler 2 on 60000] master.ServerManager(267):
All user tables quiesced. Proceeding with shutdown
    [junit] 2009-06-19 01:05:35,346 DEBUG [IPC Server handler 2 on 60000] master.RegionManager(592):
telling root scanner to stop
    [junit] 2009-06-19 01:05:35,346 DEBUG [IPC Server handler 2 on 60000] master.RegionManager(596):
telling meta scanner to stop
    [junit] 2009-06-19 01:05:35,346 DEBUG [IPC Server handler 2 on 60000] master.RegionManager(600):
meta and root scanners notified
    [junit] 2009-06-19 01:05:35,347 INFO  [RegionServer:0] regionserver.HRegionServer(489):
MSG_REGIONSERVER_STOP
    [junit] 2009-06-19 01:05:35,347 DEBUG [RegionServer:0] hbase.RegionHistorian(333): Offlined
    [junit] 2009-06-19 01:05:35,348 INFO  [RegionServer:0.logFlusher] hbase.Chore(82): RegionServer:0.logFlusher
exiting
    [junit] 2009-06-19 01:05:35,348 INFO  [RegionServer:0.compactor] regionserver.CompactSplitThread(133):
RegionServer:0.compactor exiting
    [junit] 2009-06-19 01:05:35,348 INFO  [RegionServer:0.logRoller] regionserver.LogRoller(106):
LogRoller exiting.
    [junit] 2009-06-19 01:05:35,348 INFO  [RegionServer:0.cacheFlusher] regionserver.MemcacheFlusher(167):
RegionServer:0.cacheFlusher exiting
    [junit] 2009-06-19 01:05:35,348 DEBUG [RegionServer:0] regionserver.HRegionServer(1605):
closing region .META.,,1
    [junit] 2009-06-19 01:05:35,348 INFO  [RegionServer:0.majorCompactionChecker] hbase.Chore(82):
RegionServer:0.majorCompactionChecker exiting
    [junit] 2009-06-19 01:05:35,350 DEBUG [RegionServer:0] regionserver.HRegion(436): Closing
.META.,,1: compactions & flushes disabled 
    [junit] 2009-06-19 01:05:35,350 DEBUG [RegionServer:0] regionserver.HRegion(456): waiting
for 5 scanners to finish
    [junit] 2009-06-19 01:05:36,328 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:36,345 INFO  [RegionServer:0.worker] regionserver.HRegionServer$Worker(1519):
worker thread exiting
    [junit] 2009-06-19 01:05:37,328 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:38,328 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:39,328 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:40,328 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:41,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:42,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:43,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:43,349 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -7057873500475690031 lease expired
    [junit] 2009-06-19 01:05:44,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:45,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:46,329 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:47,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:48,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:49,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:50,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:51,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:52,330 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:53,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:53,355 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -7161831297906851690 lease expired
    [junit] 2009-06-19 01:05:54,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:55,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:56,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:57,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:58,331 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:05:59,332 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:00,332 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:01,332 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:02,332 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:03,332 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:04,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:05,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:06,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:07,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:08,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:09,333 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:10,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:11,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:12,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:13,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:13,361 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -326795541236199194 lease expired
    [junit] 2009-06-19 01:06:14,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:15,334 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:16,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:17,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:18,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:19,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:20,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:21,335 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:22,336 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:23,336 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:24,337 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:25,338 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:26,338 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:27,338 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:28,339 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:29,339 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:30,340 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:31,340 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:32,341 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:33,341 INFO  [HMaster] master.ServerManager(764): Waiting on
following regionserver(s) to go down [address: 67.195.138.9:51596, startcode: 1245373403912,
load: (requests=0, regions=2, usedHeap=45, maxHeap=493)]
    [junit] 2009-06-19 01:06:33,374 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -8082634026080544383 lease expired
    [junit] 2009-06-19 01:06:33,380 INFO  [RegionServer:0.leaseChecker] regionserver.HRegionServer$ScannerListener(1915):
Scanner -3663490815845591996 lease expired
    [junit] 2009-06-19 01:06:33,381 DEBUG [RegionServer:0] regionserver.HRegion(466): Updates
disabled for region, no outstanding scanners on .META.,,1
    [junit] 2009-06-19 01:06:33,381 INFO  [RegionServer:0.leaseChecker] hbase.Leases(123):
RegionServer:0.leaseChecker closing leases
    [junit] 2009-06-19 01:06:33,381 DEBUG [RegionServer:0] regionserver.HRegion(473): No more
row locks outstanding on region .META.,,1
    [junit] 2009-06-19 01:06:33,381 INFO  [RegionServer:0.leaseChecker] hbase.Leases(130):
RegionServer:0.leaseChecker closed leases
    [junit] 2009-06-19 01:06:33,382 DEBUG [RegionServer:0] regionserver.HRegion(884): Started
memcache flush for region .META.,,1. Current region memcache size 4.8k
    [junit] 2009-06-19 01:06:33,447 DEBUG [RegionServer:0] regionserver.Store(526): Added
hdfs://localhost:55283/user/hudson/.META./1028785192/historian/218494063902918491, entries=19,
sequenceid=95, memsize=4.0k, filesize=2.2k to .META.,,1
    [junit] 2009-06-19 01:06:33,527 DEBUG [RegionServer:0] regionserver.Store(526): Added
hdfs://localhost:55283/user/hudson/.META./1028785192/info/3825367402822528303, entries=5,
sequenceid=95, memsize=810.0, filesize=576.0 to .META.,,1
    [junit] 2009-06-19 01:06:33,527 DEBUG [RegionServer:0] regionserver.HRegion(961): Finished
memcache flush of ~4.8k for region .META.,,1 in 146ms, sequence id=95, compaction requested=false
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.Store(445): closed
historian
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.Store(445): closed
info
    [junit] 2009-06-19 01:06:33,528 INFO  [RegionServer:0] regionserver.HRegion(485): Closed
.META.,,1
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.HRegionServer(1605):
closing region -ROOT-,,0
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.HRegion(436): Closing
-ROOT-,,0: compactions & flushes disabled 
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.HRegion(466): Updates
disabled for region, no outstanding scanners on -ROOT-,,0
    [junit] 2009-06-19 01:06:33,528 DEBUG [RegionServer:0] regionserver.HRegion(473): No more
row locks outstanding on region -ROOT-,,0
    [junit] 2009-06-19 01:06:33,529 DEBUG [RegionServer:0] regionserver.HRegion(884): Started
memcache flush for region -ROOT-,,0. Current region memcache size 347.0
    [junit] 2009-06-19 01:06:33,554 DEBUG [RegionServer:0] regionserver.Store(526): Added
hdfs://localhost:55283/user/hudson/-ROOT-/70236052/info/1391500223335431671, entries=2, sequenceid=96,
memsize=347.0, filesize=461.0 to -ROOT-,,0
    [junit] 2009-06-19 01:06:33,555 DEBUG [RegionServer:0] regionserver.HRegion(961): Finished
memcache flush of ~347.0 for region -ROOT-,,0 in 26ms, sequence id=96, compaction requested=false
    [junit] 2009-06-19 01:06:33,555 DEBUG [RegionServer:0] regionserver.Store(445): closed
info
    [junit] 2009-06-19 01:06:33,555 INFO  [RegionServer:0] regionserver.HRegion(485): Closed
-ROOT-,,0
    [junit] 2009-06-19 01:06:33,555 DEBUG [RegionServer:0] regionserver.HLog(456): closing
hlog writer in hdfs://localhost:55283/user/hudson/.logs/vesta.apache.org,51596,1245373403912
    [junit] 2009-06-19 01:06:33,575 INFO  [RegionServer:0] regionserver.HRegionServer(656):
telling master that region server is shutting down at: 67.195.138.9:51596
    [junit] 2009-06-19 01:06:33,577 INFO  [IPC Server handler 0 on 60000] master.ServerManager(651):
Removing server's info vesta.apache.org,51596,1245373403912
    [junit] 2009-06-19 01:06:33,577 INFO  [IPC Server handler 0 on 60000] master.RegionManager(869):
Offlining META region: {server: 67.195.138.9:51596, regionname: .META.,,1, startKey: <>}
    [junit] 2009-06-19 01:06:33,577 INFO  [IPC Server handler 0 on 60000] master.RegionManager(784):
META region removed from onlineMetaRegions
    [junit] 2009-06-19 01:06:33,577 INFO  [IPC Server handler 0 on 60000] master.ServerManager(336):
Region server vesta.apache.org,51596,1245373403912: MSG_REPORT_EXITING
    [junit] 2009-06-19 01:06:33,577 DEBUG [HMaster] hbase.RegionHistorian(333): Offlined
    [junit] 2009-06-19 01:06:33,579 INFO  [RegionServer:0] regionserver.HRegionServer(663):
stopping server at: 67.195.138.9:51596
    [junit] 2009-06-19 01:06:33,580 DEBUG [HMaster] zookeeper.ZooKeeperWrapper(609): Deleting
node: 1245373403912
    [junit] 2009-06-19 01:06:33,638 INFO  [main-EventThread] master.ServerManager$ServerExpirer(787):
vesta.apache.org,51596,1245373403912 znode expired
    [junit] 2009-06-19 01:06:33,645 WARN  [HMaster] zookeeper.ZooKeeperWrapper(613): Failed
to delete /hbase/rs znodes in ZooKeeper: org.apache.zookeeper.KeeperException$NoNodeException:
KeeperErrorCode = NoNode for /hbase/rs/1245373403912
    [junit] 2009-06-19 01:06:33,739 DEBUG [RegionServer:0] zookeeper.ZooKeeperWrapper(638):
Closed connection with ZooKeeper
    [junit] 2009-06-19 01:06:33,739 INFO  [RegionServer:0] regionserver.HRegionServer(679):
RegionServer:0 exiting
    [junit] 2009-06-19 01:06:33,754 DEBUG [HMaster] zookeeper.ZooKeeperWrapper(638): Closed
connection with ZooKeeper
    [junit] 2009-06-19 01:06:33,754 DEBUG [HMaster] zookeeper.ZooKeeperWrapper(638): Closed
connection with ZooKeeper
    [junit] 2009-06-19 01:06:33,754 INFO  [HMaster] master.HMaster(431): HMaster main thread
exiting
    [junit] 2009-06-19 01:06:34,739 INFO  [main] hbase.LocalHBaseCluster(293): Shutdown HMaster
1 region server(s)
    [junit] 2009-06-19 01:06:34,849 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:06:34,979 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:06:35,116 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:06:35,222 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:06:35,329 DEBUG [main] zookeeper.ZooKeeperWrapper(638): Closed connection
with ZooKeeper
    [junit] 2009-06-19 01:06:35,331 INFO  [main] hbase.HBaseTestCase(626): Shutting down FileSystem
    [junit] 2009-06-19 01:06:35,331 INFO  [main] hbase.HBaseTestCase(633): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-19 01:06:35,430 DEBUG [main-EventThread] client.HConnectionManager$TableServers(213):
Got ZooKeeper event, state: Disconnected, type: None, path: null
    [junit] 2009-06-19 01:06:35,434 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1642bd6]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:37261, storageID=DS-1428877900-67.195.138.9-37261-1245373402912,
infoPort=60638, ipcPort=43292):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:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] Shutting down DataNode 0
    [junit] 2009-06-19 01:06:36,537 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@12c9557]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:57031, storageID=DS-2015932871-67.195.138.9-57031-1245373402179,
infoPort=44033, ipcPort=50530):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:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2009-06-19 01:06:37,673 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@18166e5]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 198.106 sec
    [junit] 2009-06-19 01:06:37,705 INFO  [Thread-121] regionserver.HRegionServer$ShutdownThread(949):
Starting shutdown thread.
    [junit] 2009-06-19 01:06:37,706 INFO  [Thread-121] regionserver.HRegionServer$ShutdownThread(957):
Shutdown thread complete
    [junit] Running org.apache.hadoop.hbase.util.TestBase64
    [junit] 
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.134 sec
    [junit] Running org.apache.hadoop.hbase.util.TestBytes
    [junit] AAA
    [junit] CCC
    [junit] EEE
    [junit] AAA
    [junit] BBB
    [junit] CCC
    [junit] DDD
    [junit] http://A
    [junit] http://]
    [junit] http://z
    [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 0.088 sec
    [junit] Running org.apache.hadoop.hbase.util.TestKeying
    [junit] Original url http://abc:bcd@www.example.com/index.html?query=something#middle,
Transformed url r:http://abc:bcd@com.example.www/index.html?query=something#middle
    [junit] Original url file:///usr/bin/java, Transformed url file:///usr/bin/java
    [junit] Original url dns:www.powerset.com, Transformed url dns:www.powerset.com
    [junit] Original url dns://dns.powerset.com/www.powerset.com, Transformed url r:dns://com.powerset.dns/www.powerset.com
    [junit] Original url http://one.two.three/index.html, Transformed url r:http://three.two.one/index.html
    [junit] Original url https://one.two.three:9443/index.html, Transformed url r:https://three.two.one:9443/index.html
    [junit] Original url ftp://one.two.three/index.html, Transformed url r:ftp://three.two.one/index.html
    [junit] Original url filename, Transformed url filename
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.075 sec
    [junit] Running org.apache.hadoop.hbase.util.TestRootPath
    [junit] 2009-06-19 01:06:39,067 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:212)
    [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.072 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build.xml :461: Tests failed!

Total time: 41 minutes 27 seconds
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Publishing Clover coverage report...


Mime
View raw message