You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by hu...@lucene.zones.apache.org on 2007/12/13 13:07:06 UTC
Build failed in Hudson: Hadoop-Nightly #331
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/331/changes
Changes:
[stack] HADOOP-2047 Add an '--master=X' and '--html' command-line parameters to shell
[dhruba] HADOOP-1841. Prevent slow clients from consuming threads in the NameNode.
(dhruba)
[stack] HADOOP-2407 Keeping MapFile.Reader open is expensive: Part 2
------------------------------------------
[...truncated 47873 lines...]
[junit] 2007-12-13 12:03:43,974 DEBUG [main] hbase.HConnectionManager$TableServers(732): Found 1 region(s) for r1 at address: 140.211.11.75:64007, 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-13 12:03:44,016 INFO [IPC Server handler 2 on 60000] hbase.HLog(302): new log writer created at /hbase/hregion_389335723/log/hlog.dat.000
[junit] 2007-12-13 12:03:44,062 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(600): starting 389335723/d (no reconstruction log)
[junit] 2007-12-13 12:03:44,068 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(627): maximum sequence id for hstore 389335723/d is -1
[junit] 2007-12-13 12:03:44,394 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(600): starting 389335723/e (no reconstruction log)
[junit] 2007-12-13 12:03:44,398 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(627): maximum sequence id for hstore 389335723/e is -1
[junit] 2007-12-13 12:03:44,400 DEBUG [IPC Server handler 2 on 60000] hbase.HRegion(299): Next sequence id for region r2,,1197547423990 is 0
[junit] 2007-12-13 12:03:44,403 INFO [IPC Server handler 2 on 60000] hbase.HRegion(327): region r2,,1197547423990 available
[junit] 2007-12-13 12:03:44,406 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(874): closed 389335723/e
[junit] 2007-12-13 12:03:44,407 DEBUG [IPC Server handler 2 on 60000] hbase.HStore(874): closed 389335723/d
[junit] 2007-12-13 12:03:44,408 INFO [IPC Server handler 2 on 60000] hbase.HRegion(428): closed r2,,1197547423990
[junit] 2007-12-13 12:03:44,408 DEBUG [IPC Server handler 2 on 60000] hbase.HLog(388): closing log writer in /hbase/hregion_389335723/log
[junit] 2007-12-13 12:03:44,427 INFO [IPC Server handler 2 on 60000] hbase.HMaster(2522): created table r2
[junit] 2007-12-13 12:03:44,428 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
[junit] 2007-12-13 12:03:44,434 DEBUG [main] hbase.HConnectionManager$TableServers(782): no server address for regionname: r2,,1197547423990, 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-13 12:03:44,437 DEBUG [main] hbase.HConnectionManager$TableServers(680): Sleeping. Table r2 not currently being served.
[junit] 2007-12-13 12:03:44,736 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1833): assigning region r2,,1197547423990 to the only server 140.211.11.75:64007
[junit] 2007-12-13 12:03:44,737 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1124): MSG_REGION_OPEN : r2,,1197547423990
[junit] 2007-12-13 12:03:44,743 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 389335723/d (no reconstruction log)
[junit] 2007-12-13 12:03:44,747 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 389335723/d is -1
[junit] 2007-12-13 12:03:44,756 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 389335723/e (no reconstruction log)
[junit] 2007-12-13 12:03:44,761 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 389335723/e is -1
[junit] 2007-12-13 12:03:44,763 DEBUG [RegionServer:0.worker] hbase.HRegion(299): Next sequence id for region r2,,1197547423990 is 0
[junit] 2007-12-13 12:03:44,767 INFO [RegionServer:0.worker] hbase.HRegion(327): region r2,,1197547423990 available
[junit] 2007-12-13 12:03:45,745 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(832): Started memcache flush for region -ROOT-,,0. Size 92.0
[junit] 2007-12-13 12:03:45,746 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1491): Received MSG_REPORT_PROCESS_OPEN : r2,,1197547423990 from 140.211.11.75:64007
[junit] 2007-12-13 12:03:45,748 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1491): Received MSG_REPORT_OPEN : r2,,1197547423990 from 140.211.11.75:64007
[junit] 2007-12-13 12:03:45,749 INFO [IPC Server handler 0 on 60000] hbase.HMaster(1552): 140.211.11.75:64007 serving r2,,1197547423990
[junit] 2007-12-13 12:03:45,755 DEBUG [HMaster] hbase.HMaster(1051): Main processing loop: PendingOpenOperation from 140.211.11.75:64007
[junit] 2007-12-13 12:03:45,755 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2433): regionname: r2,,1197547423990, 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:64007
[junit] 2007-12-13 12:03:45,756 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1882): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
[junit] 2007-12-13 12:03:45,756 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2447): updating row r2,,1197547423990 in table .META.,,1 with startcode 1197547415555 and server 140.211.11.75:64007
[junit] 2007-12-13 12:03:45,852 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added -70236052/info/6357274457474681183 with sequence id 23 and size 230.0
[junit] 2007-12-13 12:03:45,853 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(938): Finished memcache flush for region -ROOT-,,0 in 108ms, sequenceid=23
[junit] 2007-12-13 12:03:45,854 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore -ROOT-,,0/info not needed.
[junit] 2007-12-13 12:03:45,855 DEBUG [RegionServer:0.compactor] hbase.HRegion(702): region -ROOT-,,0 does not need compaction
[junit] 2007-12-13 12:03:46,795 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(213): HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:03:46,828 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(249): 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:46,832 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(295): HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:03:47,694 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(832): Started memcache flush for region .META.,,1. Size 472.0
[junit] 2007-12-13 12:03:47,770 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 1028785192/info/2553418326554709950 with sequence id 26 and size 666.0
[junit] 2007-12-13 12:03:47,771 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(938): Finished memcache flush for region .META.,,1 in 77ms, sequenceid=26
[junit] 2007-12-13 12:03:47,772 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore .META.,,1/info not needed.
[junit] 2007-12-13 12:03:47,772 DEBUG [RegionServer:0.compactor] hbase.HRegion(702): region .META.,,1 does not need compaction
[junit] 2007-12-13 12:03:48,734 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(213): HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:03:48,753 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r1,,1197547415583, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:48,756 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r2,,1197547423990, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:48,758 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(295): HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:03:48,759 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-13 12:03:49,444 DEBUG [main] hbase.HConnectionManager$TableServers(689): Wake. Retry finding table r2
[junit] 2007-12-13 12:03:49,472 DEBUG [main] hbase.HConnectionManager$TableServers(732): Found 1 region(s) for r2 at address: 140.211.11.75:64007, 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-13 12:03:49,564 INFO [IPC Server handler 4 on 60000] hbase.HLog(302): new log writer created at /hbase/hregion_620920710/log/hlog.dat.000
[junit] 2007-12-13 12:03:49,572 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(600): starting 620920710/a (no reconstruction log)
[junit] 2007-12-13 12:03:49,577 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(627): maximum sequence id for hstore 620920710/a is -1
[junit] 2007-12-13 12:03:49,589 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(600): starting 620920710/b (no reconstruction log)
[junit] 2007-12-13 12:03:49,592 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(627): maximum sequence id for hstore 620920710/b is -1
[junit] 2007-12-13 12:03:49,600 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(600): starting 620920710/c (no reconstruction log)
[junit] 2007-12-13 12:03:49,603 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(627): maximum sequence id for hstore 620920710/c is -1
[junit] 2007-12-13 12:03:49,612 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(600): starting 620920710/d (no reconstruction log)
[junit] 2007-12-13 12:03:49,615 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(627): maximum sequence id for hstore 620920710/d is -1
[junit] 2007-12-13 12:03:49,626 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(600): starting 620920710/e (no reconstruction log)
[junit] 2007-12-13 12:03:49,628 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(627): maximum sequence id for hstore 620920710/e is -1
[junit] 2007-12-13 12:03:49,629 DEBUG [IPC Server handler 4 on 60000] hbase.HRegion(299): Next sequence id for region result_table,,1197547429479 is 0
[junit] 2007-12-13 12:03:49,631 INFO [IPC Server handler 4 on 60000] hbase.HRegion(327): region result_table,,1197547429479 available
[junit] 2007-12-13 12:03:49,633 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(874): closed 620920710/e
[junit] 2007-12-13 12:03:49,634 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(874): closed 620920710/d
[junit] 2007-12-13 12:03:49,635 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(874): closed 620920710/a
[junit] 2007-12-13 12:03:49,635 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(874): closed 620920710/b
[junit] 2007-12-13 12:03:49,635 DEBUG [IPC Server handler 4 on 60000] hbase.HStore(874): closed 620920710/c
[junit] 2007-12-13 12:03:49,635 INFO [IPC Server handler 4 on 60000] hbase.HRegion(428): closed result_table,,1197547429479
[junit] 2007-12-13 12:03:49,636 DEBUG [IPC Server handler 4 on 60000] hbase.HLog(388): closing log writer in /hbase/hregion_620920710/log
[junit] 2007-12-13 12:03:49,650 INFO [IPC Server handler 4 on 60000] hbase.HMaster(2522): created table result_table
[junit] 2007-12-13 12:03:49,651 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for result_table. Doing a find...
[junit] 2007-12-13 12:03:49,668 DEBUG [main] hbase.HConnectionManager$TableServers(782): no server address for regionname: result_table,,1197547429479, 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-13 12:03:49,670 DEBUG [main] hbase.HConnectionManager$TableServers(680): Sleeping. Table result_table not currently being served.
[junit] 2007-12-13 12:03:49,734 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(832): Started memcache flush for region r1,,1197547415583. Size 310.0
[junit] 2007-12-13 12:03:49,785 INFO [IPC Server handler 0 on 60000] hbase.HMaster(1833): assigning region result_table,,1197547429479 to the only server 140.211.11.75:64007
[junit] 2007-12-13 12:03:49,787 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1124): MSG_REGION_OPEN : result_table,,1197547429479
[junit] 2007-12-13 12:03:49,792 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 620920710/a (no reconstruction log)
[junit] 2007-12-13 12:03:49,795 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 620920710/a is -1
[junit] 2007-12-13 12:03:49,801 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 620920710/b (no reconstruction log)
[junit] 2007-12-13 12:03:49,804 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 620920710/b is -1
[junit] 2007-12-13 12:03:49,811 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 620920710/c (no reconstruction log)
[junit] 2007-12-13 12:03:49,814 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 620920710/c is -1
[junit] 2007-12-13 12:03:49,820 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 620920710/d (no reconstruction log)
[junit] 2007-12-13 12:03:49,824 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 620920710/d is -1
[junit] 2007-12-13 12:03:49,831 DEBUG [RegionServer:0.worker] hbase.HStore(600): starting 620920710/e (no reconstruction log)
[junit] 2007-12-13 12:03:49,835 DEBUG [RegionServer:0.worker] hbase.HStore(627): maximum sequence id for hstore 620920710/e is -1
[junit] 2007-12-13 12:03:49,837 DEBUG [RegionServer:0.worker] hbase.HRegion(299): Next sequence id for region result_table,,1197547429479 is 0
[junit] 2007-12-13 12:03:49,840 INFO [RegionServer:0.worker] hbase.HRegion(327): region result_table,,1197547429479 available
[junit] 2007-12-13 12:03:50,215 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 1980559204/a/890722964304868411 with sequence id 34 and size 270.0
[junit] 2007-12-13 12:03:50,652 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 1980559204/b/2729939485717801457 with sequence id 34 and size 270.0
[junit] 2007-12-13 12:03:50,720 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 1980559204/c/1590961786234586823 with sequence id 34 and size 310.0
[junit] 2007-12-13 12:03:50,721 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(938): Finished memcache flush for region r1,,1197547415583 in 987ms, sequenceid=34
[junit] 2007-12-13 12:03:50,721 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore r1,,1197547415583/a not needed.
[junit] 2007-12-13 12:03:50,722 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore r1,,1197547415583/b not needed.
[junit] 2007-12-13 12:03:50,722 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore r1,,1197547415583/c not needed.
[junit] 2007-12-13 12:03:50,723 DEBUG [RegionServer:0.compactor] hbase.HRegion(702): region r1,,1197547415583 does not need compaction
[junit] 2007-12-13 12:03:50,796 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1491): Received MSG_REPORT_PROCESS_OPEN : result_table,,1197547429479 from 140.211.11.75:64007
[junit] 2007-12-13 12:03:50,796 DEBUG [IPC Server handler 3 on 60000] hbase.HMaster(1491): Received MSG_REPORT_OPEN : result_table,,1197547429479 from 140.211.11.75:64007
[junit] 2007-12-13 12:03:50,796 INFO [IPC Server handler 3 on 60000] hbase.HMaster(1552): 140.211.11.75:64007 serving result_table,,1197547429479
[junit] 2007-12-13 12:03:50,797 DEBUG [HMaster] hbase.HMaster(1051): Main processing loop: PendingOpenOperation from 140.211.11.75:64007
[junit] 2007-12-13 12:03:50,798 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2433): regionname: result_table,,1197547429479, 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:64007
[junit] 2007-12-13 12:03:50,798 DEBUG [HMaster] hbase.HMaster$RegionServerOperation(1882): numberOfMetaRegions: 1, onlineMetaRegions.size(): 1
[junit] 2007-12-13 12:03:50,798 INFO [HMaster] hbase.HMaster$ProcessRegionOpen(2447): updating row result_table,,1197547429479 in table .META.,,1 with startcode 1197547415555 and server 140.211.11.75:64007
[junit] 2007-12-13 12:03:54,673 DEBUG [main] hbase.HConnectionManager$TableServers(689): Wake. Retry finding table result_table
[junit] 2007-12-13 12:03:54,693 DEBUG [main] hbase.HConnectionManager$TableServers(732): Found 1 region(s) for result_table at address: 140.211.11.75:64007, 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-13 12:03:54,773 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(832): Started memcache flush for region r2,,1197547423990. Size 132.0
[junit] 2007-12-13 12:03:54,845 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 389335723/e/7982846400724143362 with sequence id 37 and size 218.0
[junit] 2007-12-13 12:03:54,912 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 389335723/d/4609970554346907880 with sequence id 37 and size 218.0
[junit] 2007-12-13 12:03:54,913 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(938): Finished memcache flush for region r2,,1197547423990 in 141ms, sequenceid=37
[junit] 2007-12-13 12:03:54,913 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore r2,,1197547423990/e not needed.
[junit] 2007-12-13 12:03:54,914 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore r2,,1197547423990/d not needed.
[junit] 2007-12-13 12:03:54,914 DEBUG [RegionServer:0.compactor] hbase.HRegion(702): region r2,,1197547423990 does not need compaction
[junit] 2007-12-13 12:03:56,802 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(213): HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:03:56,832 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(249): 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:56,834 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(295): HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:03:56,921 WARN [IPC Server handler 3 on 63846] dfs.FSDirectory(400): DIR* FSDirectory.unprotectedDelete: failed to remove /user/hudson/build/contrib/hbase/test/system because it does not exist
[junit] 2007-12-13 12:03:57,772 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(832): Started memcache flush for region .META.,,1. Size 331.0
[junit] 2007-12-13 12:03:58,742 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(213): HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:03:58,760 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r1,,1197547415583, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:58,764 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r2,,1197547423990, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:03:58,766 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(295): HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:03:58,767 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-13 12:04:04,108 WARN [IPC Server handler 4 on 63846] 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-13 12:04:04,109 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-13 12:04:04,139 DEBUG [main] hbase.HConnectionManager$TableServers(308): No servers for r1. Doing a find...
[junit] 2007-12-13 12:04:04,166 DEBUG [main] hbase.HConnectionManager$TableServers(763): Found 1 servers for table r1
[junit] 2007-12-13 12:04:04,220 DEBUG [main] mapred.TableInputFormat(175): split: 0->r1,,
[junit] 2007-12-13 12:04:05,754 DEBUG [Thread-195] mapred.TableInputFormat(175): split: 0->r1,,
[junit] 2007-12-13 12:04:05,770 DEBUG [RegionServer:0.cacheFlusher] hbase.HStore(963): Added 1028785192/info/2720680746256252064 with sequence id 38 and size 483.0
[junit] 2007-12-13 12:04:05,771 DEBUG [RegionServer:0.cacheFlusher] hbase.HRegion(938): Finished memcache flush for region .META.,,1 in 7999ms, sequenceid=38
[junit] 2007-12-13 12:04:05,771 DEBUG [RegionServer:0.compactor] hbase.HStore(986): compaction for HStore .META.,,1/info not needed.
[junit] 2007-12-13 12:04:05,772 DEBUG [RegionServer:0.compactor] hbase.HRegion(702): region .META.,,1 does not need compaction
[junit] 2007-12-13 12:04:06,042 DEBUG [Thread-195] hbase.HConnectionManager$TableServers(308): No servers for r2. Doing a find...
[junit] 2007-12-13 12:04:06,073 DEBUG [Thread-195] hbase.HConnectionManager$TableServers(763): Found 1 servers for table r2
[junit] 2007-12-13 12:04:06,840 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(213): HMaster.rootScanner scanning meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:04:07,836 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-13 12:04:07,838 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-13 12:04:07,853 INFO [main] algebra.TestTableJoinMapReduce(226): result_table.column.size: 5
[junit] 2007-12-13 12:04:07,858 INFO [main] algebra.TestTableJoinMapReduce(226): result_table.column.size: 5
[junit] 2007-12-13 12:04:07,859 DEBUG [HMaster.rootScanner] hbase.HMaster$BaseScanner(249): 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:04:07,861 INFO [main] algebra.TestTableJoinMapReduce(226): result_table.column.size: 5
[junit] 2007-12-13 12:04:07,862 INFO [HMaster.rootScanner] hbase.HMaster$BaseScanner(295): HMaster.rootScanner scan of meta region regionname: -ROOT-,,0, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:04:07,864 INFO [main] algebra.TestTableJoinMapReduce(230): result_table.row.count: 3
[junit] 2007-12-13 12:04:07,865 DEBUG [main] hbase.LocalHBaseCluster(212): Shutting down HBase Cluster
[junit] 2007-12-13 12:04:07,866 INFO [main] hbase.HMaster(2501): Cluster shutdown requested. Starting to quiesce servers
[junit] 2007-12-13 12:04:08,102 INFO [RegionServer:0] hbase.HRegionServer(745): Got quiesce server message
[junit] 2007-12-13 12:04:08,103 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1124): MSG_REGIONSERVER_QUIESCE :
[junit] 2007-12-13 12:04:08,104 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266): closing region r1,,1197547415583
[junit] 2007-12-13 12:04:08,105 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 1980559204/a
[junit] 2007-12-13 12:04:08,105 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 1980559204/b
[junit] 2007-12-13 12:04:08,106 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 1980559204/c
[junit] 2007-12-13 12:04:08,106 INFO [RegionServer:0.worker] hbase.HRegion(428): closed r1,,1197547415583
[junit] 2007-12-13 12:04:08,107 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266): closing region r2,,1197547423990
[junit] 2007-12-13 12:04:08,108 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 389335723/e
[junit] 2007-12-13 12:04:08,108 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 389335723/d
[junit] 2007-12-13 12:04:08,109 INFO [RegionServer:0.worker] hbase.HRegion(428): closed r2,,1197547423990
[junit] 2007-12-13 12:04:08,110 DEBUG [RegionServer:0.worker] hbase.HRegionServer(1266): closing region result_table,,1197547429479
[junit] 2007-12-13 12:04:08,110 DEBUG [RegionServer:0.worker] hbase.HRegion(832): Started memcache flush for region result_table,,1197547429479. Size 306.0
[junit] 2007-12-13 12:04:08,203 DEBUG [RegionServer:0.worker] hbase.HStore(963): Added 620920710/e/3910656382612005546 with sequence id 54 and size 212.0
[junit] 2007-12-13 12:04:08,271 DEBUG [RegionServer:0.worker] hbase.HStore(963): Added 620920710/d/4732037202766003854 with sequence id 54 and size 212.0
[junit] 2007-12-13 12:04:08,358 DEBUG [RegionServer:0.worker] hbase.HStore(963): Added 620920710/a/6807573889681067070 with sequence id 54 and size 206.0
[junit] 2007-12-13 12:04:08,425 DEBUG [RegionServer:0.worker] hbase.HStore(963): Added 620920710/b/4569672967393469275 with sequence id 54 and size 206.0
[junit] 2007-12-13 12:04:08,749 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(213): HMaster.metaScanner scanning meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007}
[junit] 2007-12-13 12:04:08,784 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r1,,1197547415583, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:04:08,786 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: r2,,1197547423990, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:04:08,788 DEBUG [HMaster.metaScanner] hbase.HMaster$BaseScanner(249): HMaster.metaScanner regioninfo: {regionname: result_table,,1197547429479, 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:64007, startCode: 1197547415555
[junit] 2007-12-13 12:04:08,790 INFO [HMaster.metaScanner] hbase.HMaster$BaseScanner(295): HMaster.metaScanner scan of meta region regionname: .META.,,1, startKey: <>, server: 140.211.11.75:64007} complete
[junit] 2007-12-13 12:04:08,790 INFO [HMaster.metaScanner] hbase.HMaster$MetaScanner(763): all meta regions scanned
[junit] 2007-12-13 12:04:08,904 DEBUG [RegionServer:0.worker] hbase.HStore(963): Added 620920710/c/1975184484325683493 with sequence id 54 and size 230.0
[junit] 2007-12-13 12:04:08,904 DEBUG [RegionServer:0.worker] hbase.HRegion(938): Finished memcache flush for region result_table,,1197547429479 in 794ms, sequenceid=54
[junit] 2007-12-13 12:04:08,905 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 620920710/e
[junit] 2007-12-13 12:04:08,905 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 620920710/d
[junit] 2007-12-13 12:04:08,905 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 620920710/a
[junit] 2007-12-13 12:04:08,906 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 620920710/b
[junit] 2007-12-13 12:04:08,906 DEBUG [RegionServer:0.worker] hbase.HStore(874): closed 620920710/c
[junit] 2007-12-13 12:04:08,906 INFO [RegionServer:0.worker] hbase.HRegion(428): closed result_table,,1197547429479
[junit] 2007-12-13 12:04:09,110 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1343): Region server 140.211.11.75:64007 quiesced
[junit] 2007-12-13 12:04:09,111 INFO [IPC Server handler 4 on 60000] hbase.HMaster(1347): All user tables quiesced. Proceeding with shutdown
[junit] 2007-12-13 12:04:09,111 INFO [HMaster.rootScanner] hbase.Chore(66): HMaster.rootScanner exiting
[junit] 2007-12-13 12:04:09,112 INFO [RegionServer:0] hbase.HRegionServer(739): Got regionserver stop message
[junit] 2007-12-13 12:04:09,111 INFO [HMaster.metaScanner] hbase.Chore(66): HMaster.metaScanner exiting
[junit] 2007-12-13 12:04:09,112 INFO [RegionServer:0] hbase.Leases(109): RegionServer:0 closing leases
[junit] 2007-12-13 12:04:09,113 INFO [RegionServer:0.leaseChecker] hbase.Chore(66): RegionServer:0.leaseChecker exiting
[junit] 2007-12-13 12:04:09,113 INFO [RegionServer:0] hbase.Leases(125): RegionServer:0 closed leases
[junit] 2007-12-13 12:04:09,115 INFO [RegionServer:0.cacheFlusher] hbase.HRegionServer$Flusher(510): RegionServer:0.cacheFlusher exiting
[junit] 2007-12-13 12:04:09,114 INFO [RegionServer:0.compactor] hbase.HRegionServer$Compactor(400): RegionServer:0.compactor exiting
[junit] 2007-12-13 12:04:09,114 INFO [RegionServer:0.splitter] hbase.HRegionServer$Splitter(277): RegionServer:0.splitter exiting
[junit] 2007-12-13 12:04:09,114 DEBUG [RegionServer:0] hbase.HRegionServer(1232): closing region -ROOT-,,0
[junit] 2007-12-13 12:04:09,117 DEBUG [RegionServer:0] hbase.HStore(874): closed -70236052/info
[junit] 2007-12-13 12:04:09,117 INFO [RegionServer:0] hbase.HRegion(428): closed -ROOT-,,0
[junit] 2007-12-13 12:04:09,118 DEBUG [RegionServer:0] hbase.HRegionServer(1232): closing region .META.,,1
[junit] 2007-12-13 12:04:09,118 DEBUG [RegionServer:0] hbase.HStore(874): closed 1028785192/info
[junit] 2007-12-13 12:04:09,118 INFO [RegionServer:0] hbase.HRegion(428): closed .META.,,1
[junit] 2007-12-13 12:04:09,118 DEBUG [RegionServer:0] hbase.HLog(388): closing log writer in /hbase/log_140.211.11.75_1197547415555_64007
[junit] 2007-12-13 12:04:09,128 INFO [RegionServer:0] hbase.HRegionServer(859): telling master that region server is shutting down at: 140.211.11.75:64007
[junit] 2007-12-13 12:04:09,130 DEBUG [IPC Server handler 0 on 60000] hbase.HMaster(1310): Region server 140.211.11.75:64007: MSG_REPORT_EXITING -- cancelling lease
[junit] 2007-12-13 12:04:09,130 INFO [IPC Server handler 0 on 60000] hbase.HMaster(1456): Cancelling lease for 140.211.11.75:64007
[junit] 2007-12-13 12:04:09,130 INFO [IPC Server handler 0 on 60000] hbase.HMaster(1317): Region server 140.211.11.75:64007: MSG_REPORT_EXITING -- lease cancelled
[junit] 2007-12-13 12:04:09,131 INFO [RegionServer:0] hbase.HRegionServer(866): stopping server at: 140.211.11.75:64007
[junit] 2007-12-13 12:04:09,909 INFO [RegionServer:0.worker] hbase.HRegionServer$Worker(1173): worker thread exiting
[junit] 2007-12-13 12:04:09,909 INFO [RegionServer:0] hbase.HRegionServer(871): RegionServer:0 exiting
[junit] 2007-12-13 12:04:10,119 INFO [HMaster] hbase.Leases(109): HMaster closing leases
[junit] 2007-12-13 12:04:10,120 INFO [HMaster.leaseChecker] hbase.Chore(66): HMaster.leaseChecker exiting
[junit] 2007-12-13 12:04:10,121 INFO [HMaster] hbase.Leases(125): HMaster closed leases
[junit] 2007-12-13 12:04:10,121 INFO [HMaster] hbase.HMaster(1135): HMaster main thread exiting
[junit] 2007-12-13 12:04:10,122 INFO [main] hbase.LocalHBaseCluster(238): Shutdown HMaster 1 region server(s)
[junit] 2007-12-13 12:04:10,149 INFO [main] hbase.StaticTestEnvironment(133): Shutting down FileSystem
[junit] 2007-12-13 12:04:10,869 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: 43.377 sec
[junit] Running org.apache.hadoop.hbase.util.TestBase64
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.661 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.311 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.34 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: 55 minutes 23 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-1841
Updating HADOOP-2407
Updating HADOOP-2047