You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@hbase.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/07/02 05:26:33 UTC

Build failed in Jenkins: HBase-TRUNK #5258

See <https://builds.apache.org/job/HBase-TRUNK/5258/changes>

Changes:

[stack] HBASE-11240 Print hdfs pipeline when hlog's sync is slow (Liu Shaohui)

------------------------------------------
[...truncated 2786 lines...]
	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
	at org.apache.hadoop.ipc.Server$Responder.doRunLoop(Server.java:835)
	at org.apache.hadoop.ipc.Server$Responder.run(Server.java:818)

"org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@17478ee" daemon prio=10 tid=0x5f99c800 nid=0x2c56 waiting on condition [0x6235c000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor.run(BlockManager.java:3336)
	at java.lang.Thread.run(Thread.java:724)

"org.apache.hadoop.hdfs.server.blockmanagement.HeartbeatManager$Monitor@1af8758" daemon prio=10 tid=0x5f99b400 nid=0x2c55 waiting on condition [0x623ad000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.hdfs.server.blockmanagement.HeartbeatManager$Monitor.run(HeartbeatManager.java:307)
	at java.lang.Thread.run(Thread.java:724)

"org.apache.hadoop.hdfs.server.blockmanagement.DecommissionManager$Monitor@296cf1" daemon prio=10 tid=0x5f99a800 nid=0x2c54 waiting on condition [0x623fe000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.hdfs.server.blockmanagement.DecommissionManager$Monitor.run(DecommissionManager.java:76)
	at java.lang.Thread.run(Thread.java:724)

"org.apache.hadoop.hdfs.server.blockmanagement.PendingReplicationBlocks$PendingReplicationMonitor@120aea8" daemon prio=10 tid=0x5f999000 nid=0x2c53 waiting on condition [0x6339b000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.hdfs.server.blockmanagement.PendingReplicationBlocks$PendingReplicationMonitor.run(PendingReplicationBlocks.java:221)
	at java.lang.Thread.run(Thread.java:724)

"org.apache.hadoop.util.JvmPauseMonitor$Monitor@83ba01" daemon prio=10 tid=0x5f993000 nid=0x2c4d waiting on condition [0x6377d000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.util.JvmPauseMonitor$Monitor.run(JvmPauseMonitor.java:161)
	at java.lang.Thread.run(Thread.java:724)

"IPC Server idle connection scanner for port 48639" daemon prio=10 tid=0x5f970400 nid=0x2c4c in Object.wait() [0x6397d000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.util.TimerThread.mainLoop(Timer.java:552)
	- locked <0x80e4d8b0> (a java.util.TaskQueue)
	at java.util.TimerThread.run(Timer.java:505)

"Socket Reader #1 for port 48639" prio=10 tid=0x5f96f400 nid=0x2c4b runnable [0x63b7d000]
   java.lang.Thread.State: RUNNABLE
	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:228)
	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:81)
	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
	- locked <0x80e4f9e8> (a sun.nio.ch.Util$2)
	- locked <0x80e4f9d8> (a java.util.Collections$UnmodifiableSet)
	- locked <0x80e4f0c8> (a sun.nio.ch.EPollSelectorImpl)
	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:102)
	at org.apache.hadoop.ipc.Server$Listener$Reader.doRunLoop(Server.java:616)
	- locked <0x80e5f3a8> (a org.apache.hadoop.ipc.Server$Listener$Reader)
	at org.apache.hadoop.ipc.Server$Listener$Reader.run(Server.java:595)

"Timer-0" daemon prio=10 tid=0x60167800 nid=0x2c43 in Object.wait() [0x6a17d000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.util.TimerThread.mainLoop(Timer.java:552)
	- locked <0x7fde3260> (a java.util.TaskQueue)
	at java.util.TimerThread.run(Timer.java:505)

"10631836@qtp-23952364-1 - Acceptor0 SelectChannelConnector@localhost:39068" daemon prio=10 tid=0x6014b800 nid=0x2c3c runnable [0x6a37d000]
   java.lang.Thread.State: RUNNABLE
	at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
	at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:228)
	at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:81)
	at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
	- locked <0x7fdda988> (a sun.nio.ch.Util$2)
	- locked <0x7fdda998> (a java.util.Collections$UnmodifiableSet)
	- locked <0x7fdda948> (a sun.nio.ch.EPollSelectorImpl)
	at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
	at org.mortbay.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:498)
	at org.mortbay.io.nio.SelectorManager.doSelect(SelectorManager.java:192)
	at org.mortbay.jetty.nio.SelectChannelConnector.accept(SelectChannelConnector.java:124)
	at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:708)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)

"14863649@qtp-23952364-0" daemon prio=10 tid=0x6014b000 nid=0x2c3b in Object.wait() [0x6a97d000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:626)
	- locked <0x7fddada8> (a org.mortbay.thread.QueuedThreadPool$PoolThread)

"Timer for 'NameNode' metrics system" daemon prio=10 tid=0x5fef9800 nid=0x2c3a in Object.wait() [0x6a57d000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.util.TimerThread.mainLoop(Timer.java:552)
	- locked <0x7fdf4410> (a java.util.TaskQueue)
	at java.util.TimerThread.run(Timer.java:505)

"pool-1-thread-1" prio=10 tid=0x62c2a000 nid=0x2c1d waiting on condition [0x6ab7d000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation$StubMaker.makeStub(ConnectionManager.java:1489)
	- locked <0xdf3b80a8> (a java.lang.Object)
	at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation$MasterServiceStubMaker.makeStub(ConnectionManager.java:1524)
	at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.getKeepAliveMasterService(ConnectionManager.java:1732)
	- locked <0xdf3b80a8> (a java.lang.Object)
	at org.apache.hadoop.hbase.client.HBaseAdmin$MasterCallable.prepare(HBaseAdmin.java:3491)
	at org.apache.hadoop.hbase.client.RpcRetryingCaller.callWithRetries(RpcRetryingCaller.java:101)
	at org.apache.hadoop.hbase.client.HBaseAdmin.executeCallable(HBaseAdmin.java:3518)
	at org.apache.hadoop.hbase.client.HBaseAdmin.disableTableAsync(HBaseAdmin.java:981)
	at org.apache.hadoop.hbase.client.HBaseAdmin.disableTable(HBaseAdmin.java:1014)
	at org.apache.hadoop.hbase.client.TestReplicaWithCluster.testChangeTable(TestReplicaWithCluster.java:217)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
	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.run(ParentRunner.java:309)
	at org.junit.runners.Suite.runChild(Suite.java:127)
	at org.junit.runners.Suite.runChild(Suite.java:26)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:724)

"Service Thread" daemon prio=10 tid=0x63c27000 nid=0x2c1a runnable [0x00000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" daemon prio=10 tid=0x63c25000 nid=0x2c19 waiting on condition [0x00000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" daemon prio=10 tid=0x63c23000 nid=0x2c18 waiting on condition [0x00000000]
   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" daemon prio=10 tid=0x63c21800 nid=0x2c17 runnable [0x00000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" daemon prio=10 tid=0x640e7000 nid=0x2c16 in Object.wait() [0x6c07d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
	- locked <0x7cb28630> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
	at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189)

"Reference Handler" daemon prio=10 tid=0x640e5000 nid=0x2c15 in Object.wait() [0x6c27b000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.lang.Object.wait(Object.java:503)
	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
	- locked <0x7cb28060> (a java.lang.ref.Reference$Lock)

"main" prio=10 tid=0xf6806800 nid=0x2c00 waiting on condition [0xf6959000]
   java.lang.Thread.State: WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x7cb40a90> (a java.util.concurrent.FutureTask$Sync)
	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:834)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:994)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1303)
	at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:248)
	at java.util.concurrent.FutureTask.get(FutureTask.java:111)
	at org.apache.maven.surefire.junitcore.AsynchronousRunner.waitForCompletion(AsynchronousRunner.java:73)
	at org.apache.maven.surefire.junitcore.AsynchronousRunner.finished(AsynchronousRunner.java:58)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:242)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:160)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:138)
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:61)
	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:137)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
	at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
	at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)

"VM Thread" prio=10 tid=0x643f8800 nid=0x2c14 runnable 

"GC task thread#0 (ParallelGC)" prio=10 tid=0xf6810c00 nid=0x2c01 runnable 

"GC task thread#1 (ParallelGC)" prio=10 tid=0xf6812000 nid=0x2c02 runnable 

"GC task thread#2 (ParallelGC)" prio=10 tid=0xf6813800 nid=0x2c04 runnable 

"GC task thread#3 (ParallelGC)" prio=10 tid=0xf6815000 nid=0x2c05 runnable 

"GC task thread#4 (ParallelGC)" prio=10 tid=0xf6816400 nid=0x2c06 runnable 

"GC task thread#5 (ParallelGC)" prio=10 tid=0xf6817c00 nid=0x2c07 runnable 

"GC task thread#6 (ParallelGC)" prio=10 tid=0xf6819400 nid=0x2c08 runnable 

"GC task thread#7 (ParallelGC)" prio=10 tid=0xf681a800 nid=0x2c09 runnable 

"GC task thread#8 (ParallelGC)" prio=10 tid=0xf681c000 nid=0x2c0a runnable 

"GC task thread#9 (ParallelGC)" prio=10 tid=0xf681d800 nid=0x2c0b runnable 

"GC task thread#10 (ParallelGC)" prio=10 tid=0xf681ec00 nid=0x2c0c runnable 

"GC task thread#11 (ParallelGC)" prio=10 tid=0xf6820400 nid=0x2c0d runnable 

"GC task thread#12 (ParallelGC)" prio=10 tid=0xf6821c00 nid=0x2c0e runnable 

"GC task thread#13 (ParallelGC)" prio=10 tid=0xf6823400 nid=0x2c0f runnable 

"GC task thread#14 (ParallelGC)" prio=10 tid=0xf6824800 nid=0x2c10 runnable 

"GC task thread#15 (ParallelGC)" prio=10 tid=0xf6826000 nid=0x2c11 runnable 

"GC task thread#16 (ParallelGC)" prio=10 tid=0xf6827800 nid=0x2c12 runnable 

"GC task thread#17 (ParallelGC)" prio=10 tid=0xf6828c00 nid=0x2c13 runnable 

"VM Periodic Task Thread" prio=10 tid=0x63c29000 nid=0x2c1b waiting on condition 

JNI global references: 261

8650: Unable to open socket file: target process not responding or HotSpot VM not loaded
The -F option can be used when the target process is not responding
28943: Unable to open socket file: target process not responding or HotSpot VM not loaded
The -F option can be used when the target process is not responding
POST BUILD TASK : FAILURE
END OF POST BUILD TASK : 0
Archiving artifacts
Sending artifact delta relative to HBase-TRUNK #5256
Archived 1722 artifacts
Archive block size is 32768
Received 70 blocks and 652785620 bytes
Compression is 0.4%
Took 2 min 41 sec
Recording test results
Updating HBASE-11240