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 #643
Date Tue, 16 Jun 2009 08:56:10 GMT
See http://hudson.zones.apache.org/hudson/job/HBase-Patch/643/changes

Changes:

[rawson] fix build from 1528

[stack] HBASE-1447  Take last version of the hbase-1249 design doc. and make documentation
out of it

[stack] HBASE-1528 Ensure scanners work across memcache snapshot

[apurtell] HBASE-1529 familyMap not invalidated when Result is (re)read as a Writable

------------------------------------------
[...truncated 23358 lines...]
    [junit] 
    [junit] 2009-06-16 09:17:51,624 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@e4e358]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] 2009-06-16 09:17:52.590::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:17:52.597::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_37254_hdfs____b5oavr/webapp
    [junit] 2009-06-16 09:17:52.769::INFO:  Started SelectChannelConnector@localhost:37254
    [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-16 09:17:53.273::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:17:53.280::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_40513_datanode____.r6gmm6/webapp
    [junit] 2009-06-16 09:17:53.449::INFO:  Started SelectChannelConnector@localhost:40513
    [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-16 09:17:53.968::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:17:53.975::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_47721_datanode____.z2opqe/webapp
    [junit] 2009-06-16 09:17:54.146::INFO:  Started SelectChannelConnector@localhost:47721
    [junit] 2009-06-16 09:17:54,305 INFO  [main] regionserver.HLog(209): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
    [junit] 2009-06-16 09:17:54,314 INFO  [main] regionserver.HLog(299): New hlog /user/hudson/testscanner/116352605/.logs/hlog.dat.1245143874306
    [junit] 2009-06-16 09:17:54,315 DEBUG [main] regionserver.HRegion(264): Opening region
testscanner,,1245143874287, encoded=116352605
    [junit] 2009-06-16 09:17:54,386 INFO  [main] regionserver.HRegion(339): region testscanner,,1245143874287/116352605
available; sequence id is 0
    [junit] 2009-06-16 09:17:54,870 INFO  [main] regionserver.TestScanner(370): Added: 17576
    [junit] 2009-06-16 09:17:54,870 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-16 09:17:55,769 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-16 09:17:55,770 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-16 09:17:55,773 INFO  [main] regionserver.TestScanner(432): Starting flush
at flush index 100
    [junit] 2009-06-16 09:17:55,774 DEBUG [main] regionserver.HRegion(884): Started memcache
flush for region testscanner,,1245143874287. Current region memcache size 2.6m
    [junit] 2009-06-16 09:17:55,906 DEBUG [main] regionserver.Store(526): Added hdfs://localhost:55943/user/hudson/testscanner/116352605/info/8996169548985904470,
entries=17576, sequenceid=17577, memsize=2.6m, filesize=691.0k to testscanner,,1245143874287
    [junit] 2009-06-16 09:17:55,907 DEBUG [main] regionserver.HRegion(961): Finished memcache
flush of ~2.6m for region testscanner,,1245143874287 in 133ms, sequence id=17577, compaction
requested=false
    [junit] 2009-06-16 09:17:55,908 INFO  [main] regionserver.TestScanner$1(437): Finishing
flush
    [junit] 2009-06-16 09:17:55,908 INFO  [main] regionserver.TestScanner(448): Continuing
on after kicking off background flush
    [junit] 2009-06-16 09:17:55,908 INFO  [main] regionserver.TestScanner(427): after next()
just after next flush
    [junit] 2009-06-16 09:17:56,601 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-16 09:17:56,602 DEBUG [main] regionserver.HRegion(436): Closing testscanner,,1245143874287:
compactions & flushes disabled 
    [junit] 2009-06-16 09:17:56,602 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on testscanner,,1245143874287
    [junit] 2009-06-16 09:17:56,602 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region testscanner,,1245143874287
    [junit] 2009-06-16 09:17:56,602 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-16 09:17:56,603 INFO  [main] regionserver.HRegion(485): Closed testscanner,,1245143874287
    [junit] 2009-06-16 09:17:56,603 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:55943/user/hudson/testscanner/116352605/.logs
    [junit] 2009-06-16 09:17:56,633 INFO  [main] hbase.HBaseTestCase(612): Shutting down FileSystem
    [junit] 2009-06-16 09:17:56,634 INFO  [main] hbase.HBaseTestCase(619): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-16 09:17:56,741 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1371566]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:39764, storageID=DS-658804951-67.195.138.9-39764-1245143874152,
infoPort=47721, ipcPort=36640):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-16 09:17:57,846 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@fe135d]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:40085, storageID=DS-217862166-67.195.138.9-40085-1245143873453,
infoPort=40513, ipcPort=49222):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-16 09:17:58,049 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@1629e96]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] 2009-06-16 09:17:58.859::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:17:58.864::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_43597_hdfs____j10ezi/webapp
    [junit] 2009-06-16 09:17:59.033::INFO:  Started SelectChannelConnector@localhost:43597
    [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-16 09:17:59.532::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:17:59.538::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_47063_datanode____vdi8oh/webapp
    [junit] 2009-06-16 09:17:59.699::INFO:  Started SelectChannelConnector@localhost:47063
    [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-16 09:18:00.250::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:18:00.256::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_46165_datanode____.fzgiln/webapp
    [junit] 2009-06-16 09:18:00.419::INFO:  Started SelectChannelConnector@localhost:46165
    [junit] 2009-06-16 09:18:00,628 INFO  [main] regionserver.HLog(209): HLog configuration:
blocksize=67108864, rollsize=63753420, enabled=true, flushlogentries=100, optionallogflushinternal=10000ms
    [junit] 2009-06-16 09:18:00,644 INFO  [main] regionserver.HLog(299): New hlog /user/hudson/testscanner/945683522/.logs/hlog.dat.1245143880628
    [junit] 2009-06-16 09:18:00,645 DEBUG [main] regionserver.HRegion(264): Opening region
testscanner,,1245143880596, encoded=945683522
    [junit] 2009-06-16 09:18:00,688 INFO  [main] regionserver.HRegion(339): region testscanner,,1245143880596/945683522
available; sequence id is 0
    [junit] 2009-06-16 09:18:00,917 INFO  [main] regionserver.TestScanner(394): Added: 17576
    [junit] 2009-06-16 09:18:00,917 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-16 09:18:01,370 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-16 09:18:01,371 INFO  [main] regionserver.TestScanner(419): Taking out
counting scan
    [junit] 2009-06-16 09:18:01,374 INFO  [main] regionserver.TestScanner(432): Starting flush
at flush index 100
    [junit] 2009-06-16 09:18:01,375 INFO  [main] regionserver.TestScanner(448): Continuing
on after kicking off background flush
    [junit] 2009-06-16 09:18:01,375 DEBUG [Thread-334] regionserver.HRegion(884): Started
memcache flush for region testscanner,,1245143880596. Current region memcache size 2.6m
    [junit] 2009-06-16 09:18:01,375 INFO  [main] regionserver.TestScanner(427): after next()
just after next flush
    [junit] 2009-06-16 09:18:01,482 DEBUG [Thread-334] regionserver.Store(526): Added hdfs://localhost:58975/user/hudson/testscanner/945683522/info/7281789039702252371,
entries=17576, sequenceid=17577, memsize=2.6m, filesize=691.0k to testscanner,,1245143880596
    [junit] 2009-06-16 09:18:01,485 DEBUG [Thread-334] regionserver.HRegion(961): Finished
memcache flush of ~2.6m for region testscanner,,1245143880596 in 110ms, sequence id=17577,
compaction requested=false
    [junit] 2009-06-16 09:18:01,486 INFO  [Thread-334] regionserver.TestScanner$1(437): Finishing
flush
    [junit] 2009-06-16 09:18:01,971 INFO  [main] regionserver.TestScanner(453): Found 17575
items
    [junit] 2009-06-16 09:18:01,972 DEBUG [main] regionserver.HRegion(436): Closing testscanner,,1245143880596:
compactions & flushes disabled 
    [junit] 2009-06-16 09:18:01,972 DEBUG [main] regionserver.HRegion(466): Updates disabled
for region, no outstanding scanners on testscanner,,1245143880596
    [junit] 2009-06-16 09:18:01,972 DEBUG [main] regionserver.HRegion(473): No more row locks
outstanding on region testscanner,,1245143880596
    [junit] 2009-06-16 09:18:01,973 DEBUG [main] regionserver.Store(445): closed info
    [junit] 2009-06-16 09:18:01,973 INFO  [main] regionserver.HRegion(485): Closed testscanner,,1245143880596
    [junit] 2009-06-16 09:18:01,974 DEBUG [main] regionserver.HLog(456): closing hlog writer
in hdfs://localhost:58975/user/hudson/testscanner/945683522/.logs
    [junit] 2009-06-16 09:18:02,007 INFO  [main] hbase.HBaseTestCase(612): Shutting down FileSystem
    [junit] 2009-06-16 09:18:02,008 INFO  [main] hbase.HBaseTestCase(619): Shutting down Mini
DFS 
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 1
    [junit] 2009-06-16 09:18:02,114 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1c2e163]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:58374, storageID=DS-1823329271-67.195.138.9-58374-1245143880422,
infoPort=46165, ipcPort=34929):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-16 09:18:03,225 WARN  [org.apache.hadoop.hdfs.server.datanode.DataXceiverServer@1461b5b]
datanode.DataXceiverServer(137): DatanodeRegistration(127.0.0.1:39234, storageID=DS-1455394431-67.195.138.9-39234-1245143879701,
infoPort=47063, ipcPort=45611):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-16 09:18:04,452 WARN  [org.apache.hadoop.hdfs.server.namenode.FSNamesystem$ReplicationMonitor@b98a06]
namenode.FSNamesystem$ReplicationMonitor(2306): ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException:
sleep interrupted
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 24.181 sec
    [junit] Running org.apache.hadoop.hbase.regionserver.TestStore
    [junit] 2009-06-16 09:18:05,248 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/907321421/family/3449560078394243732,
entries=2, sequenceid=1245143884809, memsize=298.0, filesize=385.0 to table,,1245143885189
    [junit] 2009-06-16 09:18:05,273 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/907321421/family/2013856511298433683,
entries=2, sequenceid=1245143884810, memsize=298.0, filesize=385.0 to table,,1245143885189
    [junit] 2009-06-16 09:18:05,287 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromFilesOnly/907321421/family/3734350420027666692,
entries=2, sequenceid=1245143884811, memsize=298.0, filesize=385.0 to table,,1245143885189
    [junit] 2009-06-16 09:18:05,359 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromMemCacheAndFiles/1490861299/family/8503563494733908307,
entries=2, sequenceid=1245143884809, memsize=298.0, filesize=385.0 to table,,1245143885346
    [junit] 2009-06-16 09:18:05,372 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testGet_FromMemCacheAndFiles/1490861299/family/2273667784886685843,
entries=2, sequenceid=1245143884810, memsize=298.0, filesize=385.0 to table,,1245143885346
    [junit] 2009-06-16 09:18:05,537 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testIncrementColumnValue_UpdatingFromSF/278251907/family/5831305377021493877,
entries=2, sequenceid=1245143884809, memsize=314.0, filesize=401.0 to table,,1245143885511
    [junit] 2009-06-16 09:18:05,586 DEBUG [main] regionserver.Store(526): Added test/build/data/TestStore/testIncrementColumnValue_AddingNewAfterSFCheck/1853976967/family/9082215623633883202,
entries=2, sequenceid=1245143884809, memsize=314.0, filesize=401.0 to table,,1245143885571
    [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.778 sec
    [junit] Running org.apache.hadoop.hbase.regionserver.TestStoreFile
    [junit] 2009-06-16 09:18:07.380::INFO:  Logging to STDERR via org.mortbay.log.StdErrLog
    [junit] 2009-06-16 09:18:07.428::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:18:07.459::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_56849_hdfs____tyb12e/webapp
    [junit] 2009-06-16 09:18:07.902::INFO:  Started SelectChannelConnector@localhost:56849
    [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-16 09:18:08.463::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:18:08.469::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_40901_datanode____jl0r1h/webapp
    [junit] 2009-06-16 09:18:08.697::INFO:  Started SelectChannelConnector@localhost:40901
    [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-16 09:18:09.262::INFO:  jetty-6.1.14
    [junit] 2009-06-16 09:18:09.269::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_42952_datanode____r2jqrj/webapp
    [junit] 2009-06-16 09:18:09.478::INFO:  Started SelectChannelConnector@localhost:42952
    [junit] 2009-06-16 09:18:09,982 INFO  [main] regionserver.TestStoreFile(164): Midkey:
Mime
View raw message