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 #332
Date Fri, 14 Dec 2007 12:52:26 GMT
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/332/changes

Changes:

[dhruba] Modified CHANGES.txt to indicate that HADOOP-2158 is part of the
0.15.2 release.

[jimk] HADOOP-2418 Fix assertion failures in TestTableMapReduce, TestTableIndex, and TestTableJoinMapReduce

[jimk] HADOOP-2417 Fix critical shutdown problem introduced by HADOOP-2338

[stack] HADOOP-2351 If select command returns no result, it doesn't need to show
the header information

------------------------------------------
[...truncated 48296 lines...]
    [junit] 2007-12-14 12:51:12,724 DEBUG [main] hbase.HConnectionManager$TableServers(782):
no server address for regionname: r2,,1197636672235, startKey: <>, tableDesc: {name:
r2, families: {d:={name: d, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}}}
    [junit] 2007-12-14 12:51:12,727 DEBUG [main] hbase.HConnectionManager$TableServers(680):
Sleeping. Table r2 not currently being served.
    [junit] 2007-12-14 12:51:12,990 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(1845):
assigning region r2,,1197636672235 to the only server 140.211.11.75:59481
    [junit] 2007-12-14 12:51:12,993 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1124):
MSG_REGION_OPEN : r2,,1197636672235
    [junit] 2007-12-14 12:51:13,001 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
-14954156/d (no reconstruction log)
    [junit] 2007-12-14 12:51:13,006 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore -14954156/d is -1
    [junit] 2007-12-14 12:51:13,014 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
-14954156/e (no reconstruction log)
    [junit] 2007-12-14 12:51:13,031 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore -14954156/e is -1
    [junit] 2007-12-14 12:51:13,033 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next
sequence id for region r2,,1197636672235 is 0
    [junit] 2007-12-14 12:51:13,036 INFO  [RegionServer:0.worker] hbase.HRegion(328): region
r2,,1197636672235 available
    [junit] 2007-12-14 12:51:13,959 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region -ROOT-,,0. Size 92.0
    [junit] 2007-12-14 12:51:14,000 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1503):
Received MSG_REPORT_PROCESS_OPEN : r2,,1197636672235 from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:14,001 DEBUG [IPC Server handler 1 on 60000] hbase.HMaster(1503):
Received MSG_REPORT_OPEN : r2,,1197636672235 from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:14,002 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(1564):
140.211.11.75:59481 serving r2,,1197636672235
    [junit] 2007-12-14 12:51:14,002 DEBUG [HMaster] hbase.HMaster(1049): Main processing loop:
PendingOpenOperation from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:14,003 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2445):
regionname: r2,,1197636672235, startKey: <>, tableDesc: {name: r2, families: {d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, e:={name: e, max versions: 3, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}} open on 140.211.11.75:59481
    [junit] 2007-12-14 12:51:14,004 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1894):
numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2007-12-14 12:51:14,005 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2459):
updating row r2,,1197636672235 in table .META.,,1 with startcode 1197636663827 and server
140.211.11.75:59481
    [junit] 2007-12-14 12:51:14,443 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -70236052/info/1849186245521019441 with sequence id 23 and size 230.0
    [junit] 2007-12-14 12:51:14,444 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region -ROOT-,,0 in 485ms, sequenceid=23
    [junit] 2007-12-14 12:51:14,445 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore -ROOT-,,0/info not needed.
    [junit] 2007-12-14 12:51:14,446 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
-ROOT-,,0 does not need compaction
    [junit] 2007-12-14 12:51:15,020 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211):
HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:15,058 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(247):
HMaster.rootScanner regioninfo: {regionname: .META.,,1, startKey: <>, tableDesc: {name:
.META., families: {info:={name: info, max versions: 1, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:15,063 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293):
HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:15,958 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region .META.,,1. Size 472.0
    [junit] 2007-12-14 12:51:16,840 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1028785192/info/1898041545991170411 with sequence id 26 and size 666.0
    [junit] 2007-12-14 12:51:16,841 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region .META.,,1 in 883ms, sequenceid=26
    [junit] 2007-12-14 12:51:16,842 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore .META.,,1/info not needed.
    [junit] 2007-12-14 12:51:16,842 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
.META.,,1 does not need compaction
    [junit] 2007-12-14 12:51:16,968 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:16,989 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1197636663854, startKey: <>, tableDesc:
{name: r1, families: {a:={name: a, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}, c:={name: c, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:16,992 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1197636672235, startKey: <>, tableDesc:
{name: r2, families: {d:={name: d, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481,
startCode: 1197636663827
    [junit] 2007-12-14 12:51:16,995 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:16,996 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(761):
all meta regions scanned
    [junit] 2007-12-14 12:51:17,738 DEBUG [main] hbase.HConnectionManager$TableServers(689):
Wake. Retry finding table r2
    [junit] 2007-12-14 12:51:17,757 DEBUG [main] hbase.HConnectionManager$TableServers(732):
Found 1 region(s) for r2 at address: 140.211.11.75:59481, regioninfo: regionname: .META.,,1,
startKey: <>, tableDesc: {name: .META., families: {info:={name: info, max versions:
1, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
    [junit] 2007-12-14 12:51:17,791 INFO  [IPC Server handler 3 on 60000] hbase.HLog(302):
new log writer created at /hbase/hregion_1044139673/log/hlog.dat.000
    [junit] 2007-12-14 12:51:17,799 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(600):
starting 1044139673/a (no reconstruction log)
    [junit] 2007-12-14 12:51:17,801 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(627):
maximum sequence id for hstore 1044139673/a is -1
    [junit] 2007-12-14 12:51:17,809 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(600):
starting 1044139673/b (no reconstruction log)
    [junit] 2007-12-14 12:51:17,812 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(627):
maximum sequence id for hstore 1044139673/b is -1
    [junit] 2007-12-14 12:51:17,820 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(600):
starting 1044139673/c (no reconstruction log)
    [junit] 2007-12-14 12:51:17,824 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(627):
maximum sequence id for hstore 1044139673/c is -1
    [junit] 2007-12-14 12:51:17,833 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(600):
starting 1044139673/d (no reconstruction log)
    [junit] 2007-12-14 12:51:17,836 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(627):
maximum sequence id for hstore 1044139673/d is -1
    [junit] 2007-12-14 12:51:17,844 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(600):
starting 1044139673/e (no reconstruction log)
    [junit] 2007-12-14 12:51:17,847 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(627):
maximum sequence id for hstore 1044139673/e is -1
    [junit] 2007-12-14 12:51:17,850 DEBUG [IPC Server handler 3 on 60000] hbase.HRegion(300):
Next sequence id for region result_table,,1197636677764 is 0
    [junit] 2007-12-14 12:51:17,853 INFO  [IPC Server handler 3 on 60000] hbase.HRegion(328):
region result_table,,1197636677764 available
    [junit] 2007-12-14 12:51:17,855 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(874):
closed 1044139673/e
    [junit] 2007-12-14 12:51:17,856 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(874):
closed 1044139673/d
    [junit] 2007-12-14 12:51:17,857 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(874):
closed 1044139673/a
    [junit] 2007-12-14 12:51:17,858 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(874):
closed 1044139673/b
    [junit] 2007-12-14 12:51:17,859 DEBUG [IPC Server handler 3 on 60000] hbase.HStore(874):
closed 1044139673/c
    [junit] 2007-12-14 12:51:17,859 INFO  [IPC Server handler 3 on 60000] hbase.HRegion(430):
closed result_table,,1197636677764
    [junit] 2007-12-14 12:51:17,860 DEBUG [IPC Server handler 3 on 60000] hbase.HLog(388):
closing log writer in /hbase/hregion_1044139673/log
    [junit] 2007-12-14 12:51:17,873 INFO  [IPC Server handler 3 on 60000] hbase.HMaster(2534):
created table result_table
    [junit] 2007-12-14 12:51:17,874 DEBUG [main] hbase.HConnectionManager$TableServers(308):
No servers for result_table. Doing a find...
    [junit] 2007-12-14 12:51:17,893 DEBUG [main] hbase.HConnectionManager$TableServers(782):
no server address for regionname: result_table,,1197636677764, startKey: <>, tableDesc:
{name: result_table, families: {a:={name: a, max versions: 3, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, c:={name: c, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name:
d, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, e:={name: e, max versions: 3, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}}}
    [junit] 2007-12-14 12:51:17,895 DEBUG [main] hbase.HConnectionManager$TableServers(680):
Sleeping. Table result_table not currently being served.
    [junit] 2007-12-14 12:51:17,988 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region r1,,1197636663854. Size 310.0
    [junit] 2007-12-14 12:51:18,039 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(1845):
assigning region result_table,,1197636677764 to the only server 140.211.11.75:59481
    [junit] 2007-12-14 12:51:18,041 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1124):
MSG_REGION_OPEN : result_table,,1197636677764
    [junit] 2007-12-14 12:51:18,047 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
1044139673/a (no reconstruction log)
    [junit] 2007-12-14 12:51:18,051 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore 1044139673/a is -1
    [junit] 2007-12-14 12:51:18,059 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
1044139673/b (no reconstruction log)
    [junit] 2007-12-14 12:51:18,063 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore 1044139673/b is -1
    [junit] 2007-12-14 12:51:18,069 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
1044139673/c (no reconstruction log)
    [junit] 2007-12-14 12:51:18,075 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore 1044139673/c is -1
    [junit] 2007-12-14 12:51:18,092 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
1044139673/d (no reconstruction log)
    [junit] 2007-12-14 12:51:18,097 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore 1044139673/d is -1
    [junit] 2007-12-14 12:51:18,108 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
1044139673/e (no reconstruction log)
    [junit] 2007-12-14 12:51:18,113 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum
sequence id for hstore 1044139673/e is -1
    [junit] 2007-12-14 12:51:18,115 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next
sequence id for region result_table,,1197636677764 is 0
    [junit] 2007-12-14 12:51:18,117 INFO  [RegionServer:0.worker] hbase.HRegion(328): region
result_table,,1197636677764 available
    [junit] 2007-12-14 12:51:18,911 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -307698310/a/2722908792801866272 with sequence id 34 and size 270.0
    [junit] 2007-12-14 12:51:19,074 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -307698310/b/1081397778925511552 with sequence id 34 and size 270.0
    [junit] 2007-12-14 12:51:19,081 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1503):
Received MSG_REPORT_PROCESS_OPEN : result_table,,1197636677764 from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:19,081 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1503):
Received MSG_REPORT_OPEN : result_table,,1197636677764 from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:19,082 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1564):
140.211.11.75:59481 serving result_table,,1197636677764
    [junit] 2007-12-14 12:51:19,082 DEBUG [HMaster] hbase.HMaster(1049): Main processing loop:
PendingOpenOperation from 140.211.11.75:59481
    [junit] 2007-12-14 12:51:19,083 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2445):
regionname: result_table,,1197636677764, startKey: <>, tableDesc: {name: result_table,
families: {a:={name: a, max versions: 3, compression: NONE, in memory: false, max length:
2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression: NONE, in memory:
false, max length: 2147483647, bloom filter: none}, c:={name: c, max versions: 3, compression:
NONE, in memory: false, max length: 2147483647, bloom filter: none}, d:={name: d, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}, e:={name:
e, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}}} open on 140.211.11.75:59481
    [junit] 2007-12-14 12:51:19,083 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1894):
numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2007-12-14 12:51:19,083 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2459):
updating row result_table,,1197636677764 in table .META.,,1 with startcode 1197636663827 and
server 140.211.11.75:59481
    [junit] 2007-12-14 12:51:19,584 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -307698310/c/2410404747330952739 with sequence id 34 and size 310.0
    [junit] 2007-12-14 12:51:19,585 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region r1,,1197636663854 in 1597ms, sequenceid=34
    [junit] 2007-12-14 12:51:19,585 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore r1,,1197636663854/a not needed.
    [junit] 2007-12-14 12:51:19,586 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore r1,,1197636663854/b not needed.
    [junit] 2007-12-14 12:51:19,586 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore r1,,1197636663854/c not needed.
    [junit] 2007-12-14 12:51:19,586 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
r1,,1197636663854 does not need compaction
    [junit] 2007-12-14 12:51:22,897 DEBUG [main] hbase.HConnectionManager$TableServers(689):
Wake. Retry finding table result_table
    [junit] 2007-12-14 12:51:22,919 DEBUG [main] hbase.HConnectionManager$TableServers(732):
Found 1 region(s) for result_table at address: 140.211.11.75:59481, regioninfo: regionname:
.META.,,1, startKey: <>, tableDesc: {name: .META., families: {info:={name: info, max
versions: 1, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
    [junit] 2007-12-14 12:51:23,037 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region r2,,1197636672235. Size 132.0
    [junit] 2007-12-14 12:51:23,090 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -14954156/e/125792422803781023 with sequence id 37 and size 218.0
    [junit] 2007-12-14 12:51:23,151 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added -14954156/d/1108922815163356333 with sequence id 37 and size 218.0
    [junit] 2007-12-14 12:51:23,152 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region r2,,1197636672235 in 116ms, sequenceid=37
    [junit] 2007-12-14 12:51:23,152 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore r2,,1197636672235/e not needed.
    [junit] 2007-12-14 12:51:23,153 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore r2,,1197636672235/d not needed.
    [junit] 2007-12-14 12:51:23,153 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
r2,,1197636672235 does not need compaction
    [junit] 2007-12-14 12:51:23,291 WARN  [IPC Server handler 0 on 59435] dfs.FSDirectory(400):
DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system
because it does not exist
    [junit] 2007-12-14 12:51:25,069 WARN  [IPC Server handler 3 on 59435] dfs.FSDirectory(400):
DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system/job_local_1
because it does not exist
    [junit] 2007-12-14 12:51:25,072 WARN  [main] mapred.JobClient(496): No job jar file set.
 User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
    [junit] 2007-12-14 12:51:25,096 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211):
HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:25,176 DEBUG [main] hbase.HConnectionManager$TableServers(308):
No servers for r1. Doing a find...
    [junit] 2007-12-14 12:51:25,321 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(247):
HMaster.rootScanner regioninfo: {regionname: .META.,,1, startKey: <>, tableDesc: {name:
.META., families: {info:={name: info, max versions: 1, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:25,323 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293):
HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:25,327 DEBUG [main] hbase.HConnectionManager$TableServers(763):
Found 1 servers for table r1
    [junit] 2007-12-14 12:51:25,373 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2007-12-14 12:51:25,536 DEBUG [Thread-186] mapred.TableInputFormat(175): split:
0->r1,,
    [junit] 2007-12-14 12:51:25,711 DEBUG [Thread-186] hbase.HConnectionManager$TableServers(308):
No servers for r2. Doing a find...
    [junit] 2007-12-14 12:51:25,725 DEBUG [Thread-186] hbase.HConnectionManager$TableServers(763):
Found 1 servers for table r2
    [junit] 2007-12-14 12:51:26,953 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region .META.,,1. Size 331.0
    [junit] 2007-12-14 12:51:27,078 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:27,905 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1197636663854, startKey: <>, tableDesc:
{name: r1, families: {a:={name: a, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}, c:={name: c, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:27,908 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1197636672235, startKey: <>, tableDesc:
{name: r2, families: {d:={name: d, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481,
startCode: 1197636663827
    [junit] 2007-12-14 12:51:27,911 ERROR [expireTrackers] mapred.JobTracker$ExpireTrackers(309):
Tracker Expiry Thread got exception: java.lang.InterruptedException: sleep interrupted
    [junit] 	at java.lang.Thread.sleep(Native Method)
    [junit] 	at org.apache.hadoop.mapred.JobTracker$ExpireTrackers.run(JobTracker.java:264)
    [junit] 	at java.lang.Thread.run(Thread.java:595)

    [junit] 2007-12-14 12:51:27,913 WARN  [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2035):
Task Commit Thread exiting, got interrupted: java.lang.InterruptedException
    [junit] 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1815)
    [junit] 	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1850)
    [junit] 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:359)
    [junit] 	at org.apache.hadoop.mapred.JobTracker$TaskCommitQueue.run(JobTracker.java:1937)

    [junit] 2007-12-14 12:51:27,916 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:27,916 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(761):
all meta regions scanned
    [junit] 2007-12-14 12:51:27,967 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1028785192/info/4422655169124344552 with sequence id 53 and size 483.0
    [junit] 2007-12-14 12:51:27,968 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region .META.,,1 in 1015ms, sequenceid=53
    [junit] 2007-12-14 12:51:27,968 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore .META.,,1/info not needed.
    [junit] 2007-12-14 12:51:27,969 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
.META.,,1 does not need compaction
    [junit] 2007-12-14 12:51:28,126 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region result_table,,1197636677764. Size 306.0
    [junit] 2007-12-14 12:51:28,609 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1044139673/e/4850722542416381598 with sequence id 54 and size 212.0
    [junit] 2007-12-14 12:51:29,071 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1044139673/d/1689714923392203638 with sequence id 54 and size 212.0
    [junit] 2007-12-14 12:51:29,546 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1044139673/a/7506014536354338021 with sequence id 54 and size 206.0
    [junit] 2007-12-14 12:51:29,592 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1044139673/b/864471632588877996 with sequence id 54 and size 206.0
    [junit] 2007-12-14 12:51:30,050 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963):
Added 1044139673/c/7645926178245662382 with sequence id 54 and size 230.0
    [junit] 2007-12-14 12:51:30,051 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region result_table,,1197636677764 in 1926ms, sequenceid=54
    [junit] 2007-12-14 12:51:30,051 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore result_table,,1197636677764/e not needed.
    [junit] 2007-12-14 12:51:30,052 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore result_table,,1197636677764/d not needed.
    [junit] 2007-12-14 12:51:30,052 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore result_table,,1197636677764/a not needed.
    [junit] 2007-12-14 12:51:30,053 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore result_table,,1197636677764/b not needed.
    [junit] 2007-12-14 12:51:30,053 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction
for HStore result_table,,1197636677764/c not needed.
    [junit] 2007-12-14 12:51:30,053 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
result_table,,1197636677764 does not need compaction
    [junit] 2007-12-14 12:51:35,104 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211):
HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:35,129 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(247):
HMaster.rootScanner regioninfo: {regionname: .META.,,1, startKey: <>, tableDesc: {name:
.META., families: {info:={name: info, max versions: 1, compression: NONE, in memory: false,
max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:35,131 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293):
HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:37,083 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481}
    [junit] 2007-12-14 12:51:37,134 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1197636663854, startKey: <>, tableDesc:
{name: r1, families: {a:={name: a, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, b:={name: b, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}, c:={name: c, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}}, server:
140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:37,137 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1197636672235, startKey: <>, tableDesc:
{name: r2, families: {d:={name: d, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}, e:={name: e, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481,
startCode: 1197636663827
    [junit] 2007-12-14 12:51:37,140 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: result_table,,1197636677764, startKey: <>,
tableDesc: {name: result_table, families: {a:={name: a, max versions: 3, compression: NONE,
in memory: false, max length: 2147483647, bloom filter: none}, b:={name: b, max versions:
3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}, c:={name:
c, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter:
none}, d:={name: d, max versions: 3, compression: NONE, in memory: false, max length: 2147483647,
bloom filter: none}, e:={name: e, max versions: 3, compression: NONE, in memory: false, max
length: 2147483647, bloom filter: none}}}}, server: 140.211.11.75:59481, startCode: 1197636663827
    [junit] 2007-12-14 12:51:37,144 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:59481} complete
    [junit] 2007-12-14 12:51:37,144 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(761):
all meta regions scanned
    [junit] 2007-12-14 12:51:38,021 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-14 12:51:38,025 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-14 12:51:38,029 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-14 12:51:38,031 INFO  [main] algebra.TestTableJoinMapReduce(244): result_table.row.count:
3
    [junit] 2007-12-14 12:51:38,031 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down
HBase Cluster
    [junit] 2007-12-14 12:51:38,032 INFO  [main] hbase.HMaster(2513): Cluster shutdown requested.
Starting to quiesce servers
    [junit] 2007-12-14 12:51:38,505 INFO  [RegionServer:0] hbase.HRegionServer(745): Got quiesce
server message
    [junit] 2007-12-14 12:51:38,506 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1124):
MSG_REGIONSERVER_QUIESCE : 
    [junit] 2007-12-14 12:51:38,507 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266):
closing region r1,,1197636663854
    [junit] 2007-12-14 12:51:38,508 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
-307698310/a
    [junit] 2007-12-14 12:51:38,508 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
-307698310/b
    [junit] 2007-12-14 12:51:38,509 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
-307698310/c
    [junit] 2007-12-14 12:51:38,510 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
r1,,1197636663854
    [junit] 2007-12-14 12:51:38,510 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266):
closing region r2,,1197636672235
    [junit] 2007-12-14 12:51:38,511 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
-14954156/e
    [junit] 2007-12-14 12:51:38,511 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
-14954156/d
    [junit] 2007-12-14 12:51:38,512 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
r2,,1197636672235
    [junit] 2007-12-14 12:51:38,512 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266):
closing region result_table,,1197636677764
    [junit] 2007-12-14 12:51:38,514 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
1044139673/e
    [junit] 2007-12-14 12:51:38,515 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
1044139673/d
    [junit] 2007-12-14 12:51:38,516 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
1044139673/a
    [junit] 2007-12-14 12:51:38,517 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
1044139673/b
    [junit] 2007-12-14 12:51:38,518 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed
1044139673/c
    [junit] 2007-12-14 12:51:38,519 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
result_table,,1197636677764
    [junit] 2007-12-14 12:51:39,514 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1350):
Region server 140.211.11.75:59481 quiesced
    [junit] 2007-12-14 12:51:39,515 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1358):
All user tables quiesced. Proceeding with shutdown
    [junit] 2007-12-14 12:51:39,515 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1184):
telling root scanner to stop
    [junit] 2007-12-14 12:51:39,516 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1192):
telling meta scanner to stop
    [junit] 2007-12-14 12:51:39,517 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1200):
meta and root scanners notified
    [junit] 2007-12-14 12:51:39,518 INFO  [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner
exiting
    [junit] 2007-12-14 12:51:39,519 INFO  [RegionServer:0] hbase.HRegionServer(739): Got regionserver
stop message
    [junit] 2007-12-14 12:51:39,517 INFO  [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner
exiting
    [junit] 2007-12-14 12:51:39,520 INFO  [RegionServer:0] hbase.Leases(109): RegionServer:0
closing leases
    [junit] 2007-12-14 12:51:39,522 INFO  [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker
exiting
    [junit] 2007-12-14 12:51:39,522 INFO  [RegionServer:0] hbase.Leases(125): RegionServer:0
closed leases
    [junit] 2007-12-14 12:51:39,523 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1173):
worker thread exiting
    [junit] 2007-12-14 12:51:39,524 INFO  [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(510):
RegionServer:0.cacheFlusher exiting
    [junit] 2007-12-14 12:51:39,524 INFO  [RegionServer:0.compactor] hbase.HRegionServer$Compactor(400):
RegionServer:0.compactor exiting
    [junit] 2007-12-14 12:51:39,524 DEBUG [RegionServer:0] hbase.HRegionServer(1232): closing
region -ROOT-,,0
    [junit] 2007-12-14 12:51:39,524 INFO  [RegionServer:0.splitter] hbase.HRegionServer$Splitter(277):
RegionServer:0.splitter exiting
    [junit] 2007-12-14 12:51:39,529 DEBUG [RegionServer:0] hbase.HStore(874): closed -70236052/info
    [junit] 2007-12-14 12:51:39,530 INFO  [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0
    [junit] 2007-12-14 12:51:39,530 DEBUG [RegionServer:0] hbase.HRegionServer(1232): closing
region .META.,,1
    [junit] 2007-12-14 12:51:39,531 DEBUG [RegionServer:0] hbase.HStore(874): closed 1028785192/info
    [junit] 2007-12-14 12:51:39,532 INFO  [RegionServer:0] hbase.HRegion(430): closed .META.,,1
    [junit] 2007-12-14 12:51:39,533 DEBUG [RegionServer:0] hbase.HLog(388): closing log writer
in /hbase/log_140.211.11.75_1197636663827_59481
    [junit] 2007-12-14 12:51:39,957 INFO  [RegionServer:0] hbase.HRegionServer(859): telling
master that region server is shutting down at: 140.211.11.75:59481
    [junit] 2007-12-14 12:51:39,959 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1317):
Region server 140.211.11.75:59481: MSG_REPORT_EXITING -- cancelling lease
    [junit] 2007-12-14 12:51:39,960 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1468):
Cancelling lease for 140.211.11.75:59481
    [junit] 2007-12-14 12:51:39,960 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1324):
Region server 140.211.11.75:59481: MSG_REPORT_EXITING -- lease cancelled
    [junit] 2007-12-14 12:51:39,961 INFO  [RegionServer:0] hbase.HRegionServer(866): stopping
server at: 140.211.11.75:59481
    [junit] 2007-12-14 12:51:39,962 INFO  [RegionServer:0] hbase.HRegionServer(871): RegionServer:0
exiting
    [junit] 2007-12-14 12:51:40,503 DEBUG [HMaster] hbase.HMaster(1184): telling root scanner
to stop
    [junit] 2007-12-14 12:51:40,503 DEBUG [HMaster] hbase.HMaster(1192): telling meta scanner
to stop
    [junit] 2007-12-14 12:51:40,504 DEBUG [HMaster] hbase.HMaster(1200): meta and root scanners
notified
    [junit] 2007-12-14 12:51:40,506 INFO  [HMaster] hbase.Leases(109): HMaster closing leases
    [junit] 2007-12-14 12:51:40,508 INFO  [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker
exiting
    [junit] 2007-12-14 12:51:40,509 INFO  [HMaster] hbase.Leases(125): HMaster closed leases
    [junit] 2007-12-14 12:51:40,510 INFO  [HMaster] hbase.HMaster(1133): HMaster main thread
exiting
    [junit] 2007-12-14 12:51:40,511 INFO  [main] hbase.LocalHBaseCluster(238): Shutdown HMaster
1 region server(s)
    [junit] 2007-12-14 12:51:40,530 INFO  [main] hbase.StaticTestEnvironment(133): Shutting
down FileSystem
    [junit] 2007-12-14 12:51:41,202 INFO  [main] hbase.StaticTestEnvironment(140): Shutting
down Mini DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 0
    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 44.144 sec
    [junit] Running org.apache.hadoop.hbase.util.TestBase64

    [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.372 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.07 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.219 sec

BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :515: 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: 61 minutes 30 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2417
Updating HADOOP-2418
Updating HADOOP-2338
Updating HADOOP-2158
Updating HADOOP-2351


Mime
View raw message