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 #338
Date Wed, 19 Dec 2007 12:26:24 GMT
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/338/changes

Changes:

[acmurthy] HADOOP-2215.  Fix documentation in cluster_setup.html & mapred_tutorial.html
reflect that mapred.tasktracker.tasks.maximum has been superceeded by mapred.tasktracker.{map|reduce}.tasks.maximum.
Contributed by Amareshwari Sri Ramadasu.

[acmurthy] HADOOP-2453. Fix the configuration for wordcount-simple example in Hadoop Pipes
which currently produces an XML parsing error. Contributed by Amareshwari Sri Ramadasu.

[acmurthy] HADOOP-2227. Use the LocalDirAllocator uniformly for handling all of the temporary
storage required for a given task. It also implies that mapred.local.dir.minspacestart is
handled by checking if there is enough free-space on any one of the available disks. Contributed
by Amareshwari Sri Ramadasu.

[jimk] HADOOP-2458 HStoreFile.writeSplitInfo should just call HStoreFile.Reference.write

HADOOP-2458 HStoreFile.writeSplitInfo should just call HStoreFile.Reference.write

[dhruba] HADOOP-2432. HDFS includes the name of the file while throwing
"File does not exist"  exception. (Jim Kellerman via dhruba)

[dhruba] HADOOP-2326. The initial block report at Datanode startup time has
a random backoff period.  (Sanjay Radia via dhruba)

[dhruba] HADOOP-2460.  When the namenode encounters ioerrors on writing a
transaction log, it stops writing new transactions to that one.
(Raghu Angadi via dhruba)

[nigel] HADOOP-2459. Fix package target so that src/docs/build files are not included in the
release. Contributed by Nigel Daley.

[dhruba] HADOOP-2434. FSDatasetInterface read interface causes HDFS reads to occur
in 1 byte chunks, causing performance degradation.
(Raghu Angadi via dhruba)

[stack] HADOOP-2455 Error in Help-string of create command

[dhruba] HADOOP-2422.  dfs -cat multiple files fail with 'Unable to write to
output stream'.  (Raghu Angadi via dhruba)

------------------------------------------
[...truncated 47988 lines...]
    [junit] 2007-12-19 12:23:15,754 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554}
    [junit] 2007-12-19 12:23:15,779 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1198066982497, startKey: <>, encodedName(638402188)
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:33554, startCode: 1198066982473
    [junit] 2007-12-19 12:23:15,782 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1198066990990, startKey: <>, encodedName(1183766980)
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:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:15,785 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554} complete
    [junit] 2007-12-19 12:23:15,785 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763):
all meta regions scanned
    [junit] 2007-12-19 12:23:16,973 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region r1,,1198066982497. Size 310.0
    [junit] 2007-12-19 12:23:17,459 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 638402188/a/488125110685985629 with sequence id 27 and size 270.0 for r1,,1198066982497/a
    [junit] 2007-12-19 12:23:17,518 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 638402188/b/4435565138312563068 with sequence id 27 and size 270.0 for r1,,1198066982497/b
    [junit] 2007-12-19 12:23:17,523 DEBUG [main] hbase.HConnectionManager$TableServers(689):
Wake. Retry finding table r2
    [junit] 2007-12-19 12:23:17,547 DEBUG [main] hbase.HConnectionManager$TableServers(732):
Found 1 region(s) for r2 at address: 140.211.11.75:33554, 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] 2007-12-19 12:23:17,586 INFO  [IPC Server handler 1 on 60000] hbase.HLog(302):
new log writer created at /hbase/hregion_1312418194/log/hlog.dat.000
    [junit] 2007-12-19 12:23:17,593 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(600):
starting result_table,,1198066997557/a (1312418194/a) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,596 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(628):
maximum sequence id for hstore result_table,,1198066997557/a (1312418194/a) is -1
    [junit] 2007-12-19 12:23:17,602 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 638402188/c/415991120243090356 with sequence id 27 and size 310.0 for r1,,1198066982497/c
    [junit] 2007-12-19 12:23:17,603 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region r1,,1198066982497 in 630ms, sequenceid=27
    [junit] 2007-12-19 12:23:17,604 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore r1,,1198066982497/a not needed.
    [junit] 2007-12-19 12:23:17,604 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore r1,,1198066982497/b not needed.
    [junit] 2007-12-19 12:23:17,605 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore r1,,1198066982497/c not needed.
    [junit] 2007-12-19 12:23:17,605 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
r1,,1198066982497 does not need compaction
    [junit] 2007-12-19 12:23:17,612 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(600):
starting result_table,,1198066997557/b (1312418194/b) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,614 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(628):
maximum sequence id for hstore result_table,,1198066997557/b (1312418194/b) is -1
    [junit] 2007-12-19 12:23:17,626 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(600):
starting result_table,,1198066997557/c (1312418194/c) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,629 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(628):
maximum sequence id for hstore result_table,,1198066997557/c (1312418194/c) is -1
    [junit] 2007-12-19 12:23:17,637 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(600):
starting result_table,,1198066997557/d (1312418194/d) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,639 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(628):
maximum sequence id for hstore result_table,,1198066997557/d (1312418194/d) is -1
    [junit] 2007-12-19 12:23:17,646 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(600):
starting result_table,,1198066997557/e (1312418194/e) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,648 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(628):
maximum sequence id for hstore result_table,,1198066997557/e (1312418194/e) is -1
    [junit] 2007-12-19 12:23:17,649 DEBUG [IPC Server handler 1 on 60000] hbase.HRegion(300):
Next sequence id for region result_table,,1198066997557 is 0
    [junit] 2007-12-19 12:23:17,651 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(328):
region result_table,,1198066997557 available
    [junit] 2007-12-19 12:23:17,653 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(879):
closed result_table,,1198066997557/e (1312418194/e)
    [junit] 2007-12-19 12:23:17,654 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(879):
closed result_table,,1198066997557/d (1312418194/d)
    [junit] 2007-12-19 12:23:17,654 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(879):
closed result_table,,1198066997557/a (1312418194/a)
    [junit] 2007-12-19 12:23:17,654 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(879):
closed result_table,,1198066997557/b (1312418194/b)
    [junit] 2007-12-19 12:23:17,655 DEBUG [IPC Server handler 1 on 60000] hbase.HStore(879):
closed result_table,,1198066997557/c (1312418194/c)
    [junit] 2007-12-19 12:23:17,655 INFO  [IPC Server handler 1 on 60000] hbase.HRegion(430):
closed result_table,,1198066997557
    [junit] 2007-12-19 12:23:17,655 DEBUG [IPC Server handler 1 on 60000] hbase.HLog(388):
closing log writer in /hbase/hregion_1312418194/log
    [junit] 2007-12-19 12:23:17,668 INFO  [IPC Server handler 1 on 60000] hbase.HMaster(2547):
created table result_table
    [junit] 2007-12-19 12:23:17,669 DEBUG [main] hbase.HConnectionManager$TableServers(308):
No servers for result_table. Doing a find...
    [junit] 2007-12-19 12:23:17,683 DEBUG [main] hbase.HConnectionManager$TableServers(782):
no server address for regionname: result_table,,1198066997557, startKey: <>, encodedName(1312418194)
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-19 12:23:17,685 DEBUG [main] hbase.HConnectionManager$TableServers(680):
Sleeping. Table result_table not currently being served.
    [junit] 2007-12-19 12:23:17,905 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1858):
assigning region result_table,,1198066997557 to the only server 140.211.11.75:33554
    [junit] 2007-12-19 12:23:17,906 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153):
MSG_REGION_OPEN : result_table,,1198066997557
    [junit] 2007-12-19 12:23:17,912 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
result_table,,1198066997557/a (1312418194/a) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,915 DEBUG [RegionServer:0.worker] hbase.HStore(628): maximum
sequence id for hstore result_table,,1198066997557/a (1312418194/a) is -1
    [junit] 2007-12-19 12:23:17,920 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
result_table,,1198066997557/b (1312418194/b) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,922 DEBUG [RegionServer:0.worker] hbase.HStore(628): maximum
sequence id for hstore result_table,,1198066997557/b (1312418194/b) is -1
    [junit] 2007-12-19 12:23:17,927 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
result_table,,1198066997557/c (1312418194/c) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,930 DEBUG [RegionServer:0.worker] hbase.HStore(628): maximum
sequence id for hstore result_table,,1198066997557/c (1312418194/c) is -1
    [junit] 2007-12-19 12:23:17,934 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
result_table,,1198066997557/d (1312418194/d) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,937 DEBUG [RegionServer:0.worker] hbase.HStore(628): maximum
sequence id for hstore result_table,,1198066997557/d (1312418194/d) is -1
    [junit] 2007-12-19 12:23:17,942 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting
result_table,,1198066997557/e (1312418194/e) (no reconstruction log)
    [junit] 2007-12-19 12:23:17,944 DEBUG [RegionServer:0.worker] hbase.HStore(628): maximum
sequence id for hstore result_table,,1198066997557/e (1312418194/e) is -1
    [junit] 2007-12-19 12:23:17,945 DEBUG [RegionServer:0.worker] hbase.HRegion(300): Next
sequence id for region result_table,,1198066997557 is 0
    [junit] 2007-12-19 12:23:17,947 INFO  [RegionServer:0.worker] hbase.HRegion(328): region
result_table,,1198066997557 available
    [junit] 2007-12-19 12:23:18,915 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1516):
Received MSG_REPORT_PROCESS_OPEN : result_table,,1198066997557 from 140.211.11.75:33554
    [junit] 2007-12-19 12:23:18,916 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1516):
Received MSG_REPORT_OPEN : result_table,,1198066997557 from 140.211.11.75:33554
    [junit] 2007-12-19 12:23:18,916 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1577):
140.211.11.75:33554 serving result_table,,1198066997557
    [junit] 2007-12-19 12:23:18,917 DEBUG [HMaster] hbase.HMaster(1055): Main processing loop:
PendingOpenOperation from 140.211.11.75:33554
    [junit] 2007-12-19 12:23:18,917 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2458):
regionname: result_table,,1198066997557, startKey: <>, encodedName(1312418194) 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:33554
    [junit] 2007-12-19 12:23:18,918 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1907):
numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
    [junit] 2007-12-19 12:23:18,918 INFO  [HMaster] hbase.HMaster$ProcessRegionOpen(2472):
updating row result_table,,1198066997557 in table .META.,,1 with startcode 1198066982473 and
server 140.211.11.75:33554
    [junit] 2007-12-19 12:23:22,699 DEBUG [main] hbase.HConnectionManager$TableServers(689):
Wake. Retry finding table result_table
    [junit] 2007-12-19 12:23:22,718 DEBUG [main] hbase.HConnectionManager$TableServers(732):
Found 1 region(s) for result_table at address: 140.211.11.75:33554, 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] 2007-12-19 12:23:22,893 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region r2,,1198066990990. Size 132.0
    [junit] 2007-12-19 12:23:23,591 WARN  [IPC Server handler 4 on 33385] dfs.FSDirectory(400):
DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system
because it does not exist
    [junit] 2007-12-19 12:23:23,852 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211):
HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:33554}
    [junit] 2007-12-19 12:23:23,875 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: 140.211.11.75:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:23,877 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293):
HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:33554} complete
    [junit] 2007-12-19 12:23:24,339 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1183766980/e/7830734886117735422 with sequence id 37 and size 218.0 for r2,,1198066990990/e
    [junit] 2007-12-19 12:23:24,416 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1183766980/d/1706191814751090211 with sequence id 37 and size 218.0 for r2,,1198066990990/d
    [junit] 2007-12-19 12:23:24,416 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region r2,,1198066990990 in 1523ms, sequenceid=37
    [junit] 2007-12-19 12:23:24,417 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore r2,,1198066990990/e not needed.
    [junit] 2007-12-19 12:23:24,417 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore r2,,1198066990990/d not needed.
    [junit] 2007-12-19 12:23:24,418 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
r2,,1198066990990 does not need compaction
    [junit] 2007-12-19 12:23:24,778 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region .META.,,1. Size 331.0
    [junit] 2007-12-19 12:23:25,528 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1028785192/info/2793544903104381406 with sequence id 38 and size 483.0 for .META.,,1/info
    [junit] 2007-12-19 12:23:25,533 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region .META.,,1 in 755ms, sequenceid=38
    [junit] 2007-12-19 12:23:25,571 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore .META.,,1/info not needed.
    [junit] 2007-12-19 12:23:25,573 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
.META.,,1 does not need compaction
    [junit] 2007-12-19 12:23:25,762 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554}
    [junit] 2007-12-19 12:23:25,889 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1198066982497, startKey: <>, encodedName(638402188)
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:33554, startCode: 1198066982473
    [junit] 2007-12-19 12:23:25,891 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1198066990990, startKey: <>, encodedName(1183766980)
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:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:25,893 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: result_table,,1198066997557, startKey: <>,
encodedName(1312418194) 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:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:25,903 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554} complete
    [junit] 2007-12-19 12:23:25,903 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763):
all meta regions scanned
    [junit] 2007-12-19 12:23:26,177 WARN  [IPC Server handler 2 on 33385] 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-19 12:23:26,178 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-19 12:23:26,212 DEBUG [main] hbase.HConnectionManager$TableServers(308):
No servers for r1. Doing a find...
    [junit] 2007-12-19 12:23:26,340 DEBUG [main] hbase.HConnectionManager$TableServers(763):
Found 1 servers for table r1
    [junit] 2007-12-19 12:23:26,456 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
    [junit] 2007-12-19 12:23:26,918 DEBUG [Thread-200] mapred.TableInputFormat(175): split:
0->r1,,
    [junit] 2007-12-19 12:23:27,379 DEBUG [Thread-200] hbase.HConnectionManager$TableServers(308):
No servers for r2. Doing a find...
    [junit] 2007-12-19 12:23:27,401 DEBUG [Thread-200] hbase.HConnectionManager$TableServers(763):
Found 1 servers for table r2
    [junit] 2007-12-19 12:23:30,769 ERROR [Thread-167] mapred.TaskTracker(954): Caught exception:
java.io.InterruptedIOException
    [junit] 	at java.net.SocketOutputStream.socketWrite0(Native Method)
    [junit] 	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:92)
    [junit] 	at java.net.SocketOutputStream.write(SocketOutputStream.java:136)
    [junit] 	at org.apache.hadoop.ipc.Client$Connection$2.write(Client.java:199)
    [junit] 	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65)
    [junit] 	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123)
    [junit] 	at java.io.DataOutputStream.flush(DataOutputStream.java:106)
    [junit] 	at org.apache.hadoop.ipc.Client$Connection.sendParam(Client.java:344)
    [junit] 	at org.apache.hadoop.ipc.Client.call(Client.java:501)
    [junit] 	at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
    [junit] 	at org.apache.hadoop.mapred.$Proxy9.heartbeat(Unknown Source)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1004)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:902)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1300)
    [junit] 	at org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:144)
    [junit] 	at java.lang.Thread.run(Thread.java:595)

    [junit] 2007-12-19 12:23:31,130 ERROR [Thread-168] mapred.TaskTracker(954): Caught exception:
java.lang.reflect.UndeclaredThrowableException
    [junit] 	at org.apache.hadoop.mapred.$Proxy9.heartbeat(Unknown Source)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.transmitHeartBeat(TaskTracker.java:1004)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.offerService(TaskTracker.java:902)
    [junit] 	at org.apache.hadoop.mapred.TaskTracker.run(TaskTracker.java:1300)
    [junit] 	at org.apache.hadoop.mapred.MiniMRCluster$TaskTrackerRunner.run(MiniMRCluster.java:144)
    [junit] 	at java.lang.Thread.run(Thread.java:595)
    [junit] Caused by: java.lang.InterruptedException
    [junit] 	at java.lang.Object.wait(Native Method)
    [junit] 	at org.apache.hadoop.ipc.Client.call(Client.java:504)
    [junit] 	at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:198)
    [junit] 	... 6 more

    [junit] 2007-12-19 12:23:31,487 WARN  [Task Commit Thread] mapred.JobTracker$TaskCommitQueue(2032):
Task Commit Thread exiting...
    [junit] 2007-12-19 12:23:33,861 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(211):
HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:33554}
    [junit] 2007-12-19 12:23:33,890 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: 140.211.11.75:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:33,896 INFO  [HMaster.rootScanner] hbase.HMaster$BaseScanner(293):
HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server:
140.211.11.75:33554} complete
    [junit] 2007-12-19 12:23:35,771 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(211):
HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554}
    [junit] 2007-12-19 12:23:35,801 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r1,,1198066982497, startKey: <>, encodedName(638402188)
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:33554, startCode: 1198066982473
    [junit] 2007-12-19 12:23:35,804 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: r2,,1198066990990, startKey: <>, encodedName(1183766980)
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:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:35,807 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(247):
HMaster.metaScanner regioninfo: {regionname: result_table,,1198066997557, startKey: <>,
encodedName(1312418194) 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:33554,
startCode: 1198066982473
    [junit] 2007-12-19 12:23:35,809 INFO  [HMaster.metaScanner] hbase.HMaster$BaseScanner(293):
HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server:
140.211.11.75:33554} complete
    [junit] 2007-12-19 12:23:35,810 INFO  [HMaster.metaScanner] hbase.HMaster$MetaScanner(763):
all meta regions scanned
    [junit] 2007-12-19 12:23:37,960 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(837):
Started memcache flush for region result_table,,1198066997557. Size 306.0
    [junit] 2007-12-19 12:23:38,917 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1312418194/e/7982109336696445847 with sequence id 54 and size 212.0 for result_table,,1198066997557/e
    [junit] 2007-12-19 12:23:39,025 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1312418194/d/8963756225468615736 with sequence id 54 and size 212.0 for result_table,,1198066997557/d
    [junit] 2007-12-19 12:23:39,553 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1312418194/a/1247085148019546602 with sequence id 54 and size 206.0 for result_table,,1198066997557/a
    [junit] 2007-12-19 12:23:39,632 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1312418194/b/358099181509157569 with sequence id 54 and size 206.0 for result_table,,1198066997557/b
    [junit] 2007-12-19 12:23:39,707 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(969):
Added 1312418194/c/3231864055550393004 with sequence id 54 and size 230.0 for result_table,,1198066997557/c
    [junit] 2007-12-19 12:23:39,708 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(943):
Finished memcache flush for region result_table,,1198066997557 in 1748ms, sequenceid=54
    [junit] 2007-12-19 12:23:39,709 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore result_table,,1198066997557/e not needed.
    [junit] 2007-12-19 12:23:39,710 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore result_table,,1198066997557/d not needed.
    [junit] 2007-12-19 12:23:39,711 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore result_table,,1198066997557/a not needed.
    [junit] 2007-12-19 12:23:39,712 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore result_table,,1198066997557/b not needed.
    [junit] 2007-12-19 12:23:39,712 DEBUG [RegionServer:0.compactor] hbase.HStore(993): compaction
for HStore result_table,,1198066997557/c not needed.
    [junit] 2007-12-19 12:23:39,713 DEBUG [RegionServer:0.compactor] hbase.HRegion(707): region
result_table,,1198066997557 does not need compaction
    [junit] 2007-12-19 12:23:41,589 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-19 12:23:41,593 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-19 12:23:41,598 INFO  [main] algebra.TestTableJoinMapReduce(239): result_table.column.size:
5
    [junit] 2007-12-19 12:23:41,605 INFO  [main] algebra.TestTableJoinMapReduce(244): result_table.row.count:
3
    [junit] 2007-12-19 12:23:41,606 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down
HBase Cluster
    [junit] 2007-12-19 12:23:41,607 INFO  [main] hbase.HMaster(2526): Cluster shutdown requested.
Starting to quiesce servers
    [junit] 2007-12-19 12:23:42,232 INFO  [RegionServer:0] hbase.HRegionServer(774): Got quiesce
server message
    [junit] 2007-12-19 12:23:42,233 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1153):
MSG_REGIONSERVER_QUIESCE : 
    [junit] 2007-12-19 12:23:42,234 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295):
closing region r1,,1198066982497
    [junit] 2007-12-19 12:23:42,240 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
r1,,1198066982497/a (638402188/a)
    [junit] 2007-12-19 12:23:42,240 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
r1,,1198066982497/b (638402188/b)
    [junit] 2007-12-19 12:23:42,241 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
r1,,1198066982497/c (638402188/c)
    [junit] 2007-12-19 12:23:42,242 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
r1,,1198066982497
    [junit] 2007-12-19 12:23:42,242 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295):
closing region r2,,1198066990990
    [junit] 2007-12-19 12:23:42,244 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
r2,,1198066990990/e (1183766980/e)
    [junit] 2007-12-19 12:23:42,250 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
r2,,1198066990990/d (1183766980/d)
    [junit] 2007-12-19 12:23:42,251 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
r2,,1198066990990
    [junit] 2007-12-19 12:23:42,251 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1295):
closing region result_table,,1198066997557
    [junit] 2007-12-19 12:23:42,252 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
result_table,,1198066997557/e (1312418194/e)
    [junit] 2007-12-19 12:23:42,253 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
result_table,,1198066997557/d (1312418194/d)
    [junit] 2007-12-19 12:23:42,254 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
result_table,,1198066997557/a (1312418194/a)
    [junit] 2007-12-19 12:23:42,255 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
result_table,,1198066997557/b (1312418194/b)
    [junit] 2007-12-19 12:23:42,256 DEBUG [RegionServer:0.worker] hbase.HStore(879): closed
result_table,,1198066997557/c (1312418194/c)
    [junit] 2007-12-19 12:23:42,256 INFO  [RegionServer:0.worker] hbase.HRegion(430): closed
result_table,,1198066997557
    [junit] 2007-12-19 12:23:43,240 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1372):
Region server 140.211.11.75:33554 quiesced
    [junit] 2007-12-19 12:23:43,242 INFO  [IPC Server handler 4 on 60000] hbase.HMaster(1380):
All user tables quiesced. Proceeding with shutdown
    [junit] 2007-12-19 12:23:43,242 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1206):
telling root scanner to stop
    [junit] 2007-12-19 12:23:43,243 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1214):
telling meta scanner to stop
    [junit] 2007-12-19 12:23:43,244 INFO  [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner
exiting
    [junit] 2007-12-19 12:23:43,244 DEBUG [IPC Server handler 4 on 60000] hbase.HMaster(1222):
meta and root scanners notified
    [junit] 2007-12-19 12:23:43,244 INFO  [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner
exiting
    [junit] 2007-12-19 12:23:43,246 INFO  [RegionServer:0] hbase.HRegionServer(768): Got regionserver
stop message
    [junit] 2007-12-19 12:23:43,247 INFO  [RegionServer:0] hbase.Leases(109): RegionServer:0
closing leases
    [junit] 2007-12-19 12:23:43,248 INFO  [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker
exiting
    [junit] 2007-12-19 12:23:43,249 INFO  [RegionServer:0] hbase.Leases(125): RegionServer:0
closed leases
    [junit] 2007-12-19 12:23:43,251 INFO  [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(535):
RegionServer:0.cacheFlusher exiting
    [junit] 2007-12-19 12:23:43,252 INFO  [RegionServer:0.splitter] hbase.HRegionServer$Splitter(302):
RegionServer:0.splitter exiting
    [junit] 2007-12-19 12:23:43,253 INFO  [RegionServer:0.compactor] hbase.HRegionServer$Compactor(425):
RegionServer:0.compactor exiting
    [junit] 2007-12-19 12:23:43,251 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing
region -ROOT-,,0
    [junit] 2007-12-19 12:23:43,255 DEBUG [RegionServer:0] hbase.HStore(879): closed -ROOT-,,0/info
(70236052/info)
    [junit] 2007-12-19 12:23:43,256 INFO  [RegionServer:0] hbase.HRegion(430): closed -ROOT-,,0
    [junit] 2007-12-19 12:23:43,256 DEBUG [RegionServer:0] hbase.HRegionServer(1261): closing
region .META.,,1
    [junit] 2007-12-19 12:23:43,257 DEBUG [RegionServer:0] hbase.HStore(879): closed .META.,,1/info
(1028785192/info)
    [junit] 2007-12-19 12:23:43,258 INFO  [RegionServer:0] hbase.HRegion(430): closed .META.,,1
    [junit] 2007-12-19 12:23:43,258 DEBUG [RegionServer:0] hbase.HLog(388): closing log writer
in /hbase/log_140.211.11.75_1198066982473_33554
    [junit] 2007-12-19 12:23:43,259 INFO  [RegionServer:0.worker] hbase.HRegionServer$Worker(1202):
worker thread exiting
    [junit] 2007-12-19 12:23:43,685 INFO  [RegionServer:0] hbase.HRegionServer(888): telling
master that region server is shutting down at: 140.211.11.75:33554
    [junit] 2007-12-19 12:23:43,687 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1339):
Region server 140.211.11.75:33554: MSG_REPORT_EXITING -- cancelling lease
    [junit] 2007-12-19 12:23:43,688 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1481):
Cancelling lease for 140.211.11.75:33554
    [junit] 2007-12-19 12:23:43,688 INFO  [IPC Server handler 0 on 60000] hbase.HMaster(1346):
Region server 140.211.11.75:33554: MSG_REPORT_EXITING -- lease cancelled
    [junit] 2007-12-19 12:23:43,689 INFO  [RegionServer:0] hbase.HRegionServer(895): stopping
server at: 140.211.11.75:33554
    [junit] 2007-12-19 12:23:43,690 INFO  [RegionServer:0] hbase.HRegionServer(900): RegionServer:0
exiting
    [junit] 2007-12-19 12:23:44,249 DEBUG [HMaster] hbase.HMaster(1206): telling root scanner
to stop
    [junit] 2007-12-19 12:23:44,250 DEBUG [HMaster] hbase.HMaster(1214): telling meta scanner
to stop
    [junit] 2007-12-19 12:23:44,250 DEBUG [HMaster] hbase.HMaster(1222): meta and root scanners
notified
    [junit] 2007-12-19 12:23:44,251 INFO  [HMaster] hbase.Leases(109): HMaster closing leases
    [junit] 2007-12-19 12:23:44,253 INFO  [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker
exiting
    [junit] 2007-12-19 12:23:44,255 INFO  [HMaster] hbase.Leases(125): HMaster closed leases
    [junit] 2007-12-19 12:23:44,255 INFO  [HMaster] hbase.HMaster(1139): HMaster main thread
exiting
    [junit] 2007-12-19 12:23:44,256 INFO  [main] hbase.LocalHBaseCluster(238): Shutdown HMaster
1 region server(s)
    [junit] 2007-12-19 12:23:44,282 INFO  [main] hbase.StaticTestEnvironment(133): Shutting
down FileSystem
    [junit] 2007-12-19 12:23:44,379 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: 48.864 sec
    [junit] 2007-12-19 12:23:45,171 INFO  [main] hbase.HRegionServer$ShutdownThread(151):
Starting shutdown thread.
    [junit] 2007-12-19 12:23:45,172 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.342 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.087 sec
    [junit] Running org.onelab.test.TestFilter
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.056 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: 70 minutes 13 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2459
Updating HADOOP-2215
Updating HADOOP-2453
Updating HADOOP-2460
Updating HADOOP-2432
Updating HADOOP-2326
Updating HADOOP-2422
Updating HADOOP-2458
Updating HADOOP-2434
Updating HADOOP-2455
Updating HADOOP-2227


Mime
View raw message