hadoop-common-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From hud...@lucene.zones.apache.org
Subject Build failed in Hudson: Hadoop-Nightly #362
Date Thu, 10 Jan 2008 20:32:13 GMT
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 [org.apache.hadoop.dfs.DataNode$DataXceiver@1f0cf51]
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  [org.apache.hadoop.dfs.DataNode$DataTransfer@167c5e3]
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: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.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


Mime
View raw message