hbase-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: HBase-Patch #646
Date Wed, 17 Jun 2009 06:03:35 GMT
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/646/changes

Changes:

[stack] Move past alpha version

[stack] HBASE-1329 Visibility into ZooKeeper

[stack] set version for alpha release

[stack] HBASE-1329 Visibility into ZooKeeper

------------------------------------------
[...truncated 23632 lines...]
    [junit] 
    [junit] 2009-06-17 06:24:58,704 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@18d4c9a]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] 2009-06-17 06:24:59.553::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:24:59.559::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
 to /tmp/Jetty_localhost_37415_hdfs____5uye5a/webapp
    [junit] 2009-06-17 06:24:59.733::INFO:  Started SelectChannelConnector@localhost:37415
    [junit] Starting DataNode 0 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2

    [junit] 2009-06-17 06:25:00.230::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:00.236::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_52626_datanode____.jo5aak/webapp
    [junit] 2009-06-17 06:25:00.404::INFO:  Started SelectChannelConnector@localhost:52626
    [junit] Starting DataNode 1 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4

    [junit] 2009-06-17 06:25:00.911::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:00.917::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_40241_datanode____opgxwa/webapp
    [junit] 2009-06-17 06:25:01.082::INFO:  Started SelectChannelConnector@localhost:40241
    [junit] 2009-06-17 06:25:01,252 INFO  [main] regionserver.HLog(209): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
    [junit] 2009-06-17 06:25:01,261 INFO  [main] regionserver.HLog(299): New hlog /user/hudson/testscanner/1682632464/.logs/hlog.dat.1245219901253
    [junit] 2009-06-17 06:25:01,261 DEBUG [main] regionserver.HRegion(264): Opening region
testscanner,,1245219901234, encoded=1682632464
    [junit] 2009-06-17 06:25:01,291 INFO  [main] regionserver.HRegion(339): region testscanner,,1245219901234/1682632464
available; sequence id is 0
    [junit] 2009-06-17 06:25:01,855 INFO  [main] regionserver.TestScanner(370): Added: 17576
    [junit] 2009-06-17 06:25:01,855 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-17 06:25:02,747 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-17 06:25:02,748 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-17 06:25:02,751 INFO  [main] regionserver.TestScanner(432): Starting flush
at flush index 100
    [junit] 2009-06-17 06:25:02,752 DEBUG [main] regionserver.HRegion(884): Started memcache
flush for region testscanner,,1245219901234. Current region memcache size 2.6m
    [junit] 2009-06-17 06:25:02,913 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:42349/user/hudson/testscanner/1682632464/info/591614886643743534,
entries=17576, sequenceid=17577, memsize=2.6m, filesize=691.0k to testscanner,,1245219901234
    [junit] 2009-06-17 06:25:02,915 DEBUG [main] regionserver.HRegion(961): Finished memcache
flush of ~2.6m for region testscanner,,1245219901234 in 163ms, sequence id=17577, compaction
requested=false
    [junit] 2009-06-17 06:25:02,915 INFO  [main] regionserver.TestScanner$1(437): Finishing
flush
    [junit] 2009-06-17 06:25:02,915 INFO  [main] regionserver.TestScanner(448): Continuing
on after kicking off background flush
    [junit] 2009-06-17 06:25:02,915 INFO  [main] regionserver.TestScanner(427): after next()
just after next flush
    [junit] 2009-06-17 06:25:03,616 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-17 06:25:03,616 DEBUG [main] regionserver.HRegion(436): Closing testscanner,,1245219901234:
compactions & flushes disabled 
    [junit] 2009-06-17 06:25:03,616 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on testscanner,,1245219901234
    [junit] 2009-06-17 06:25:03,617 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region testscanner,,1245219901234
    [junit] 2009-06-17 06:25:03,617 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-17 06:25:03,617 INFO  [main] regionserver.HRegion(485): Closed testscanner,,1245219901234
    [junit] 2009-06-17 06:25:03,617 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:42349/user/hudson/testscanner/1682632464/.logs
    [junit] 2009-06-17 06:25:03,645 INFO  [main] hbase.HBaseTestCase(626): Shutting down FileSystem
    [junit] 2009-06-17 06:25:03,645 INFO  [main] hbase.HBaseTestCase(633): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-17 06:25:03,783 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@16d3046]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:60968, storageID=DS-1036161422-67.195.138.9-60968-1245219901086,
infoPort=40241, ipcPort=50681):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] Shutting down DataNode 0
    [junit] 2009-06-17 06:25:04,888 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1bb9533]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:34766, storageID=DS-1005150283-67.195.138.9-34766-1245219900408,
infoPort=52626, ipcPort=55436):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2009-06-17 06:25:05,092 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@189cab]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] 2009-06-17 06:25:05.958::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:05.963::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
 to /tmp/Jetty_localhost_37642_hdfs____qckr22/webapp
    [junit] 2009-06-17 06:25:06.134::INFO:  Started SelectChannelConnector@localhost:37642
    [junit] Starting DataNode 0 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2

    [junit] 2009-06-17 06:25:06.669::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:06.674::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_50979_datanode____fnen2f/webapp
    [junit] 2009-06-17 06:25:06.837::INFO:  Started SelectChannelConnector@localhost:50979
    [junit] Starting DataNode 1 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4

    [junit] 2009-06-17 06:25:07.322::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:07.328::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_42928_datanode____n1fi3s/webapp
    [junit] 2009-06-17 06:25:07.490::INFO:  Started SelectChannelConnector@localhost:42928
    [junit] 2009-06-17 06:25:07,639 INFO  [main] regionserver.HLog(209): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
    [junit] 2009-06-17 06:25:07,652 INFO  [main] regionserver.HLog(299): New hlog /user/hudson/testscanner/525044773/.logs/hlog.dat.1245219907640
    [junit] 2009-06-17 06:25:07,652 DEBUG [main] regionserver.HRegion(264): Opening region
testscanner,,1245219907616, encoded=525044773
    [junit] 2009-06-17 06:25:07,682 INFO  [main] regionserver.HRegion(339): region testscanner,,1245219907616/525044773
available; sequence id is 0
    [junit] 2009-06-17 06:25:07,924 INFO  [main] regionserver.TestScanner(394): Added: 17576
    [junit] 2009-06-17 06:25:07,924 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-17 06:25:08,425 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-17 06:25:08,426 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-17 06:25:08,429 INFO  [main] regionserver.TestScanner(432): Starting flush
at flush index 100
    [junit] 2009-06-17 06:25:08,429 INFO  [main] regionserver.TestScanner(448): Continuing
on after kicking off background flush
    [junit] 2009-06-17 06:25:08,429 DEBUG [Thread-334] regionserver.HRegion(884): Started
memcache flush for region testscanner,,1245219907616. Current region memcache size 2.6m
    [junit] 2009-06-17 06:25:08,429 INFO  [main] regionserver.TestScanner(427): after next()
just after next flush
    [junit] 2009-06-17 06:25:08,552 DEBUG [Thread-334] regionserver.Store(526): Added hdfs://localhost:42247/user/hudson/testscanner/525044773/info/2837628977765680963,
entries=17576, sequenceid=17577, memsize=2.6m, filesize=691.0k to testscanner,,1245219907616
    [junit] 2009-06-17 06:25:08,554 DEBUG [Thread-334] regionserver.HRegion(961): Finished
memcache flush of ~2.6m for region testscanner,,1245219907616 in 125ms, sequence id=17577,
compaction requested=false
    [junit] 2009-06-17 06:25:08,554 INFO  [Thread-334] regionserver.TestScanner$1(437): Finishing
flush
    [junit] 2009-06-17 06:25:08,997 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-17 06:25:08,998 DEBUG [main] regionserver.HRegion(436): Closing testscanner,,1245219907616:
compactions & flushes disabled 
    [junit] 2009-06-17 06:25:08,998 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on testscanner,,1245219907616
    [junit] 2009-06-17 06:25:08,998 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region testscanner,,1245219907616
    [junit] 2009-06-17 06:25:08,999 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-17 06:25:09,000 INFO  [main] regionserver.HRegion(485): Closed testscanner,,1245219907616
    [junit] 2009-06-17 06:25:09,000 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:42247/user/hudson/testscanner/525044773/.logs
    [junit] 2009-06-17 06:25:09,028 INFO  [main] hbase.HBaseTestCase(626): Shutting down FileSystem
    [junit] 2009-06-17 06:25:09,028 INFO  [main] hbase.HBaseTestCase(633): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-17 06:25:09,136 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@6dd60e]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:36104, storageID=DS-1445922656-67.195.138.9-36104-1245219907492,
infoPort=42928, ipcPort=46217):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] Shutting down DataNode 0
    [junit] 2009-06-17 06:25:10,271 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@28bda]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:53096, storageID=DS-898589614-67.195.138.9-53096-1245219906840,
infoPort=50979, ipcPort=59647):DataXceiveServer: java.nio.channels.AsynchronousCloseException
    [junit] 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
    [junit] 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
    [junit] 	at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
    [junit] 	at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
    [junit] 	at java.lang.Thread.run(Thread.java:619)
    [junit] 
    [junit] 2009-06-17 06:25:11,473 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@128647a]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 70.322 sec
    [junit] Running org.apache.hadoop.hbase.regionserver.TestStore
    [junit] 2009-06-17 06:25:12,279 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/625128906/family/5383074947363245313,
entries=2, sequenceid=1245219911840, memsize=298.0, filesize=385.0 to table,,1245219912220
    [junit] 2009-06-17 06:25:12,304 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/625128906/family/3891089324708367243,
entries=2, sequenceid=1245219911841, memsize=298.0, filesize=385.0 to table,,1245219912220
    [junit] 2009-06-17 06:25:12,318 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/625128906/family/4214217589540847581,
entries=2, sequenceid=1245219911842, memsize=298.0, filesize=385.0 to table,,1245219912220
    [junit] 2009-06-17 06:25:12,391 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromMemCacheAndFiles/154240759/family/6698873312271503579,
entries=2, sequenceid=1245219911841, memsize=298.0, filesize=385.0 to table,,1245219912377
    [junit] 2009-06-17 06:25:12,403 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromMemCacheAndFiles/154240759/family/2437195315313046621,
entries=2, sequenceid=1245219911842, memsize=298.0, filesize=385.0 to table,,1245219912377
    [junit] 2009-06-17 06:25:12,563 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testIncrementColumnValue_UpdatingFromSF/777946133/family/489458638109524370,
entries=2, sequenceid=1245219911841, memsize=314.0, filesize=401.0 to table,,1245219912544
    [junit] 2009-06-17 06:25:12,612 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testIncrementColumnValue_AddingNewAfterSFCheck/703737692/family/3181094821837017346,
entries=2, sequenceid=1245219911841, memsize=314.0, filesize=401.0 to table,,1245219912597
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.773 sec
    [junit] Running org.apache.hadoop.hbase.regionserver.TestStoreFile
    [junit] 2009-06-17 06:25:14.708::INFO:  Logging to STDERR via org.mortbay.log.StdErrLog
    [junit] 2009-06-17 06:25:14.755::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:14.786::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/hdfs
 to /tmp/Jetty_localhost_52764_hdfs____.j00tem/webapp
    [junit] 2009-06-17 06:25:37.626::INFO:  Started SelectChannelConnector@localhost:52764
    [junit] Starting DataNode 0 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data1,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data2

    [junit] 2009-06-17 06:25:38.329::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:38.336::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_36644_datanode____.y6y0me/webapp
    [junit] 2009-06-17 06:25:38.560::INFO:  Started SelectChannelConnector@localhost:36644
    [junit] Starting DataNode 1 with dfs.data.dir: http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/HBase-Patch/trunk/build/test/data/dfs/data/data4

    [junit] 2009-06-17 06:25:39.209::INFO:  jetty-6.1.14
    [junit] 2009-06-17 06:25:39.215::INFO:  Extract jar:http://hudson.zones.apache.org/hudson/job/HBase-Patch/ws/trunk/lib/hadoop-0.20.0-plus4681-core.jar!/webapps/datanode
 to /tmp/Jetty_localhost_53201_datanode____.ftt1l0/webapp
    [junit] 2009-06-17 06:25:39.417::INFO:  Started SelectChannelConnector@localhost:53201
    [junit] 2009-06-17 06:25:39,847 INFO  [main] regionserver.TestStoreFile(164): Midkey:
Mime
View raw message