[ 
https://issues.apache.org/jira/browse/HBASE-19910?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Duo Zhang resolved HBASE-19910.
-------------------------------
       Resolution: Fixed
         Assignee: stack
     Hadoop Flags: Reviewed
    Fix Version/s: 2.0.0-beta-2

Resolve.

> TestBucketCache TimesOut
> ------------------------
>
>                 Key: HBASE-19910
>                 URL: https://issues.apache.org/jira/browse/HBASE-19910
>             Project: HBase
>          Issue Type: Sub-task
>            Reporter: stack
>            Assignee: stack
>            Priority: Major
>             Fix For: 2.0.0-beta-2
>
>         Attachments: 0001-HBASE-19910-TestBucketCache-TimesOut.patch
>
>
> See 
> https://builds.apache.org/view/H-L/view/HBase/job/PreCommit-HBASE-Build/11303/testReport/org.apache.hadoop.hbase.master.balancer/TestRegionLocationFinder/org_apache_hadoop_hbase_master_balancer_TestRegionLocationFinder/
> This is  small test. Runs fast locally. 8 tests. Each is a second or two. Odd 
> though up on jenkins is that in the middle of one, there is a 19 second 
> pause. See here:
> 2018-02-01 00:56:30,013 INFO  [Time-limited test] util.ByteBufferArray(70): 
> Allocating buffers total=32 MB, sizePerBuffer=2 MB, count=16
> 2018-02-01 00:56:49,678 INFO  [Time-limited test] bucket.BucketCache(279): 
> Instantiating BucketCache with acceptableFactor: 0.95, minFactor: 0.85, 
> extraFreeFactor: 0.1, singleFactor: 0.25, multiFactor: 0.5, memoryFactor: 0.25
> Here is full test run:
> 2018-02-01 00:56:29,981 INFO  [Time-limited test] hbase.ResourceChecker(148): 
> before: io.hfile.bucket.TestBucketCache#testInvalidCacheSplitFactorConfig[1: 
> blockSize=16,384, bucketSizes=[I@20322d26] Thread=77, OpenFileDescriptor=263, 
> MaxFileDescriptor=1048576, SystemLoadAverage=2127, ProcessCount=9, 
> AvailableMemoryMB=7801
> 2018-02-01 00:56:30,013 INFO  [Time-limited test] util.ByteBufferArray(70): 
> Allocating buffers total=32 MB, sizePerBuffer=2 MB, count=16
> 2018-02-01 00:56:49,678 INFO  [Time-limited test] bucket.BucketCache(279): 
> Instantiating BucketCache with acceptableFactor: 0.95, minFactor: 0.85, 
> extraFreeFactor: 0.1, singleFactor: 0.25, multiFactor: 0.5, memoryFactor: 0.25
> 2018-02-01 00:56:49,689 INFO  [Time-limited test] 
> bucket.BucketAllocator(334): Cache totalSize=33288192, buckets=63, bucket 
> capacity=528384=(4*132096)=(FEWEST_ITEMS_IN_BUCKET*(largest configured 
> bucketcache size))
> 2018-02-01 00:56:49,690 INFO  [Time-limited test] bucket.BucketCache(322): 
> Started bucket cache; ioengine=offheap, capacity=32 MB, blockSize=16 KB, 
> writerThreadNum=3, writerQLen=64, persistencePath=null, 
> bucketAllocator=org.apache.hadoop.hbase.io.hfile.bucket.BucketAllocator
> 2018-02-01 00:56:50,020 INFO  [Time-limited test] util.ByteBufferArray(70): 
> Allocating buffers total=32 MB, sizePerBuffer=2 MB, count=16
> 2018-02-01 00:56:50,080 ERROR [Time-limited test] util.ByteBufferArray(101): 
> Buffer creation interrupted
> java.lang.InterruptedException
>       at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404)
>       at java.util.concurrent.FutureTask.get(FutureTask.java:191)
>       at 
> org.apache.hadoop.hbase.util.ByteBufferArray.createBuffers(ByteBufferArray.java:96)
>       at 
> org.apache.hadoop.hbase.util.ByteBufferArray.<init>(ByteBufferArray.java:74)
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.ByteBufferIOEngine.<init>(ByteBufferIOEngine.java:86)
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.BucketCache.getIOEngineFromName(BucketCache.java:384)
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.BucketCache.<init>(BucketCache.java:262)
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.TestBucketCache.checkConfigValues(TestBucketCache.java:387)
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.TestBucketCache.testInvalidCacheSplitFactorConfig(TestBucketCache.java:377)
>       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>       at 
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
>       at 
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>       at java.lang.reflect.Method.invoke(Method.java:498)
>       at 
> org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
>       at 
> org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
>       at 
> org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
>       at 
> org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
>       at 
> org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
>       at 
> org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
>       at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
>       at 
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
>       at 
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
>       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
>       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
>       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
>       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
>       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
>       at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
>       at org.junit.runners.Suite.runChild(Suite.java:128)
>       at org.junit.runners.Suite.runChild(Suite.java:27)
>       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
>       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
>       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
>       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
>       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
>       at 
> org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:298)
>       at 
> org.junit.internal.runners.statements.FailOnTimeout$CallableStatement.call(FailOnTimeout.java:292)
>       at java.util.concurrent.FutureTask.run(FutureTask.java:266)
>       at java.lang.Thread.run(Thread.java:748)
> 2018-02-01 00:56:51,112 INFO  [Time-limited test] bucket.BucketCache(1171): 
> Shutdown bucket cache: IO persistent=false; path to write=null
> 2018-02-01 00:56:51,112 INFO  [Time-limited test-BucketCacheWriter-2] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-2 
> exiting, cacheEnabled=false
> 2018-02-01 00:56:51,112 INFO  [Time-limited test-BucketCacheWriter-1] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-1 
> exiting, cacheEnabled=false
> 2018-02-01 00:56:51,112 INFO  [Time-limited test-BucketCacheWriter-0] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-0 
> exiting, cacheEnabled=false
> 2018-02-01 00:56:51,149 INFO  [Time-limited test] util.ByteBufferArray(70): 
> Allocating buffers total=32 MB, sizePerBuffer=2 MB, count=16
> 2018-02-01 00:56:51,157 INFO  [Time-limited test] bucket.BucketCache(279): 
> Instantiating BucketCache with acceptableFactor: 0.95, minFactor: 0.85, 
> extraFreeFactor: 0.1, singleFactor: 0.25, multiFactor: 0.5, memoryFactor: 0.25
> 2018-02-01 00:56:51,157 INFO  [Time-limited test] 
> bucket.BucketAllocator(334): Cache totalSize=33288192, buckets=63, bucket 
> capacity=528384=(4*132096)=(FEWEST_ITEMS_IN_BUCKET*(largest configured 
> bucketcache size))
> 2018-02-01 00:56:51,158 INFO  [Time-limited test] bucket.BucketCache(322): 
> Started bucket cache; ioengine=offheap, capacity=32 MB, blockSize=16 KB, 
> writerThreadNum=3, writerQLen=64, persistencePath=null, 
> bucketAllocator=org.apache.hadoop.hbase.io.hfile.bucket.BucketAllocator
> 2018-02-01 00:56:51,158 INFO  [Time-limited test] bucket.BucketCache(1171): 
> Shutdown bucket cache: IO persistent=false; path to write=null
> 2018-02-01 00:56:51,158 INFO  [Time-limited test-BucketCacheWriter-0] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-0 
> exiting, cacheEnabled=false
> 2018-02-01 00:56:51,158 INFO  [Time-limited test-BucketCacheWriter-2] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-2 
> exiting, cacheEnabled=false
> 2018-02-01 00:56:51,158 INFO  [Time-limited test-BucketCacheWriter-1] 
> bucket.BucketCache$WriterThread(928): Time-limited test-BucketCacheWriter-1 
> exiting, cacheEnabled=false
> It does not look like > 30 seconds. More like 22seconds. Hmm.. Signal not so 
> clear on this one.
> Here is complaint.
> org.junit.runners.model.TestTimedOutException: test timed out after 30 seconds
>       at 
> org.apache.hadoop.hbase.io.hfile.bucket.TestBucketCache.setup(TestBucketCache.java:130)
> Let me try making it a medium test since it fails frequently.



--
This message was sent by Atlassian JIRA
(v7.6.3#76005)

Reply via email to