You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ozone.apache.org by "Attila Doroszlai (Jira)" <ji...@apache.org> on 2020/03/03 19:45:01 UTC

[jira] [Updated] (HDDS-2989) Intermittent timeout in TestBlockManager

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

Attila Doroszlai updated HDDS-2989:
-----------------------------------
    Target Version/s: 0.6.0  (was: 0.5.0)

> Intermittent timeout in TestBlockManager
> ----------------------------------------
>
>                 Key: HDDS-2989
>                 URL: https://issues.apache.org/jira/browse/HDDS-2989
>             Project: Hadoop Distributed Data Store
>          Issue Type: Bug
>          Components: test
>            Reporter: Attila Doroszlai
>            Priority: Major
>
> {code:title=https://github.com/apache/hadoop-ozone/runs/430663688}
> 2020-02-06T21:44:53.5319531Z [ERROR] Tests run: 9, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 5.344 s <<< FAILURE! - in org.apache.hadoop.hdds.scm.block.TestBlockManager
> 2020-02-06T21:44:53.5319796Z [ERROR] testMultipleBlockAllocation(org.apache.hadoop.hdds.scm.block.TestBlockManager)  Time elapsed: 1.167 s  <<< ERROR!
> 2020-02-06T21:44:53.5319942Z java.util.concurrent.TimeoutException: 
> 2020-02-06T21:44:53.5320496Z Timed out waiting for condition. Thread diagnostics:
> 2020-02-06T21:44:53.5320839Z Timestamp: 2020-02-06 09:44:52,261
> 2020-02-06T21:44:53.5320901Z 
> 2020-02-06T21:44:53.5321178Z "Thread-26"  prio=5 tid=46 runnable
> 2020-02-06T21:44:53.5321292Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5321391Z         at java.lang.Thread.dumpThreads(Native Method)
> 2020-02-06T21:44:53.5326891Z         at java.lang.Thread.getAllStackTraces(Thread.java:1610)
> 2020-02-06T21:44:53.5327144Z         at org.apache.hadoop.test.TimedOutTestsListener.buildThreadDump(TimedOutTestsListener.java:87)
> 2020-02-06T21:44:53.5327309Z         at org.apache.hadoop.test.TimedOutTestsListener.buildThreadDiagnosticString(TimedOutTestsListener.java:73)
> 2020-02-06T21:44:53.5327465Z         at org.apache.hadoop.test.GenericTestUtils.waitFor(GenericTestUtils.java:389)
> 2020-02-06T21:44:53.5327618Z         at org.apache.hadoop.hdds.scm.block.TestBlockManager.testMultipleBlockAllocation(TestBlockManager.java:280)
> 2020-02-06T21:44:53.5388042Z         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 2020-02-06T21:44:53.5388702Z         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 2020-02-06T21:44:53.5388905Z         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 2020-02-06T21:44:53.5389045Z         at java.lang.reflect.Method.invoke(Method.java:498)
> 2020-02-06T21:44:53.5389195Z         at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
> 2020-02-06T21:44:53.5389331Z         at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> 2020-02-06T21:44:53.5389662Z         at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
> 2020-02-06T21:44:53.5389776Z         at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> 2020-02-06T21:44:53.5389916Z         at org.junit.internal.runners.statements.FailOnTimeout$StatementThread.run(FailOnTimeout.java:74)
> 2020-02-06T21:44:53.5390040Z "Signal Dispatcher" daemon prio=9 tid=4 runnable
> 2020-02-06T21:44:53.5390156Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5390783Z "EventQueue-CloseContainerForCloseContainerEventHandler"  prio=5 tid=32 in Object.wait()
> 2020-02-06T21:44:53.5390916Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5391019Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5391149Z         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> 2020-02-06T21:44:53.5391299Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> 2020-02-06T21:44:53.5391448Z         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> 2020-02-06T21:44:53.5391587Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5391721Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5391844Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5391971Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5392100Z "IPC Server idle connection scanner for port 43801" daemon prio=5 tid=24 in Object.wait()
> 2020-02-06T21:44:53.5392227Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5392347Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5392463Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5392567Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5392694Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5393004Z "Thread-28" daemon prio=5 tid=48 timed_waiting
> 2020-02-06T21:44:53.5393121Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5393232Z         at java.lang.Thread.sleep(Native Method)
> 2020-02-06T21:44:53.5393352Z         at org.apache.hadoop.hdds.scm.safemode.SafeModeHandler.lambda$onMessage$0(SafeModeHandler.java:113)
> 2020-02-06T21:44:53.5393504Z         at org.apache.hadoop.hdds.scm.safemode.SafeModeHandler$$Lambda$38/725596393.run(Unknown Source)
> 2020-02-06T21:44:53.5393634Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5393927Z "pool-9-thread-1"  prio=5 tid=45 timed_waiting
> 2020-02-06T21:44:53.5394061Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5394260Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5406780Z         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> 2020-02-06T21:44:53.5427435Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> 2020-02-06T21:44:53.5428120Z         at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
> 2020-02-06T21:44:53.5428601Z         at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
> 2020-02-06T21:44:53.5428758Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5428918Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5429052Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5429184Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5429297Z "main"  prio=5 tid=1 timed_waiting
> 2020-02-06T21:44:53.5429405Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5429501Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5429775Z         at java.lang.Thread.join(Thread.java:1260)
> 2020-02-06T21:44:53.5429900Z         at org.junit.internal.runners.statements.FailOnTimeout.evaluateStatement(FailOnTimeout.java:26)
> 2020-02-06T21:44:53.5430034Z         at org.junit.internal.runners.statements.FailOnTimeout.evaluate(FailOnTimeout.java:17)
> 2020-02-06T21:44:53.5430162Z         at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
> 2020-02-06T21:44:53.5468996Z         at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
> 2020-02-06T21:44:53.5469178Z         at org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:168)
> 2020-02-06T21:44:53.5469334Z         at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
> 2020-02-06T21:44:53.5469466Z         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
> 2020-02-06T21:44:53.5469587Z         at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
> 2020-02-06T21:44:53.5469708Z         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
> 2020-02-06T21:44:53.5469841Z         at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
> 2020-02-06T21:44:53.5469967Z         at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
> 2020-02-06T21:44:53.5470091Z         at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
> 2020-02-06T21:44:53.5470215Z         at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
> 2020-02-06T21:44:53.5470339Z         at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
> 2020-02-06T21:44:53.5470449Z         at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
> 2020-02-06T21:44:53.5470577Z         at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
> 2020-02-06T21:44:53.5470706Z         at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365)
> 2020-02-06T21:44:53.5470851Z         at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273)
> 2020-02-06T21:44:53.5470989Z         at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238)
> 2020-02-06T21:44:53.5471123Z         at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159)
> 2020-02-06T21:44:53.5471252Z         at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
> 2020-02-06T21:44:53.5471391Z         at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
> 2020-02-06T21:44:53.5471525Z         at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
> 2020-02-06T21:44:53.5471791Z         at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
> 2020-02-06T21:44:53.5471932Z "IPC Server idle connection scanner for port 37265" daemon prio=5 tid=39 in Object.wait()
> 2020-02-06T21:44:53.5472061Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5472164Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5472277Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5472395Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5509093Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5509324Z "Socket Reader #1 for port 37265"  prio=5 tid=38 runnable
> 2020-02-06T21:44:53.5509481Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5509580Z         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> 2020-02-06T21:44:53.5509772Z         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> 2020-02-06T21:44:53.5509940Z         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> 2020-02-06T21:44:53.5510121Z         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> 2020-02-06T21:44:53.5510287Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> 2020-02-06T21:44:53.5510624Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
> 2020-02-06T21:44:53.5510740Z         at org.apache.hadoop.ipc.Server$Listener$Reader.doRunLoop(Server.java:1097)
> 2020-02-06T21:44:53.5510912Z         at org.apache.hadoop.ipc.Server$Listener$Reader.run(Server.java:1076)
> 2020-02-06T21:44:53.5511550Z "EventQueue-DatanodeCommandForDatanodeCommandHandler"  prio=5 tid=31 in Object.wait()
> 2020-02-06T21:44:53.5511733Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5511887Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5511993Z         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> 2020-02-06T21:44:53.5512181Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> 2020-02-06T21:44:53.5512357Z         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> 2020-02-06T21:44:53.5512530Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5512725Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5512895Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5513065Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5513394Z "ForkJoinPool.commonPool-worker-1" daemon prio=5 tid=30 timed_waiting
> 2020-02-06T21:44:53.5513552Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5513695Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5513849Z         at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1824)
> 2020-02-06T21:44:53.5753126Z         at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1693)
> 2020-02-06T21:44:53.5806455Z         at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)
> 2020-02-06T21:44:53.5807208Z "EventQueue-DatanodeCommandForDatanodeCommandHandler"  prio=5 tid=49 in Object.wait()
> 2020-02-06T21:44:53.5807347Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5807455Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5807562Z         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> 2020-02-06T21:44:53.5807703Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> 2020-02-06T21:44:53.5807837Z         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> 2020-02-06T21:44:53.5807968Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5808098Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5808388Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5808510Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5808851Z "EventQueue-SafemodestatusForSafeModeHandler"  prio=5 tid=47 in Object.wait()
> 2020-02-06T21:44:53.5808977Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5809088Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5809206Z         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> 2020-02-06T21:44:53.5809346Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> 2020-02-06T21:44:53.5809471Z         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> 2020-02-06T21:44:53.5809599Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5809731Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5809862Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5809982Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5810366Z "Thread-14" daemon prio=5 tid=29 timed_waiting
> 2020-02-06T21:44:53.5810463Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5810573Z         at java.lang.Thread.sleep(Native Method)
> 2020-02-06T21:44:53.5810706Z         at org.apache.hadoop.hdds.scm.safemode.SafeModeHandler.lambda$onMessage$0(SafeModeHandler.java:113)
> 2020-02-06T21:44:53.5810853Z         at org.apache.hadoop.hdds.scm.safemode.SafeModeHandler$$Lambda$38/725596393.run(Unknown Source)
> 2020-02-06T21:44:53.5810983Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5811095Z "Finalizer" daemon prio=8 tid=3 in Object.wait()
> 2020-02-06T21:44:53.5811193Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5811312Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5811435Z         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144)
> 2020-02-06T21:44:53.5811563Z         at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165)
> 2020-02-06T21:44:53.5811691Z         at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:216)
> 2020-02-06T21:44:53.5811875Z "IPC Server idle connection scanner for port 39545" daemon prio=5 tid=16 in Object.wait()
> 2020-02-06T21:44:53.5811991Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5812106Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5812217Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5812333Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5812449Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5812553Z "Reference Handler" daemon prio=10 tid=2 in Object.wait()
> 2020-02-06T21:44:53.5812666Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5812784Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5812896Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5813013Z         at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
> 2020-02-06T21:44:53.5813143Z         at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)
> 2020-02-06T21:44:53.5813253Z "Socket Reader #1 for port 45489"  prio=5 tid=34 runnable
> 2020-02-06T21:44:53.5813364Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5813478Z         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> 2020-02-06T21:44:53.5813599Z         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> 2020-02-06T21:44:53.5813725Z         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> 2020-02-06T21:44:53.5813836Z         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> 2020-02-06T21:44:53.5813963Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> 2020-02-06T21:44:53.5814141Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
> 2020-02-06T21:44:53.5814273Z         at org.apache.hadoop.ipc.Server$Listener$Reader.doRunLoop(Server.java:1097)
> 2020-02-06T21:44:53.5814402Z         at org.apache.hadoop.ipc.Server$Listener$Reader.run(Server.java:1076)
> 2020-02-06T21:44:53.5814533Z "Socket Reader #1 for port 39771"  prio=5 tid=42 runnable
> 2020-02-06T21:44:53.5814630Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5814744Z         at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> 2020-02-06T21:44:53.5814866Z         at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> 2020-02-06T21:44:53.5814990Z         at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> 2020-02-06T21:44:53.5815117Z         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> 2020-02-06T21:44:53.5815222Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> 2020-02-06T21:44:53.5815342Z         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
> 2020-02-06T21:44:53.5815472Z         at org.apache.hadoop.ipc.Server$Listener$Reader.doRunLoop(Server.java:1097)
> 2020-02-06T21:44:53.5815601Z         at org.apache.hadoop.ipc.Server$Listener$Reader.run(Server.java:1076)
> 2020-02-06T21:44:53.5816007Z "EventQueue-SafemodestatusForSafeModeHandler"  prio=5 tid=28 in Object.wait()
> 2020-02-06T21:44:53.5816130Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5816230Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5816350Z         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
> 2020-02-06T21:44:53.5816493Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> 2020-02-06T21:44:53.5816633Z         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
> 2020-02-06T21:44:53.5816769Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5816906Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5817026Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5817155Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5817278Z "IPC Server idle connection scanner for port 39771" daemon prio=5 tid=43 in Object.wait()
> 2020-02-06T21:44:53.5817404Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5817520Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5817628Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5817728Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5817844Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5817967Z "IPC Server idle connection scanner for port 45489" daemon prio=5 tid=35 in Object.wait()
> 2020-02-06T21:44:53.5818089Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5818205Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5818301Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5818413Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5818533Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5818647Z "process reaper" daemon prio=10 tid=11 timed_waiting
> 2020-02-06T21:44:53.5818757Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5818864Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5818970Z         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> 2020-02-06T21:44:53.5819108Z         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
> 2020-02-06T21:44:53.5819245Z         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
> 2020-02-06T21:44:53.5819376Z         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
> 2020-02-06T21:44:53.5819581Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
> 2020-02-06T21:44:53.5819716Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5819837Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5819951Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5820283Z "surefire-forkedjvm-command-thread" daemon prio=5 tid=9 runnable
> 2020-02-06T21:44:53.5820398Z java.lang.Thread.State: RUNNABLE
> 2020-02-06T21:44:53.5820509Z         at java.io.FileInputStream.readBytes(Native Method)
> 2020-02-06T21:44:53.5820617Z         at java.io.FileInputStream.read(FileInputStream.java:255)
> 2020-02-06T21:44:53.5820740Z         at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> 2020-02-06T21:44:53.5820867Z         at java.io.BufferedInputStream.read(BufferedInputStream.java:265)
> 2020-02-06T21:44:53.5820996Z         at java.io.DataInputStream.readInt(DataInputStream.java:387)
> 2020-02-06T21:44:53.5821131Z         at org.apache.maven.surefire.booter.MasterProcessCommand.decode(MasterProcessCommand.java:115)
> 2020-02-06T21:44:53.5821272Z         at org.apache.maven.surefire.booter.CommandReader$CommandRunnable.run(CommandReader.java:390)
> 2020-02-06T21:44:53.5821432Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5821557Z "IPC Server idle connection scanner for port 40327" daemon prio=5 tid=20 in Object.wait()
> 2020-02-06T21:44:53.5821681Z java.lang.Thread.State: WAITING (on object monitor)
> 2020-02-06T21:44:53.5821796Z         at java.lang.Object.wait(Native Method)
> 2020-02-06T21:44:53.5821906Z         at java.lang.Object.wait(Object.java:502)
> 2020-02-06T21:44:53.5822020Z         at java.util.TimerThread.mainLoop(Timer.java:526)
> 2020-02-06T21:44:53.5822121Z         at java.util.TimerThread.run(Timer.java:505)
> 2020-02-06T21:44:53.5822442Z "surefire-forkedjvm-ping-30s" daemon prio=5 tid=10 timed_waiting
> 2020-02-06T21:44:53.5822562Z java.lang.Thread.State: TIMED_WAITING
> 2020-02-06T21:44:53.5822668Z         at sun.misc.Unsafe.park(Native Method)
> 2020-02-06T21:44:53.5822785Z         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
> 2020-02-06T21:44:53.5822918Z         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
> 2020-02-06T21:44:53.5823066Z         at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
> 2020-02-06T21:44:53.5823218Z         at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
> 2020-02-06T21:44:53.5823353Z         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
> 2020-02-06T21:44:53.5823483Z         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
> 2020-02-06T21:44:53.5823618Z         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> 2020-02-06T21:44:53.5823739Z         at java.lang.Thread.run(Thread.java:748)
> 2020-02-06T21:44:53.5823800Z 
> 2020-02-06T21:44:53.5823847Z 
> 2020-02-06T21:44:53.5823955Z 	at org.apache.hadoop.test.GenericTestUtils.waitFor(GenericTestUtils.java:389)
> 2020-02-06T21:44:53.5824106Z 	at org.apache.hadoop.hdds.scm.block.TestBlockManager.testMultipleBlockAllocation(TestBlockManager.java:280)
> 2020-02-06T21:44:53.5824242Z 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 2020-02-06T21:44:53.5824367Z 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> 2020-02-06T21:44:53.5824639Z 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 2020-02-06T21:44:53.5824876Z 	at java.lang.reflect.Method.invoke(Method.java:498)
> 2020-02-06T21:44:53.5825000Z 	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
> 2020-02-06T21:44:53.5825204Z 	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> 2020-02-06T21:44:53.5825339Z 	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
> 2020-02-06T21:44:53.5825465Z 	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> 2020-02-06T21:44:53.5825599Z 	at org.junit.internal.runners.statements.FailOnTimeout$StatementThread.run(FailOnTimeout.java:74)
> {code}



--
This message was sent by Atlassian Jira
(v8.3.4#803005)

---------------------------------------------------------------------
To unsubscribe, e-mail: ozone-issues-unsubscribe@hadoop.apache.org
For additional commands, e-mail: ozone-issues-help@hadoop.apache.org