See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/362/changes
------------------------------------------
[...truncated 50372 lines...]
[junit] 2008-01-10 20:26:25,068 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1486): Cancelling lease for 140.211.11.75:51816
[junit] 2008-01-10 20:26:25,068 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1351): Region server 140.211.11.75:51816: MSG_REPORT_EXITING --
lease cancelled
[junit] 2008-01-10 20:26:25,069 INFO [RegionServer:0]
hbase.HRegionServer(895): stopping server at: 140.211.11.75:51816
[junit] 2008-01-10 20:26:25,069 INFO [RegionServer:0]
hbase.HRegionServer(900): RegionServer:0 exiting
[junit] 2008-01-10 20:26:25,070 INFO [HMaster] hbase.Leases(109): HMaster
closing leases
[junit] 2008-01-10 20:26:25,071 INFO [HMaster.leaseChecker]
hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2008-01-10 20:26:25,072 INFO [HMaster] hbase.Leases(125): HMaster
closed leases
[junit] 2008-01-10 20:26:25,073 INFO [HMaster] hbase.HMaster(1141):
HMaster main thread exiting
[junit] 2008-01-10 20:26:25,888 ERROR [EMAIL PROTECTED]
dfs.DataNode$DataXceiver(883): 127.0.0.1:51731:DataXceiver:
java.io.IOException: Block blk_-9189605151479631312 is valid, and cannot be
written to.
[junit] at
org.apache.hadoop.dfs.FSDataset.writeToBlock(FSDataset.java:582)
[junit] at
org.apache.hadoop.dfs.DataNode$BlockReceiver.<init>(DataNode.java:1498)
[junit] at
org.apache.hadoop.dfs.DataNode$DataXceiver.writeBlock(DataNode.java:973)
[junit] at
org.apache.hadoop.dfs.DataNode$DataXceiver.run(DataNode.java:868)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2008-01-10 20:26:25,888 WARN [EMAIL PROTECTED]
dfs.DataNode$DataTransfer(1709): 127.0.0.1:51701:Failed to transfer
blk_-9189605151479631312 to 127.0.0.1:51731 got java.io.IOException: operation
failed at /127.0.0.1
[junit] at
org.apache.hadoop.dfs.DataNode.receiveResponse(DataNode.java:766)
[junit] at org.apache.hadoop.dfs.DataNode.access$200(DataNode.java:82)
[junit] at
org.apache.hadoop.dfs.DataNode$DataTransfer.run(DataNode.java:1705)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2008-01-10 20:26:26,077 INFO [main] hbase.LocalHBaseCluster(243):
Shutdown HMaster 1 region server(s)
[junit] 2008-01-10 20:26:26,077 INFO [main]
hbase.StaticTestEnvironment(135): Shutting down FileSystem
[junit] 2008-01-10 20:26:26,567 INFO [main]
hbase.StaticTestEnvironment(142): Shutting down Mini DFS
[junit] 2008-01-10 20:26:47,846 INFO [main] hbase.HMaster(885): Root
region dir: /hbase/hregion_70236052
[junit] 2008-01-10 20:26:47,850 INFO [main] hbase.HMaster(894): bootstrap:
creating ROOT and first META regions
[junit] 2008-01-10 20:26:48,446 INFO [main] hbase.HLog(313): new log
writer created at /hbase/hregion_70236052/log/hlog.dat.000
[junit] 2008-01-10 20:26:48,756 DEBUG [main] hbase.HStore(599): starting
-ROOT-,,0/info (70236052/info) (no reconstruction log)
[junit] 2008-01-10 20:26:48,758 DEBUG [main] hbase.HStore(627): maximum
sequence id for hstore -ROOT-,,0/info (70236052/info) is -1
[junit] 2008-01-10 20:26:48,759 DEBUG [main] hbase.HRegion(300): Next
sequence id for region -ROOT-,,0 is 0
[junit] 2008-01-10 20:26:48,761 INFO [main] hbase.HRegion(328): region
-ROOT-,,0 available
[junit] 2008-01-10 20:26:49,248 INFO [main] hbase.HLog(313): new log
writer created at /hbase/hregion_1028785192/log/hlog.dat.000
[junit] 2008-01-10 20:26:49,640 DEBUG [main] hbase.HStore(599): starting
.META.,,1/info (1028785192/info) (no reconstruction log)
[junit] 2008-01-10 20:26:49,642 DEBUG [main] hbase.HStore(627): maximum
sequence id for hstore .META.,,1/info (1028785192/info) is -1
[junit] 2008-01-10 20:26:49,643 DEBUG [main] hbase.HRegion(300): Next
sequence id for region .META.,,1 is 0
[junit] 2008-01-10 20:26:49,646 INFO [main] hbase.HRegion(328): region
.META.,,1 available
[junit] 2008-01-10 20:26:49,647 DEBUG [main] hbase.HRegion(837): Started
memcache flush for region -ROOT-,,0. Size 92.0
[junit] 2008-01-10 20:26:51,517 DEBUG [main] hbase.HStore(970): Added
70236052/info/261292484060838262 with 1 entries, sequence id 1, and size 216.0
for -ROOT-,,0/info
[junit] 2008-01-10 20:26:51,518 DEBUG [main] hbase.HRegion(943): Finished
memcache flush for region -ROOT-,,0 in 1872ms, sequenceid=1
[junit] 2008-01-10 20:26:51,519 DEBUG [main] hbase.HStore(878): closed
-ROOT-,,0/info (70236052/info)
[junit] 2008-01-10 20:26:51,519 INFO [main] hbase.HRegion(430): closed
-ROOT-,,0
[junit] 2008-01-10 20:26:51,519 DEBUG [main] hbase.HLog(399): closing log
writer in /hbase/hregion_70236052/log
[junit] 2008-01-10 20:26:52,609 DEBUG [main] hbase.HStore(878): closed
.META.,,1/info (1028785192/info)
[junit] 2008-01-10 20:26:52,610 INFO [main] hbase.HRegion(430): closed
.META.,,1
[junit] 2008-01-10 20:26:52,611 DEBUG [main] hbase.HLog(399): closing log
writer in /hbase/hregion_1028785192/log
[junit] 2008-01-10 20:26:53,765 INFO [main] hbase.HMaster(952): HMaster
initialized on 127.0.0.1:60000
[junit] 2008-01-10 20:26:53,768 DEBUG [RegionServer:0]
hbase.HRegionServer(1060): Telling master at 127.0.0.1:60000 that we are up
[junit] 2008-01-10 20:26:53,769 DEBUG [HMaster] hbase.HMaster(1185):
Started service threads
[junit] 2008-01-10 20:26:53,771 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1268): received start message from: 140.211.11.75:52444
[junit] 2008-01-10 20:26:53,771 INFO [main] shell.TestHBaseShell(161):
Running command: create table testInsertSelectDelete (testInsertSelectDelete);
[junit] 2008-01-10 20:26:53,772 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:26:53,772 DEBUG [RegionServer:0]
hbase.HRegionServer(1077): Done telling master we are up
[junit] 2008-01-10 20:26:53,773 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for .META.. Doing a
find...
[junit] 2008-01-10 20:26:53,773 DEBUG [RegionServer:0]
hbase.HRegionServer(913): Config from master: fs.default.name=localhost:52355
[junit] 2008-01-10 20:26:53,773 DEBUG [RegionServer:0]
hbase.HRegionServer(913): Config from master: hbase.rootdir=/hbase
[junit] 2008-01-10 20:26:53,774 INFO [RegionServer:0]
hbase.HRegionServer(933): Root dir: /hbase
[junit] 2008-01-10 20:26:53,774 DEBUG [main]
hbase.HConnectionManager$TableServers(581): Sleeping. Waiting for root region.
[junit] 2008-01-10 20:26:53,775 DEBUG [RegionServer:0]
hbase.HRegionServer(938): Log dir /hbase/log_140.211.11.75_1199996813767_52444
[junit] 2008-01-10 20:26:54,686 INFO [RegionServer:0] hbase.HLog(313): new
log writer created at /hbase/log_140.211.11.75_1199996813767_52444/hlog.dat.000
[junit] 2008-01-10 20:26:54,688 INFO [RegionServer:0]
hbase.HRegionServer(992): HRegionServer started at: 140.211.11.75:52444
[junit] 2008-01-10 20:26:54,689 INFO [IPC Server handler 0 on 60000]
hbase.HMaster(1863): assigning region -ROOT-,,0 to the only server
140.211.11.75:52444
[junit] 2008-01-10 20:26:54,690 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN : -ROOT-,,0
[junit] 2008-01-10 20:26:54,696 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting -ROOT-,,0/info (70236052/info) (no reconstruction
log)
[junit] 2008-01-10 20:26:54,706 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore -ROOT-,,0/info
(70236052/info) is 1
[junit] 2008-01-10 20:26:54,718 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region -ROOT-,,0 is 2
[junit] 2008-01-10 20:26:54,720 INFO [RegionServer:0.worker]
hbase.HRegion(328): region -ROOT-,,0 available
[junit] 2008-01-10 20:26:54,720 DEBUG [RegionServer:0.worker]
hbase.HLog(265): changing sequence number from 0 to 2
[junit] 2008-01-10 20:26:55,695 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN : -ROOT-,,0 from
140.211.11.75:52444
[junit] 2008-01-10 20:26:55,696 DEBUG [IPC Server handler 2 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN : -ROOT-,,0 from
140.211.11.75:52444
[junit] 2008-01-10 20:26:55,696 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(1582): 140.211.11.75:52444 serving -ROOT-,,0
[junit] 2008-01-10 20:26:55,696 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:26:55,710 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server: , startCode: -1
[junit] 2008-01-10 20:26:55,711 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(469): Current assignment of .META.,,1 is no good:
storedInfo: null, startCode: -1, storedInfo.startCode: -1, unassignedRegions:
false, pendingRegions: false
[junit] 2008-01-10 20:26:55,712 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:26:55,713 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:26:55,728 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server: , startCode: -1
[junit] 2008-01-10 20:26:55,730 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:26:56,705 INFO [IPC Server handler 3 on 60000]
hbase.HMaster(1863): assigning region .META.,,1 to the only server
140.211.11.75:52444
[junit] 2008-01-10 20:26:56,706 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN : .META.,,1
[junit] 2008-01-10 20:26:56,897 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting .META.,,1/info (1028785192/info) (no reconstruction
log)
[junit] 2008-01-10 20:26:56,900 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore .META.,,1/info
(1028785192/info) is -1
[junit] 2008-01-10 20:26:56,901 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region .META.,,1 is 0
[junit] 2008-01-10 20:26:56,902 INFO [RegionServer:0.worker]
hbase.HRegion(328): region .META.,,1 available
[junit] 2008-01-10 20:26:57,715 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN : .META.,,1 from
140.211.11.75:52444
[junit] 2008-01-10 20:26:57,715 DEBUG [IPC Server handler 1 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN : .META.,,1 from
140.211.11.75:52444
[junit] 2008-01-10 20:26:57,716 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1582): 140.211.11.75:52444 serving .META.,,1
[junit] 2008-01-10 20:26:57,716 DEBUG [HMaster] hbase.HMaster(1057): Main
processing loop: PendingOpenOperation from 140.211.11.75:52444
[junit] 2008-01-10 20:26:57,716 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2463): regionname: .META.,,1, startKey: <>,
encodedName(1028785192) tableDesc: {name: .META., families: {info:={name: info,
max versions: 1, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}} open on 140.211.11.75:52444
[junit] 2008-01-10 20:26:57,717 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2477): updating row .META.,,1 in table
-ROOT-,,0 with startcode 1199996813767 and server 140.211.11.75:52444
[junit] 2008-01-10 20:26:57,719 DEBUG [HMaster]
hbase.HMaster$ProcessRegionOpen(2495): Adding {regionname: .META.,,1, startKey:
<>, server: 140.211.11.75:52444} to regions to scan
[junit] 2008-01-10 20:26:57,719 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:26:57,721 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:26:57,722 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:26:57,723 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:26:57,724 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(768): all meta regions scanned
[junit] 2008-01-10 20:26:58,784 DEBUG [main]
hbase.HConnectionManager$TableServers(585): Wake. Retry finding root region.
[junit] 2008-01-10 20:26:58,800 DEBUG [main]
hbase.HConnectionManager$TableServers(728): Found 1 region(s) for .META. at
address: 140.211.11.75:52444, regioninfo: regionname: -ROOT-,,0, startKey: <>,
encodedName(70236052) tableDesc: {name: -ROOT-, families: {info:={name: info,
max versions: 1, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}}
[junit] 2008-01-10 20:26:59,329 INFO [IPC Server handler 2 on 60000]
hbase.HLog(313): new log writer created at
/hbase/hregion_354025237/log/hlog.dat.000
[junit] 2008-01-10 20:26:59,891 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(599): starting
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete) (no reconstruction log)
[junit] 2008-01-10 20:26:59,894 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(627): maximum sequence id for hstore
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete) is -1
[junit] 2008-01-10 20:26:59,895 DEBUG [IPC Server handler 2 on 60000]
hbase.HRegion(300): Next sequence id for region
testInsertSelectDelete,,1199996818803 is 0
[junit] 2008-01-10 20:26:59,897 INFO [IPC Server handler 2 on 60000]
hbase.HRegion(328): region testInsertSelectDelete,,1199996818803 available
[junit] 2008-01-10 20:26:59,899 DEBUG [IPC Server handler 2 on 60000]
hbase.HStore(878): closed
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete)
[junit] 2008-01-10 20:26:59,899 INFO [IPC Server handler 2 on 60000]
hbase.HRegion(430): closed testInsertSelectDelete,,1199996818803
[junit] 2008-01-10 20:26:59,900 DEBUG [IPC Server handler 2 on 60000]
hbase.HLog(399): closing log writer in /hbase/hregion_354025237/log
[junit] 2008-01-10 20:27:00,449 INFO [IPC Server handler 2 on 60000]
hbase.HMaster(2552): created table testInsertSelectDelete
[junit] 2008-01-10 20:27:00,450 DEBUG [main]
hbase.HConnectionManager$TableServers(308): No servers for
testInsertSelectDelete. Doing a find...
[junit] 2008-01-10 20:27:00,454 DEBUG [main]
hbase.HConnectionManager$TableServers(778): no server address for regionname:
testInsertSelectDelete,,1199996818803, startKey: <>, encodedName(354025237)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}
[junit] 2008-01-10 20:27:00,455 DEBUG [main]
hbase.HConnectionManager$TableServers(676): Sleeping. Table
testInsertSelectDelete not currently being served.
[junit] 2008-01-10 20:27:00,744 INFO [IPC Server handler 1 on 60000]
hbase.HMaster(1863): assigning region testInsertSelectDelete,,1199996818803 to
the only server 140.211.11.75:52444
[junit] 2008-01-10 20:27:00,745 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGION_OPEN :
testInsertSelectDelete,,1199996818803
[junit] 2008-01-10 20:27:00,750 DEBUG [RegionServer:0.worker]
hbase.HStore(599): starting
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete) (no reconstruction log)
[junit] 2008-01-10 20:27:00,752 DEBUG [RegionServer:0.worker]
hbase.HStore(627): maximum sequence id for hstore
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete) is -1
[junit] 2008-01-10 20:27:00,753 DEBUG [RegionServer:0.worker]
hbase.HRegion(300): Next sequence id for region
testInsertSelectDelete,,1199996818803 is 0
[junit] 2008-01-10 20:27:00,754 INFO [RegionServer:0.worker]
hbase.HRegion(328): region testInsertSelectDelete,,1199996818803 available
[junit] 2008-01-10 20:27:01,755 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_PROCESS_OPEN :
testInsertSelectDelete,,1199996818803 from 140.211.11.75:52444
[junit] 2008-01-10 20:27:01,755 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1521): Received MSG_REPORT_OPEN :
testInsertSelectDelete,,1199996818803 from 140.211.11.75:52444
[junit] 2008-01-10 20:27:01,755 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1582): 140.211.11.75:52444 serving
testInsertSelectDelete,,1199996818803
[junit] 2008-01-10 20:27:01,756 DEBUG [HMaster] hbase.HMaster(1057): Main
processing loop: PendingOpenOperation from 140.211.11.75:52444
[junit] 2008-01-10 20:27:01,756 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2463): regionname:
testInsertSelectDelete,,1199996818803, startKey: <>, encodedName(354025237)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}} open on 140.211.11.75:52444
[junit] 2008-01-10 20:27:01,757 DEBUG [HMaster]
hbase.HMaster$RegionServerOperation(1912): numberOfMetaRegions: 1,
onlineMetaRegions.size(): 1
[junit] 2008-01-10 20:27:01,757 INFO [HMaster]
hbase.HMaster$ProcessRegionOpen(2477): updating row
testInsertSelectDelete,,1199996818803 in table .META.,,1 with startcode
1199996813767 and server 140.211.11.75:52444
[junit] 2008-01-10 20:27:04,723 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region -ROOT-,,0. Size 92.0
[junit] 2008-01-10 20:27:05,463 DEBUG [main]
hbase.HConnectionManager$TableServers(685): Wake. Retry finding table
testInsertSelectDelete
[junit] 2008-01-10 20:27:05,467 DEBUG [main]
hbase.HConnectionManager$TableServers(728): Found 1 region(s) for
testInsertSelectDelete at address: 140.211.11.75:52444, regioninfo: regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}
[junit] 2008-01-10 20:27:05,468 INFO [main] shell.TestHBaseShell(172):
STDOUT: Creating table... Please wait.
[junit] 2008-01-10 20:27:05,474 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ('testInsertSelectDelete') where row='testInsertSelectDelete';
[junit] 2008-01-10 20:27:05,475 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:27:05,487 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-10 20:27:05,487 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ('testInsertSelectDelete') where row="testInsertSelectDelete";
[junit] 2008-01-10 20:27:05,488 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:27:05,492 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-10 20:27:05,493 INFO [main] shell.TestHBaseShell(161):
Running command: insert into testInsertSelectDelete (testInsertSelectDelete)
values ("testInsertSelectDelete") where row="testInsertSelectDelete";
[junit] 2008-01-10 20:27:05,493 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:27:05,498 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-10 20:27:05,498 INFO [main] shell.TestHBaseShell(161):
Running command: select "testInsertSelectDelete" from "testInsertSelectDelete"
where row="testInsertSelectDelete";
[junit] 2008-01-10 20:27:05,498 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:27:05,663 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-10 20:27:05,664 INFO [main] shell.TestHBaseShell(161):
Running command: delete "testInsertSelectDelete:" from "testInsertSelectDelete"
where row="testInsertSelectDelete";
[junit] 2008-01-10 20:27:05,664 DEBUG [main]
shell.TableFormatterFactory(65): Table formatter class:
org.apache.hadoop.hbase.shell.formatter.AsciiTableFormatter
[junit] 2008-01-10 20:27:05,669 INFO [main] shell.TestHBaseShell(172):
STDOUT:
[junit] 2008-01-10 20:27:05,670 DEBUG [main] hbase.LocalHBaseCluster(214):
Shutting down HBase Cluster
[junit] 2008-01-10 20:27:05,670 INFO [main] hbase.HMaster(2531): Cluster
shutdown requested. Starting to quiesce servers
[junit] 2008-01-10 20:27:05,723 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(211): HMaster.rootScanner scanning meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:27:05,735 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(247): HMaster.rootScanner regioninfo: {regionname:
.META.,,1, startKey: <>, encodedName(1028785192) tableDesc: {name: .META.,
families: {info:={name: info, max versions: 1, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}}, server: , startCode: -1
[junit] 2008-01-10 20:27:05,736 DEBUG [HMaster.rootScanner]
hbase.HMaster$BaseScanner(469): Current assignment of .META.,,1 is no good:
storedInfo: null, startCode: -1, storedInfo.startCode: -1, unassignedRegions:
false, pendingRegions: false
[junit] 2008-01-10 20:27:05,737 INFO [HMaster.rootScanner]
hbase.HMaster$BaseScanner(293): HMaster.rootScanner scan of meta region
{regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:27:05,794 INFO [RegionServer:0]
hbase.HRegionServer(774): Got quiesce server message
[junit] 2008-01-10 20:27:05,795 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1154): MSG_REGIONSERVER_QUIESCE :
[junit] 2008-01-10 20:27:05,795 DEBUG [RegionServer:0.worker]
hbase.HRegionServer(1296): closing region testInsertSelectDelete,,1199996818803
[junit] 2008-01-10 20:27:05,795 DEBUG [RegionServer:0.worker]
hbase.HRegion(837): Started memcache flush for region
testInsertSelectDelete,,1199996818803. Size 294.0
[junit] 2008-01-10 20:27:07,723 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(211): HMaster.metaScanner scanning meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444}
[junit] 2008-01-10 20:27:07,727 DEBUG [HMaster.metaScanner]
hbase.HMaster$BaseScanner(247): HMaster.metaScanner regioninfo: {regionname:
testInsertSelectDelete,,1199996818803, startKey: <>, encodedName(354025237)
tableDesc: {name: testInsertSelectDelete, families:
{testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3,
compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}}}, server: 140.211.11.75:52444, startCode: 1199996813767
[junit] 2008-01-10 20:27:07,729 INFO [HMaster.metaScanner]
hbase.HMaster$BaseScanner(293): HMaster.metaScanner scan of meta region
{regionname: .META.,,1, startKey: <>, server: 140.211.11.75:52444} complete
[junit] 2008-01-10 20:27:07,729 INFO [HMaster.metaScanner]
hbase.HMaster$MetaScanner(768): all meta regions scanned
[junit] 2008-01-10 20:27:08,985 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 70236052/info/1093353082546054370 with 2 entries,
sequence id 7, and size 230.0 for -ROOT-,,0/info
[junit] 2008-01-10 20:27:08,986 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region -ROOT-,,0 in 4263ms,
sequenceid=7
[junit] 2008-01-10 20:27:08,986 DEBUG [RegionServer:0.compactor]
hbase.HStore(994): compaction for HStore -ROOT-,,0/info not needed.
[junit] 2008-01-10 20:27:08,986 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(837): Started memcache flush for region .META.,,1. Size 330.0
[junit] 2008-01-10 20:27:08,987 DEBUG [RegionServer:0.compactor]
hbase.HRegion(707): region -ROOT-,,0 does not need compaction
[junit] 2008-01-10 20:27:11,823 INFO [HMaster.leaseChecker]
hbase.Leases$Lease(342): HMaster.leaseChecker lease expired
1438883532/1438883532
[junit] 2008-01-10 20:27:11,823 INFO [HMaster.leaseChecker]
hbase.HMaster$ServerExpirer(3152): 140.211.11.75:52444 lease expired
[junit] 2008-01-10 20:27:11,853 INFO [IPC Server handler 4 on 60000]
hbase.HMaster(1385): All user tables quiesced. Proceeding with shutdown
[junit] 2008-01-10 20:27:11,854 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1211): telling root scanner to stop
[junit] 2008-01-10 20:27:11,854 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1219): telling meta scanner to stop
[junit] 2008-01-10 20:27:11,854 INFO [HMaster.rootScanner]
hbase.Chore(66): HMaster.rootScanner exiting
[junit] 2008-01-10 20:27:11,855 INFO [HMaster.metaScanner]
hbase.Chore(66): HMaster.metaScanner exiting
[junit] 2008-01-10 20:27:11,854 DEBUG [IPC Server handler 4 on 60000]
hbase.HMaster(1227): meta and root scanners notified
[junit] 2008-01-10 20:27:11,856 INFO [RegionServer:0]
hbase.HRegionServer(768): Got regionserver stop message
[junit] 2008-01-10 20:27:11,856 INFO [RegionServer:0] hbase.Leases(109):
RegionServer:0 closing leases
[junit] 2008-01-10 20:27:11,856 INFO [RegionServer:0.leaseChecker]
hbase.Chore(66): RegionServer:0.leaseChecker exiting
[junit] 2008-01-10 20:27:11,857 INFO [RegionServer:0] hbase.Leases(125):
RegionServer:0 closed leases
[junit] 2008-01-10 20:27:11,872 DEBUG [HMaster] hbase.HMaster(1211):
telling root scanner to stop
[junit] 2008-01-10 20:27:11,872 DEBUG [HMaster] hbase.HMaster(1219):
telling meta scanner to stop
[junit] 2008-01-10 20:27:11,873 DEBUG [HMaster] hbase.HMaster(1227): meta
and root scanners notified
[junit] 2008-01-10 20:27:11,873 INFO [HMaster] hbase.Leases(109): HMaster
closing leases
[junit] 2008-01-10 20:27:11,878 INFO [HMaster.leaseChecker]
hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2008-01-10 20:27:11,878 INFO [HMaster] hbase.Leases(125): HMaster
closed leases
[junit] 2008-01-10 20:27:11,878 INFO [HMaster] hbase.HMaster(1141):
HMaster main thread exiting
[junit] 2008-01-10 20:27:12,012 INFO [RegionServer:0.compactor]
hbase.HRegionServer$Compactor(425): RegionServer:0.compactor exiting
[junit] 2008-01-10 20:27:12,862 INFO [RegionServer:0.splitter]
hbase.HRegionServer$Splitter(302): RegionServer:0.splitter exiting
[junit] 2008-01-10 20:27:16,245 DEBUG [RegionServer:0.worker]
hbase.HStore(970): Added 354025237/testInsertSelectDelete/399510435279141298
with 3 entries, sequence id 12, and size 371.0 for
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
[junit] 2008-01-10 20:27:16,245 DEBUG [RegionServer:0.worker]
hbase.HRegion(943): Finished memcache flush for region
testInsertSelectDelete,,1199996818803 in 10450ms, sequenceid=12
[junit] 2008-01-10 20:27:16,246 DEBUG [RegionServer:0.worker]
hbase.HStore(878): closed
testInsertSelectDelete,,1199996818803/testInsertSelectDelete
(354025237/testInsertSelectDelete)
[junit] 2008-01-10 20:27:16,246 INFO [RegionServer:0.worker]
hbase.HRegion(430): closed testInsertSelectDelete,,1199996818803
[junit] 2008-01-10 20:27:16,246 INFO [RegionServer:0.worker]
hbase.HRegionServer$Worker(1203): worker thread exiting
[junit] 2008-01-10 20:27:19,810 DEBUG [RegionServer:0.cacheFlusher]
hbase.HStore(970): Added 1028785192/info/4661198600410606411 with 3 entries,
sequence id 13, and size 482.0 for .META.,,1/info
[junit] 2008-01-10 20:27:19,811 DEBUG [RegionServer:0.cacheFlusher]
hbase.HRegion(943): Finished memcache flush for region .META.,,1 in 10825ms,
sequenceid=13
[junit] 2008-01-10 20:27:19,811 INFO [RegionServer:0.cacheFlusher]
hbase.HRegionServer$Flusher(535): RegionServer:0.cacheFlusher exiting
[junit] 2008-01-10 20:27:19,812 DEBUG [RegionServer:0]
hbase.HRegionServer(1262): closing region -ROOT-,,0
[junit] 2008-01-10 20:27:19,812 DEBUG [RegionServer:0] hbase.HStore(878):
closed -ROOT-,,0/info (70236052/info)
[junit] 2008-01-10 20:27:19,813 INFO [RegionServer:0] hbase.HRegion(430):
closed -ROOT-,,0
[junit] 2008-01-10 20:27:19,813 DEBUG [RegionServer:0]
hbase.HRegionServer(1262): closing region .META.,,1
[junit] 2008-01-10 20:27:19,813 DEBUG [RegionServer:0] hbase.HStore(878):
closed .META.,,1/info (1028785192/info)
[junit] 2008-01-10 20:27:19,813 INFO [RegionServer:0] hbase.HRegion(430):
closed .META.,,1
[junit] 2008-01-10 20:27:19,813 DEBUG [RegionServer:0] hbase.HLog(399):
closing log writer in /hbase/log_140.211.11.75_1199996813767_52444
[junit] 2008-01-10 20:27:20,941 INFO [RegionServer:0]
hbase.HRegionServer(888): telling master that region server is shutting down
at: 140.211.11.75:52444
[junit] 2008-01-10 20:27:31,046 WARN [RegionServer:0]
hbase.HRegionServer(892): Failed to send exiting message to master:
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at
java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:520)
[junit] at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:159)
[junit] at org.apache.hadoop.ipc.Client.getConnection(Client.java:575)
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:498)
[junit] at
org.apache.hadoop.hbase.ipc.HbaseRPC$Invoker.invoke(HbaseRPC.java:210)
[junit] at $Proxy6.regionServerReport(Unknown Source)
[junit] at
org.apache.hadoop.hbase.HRegionServer.run(HRegionServer.java:890)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit] 2008-01-10 20:27:31,048 INFO [RegionServer:0]
hbase.HRegionServer(895): stopping server at: 140.211.11.75:52444
[junit] 2008-01-10 20:27:31,048 INFO [RegionServer:0]
hbase.HRegionServer(900): RegionServer:0 exiting
[junit] 2008-01-10 20:27:31,049 INFO [main] hbase.LocalHBaseCluster(243):
Shutdown HMaster 1 region server(s)
[junit] 2008-01-10 20:27:31,049 INFO [main]
hbase.StaticTestEnvironment(135): Shutting down FileSystem
[junit] 2008-01-10 20:27:31,792 INFO [main]
hbase.StaticTestEnvironment(142): Shutting down Mini DFS
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 163.468 sec
[junit] 2008-01-10 20:27:37,194 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2008-01-10 20:27:37,195 INFO [main]
hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
[junit] 2008-01-10 20:27:37,195 INFO [main]
hbase.HRegionServer$ShutdownThread(151): Starting shutdown thread.
[junit] 2008-01-10 20:27:37,195 INFO [main]
hbase.HRegionServer$ShutdownThread(156): Shutdown thread complete
[junit] Running org.apache.hadoop.hbase.util.TestBase64
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.288 sec
[junit] Running org.apache.hadoop.hbase.util.TestKeying
[junit] Original url http://abc:[EMAIL
PROTECTED]/index.html?query=something#middle, Transformed url
r:http://abc:[EMAIL PROTECTED]/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.091 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.168 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml
:516: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build.xml
:31: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build-contrib.xml
:206: Tests failed!
Total time: 180 minutes 46 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
