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/10/03 14:25:35 UTC
Build failed in Hudson: Hadoop-Nightly #259
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/259/changes
Changes:
[dhruba] HADOOP-1626. Improve dfsadmin help messages.
(Lohit Vijayarenu via dhruba)
[omalley] HADOOP-1970. Fix deadlock in reporting progress. (Vivek Ratan via omalley)
[omalley] A forgotten file from HADOOP-1926.
[omalley] HADOOP-1926. Add a random text writer so that we can benchmark the
performance of map/reduce using the compression codecs.
[cutting] HADOOP-1851. Permit specification of map output compression type and codec, independent of the final output's compression parameters. Contributed by Arun.
[jimk] HADOOP-1960 If a region server cannot talk to the master before its lease times out, it should shut itself down
------------------------------------------
[...truncated 58831 lines...]
[junit] 07/10/03 12:22:19 INFO hbase.Leases$LeaseMonitor: regionserver/0.0.0.0:64196.leaseChecker exiting
[junit] 07/10/03 12:22:19 INFO hbase.Leases: leases closed
[junit] 07/10/03 12:22:19 INFO hbase.HRegionServer$LogRoller: regionserver/0.0.0.0:64196.logRoller exiting
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: Added .META.,,1/info/5466832839757907005 with sequence id 25 and size 895.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush for region .META.,,1 in 567ms
[junit] 07/10/03 12:22:19 INFO hbase.HRegionServer$Flusher: regionserver/0.0.0.0:64196.cacheFlusher exiting
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region -ROOT-,,0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region -ROOT-,,0. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 INFO hbase.HRegionServer$SplitOrCompactChecker: regionserver/0.0.0.0:64196.splitOrCompactChecker exiting
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed -ROOT-,,0/info
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed -ROOT-,,0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region .META.,,1
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region .META.,,1. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed .META.,,1/info
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed .META.,,1
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region testCreateDeleteTableDblQuote,,9100698490570093943
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region testCreateDeleteTableDblQuote,,9100698490570093943. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed testCreateDeleteTableDblQuote,,9100698490570093943/testCreateDeleteTable
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed testCreateDeleteTableDblQuote,,9100698490570093943
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region testCreateDeleteTableSglQuote,,-3908671727357707695
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region testCreateDeleteTableSglQuote,,-3908671727357707695. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed testCreateDeleteTableSglQuote,,-3908671727357707695/testCreateDeleteTable
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed testCreateDeleteTableSglQuote,,-3908671727357707695
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region testCreateDeleteTabletestCreateDeleteTableDblQuote,,4632426675130609078
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region testCreateDeleteTabletestCreateDeleteTableDblQuote,,4632426675130609078. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed testCreateDeleteTabletestCreateDeleteTableDblQuote,,4632426675130609078/testCreateDeleteTableDblQuote
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed testCreateDeleteTabletestCreateDeleteTableDblQuote,,4632426675130609078
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing region testCreateDeleteTabletestCreateDeleteTableSglQuote,,-9063547483537725435
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache flush for region testCreateDeleteTabletestCreateDeleteTableSglQuote,,-9063547483537725435. Size 0.0
[junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed testCreateDeleteTabletestCreateDeleteTableSglQuote,,-9063547483537725435/testCreateDeleteTableSglQuote
[junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed testCreateDeleteTabletestCreateDeleteTableSglQuote,,-9063547483537725435
[junit] 07/10/03 12:22:19 DEBUG hbase.HLog: closing log writer in /hbase/log_140.211.11.75_64196
[junit] 07/10/03 12:22:19 INFO hbase.HRegionServer: stopping server at: 140.211.11.75:64196
[junit] 07/10/03 12:22:20 INFO hbase.HRegionServer: worker thread exiting
[junit] 07/10/03 12:22:20 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:64196, startcode: -544244152939251565, load: (requests: 0 regions: 6)]
[junit] 07/10/03 12:22:20 INFO hbase.HRegionServer: regionserver/0.0.0.0:64196 exiting
[junit] 07/10/03 12:22:21 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:64196, startcode: -544244152939251565, load: (requests: 0 regions: 6)]
[junit] 07/10/03 12:22:22 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:64196, startcode: -544244152939251565, load: (requests: 0 regions: 6)]
[junit] 07/10/03 12:22:23 INFO hbase.HMaster$RootScanner: HMaster.rootScanner exiting
[junit] 07/10/03 12:22:23 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:64196, startcode: -544244152939251565, load: (requests: 0 regions: 6)]
[junit] 07/10/03 12:22:24 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:64196, startcode: -544244152939251565, load: (requests: 0 regions: 6)]
[junit] 07/10/03 12:22:24 INFO hbase.Leases: Lease expired 1439863909/1439863909
[junit] 07/10/03 12:22:24 INFO hbase.HMaster: 140.211.11.75:64196 lease expired
[junit] 07/10/03 12:22:24 INFO hbase.Leases: closing leases
[junit] 07/10/03 12:22:24 INFO hbase.HMaster$MetaScanner: HMaster.metaScanner exiting
[junit] 07/10/03 12:22:24 INFO hbase.Leases$LeaseMonitor: HMaster.leaseChecker exiting
[junit] Exception in thread "HMaster.leaseChecker" java.lang.NoClassDefFoundError: org/apache/hadoop/hbase/HMaster$PendingServerShutdown
[junit] at org.apache.hadoop.hbase.HMaster$ServerExpirer.leaseExpired(HMaster.java:2915)
[junit] at org.apache.hadoop.hbase.Leases$Lease.expired(Leases.java:335)
[junit] at org.apache.hadoop.hbase.Leases$LeaseMonitor.chore(Leases.java:226)
[junit] at org.apache.hadoop.hbase.Chore.run(Chore.java:59)
[junit] 07/10/03 12:22:24 INFO hbase.Leases: leases closed
[junit] 07/10/03 12:22:24 INFO hbase.HMaster: HMaster main thread exiting
[junit] 07/10/03 12:22:24 INFO hbase.MiniHBaseCluster: Shutdown HMaster 1 region server(s)
[junit] 07/10/03 12:22:24 INFO hbase.MiniHBaseCluster: Shutting down Mini DFS cluster
[junit] 07/10/03 12:22:25 WARN fs.FSNamesystem: PendingReplicationMonitor thread received exception. java.lang.InterruptedException: sleep interrupted
[junit] 07/10/03 12:22:25 INFO hbase.MiniHBaseCluster: Shutting down FileSystem
[junit] 07/10/03 12:22:28 INFO hbase.HMaster: Root region dir: /hbase/hregion_-ROOT-,,0
[junit] 07/10/03 12:22:28 INFO hbase.HMaster: bootstrap: creating ROOT and first META regions
[junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer created at /hbase/hregion_-ROOT-,,0/log/hlog.dat.000
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting -ROOT-,,0/info (no reconstruction log)
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence id for hstore -ROOT-,,0/info is -1
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: maximum sequence id for region -ROOT-,,0 is 0
[junit] 07/10/03 12:22:28 INFO hbase.HRegion: region -ROOT-,,0 available
[junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer created at /hbase/hregion_.META.,,1/log/hlog.dat.000
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting .META.,,1/info (no reconstruction log)
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence id for hstore .META.,,1/info is -1
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: maximum sequence id for region .META.,,1 is 0
[junit] 07/10/03 12:22:28 INFO hbase.HRegion: region .META.,,1 available
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Started memcache flush for region -ROOT-,,0. Size 86.0
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Snapshotted memcache for region -ROOT-,,0 with sequence id 1 and entries 1
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: Added -ROOT-,,0/info/4593901703996312630 with sequence id 1 and size 210.0
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Finished memcache flush for region -ROOT-,,0 in 107ms
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: closed -ROOT-,,0/info
[junit] 07/10/03 12:22:28 INFO hbase.HRegion: closed -ROOT-,,0
[junit] 07/10/03 12:22:28 DEBUG hbase.HLog: closing log writer in /hbase/hregion_-ROOT-,,0/log
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Started memcache flush for region .META.,,1. Size 0.0
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: closed .META.,,1/info
[junit] 07/10/03 12:22:28 INFO hbase.HRegion: closed .META.,,1
[junit] 07/10/03 12:22:28 DEBUG hbase.HLog: closing log writer in /hbase/hregion_.META.,,1/log
[junit] 07/10/03 12:22:28 INFO hbase.HMaster: HMaster initialized on 0.0.0.0:60000
[junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting HRegionServers
[junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting Master:0.0.0.0:60000
[junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting regionserver/0.0.0.0:32783
[junit] 07/10/03 12:22:28 INFO shell.TestHBaseShell: Running command: create table testInsertSelectDelete (testInsertSelectDelete);
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Telling master we are up
[junit] 07/10/03 12:22:28 INFO hbase.HMaster: received start message from: 140.211.11.75:32783
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Done telling master we are up
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Config from master: fs.default.name=localhost:65509
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Config from master: hbase.rootdir=/hbase
[junit] 07/10/03 12:22:28 INFO hbase.HRegionServer: Root dir: /hbase
[junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Log dir /hbase/log_140.211.11.75_32783
[junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer created at /hbase/log_140.211.11.75_32783/hlog.dat.000
[junit] 07/10/03 12:22:28 INFO hbase.HRegionServer: HRegionServer started at: 140.211.11.75:32783
[junit] 07/10/03 12:22:28 INFO hbase.HMaster: assigning region -ROOT-,,0 to the only server 140.211.11.75:32783
[junit] 07/10/03 12:22:28 INFO hbase.HRegionServer: MSG_REGION_OPEN : regionname: -ROOT-,,0, startKey: <>, tableDesc: {name: -ROOT-, families: {info:={name: info, max versions: 1, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting -ROOT-,,0/info (no reconstruction log)
[junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence id for hstore -ROOT-,,0/info is 1
[junit] 07/10/03 12:22:29 DEBUG hbase.HRegion: maximum sequence id for region -ROOT-,,0 is 2
[junit] 07/10/03 12:22:29 INFO hbase.HRegion: region -ROOT-,,0 available
[junit] 07/10/03 12:22:29 DEBUG hbase.HLog: changing sequence number from 0 to 2
[junit] 07/10/03 12:22:29 INFO hbase.HMaster: 140.211.11.75:32783 serving -ROOT-,,0
[junit] 07/10/03 12:22:29 INFO hbase.HMaster: HMaster.rootScanner scanning meta region -ROOT-,,0 on 140.211.11.75:32783
[junit] 07/10/03 12:22:29 DEBUG hbase.HMaster: HMaster.rootScanner scanner: -3489143996008287228 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: , startCode: -1
[junit] 07/10/03 12:22:29 INFO hbase.HMaster: HMaster.rootScanner scan of meta region -ROOT-,,0 complete
[junit] 07/10/03 12:22:30 INFO hbase.HMaster: assigning region .META.,,1 to the only server 140.211.11.75:32783
[junit] 07/10/03 12:22:30 INFO hbase.HRegionServer: MSG_REGION_OPEN : 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] 07/10/03 12:22:30 DEBUG hbase.HStore: starting .META.,,1/info (no reconstruction log)
[junit] 07/10/03 12:22:30 DEBUG hbase.HStore: maximum sequence id for hstore .META.,,1/info is -1
[junit] 07/10/03 12:22:30 DEBUG hbase.HRegion: maximum sequence id for region .META.,,1 is 0
[junit] 07/10/03 12:22:30 INFO hbase.HRegion: region .META.,,1 available
[junit] 07/10/03 12:22:31 INFO hbase.HMaster: 140.211.11.75:32783 serving .META.,,1
[junit] 07/10/03 12:22:31 DEBUG hbase.HMaster: Main processing loop: PendingOpenOperation from 140.211.11.75:32783
[junit] 07/10/03 12:22:31 INFO hbase.HMaster: .META.,,1 open on 140.211.11.75:32783
[junit] 07/10/03 12:22:31 INFO hbase.HMaster: updating row .META.,,1 in table -ROOT-,,0 with startcode 7648640620389050501 and server 140.211.11.75:32783
[junit] 07/10/03 12:22:31 INFO hbase.HMaster: HMaster.metaScanner scanning meta region .META.,,1 on 140.211.11.75:32783
[junit] 07/10/03 12:22:31 INFO hbase.HMaster: HMaster.metaScanner scan of meta region .META.,,1 complete
[junit] 07/10/03 12:22:32 INFO hbase.HLog: new log writer created at /hbase/hregion_testInsertSelectDelete,,-9049404405875327904/log/hlog.dat.000
[junit] 07/10/03 12:22:32 DEBUG hbase.HStore: starting testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete (no reconstruction log)
[junit] 07/10/03 12:22:32 DEBUG hbase.HStore: maximum sequence id for hstore testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete is -1
[junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: maximum sequence id for region testInsertSelectDelete,,-9049404405875327904 is 0
[junit] 07/10/03 12:22:32 INFO hbase.HRegion: region testInsertSelectDelete,,-9049404405875327904 available
[junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: Started memcache flush for region testInsertSelectDelete,,-9049404405875327904. Size 0.0
[junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: Finished memcache flush; empty snapshot
[junit] 07/10/03 12:22:32 DEBUG hbase.HStore: closed testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
[junit] 07/10/03 12:22:32 INFO hbase.HRegion: closed testInsertSelectDelete,,-9049404405875327904
[junit] 07/10/03 12:22:32 DEBUG hbase.HLog: closing log writer in /hbase/hregion_testInsertSelectDelete,,-9049404405875327904/log
[junit] 07/10/03 12:22:33 INFO hbase.HMaster: created table testInsertSelectDelete
[junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager$TableServers: No servers for testInsertSelectDelete. Doing a find...
[junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager$TableServers: Found 1 region(s) for .META. at address: 140.211.11.75:32783, regioninfo: regionname: -ROOT-,,0, startKey: <>, tableDesc: {name: -ROOT-, families: {info:={name: info, max versions: 1, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager$TableServers: no server address for regionname: testInsertSelectDelete,,-9049404405875327904, startKey: <>, tableDesc: {name: testInsertSelectDelete, families: {testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager$TableServers: Sleeping. Table testInsertSelectDelete not currently being served.
[junit] 07/10/03 12:22:33 INFO hbase.HMaster: assigning region testInsertSelectDelete,,-9049404405875327904 to the only server 140.211.11.75:32783
[junit] 07/10/03 12:22:33 INFO hbase.HRegionServer: MSG_REGION_OPEN : regionname: testInsertSelectDelete,,-9049404405875327904, startKey: <>, tableDesc: {name: testInsertSelectDelete, families: {testInsertSelectDelete:={name: testInsertSelectDelete, max versions: 3, compression: NONE, in memory: false, max length: 2147483647, bloom filter: none}}}
[junit] 07/10/03 12:22:33 DEBUG hbase.HStore: starting testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete (no reconstruction log)
[junit] 07/10/03 12:22:33 DEBUG hbase.HStore: maximum sequence id for hstore testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete is -1
[junit] 07/10/03 12:22:33 DEBUG hbase.HRegion: maximum sequence id for region testInsertSelectDelete,,-9049404405875327904 is 0
[junit] 07/10/03 12:22:33 INFO hbase.HRegion: region testInsertSelectDelete,,-9049404405875327904 available
[junit] 07/10/03 12:22:34 INFO hbase.HMaster: 140.211.11.75:32783 serving testInsertSelectDelete,,-9049404405875327904
[junit] 07/10/03 12:22:34 DEBUG hbase.HMaster: Main processing loop: PendingOpenOperation from 140.211.11.75:32783
[junit] 07/10/03 12:22:34 INFO hbase.HMaster: testInsertSelectDelete,,-9049404405875327904 open on 140.211.11.75:32783
[junit] 07/10/03 12:22:34 INFO hbase.HMaster: updating row testInsertSelectDelete,,-9049404405875327904 in table .META.,,1 with startcode 7648640620389050501 and server 140.211.11.75:32783
[junit] 07/10/03 12:22:38 DEBUG hbase.HConnectionManager$TableServers: Wake. Retry finding table testInsertSelectDelete
[junit] 07/10/03 12:22:38 DEBUG hbase.HConnectionManager$TableServers: Found 1 region(s) for testInsertSelectDelete at address: 140.211.11.75:32783, 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] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT: Creating table... Please wait.
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running command: insert into testInsertSelectDelete (testInsertSelectDelete) values ('testInsertSelectDelete') where row='testInsertSelectDelete';
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running command: insert into testInsertSelectDelete (testInsertSelectDelete) values ('testInsertSelectDelete') where row="testInsertSelectDelete";
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running command: insert into testInsertSelectDelete (testInsertSelectDelete) values ("testInsertSelectDelete") where row="testInsertSelectDelete";
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running command: select "testInsertSelectDelete" from "testInsertSelectDelete" where row="testInsertSelectDelete";
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running command: delete "testInsertSelectDelete:" from "testInsertSelectDelete" where row="testInsertSelectDelete";
[junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
[junit] 07/10/03 12:22:38 INFO hbase.MiniHBaseCluster: Shutting down HBase Cluster
[junit] 07/10/03 12:22:39 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: Got regionserver stop message
[junit] 07/10/03 12:22:39 INFO hbase.Leases: closing leases
[junit] 07/10/03 12:22:39 INFO hbase.Leases$LeaseMonitor: regionserver/0.0.0.0:32783.leaseChecker exiting
[junit] 07/10/03 12:22:39 INFO hbase.Leases: leases closed
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing region -ROOT-,,0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache flush for region -ROOT-,,0. Size 92.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted memcache for region -ROOT-,,0 with sequence id 11 and entries 2
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer$SplitOrCompactChecker: regionserver/0.0.0.0:32783.splitOrCompactChecker exiting
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer$Flusher: regionserver/0.0.0.0:32783.cacheFlusher exiting
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer$LogRoller: regionserver/0.0.0.0:32783.logRoller exiting
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer: worker thread exiting
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added -ROOT-,,0/info/439354002766794287 with sequence id 11 and size 230.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished memcache flush for region -ROOT-,,0 in 155ms
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed -ROOT-,,0/info
[junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed -ROOT-,,0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing region .META.,,1
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache flush for region .META.,,1. Size 352.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted memcache for region .META.,,1 with sequence id 12 and entries 3
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added .META.,,1/info/6890257859301501449 with sequence id 12 and size 504.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished memcache flush for region .META.,,1 in 91ms
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed .META.,,1/info
[junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed .META.,,1
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing region testInsertSelectDelete,,-9049404405875327904
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache flush for region testInsertSelectDelete,,-9049404405875327904. Size 294.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted memcache for region testInsertSelectDelete,,-9049404405875327904 with sequence id 13 and entries 3
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete/7610235712646527016 with sequence id 13 and size 371.0
[junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished memcache flush for region testInsertSelectDelete,,-9049404405875327904 in 304ms
[junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
[junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed testInsertSelectDelete,,-9049404405875327904
[junit] 07/10/03 12:22:39 DEBUG hbase.HLog: closing log writer in /hbase/log_140.211.11.75_32783
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer: stopping server at: 140.211.11.75:32783
[junit] 07/10/03 12:22:39 INFO hbase.HRegionServer: regionserver/0.0.0.0:32783 exiting
[junit] 07/10/03 12:22:39 INFO hbase.HMaster$RootScanner: HMaster.rootScanner exiting
[junit] 07/10/03 12:22:40 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:41 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:41 INFO hbase.HMaster$MetaScanner: HMaster.metaScanner exiting
[junit] 07/10/03 12:22:42 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:43 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:44 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:45 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:46 INFO hbase.HMaster: Waiting on following regionserver(s) to go down (or region server lease expiration, whichever happens first): [address: 140.211.11.75:32783, startcode: 7648640620389050501, load: (requests: 0 regions: 3)]
[junit] 07/10/03 12:22:46 INFO hbase.Leases: Lease expired 1437039496/1437039496
[junit] 07/10/03 12:22:46 INFO hbase.HMaster: 140.211.11.75:32783 lease expired
[junit] 07/10/03 12:22:46 INFO hbase.Leases: closing leases
[junit] 07/10/03 12:22:46 INFO hbase.Leases$LeaseMonitor: HMaster.leaseChecker exiting
[junit] Exception in thread "HMaster.leaseChecker" java.lang.NoClassDefFoundError: org/apache/hadoop/hbase/HMaster$PendingServerShutdown
[junit] at org.apache.hadoop.hbase.HMaster$ServerExpirer.leaseExpired(HMaster.java:2915)
[junit] at org.apache.hadoop.hbase.Leases$Lease.expired(Leases.java:335)
[junit] at org.apache.hadoop.hbase.Leases$LeaseMonitor.chore(Leases.java:226)
[junit] at org.apache.hadoop.hbase.Chore.run(Chore.java:59)
[junit] 07/10/03 12:22:46 INFO hbase.Leases: leases closed
[junit] 07/10/03 12:22:46 INFO hbase.HMaster: HMaster main thread exiting
[junit] 07/10/03 12:22:46 INFO hbase.MiniHBaseCluster: Shutdown HMaster 1 region server(s)
[junit] 07/10/03 12:22:46 INFO hbase.MiniHBaseCluster: Shutting down Mini DFS cluster
[junit] 07/10/03 12:22:47 WARN fs.FSNamesystem: PendingReplicationMonitor thread received exception. java.lang.InterruptedException: sleep interrupted
[junit] 07/10/03 12:22:47 INFO hbase.MiniHBaseCluster: Shutting down FileSystem
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 91.524 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.139 sec
[junit] Running org.onelab.test.TestFilter
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.395 sec
BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :510: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/build.xml :23: The following error occurred while executing this line:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/src/contrib/hbase/build.xml :102: 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 :205: Tests failed!
Total time: 71 minutes 59 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-1926
Updating HADOOP-1626
Updating HADOOP-1851
Updating HADOOP-1970
Updating HADOOP-1960
Fwd: Build failed in Hudson: Hadoop-Nightly #259
Posted by Nigel Daley <nd...@yahoo-inc.com>.
Jim or Michael, can you look at the hbase failures from the last 2
nightly builds:
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/259/
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/258/
Thanks,
Nige
Begin forwarded message:
> From: hudson@lucene.zones.apache.org
> Date: October 3, 2007 5:25:35 AM PDT
> To: hadoop-dev@lucene.apache.org
> Subject: Build failed in Hudson: Hadoop-Nightly #259
> Reply-To: hadoop-dev@lucene.apache.org
>
> See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/
> 259/changes
>
> Changes:
>
> [dhruba] HADOOP-1626. Improve dfsadmin help messages.
> (Lohit Vijayarenu via dhruba)
>
> [omalley] HADOOP-1970. Fix deadlock in reporting progress. (Vivek
> Ratan via omalley)
>
> [omalley] A forgotten file from HADOOP-1926.
>
> [omalley] HADOOP-1926. Add a random text writer so that we can
> benchmark the
> performance of map/reduce using the compression codecs.
>
> [cutting] HADOOP-1851. Permit specification of map output
> compression type and codec, independent of the final output's
> compression parameters. Contributed by Arun.
>
> [jimk] HADOOP-1960 If a region server cannot talk to the master
> before its lease times out, it should shut itself down
>
> ------------------------------------------
> [...truncated 58831 lines...]
> [junit] 07/10/03 12:22:19 INFO hbase.Leases$LeaseMonitor:
> regionserver/0.0.0.0:64196.leaseChecker exiting
> [junit] 07/10/03 12:22:19 INFO hbase.Leases: leases closed
> [junit] 07/10/03 12:22:19 INFO hbase.HRegionServer$LogRoller:
> regionserver/0.0.0.0:64196.logRoller exiting
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: Added .META.,,1/
> info/5466832839757907005 with sequence id 25 and size 895.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush for region .META.,,1 in 567ms
> [junit] 07/10/03 12:22:19 INFO hbase.HRegionServer$Flusher:
> regionserver/0.0.0.0:64196.cacheFlusher exiting
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region -ROOT-,,0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region -ROOT-,,0. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 INFO hbase.HRegionServer
> $SplitOrCompactChecker: regionserver/
> 0.0.0.0:64196.splitOrCompactChecker exiting
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed -ROOT-,,0/
> info
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed -ROOT-,,0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region .META.,,1
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region .META.,,1. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed .META.,,1/
> info
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed .META.,,1
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region testCreateDeleteTableDblQuote,,9100698490570093943
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region testCreateDeleteTableDblQuote,,
> 9100698490570093943. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed
> testCreateDeleteTableDblQuote,,9100698490570093943/
> testCreateDeleteTable
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed
> testCreateDeleteTableDblQuote,,9100698490570093943
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region testCreateDeleteTableSglQuote,,-3908671727357707695
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region
> testCreateDeleteTableSglQuote,,-3908671727357707695. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed
> testCreateDeleteTableSglQuote,,-3908671727357707695/
> testCreateDeleteTable
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed
> testCreateDeleteTableSglQuote,,-3908671727357707695
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region testCreateDeleteTabletestCreateDeleteTableDblQuote,,
> 4632426675130609078
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region
> testCreateDeleteTabletestCreateDeleteTableDblQuote,,
> 4632426675130609078. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed
> testCreateDeleteTabletestCreateDeleteTableDblQuote,,
> 4632426675130609078/testCreateDeleteTableDblQuote
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed
> testCreateDeleteTabletestCreateDeleteTableDblQuote,,
> 4632426675130609078
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegionServer: closing
> region
> testCreateDeleteTabletestCreateDeleteTableSglQuote,,-90635474835377254
> 35
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Started memcache
> flush for region
> testCreateDeleteTabletestCreateDeleteTableSglQuote,,-90635474835377254
> 35. Size 0.0
> [junit] 07/10/03 12:22:19 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:19 DEBUG hbase.HStore: closed
> testCreateDeleteTabletestCreateDeleteTableSglQuote,,-90635474835377254
> 35/testCreateDeleteTableSglQuote
> [junit] 07/10/03 12:22:19 INFO hbase.HRegion: closed
> testCreateDeleteTabletestCreateDeleteTableSglQuote,,-90635474835377254
> 35
> [junit] 07/10/03 12:22:19 DEBUG hbase.HLog: closing log writer
> in /hbase/log_140.211.11.75_64196
> [junit] 07/10/03 12:22:19 INFO hbase.HRegionServer: stopping
> server at: 140.211.11.75:64196
> [junit] 07/10/03 12:22:20 INFO hbase.HRegionServer: worker
> thread exiting
> [junit] 07/10/03 12:22:20 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:64196, startcode: -544244152939251565, load:
> (requests: 0 regions: 6)]
> [junit] 07/10/03 12:22:20 INFO hbase.HRegionServer:
> regionserver/0.0.0.0:64196 exiting
> [junit] 07/10/03 12:22:21 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:64196, startcode: -544244152939251565, load:
> (requests: 0 regions: 6)]
> [junit] 07/10/03 12:22:22 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:64196, startcode: -544244152939251565, load:
> (requests: 0 regions: 6)]
> [junit] 07/10/03 12:22:23 INFO hbase.HMaster$RootScanner:
> HMaster.rootScanner exiting
> [junit] 07/10/03 12:22:23 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:64196, startcode: -544244152939251565, load:
> (requests: 0 regions: 6)]
> [junit] 07/10/03 12:22:24 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:64196, startcode: -544244152939251565, load:
> (requests: 0 regions: 6)]
> [junit] 07/10/03 12:22:24 INFO hbase.Leases: Lease expired
> 1439863909/1439863909
> [junit] 07/10/03 12:22:24 INFO hbase.HMaster:
> 140.211.11.75:64196 lease expired
> [junit] 07/10/03 12:22:24 INFO hbase.Leases: closing leases
> [junit] 07/10/03 12:22:24 INFO hbase.HMaster$MetaScanner:
> HMaster.metaScanner exiting
> [junit] 07/10/03 12:22:24 INFO hbase.Leases$LeaseMonitor:
> HMaster.leaseChecker exiting
> [junit] Exception in thread "HMaster.leaseChecker"
> java.lang.NoClassDefFoundError: org/apache/hadoop/hbase/HMaster
> $PendingServerShutdown
> [junit] at org.apache.hadoop.hbase.HMaster
> $ServerExpirer.leaseExpired(HMaster.java:2915)
> [junit] at org.apache.hadoop.hbase.Leases$Lease.expired
> (Leases.java:335)
> [junit] at org.apache.hadoop.hbase.Leases$LeaseMonitor.chore
> (Leases.java:226)
> [junit] at org.apache.hadoop.hbase.Chore.run(Chore.java:59)
> [junit] 07/10/03 12:22:24 INFO hbase.Leases: leases closed
> [junit] 07/10/03 12:22:24 INFO hbase.HMaster: HMaster main
> thread exiting
> [junit] 07/10/03 12:22:24 INFO hbase.MiniHBaseCluster: Shutdown
> HMaster 1 region server(s)
> [junit] 07/10/03 12:22:24 INFO hbase.MiniHBaseCluster: Shutting
> down Mini DFS cluster
> [junit] 07/10/03 12:22:25 WARN fs.FSNamesystem:
> PendingReplicationMonitor thread received exception.
> java.lang.InterruptedException: sleep interrupted
> [junit] 07/10/03 12:22:25 INFO hbase.MiniHBaseCluster: Shutting
> down FileSystem
> [junit] 07/10/03 12:22:28 INFO hbase.HMaster: Root region dir: /
> hbase/hregion_-ROOT-,,0
> [junit] 07/10/03 12:22:28 INFO hbase.HMaster: bootstrap:
> creating ROOT and first META regions
> [junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer
> created at /hbase/hregion_-ROOT-,,0/log/hlog.dat.000
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting -ROOT-,,
> 0/info (no reconstruction log)
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence
> id for hstore -ROOT-,,0/info is -1
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: maximum sequence
> id for region -ROOT-,,0 is 0
> [junit] 07/10/03 12:22:28 INFO hbase.HRegion: region -ROOT-,,0
> available
> [junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer
> created at /hbase/hregion_.META.,,1/log/hlog.dat.000
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting .META.,,
> 1/info (no reconstruction log)
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence
> id for hstore .META.,,1/info is -1
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: maximum sequence
> id for region .META.,,1 is 0
> [junit] 07/10/03 12:22:28 INFO hbase.HRegion: region .META.,,1
> available
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Started memcache
> flush for region -ROOT-,,0. Size 86.0
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Snapshotted
> memcache for region -ROOT-,,0 with sequence id 1 and entries 1
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: Added -ROOT-,,0/
> info/4593901703996312630 with sequence id 1 and size 210.0
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Finished
> memcache flush for region -ROOT-,,0 in 107ms
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: closed -ROOT-,,0/
> info
> [junit] 07/10/03 12:22:28 INFO hbase.HRegion: closed -ROOT-,,0
> [junit] 07/10/03 12:22:28 DEBUG hbase.HLog: closing log writer
> in /hbase/hregion_-ROOT-,,0/log
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Started memcache
> flush for region .META.,,1. Size 0.0
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: closed .META.,,1/
> info
> [junit] 07/10/03 12:22:28 INFO hbase.HRegion: closed .META.,,1
> [junit] 07/10/03 12:22:28 DEBUG hbase.HLog: closing log writer
> in /hbase/hregion_.META.,,1/log
> [junit] 07/10/03 12:22:28 INFO hbase.HMaster: HMaster
> initialized on 0.0.0.0:60000
> [junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting
> HRegionServers
> [junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting
> Master:0.0.0.0:60000
> [junit] 07/10/03 12:22:28 INFO hbase.MiniHBaseCluster: Starting
> regionserver/0.0.0.0:32783
> [junit] 07/10/03 12:22:28 INFO shell.TestHBaseShell: Running
> command: create table testInsertSelectDelete (testInsertSelectDelete);
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Telling
> master we are up
> [junit] 07/10/03 12:22:28 INFO hbase.HMaster: received start
> message from: 140.211.11.75:32783
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Done
> telling master we are up
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Config
> from master: fs.default.name=localhost:65509
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Config
> from master: hbase.rootdir=/hbase
> [junit] 07/10/03 12:22:28 INFO hbase.HRegionServer: Root dir: /
> hbase
> [junit] 07/10/03 12:22:28 DEBUG hbase.HRegionServer: Log dir /
> hbase/log_140.211.11.75_32783
> [junit] 07/10/03 12:22:28 INFO hbase.HLog: new log writer
> created at /hbase/log_140.211.11.75_32783/hlog.dat.000
> [junit] 07/10/03 12:22:28 INFO hbase.HRegionServer:
> HRegionServer started at: 140.211.11.75:32783
> [junit] 07/10/03 12:22:28 INFO hbase.HMaster: assigning region -
> ROOT-,,0 to the only server 140.211.11.75:32783
> [junit] 07/10/03 12:22:28 INFO hbase.HRegionServer:
> MSG_REGION_OPEN : regionname: -ROOT-,,0, startKey: <>, tableDesc:
> {name: -ROOT-, families: {info:={name: info, max versions: 1,
> compression: NONE, in memory: false, max length: 2147483647, bloom
> filter: none}}}
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: starting -ROOT-,,
> 0/info (no reconstruction log)
> [junit] 07/10/03 12:22:28 DEBUG hbase.HStore: maximum sequence
> id for hstore -ROOT-,,0/info is 1
> [junit] 07/10/03 12:22:29 DEBUG hbase.HRegion: maximum sequence
> id for region -ROOT-,,0 is 2
> [junit] 07/10/03 12:22:29 INFO hbase.HRegion: region -ROOT-,,0
> available
> [junit] 07/10/03 12:22:29 DEBUG hbase.HLog: changing sequence
> number from 0 to 2
> [junit] 07/10/03 12:22:29 INFO hbase.HMaster:
> 140.211.11.75:32783 serving -ROOT-,,0
> [junit] 07/10/03 12:22:29 INFO hbase.HMaster:
> HMaster.rootScanner scanning meta region -ROOT-,,0 on
> 140.211.11.75:32783
> [junit] 07/10/03 12:22:29 DEBUG hbase.HMaster:
> HMaster.rootScanner scanner: -3489143996008287228 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: , startCode: -1
> [junit] 07/10/03 12:22:29 INFO hbase.HMaster:
> HMaster.rootScanner scan of meta region -ROOT-,,0 complete
> [junit] 07/10/03 12:22:30 INFO hbase.HMaster: assigning
> region .META.,,1 to the only server 140.211.11.75:32783
> [junit] 07/10/03 12:22:30 INFO hbase.HRegionServer:
> MSG_REGION_OPEN : 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] 07/10/03 12:22:30 DEBUG hbase.HStore: starting .META.,,
> 1/info (no reconstruction log)
> [junit] 07/10/03 12:22:30 DEBUG hbase.HStore: maximum sequence
> id for hstore .META.,,1/info is -1
> [junit] 07/10/03 12:22:30 DEBUG hbase.HRegion: maximum sequence
> id for region .META.,,1 is 0
> [junit] 07/10/03 12:22:30 INFO hbase.HRegion: region .META.,,1
> available
> [junit] 07/10/03 12:22:31 INFO hbase.HMaster:
> 140.211.11.75:32783 serving .META.,,1
> [junit] 07/10/03 12:22:31 DEBUG hbase.HMaster: Main processing
> loop: PendingOpenOperation from 140.211.11.75:32783
> [junit] 07/10/03 12:22:31 INFO hbase.HMaster: .META.,,1 open on
> 140.211.11.75:32783
> [junit] 07/10/03 12:22:31 INFO hbase.HMaster: updating
> row .META.,,1 in table -ROOT-,,0 with startcode 7648640620389050501
> and server 140.211.11.75:32783
> [junit] 07/10/03 12:22:31 INFO hbase.HMaster:
> HMaster.metaScanner scanning meta region .META.,,1 on
> 140.211.11.75:32783
> [junit] 07/10/03 12:22:31 INFO hbase.HMaster:
> HMaster.metaScanner scan of meta region .META.,,1 complete
> [junit] 07/10/03 12:22:32 INFO hbase.HLog: new log writer
> created at /hbase/
> hregion_testInsertSelectDelete,,-9049404405875327904/log/hlog.dat.000
> [junit] 07/10/03 12:22:32 DEBUG hbase.HStore: starting
> testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
> (no reconstruction log)
> [junit] 07/10/03 12:22:32 DEBUG hbase.HStore: maximum sequence
> id for hstore testInsertSelectDelete,,-9049404405875327904/
> testInsertSelectDelete is -1
> [junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: maximum sequence
> id for region testInsertSelectDelete,,-9049404405875327904 is 0
> [junit] 07/10/03 12:22:32 INFO hbase.HRegion: region
> testInsertSelectDelete,,-9049404405875327904 available
> [junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: Started memcache
> flush for region testInsertSelectDelete,,-9049404405875327904. Size
> 0.0
> [junit] 07/10/03 12:22:32 DEBUG hbase.HRegion: Finished
> memcache flush; empty snapshot
> [junit] 07/10/03 12:22:32 DEBUG hbase.HStore: closed
> testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
> [junit] 07/10/03 12:22:32 INFO hbase.HRegion: closed
> testInsertSelectDelete,,-9049404405875327904
> [junit] 07/10/03 12:22:32 DEBUG hbase.HLog: closing log writer
> in /hbase/hregion_testInsertSelectDelete,,-9049404405875327904/log
> [junit] 07/10/03 12:22:33 INFO hbase.HMaster: created table
> testInsertSelectDelete
> [junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager
> $TableServers: No servers for testInsertSelectDelete. Doing a find...
> [junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager
> $TableServers: Found 1 region(s) for .META. at address:
> 140.211.11.75:32783, regioninfo: regionname: -ROOT-,,0, startKey:
> <>, tableDesc: {name: -ROOT-, families: {info:={name: info, max
> versions: 1, compression: NONE, in memory: false, max length:
> 2147483647, bloom filter: none}}}
> [junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager
> $TableServers: no server address for regionname:
> testInsertSelectDelete,,-9049404405875327904, startKey: <>,
> tableDesc: {name: testInsertSelectDelete, families:
> {testInsertSelectDelete:={name: testInsertSelectDelete, max
> versions: 3, compression: NONE, in memory: false, max length:
> 2147483647, bloom filter: none}}}
> [junit] 07/10/03 12:22:33 DEBUG hbase.HConnectionManager
> $TableServers: Sleeping. Table testInsertSelectDelete not currently
> being served.
> [junit] 07/10/03 12:22:33 INFO hbase.HMaster: assigning region
> testInsertSelectDelete,,-9049404405875327904 to the only server
> 140.211.11.75:32783
> [junit] 07/10/03 12:22:33 INFO hbase.HRegionServer:
> MSG_REGION_OPEN : regionname:
> testInsertSelectDelete,,-9049404405875327904, startKey: <>,
> tableDesc: {name: testInsertSelectDelete, families:
> {testInsertSelectDelete:={name: testInsertSelectDelete, max
> versions: 3, compression: NONE, in memory: false, max length:
> 2147483647, bloom filter: none}}}
> [junit] 07/10/03 12:22:33 DEBUG hbase.HStore: starting
> testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
> (no reconstruction log)
> [junit] 07/10/03 12:22:33 DEBUG hbase.HStore: maximum sequence
> id for hstore testInsertSelectDelete,,-9049404405875327904/
> testInsertSelectDelete is -1
> [junit] 07/10/03 12:22:33 DEBUG hbase.HRegion: maximum sequence
> id for region testInsertSelectDelete,,-9049404405875327904 is 0
> [junit] 07/10/03 12:22:33 INFO hbase.HRegion: region
> testInsertSelectDelete,,-9049404405875327904 available
> [junit] 07/10/03 12:22:34 INFO hbase.HMaster:
> 140.211.11.75:32783 serving
> testInsertSelectDelete,,-9049404405875327904
> [junit] 07/10/03 12:22:34 DEBUG hbase.HMaster: Main processing
> loop: PendingOpenOperation from 140.211.11.75:32783
> [junit] 07/10/03 12:22:34 INFO hbase.HMaster:
> testInsertSelectDelete,,-9049404405875327904 open on
> 140.211.11.75:32783
> [junit] 07/10/03 12:22:34 INFO hbase.HMaster: updating row
> testInsertSelectDelete,,-9049404405875327904 in table .META.,,1
> with startcode 7648640620389050501 and server 140.211.11.75:32783
> [junit] 07/10/03 12:22:38 DEBUG hbase.HConnectionManager
> $TableServers: Wake. Retry finding table testInsertSelectDelete
> [junit] 07/10/03 12:22:38 DEBUG hbase.HConnectionManager
> $TableServers: Found 1 region(s) for testInsertSelectDelete at
> address: 140.211.11.75:32783, 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] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> Creating table... Please wait.
>
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running
> command: insert into testInsertSelectDelete
> (testInsertSelectDelete) values ('testInsertSelectDelete') where
> row='testInsertSelectDelete';
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running
> command: insert into testInsertSelectDelete
> (testInsertSelectDelete) values ('testInsertSelectDelete') where
> row="testInsertSelectDelete";
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running
> command: insert into testInsertSelectDelete
> (testInsertSelectDelete) values ("testInsertSelectDelete") where
> row="testInsertSelectDelete";
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running
> command: select "testInsertSelectDelete" from
> "testInsertSelectDelete" where row="testInsertSelectDelete";
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: Running
> command: delete "testInsertSelectDelete:" from
> "testInsertSelectDelete" where row="testInsertSelectDelete";
> [junit] 07/10/03 12:22:38 INFO shell.TestHBaseShell: STDOUT:
> [junit] 07/10/03 12:22:38 INFO hbase.MiniHBaseCluster: Shutting
> down HBase Cluster
> [junit] 07/10/03 12:22:39 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: Got
> regionserver stop message
> [junit] 07/10/03 12:22:39 INFO hbase.Leases: closing leases
> [junit] 07/10/03 12:22:39 INFO hbase.Leases$LeaseMonitor:
> regionserver/0.0.0.0:32783.leaseChecker exiting
> [junit] 07/10/03 12:22:39 INFO hbase.Leases: leases closed
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing
> region -ROOT-,,0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache
> flush for region -ROOT-,,0. Size 92.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted
> memcache for region -ROOT-,,0 with sequence id 11 and entries 2
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer
> $SplitOrCompactChecker: regionserver/
> 0.0.0.0:32783.splitOrCompactChecker exiting
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer$Flusher:
> regionserver/0.0.0.0:32783.cacheFlusher exiting
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer$LogRoller:
> regionserver/0.0.0.0:32783.logRoller exiting
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer: worker
> thread exiting
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added -ROOT-,,0/
> info/439354002766794287 with sequence id 11 and size 230.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished
> memcache flush for region -ROOT-,,0 in 155ms
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed -ROOT-,,0/
> info
> [junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed -ROOT-,,0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing
> region .META.,,1
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache
> flush for region .META.,,1. Size 352.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted
> memcache for region .META.,,1 with sequence id 12 and entries 3
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added .META.,,1/
> info/6890257859301501449 with sequence id 12 and size 504.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished
> memcache flush for region .META.,,1 in 91ms
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed .META.,,1/
> info
> [junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed .META.,,1
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegionServer: closing
> region testInsertSelectDelete,,-9049404405875327904
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Started memcache
> flush for region testInsertSelectDelete,,-9049404405875327904. Size
> 294.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Snapshotted
> memcache for region testInsertSelectDelete,,-9049404405875327904
> with sequence id 13 and entries 3
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: Added
> testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete/
> 7610235712646527016 with sequence id 13 and size 371.0
> [junit] 07/10/03 12:22:39 DEBUG hbase.HRegion: Finished
> memcache flush for region
> testInsertSelectDelete,,-9049404405875327904 in 304ms
> [junit] 07/10/03 12:22:39 DEBUG hbase.HStore: closed
> testInsertSelectDelete,,-9049404405875327904/testInsertSelectDelete
> [junit] 07/10/03 12:22:39 INFO hbase.HRegion: closed
> testInsertSelectDelete,,-9049404405875327904
> [junit] 07/10/03 12:22:39 DEBUG hbase.HLog: closing log writer
> in /hbase/log_140.211.11.75_32783
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer: stopping
> server at: 140.211.11.75:32783
> [junit] 07/10/03 12:22:39 INFO hbase.HRegionServer:
> regionserver/0.0.0.0:32783 exiting
> [junit] 07/10/03 12:22:39 INFO hbase.HMaster$RootScanner:
> HMaster.rootScanner exiting
> [junit] 07/10/03 12:22:40 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:41 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:41 INFO hbase.HMaster$MetaScanner:
> HMaster.metaScanner exiting
> [junit] 07/10/03 12:22:42 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:43 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:44 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:45 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:46 INFO hbase.HMaster: Waiting on
> following regionserver(s) to go down (or region server lease
> expiration, whichever happens first): [address:
> 140.211.11.75:32783, startcode: 7648640620389050501, load:
> (requests: 0 regions: 3)]
> [junit] 07/10/03 12:22:46 INFO hbase.Leases: Lease expired
> 1437039496/1437039496
> [junit] 07/10/03 12:22:46 INFO hbase.HMaster:
> 140.211.11.75:32783 lease expired
> [junit] 07/10/03 12:22:46 INFO hbase.Leases: closing leases
> [junit] 07/10/03 12:22:46 INFO hbase.Leases$LeaseMonitor:
> HMaster.leaseChecker exiting
> [junit] Exception in thread "HMaster.leaseChecker"
> java.lang.NoClassDefFoundError: org/apache/hadoop/hbase/HMaster
> $PendingServerShutdown
> [junit] at org.apache.hadoop.hbase.HMaster
> $ServerExpirer.leaseExpired(HMaster.java:2915)
> [junit] at org.apache.hadoop.hbase.Leases$Lease.expired
> (Leases.java:335)
> [junit] at org.apache.hadoop.hbase.Leases$LeaseMonitor.chore
> (Leases.java:226)
> [junit] at org.apache.hadoop.hbase.Chore.run(Chore.java:59)
> [junit] 07/10/03 12:22:46 INFO hbase.Leases: leases closed
> [junit] 07/10/03 12:22:46 INFO hbase.HMaster: HMaster main
> thread exiting
> [junit] 07/10/03 12:22:46 INFO hbase.MiniHBaseCluster: Shutdown
> HMaster 1 region server(s)
> [junit] 07/10/03 12:22:46 INFO hbase.MiniHBaseCluster: Shutting
> down Mini DFS cluster
> [junit] 07/10/03 12:22:47 WARN fs.FSNamesystem:
> PendingReplicationMonitor thread received exception.
> java.lang.InterruptedException: sleep interrupted
> [junit] 07/10/03 12:22:47 INFO hbase.MiniHBaseCluster: Shutting
> down FileSystem
> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed:
> 91.524 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.139 sec
> [junit] Running org.onelab.test.TestFilter
> [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed:
> 0.395 sec
>
> BUILD FAILED
> http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/
> trunk/build.xml :510: The following error occurred while executing
> this line:
> http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/
> trunk/src/contrib/build.xml :23: The following error occurred while
> executing this line:
> http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/
> trunk/src/contrib/hbase/build.xml :102: 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 :205: Tests failed!
>
> Total time: 71 minutes 59 seconds
> Recording fingerprints
> Publishing Javadoc
> Recording test results
> Updating HADOOP-1926
> Updating HADOOP-1626
> Updating HADOOP-1851
> Updating HADOOP-1970
> Updating HADOOP-1960
>