Mailing-List: contact builds-help@hbase.apache.org; run by ezmlm
Precedence: bulk
Reply-To: builds@hbase.apache.org
Date: Tue, 8 Aug 2017 11:37:17 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: builds@hbase.apache.org
Message-ID: <259201639.1015.1502192237704.JavaMail.jenkins@jenkins-master>
In-Reply-To: <1286047012.957.1502184377550.JavaMail.jenkins@jenkins-master>
References: <1286047012.957.1502184377550.JavaMail.jenkins@jenkins-master>
Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_HBase-?=
 =?UTF-8?Q?1.4_=C2=BB_JDK=5F1=5F7,Hadoop&&!H13_#842?=
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: quoted-printable
archived-at: Tue, 08 Aug 2017 11:37:31 -0000

See <https://builds.apache.org/job/HBase-1.4/jdk=3DJDK_1_7,label=3DHadoop&&=
!H13/842/display/redirect?page=3Dchanges>

Changes:

[stack] HBASE-18520 Add jmx value to determine true Master Start time

[stack] Revert "HBASE-18520 Add jmx value to determine true Master Start ti=
me"

[stack] HBASE-18520 Add jmx value to determine true Master Start time; amen=
dment

------------------------------------------
[...truncated 2.34 MB...]
=09at org.apache.hadoop.hbase.protobuf.generated.MasterProtos$MasterService=
$BlockingStub.createTable(MasterProtos.java:65095)
=09at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplement=
ation$4.createTable(ConnectionManager.java:1885)
=09at org.apache.hadoop.hbase.client.HBaseAdmin$5.call(HBaseAdmin.java:763)
=09at org.apache.hadoop.hbase.client.HBaseAdmin$5.call(HBaseAdmin.java:755)
=09at org.apache.hadoop.hbase.client.RpcRetryingCaller.callWithRetries(RpcR=
etryingCaller.java:141)
=09at org.apache.hadoop.hbase.client.HBaseAdmin.executeCallable(HBaseAdmin.=
java:4443)
=09at org.apache.hadoop.hbase.client.HBaseAdmin.executeCallable(HBaseAdmin.=
java:4435)
=09at org.apache.hadoop.hbase.client.HBaseAdmin.createTableAsyncV2(HBaseAdm=
in.java:754)
=09at org.apache.hadoop.hbase.client.HBaseAdmin.createTable(HBaseAdmin.java=
:675)
=09at org.apache.hadoop.hbase.client.HBaseAdmin.createTable(HBaseAdmin.java=
:608)
=09at org.apache.hadoop.hbase.TestZooKeeper.testSanity(TestZooKeeper.java:2=
58)
=09at org.apache.hadoop.hbase.TestZooKeeper.testMasterZKSessionRecoveryFail=
ure(TestZooKeeper.java:243)

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 24.489 sec =
- in org.apache.hadoop.hbase.zookeeper.lock.TestZKInterProcessReadWriteLock
Running org.apache.hadoop.hbase.TestServerSideScanMetricsFromClientSide
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.245 sec -=
 in org.apache.hadoop.hbase.TestServerSideScanMetricsFromClientSide
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 469.919 sec=
 - in org.apache.hadoop.hbase.TestZooKeeper
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.613 sec =
- in org.apache.hadoop.hbase.TestClusterBootOrder
Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 91.184 sec=
 - in org.apache.hadoop.hbase.TestPartialResultsFromClientSide

Results :

Tests in error:=20
org.apache.hadoop.hbase.client.TestClientScannerRPCTimeout.testScannerNextR=
PCTimesout(org.apache.hadoop.hbase.client.TestClientScannerRPCTimeout)
  Run 1: TestClientScannerRPCTimeout.testScannerNextRPCTimesout:87 =C2=BB T=
ableExists testSc...
  Run 2: TestClientScannerRPCTimeout.testScannerNextRPCTimesout:87 =C2=BB T=
ableExists testSc...
  Run 3: TestClientScannerRPCTimeout.testScannerNextRPCTimesout:87 =C2=BB T=
ableExists testSc...

org.apache.hadoop.hbase.regionserver.TestRSKilledWhenInitializing.testRSTer=
minationAfterRegisteringToMasterBeforeCreatingEphemeralNode(org.apache.hado=
op.hbase.regionserver.TestRSKilledWhenInitializing)
  Run 1: TestRSKilledWhenInitializing.testRSTerminationAfterRegisteringToMa=
sterBeforeCreatingEphemeralNode:142 null
  Run 2: TestRSKilledWhenInitializing.testRSTerminationAfterRegisteringToMa=
sterBeforeCreatingEphemeralNode:115 =C2=BB TestTimedOut
  Run 3: TestRSKilledWhenInitializing.testRSTerminationAfterRegisteringToMa=
sterBeforeCreatingEphemeralNode =C2=BB=20
  Run 4: TestRSKilledWhenInitializing.testRSTerminationAfterRegisteringToMa=
sterBeforeCreatingEphemeralNode:115 =C2=BB TestTimedOut
  Run 5: TestRSKilledWhenInitializing.testRSTerminationAfterRegisteringToMa=
sterBeforeCreatingEphemeralNode =C2=BB=20

Flaked tests:=20
org.apache.hadoop.hbase.TestZooKeeper.testMasterZKSessionRecoveryFailure(or=
g.apache.hadoop.hbase.TestZooKeeper)
  Run 1: TestZooKeeper.testMasterZKSessionRecoveryFailure:243->testSanity:2=
58->Object.wait:503->Object.wait:-2 =C2=BB TestTimedOut
  Run 2: PASS

org.apache.hadoop.hbase.regionserver.TestScannerHeartbeatMessages.testScann=
erHeartbeatMessages(org.apache.hadoop.hbase.regionserver.TestScannerHeartbe=
atMessages)
  Run 1: TestScannerHeartbeatMessages.testScannerHeartbeatMessages:207->tes=
tImportanceOfHeartbeats:237 Heartbeats messages are disabled, an exception =
should be thrown. If an exception  is not thrown, the test case is not test=
ing the importance of heartbeat messages
  Run 2: PASS

org.apache.hadoop.hbase.security.access.TestAccessControlFilter.testQualifi=
erAccess(org.apache.hadoop.hbase.security.access.TestAccessControlFilter)
  Run 1: TestAccessControlFilter.testQualifierAccess:97->SecureTestUtil.cre=
ateTable:710->SecureTestUtil.createTable:726->SecureTestUtil.createTable:73=
9->Object.wait:-2 =C2=BB TestTimedOut
  Run 2: PASS


Tests run: 2832, Failures: 0, Errors: 2, Skipped: 57, Flakes: 3

[INFO] --------------------------------------------------------------------=
----
[INFO] Reactor Summary:
[INFO]=20
[INFO] Apache HBase ...................................... SUCCESS [1:48.06=
4s]
[INFO] Apache HBase - Checkstyle ......................... SUCCESS [15.316s=
]
[INFO] Apache HBase - Resource Bundle .................... SUCCESS [0.256s]
[INFO] Apache HBase - Annotations ........................ SUCCESS [1.308s]
[INFO] Apache HBase - Protocol ........................... SUCCESS [20.960s=
]
[INFO] Apache HBase - Common ............................. SUCCESS [2:48.19=
5s]
[INFO] Apache HBase - Procedure .......................... SUCCESS [3:38.02=
7s]
[INFO] Apache HBase - Metrics API ........................ SUCCESS [1.323s]
[INFO] Apache HBase - Hadoop Compatibility ............... SUCCESS [11.519s=
]
[INFO] Apache HBase - Metrics Implementation ............. SUCCESS [5.593s]
[INFO] Apache HBase - Hadoop Two Compatibility ........... SUCCESS [19.389s=
]
[INFO] Apache HBase - Client ............................. SUCCESS [1:54.65=
1s]
[INFO] Apache HBase - Prefix Tree ........................ SUCCESS [10.210s=
]
[INFO] Apache HBase - Server ............................. FAILURE [1:45:38=
.552s]
[INFO] Apache HBase - Testing Util ....................... SKIPPED
[INFO] Apache HBase - Thrift ............................. SKIPPED
[INFO] Apache HBase - Rest ............................... SKIPPED
[INFO] Apache HBase - Shell .............................. SKIPPED
[INFO] Apache HBase - Integration Tests .................. SKIPPED
[INFO] Apache HBase - Examples ........................... SKIPPED
[INFO] Apache HBase - External Block Cache ............... SKIPPED
[INFO] Apache HBase - Assembly ........................... SKIPPED
[INFO] Apache HBase - Shaded ............................. SKIPPED
[INFO] Apache HBase - Shaded - Client .................... SKIPPED
[INFO] Apache HBase - Shaded - Server .................... SKIPPED
[INFO] Apache HBase - Archetypes ......................... SKIPPED
[INFO] Apache HBase - Exemplar for hbase-client archetype  SKIPPED
[INFO] Apache HBase - Exemplar for hbase-shaded-client archetype  SKIPPED
[INFO] Apache HBase - Archetype builder .................. SKIPPED
[INFO] --------------------------------------------------------------------=
----
[INFO] BUILD FAILURE
[INFO] --------------------------------------------------------------------=
----
[INFO] Total time: 1:57:45.261s
[INFO] Finished at: Tue Aug 08 11:25:32 UTC 2017
[INFO] Final Memory: 522M/1969M
[INFO] --------------------------------------------------------------------=
----
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plug=
in:2.18.1:test (secondPartTestsExecution) on project hbase-server: There wa=
s a timeout or other error in the fork -> [Help 1]
[ERROR]=20
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e=
 switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]=20
[ERROR] For more information about the errors and possible solutions, pleas=
e read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailu=
reException
[ERROR]=20
[ERROR] After correcting the problems, you can resume the build with the co=
mmand
[ERROR]   mvn <goals> -rf :hbase-server
Build step 'Invoke top-level Maven targets' marked build as failure
Performing Post build task...
Match found for :.* : True
Logical operation result is TRUE
Running script  : # Post-build task script. TODO: Check this in and have al=
l builds reference check-in.
pwd && ls
# NOTE!!!! The below code has been copied and pasted from ./dev-tools/run-t=
est.sh
# Do not change here without syncing there and vice-versa.
ZOMBIE_TESTS_COUNT=3D`jps -v | grep surefirebooter | grep -e '-Dhbase.test'=
 | wc -l`
if [[ $ZOMBIE_TESTS_COUNT !=3D 0 ]] ; then
 echo "Suspicious java process found - waiting 30s to see if there are just=
 slow to stop"
 sleep 30
 ZOMBIE_TESTS_COUNT=3D`jps -v | grep surefirebooter | grep -e '-Dhbase.test=
' | wc -l`
 if [[ $ZOMBIE_TESTS_COUNT !=3D 0 ]] ; then
   echo " {color:red}There appear to be $ZOMBIE_TESTS_COUNT zombie tests{co=
lor}, they should have been killed by surefire but survived"
   jps -v | grep surefirebooter | grep -e '-Dhbase.test'
   jps -v | grep surefirebooter | grep -e '-Dhbase.test' | cut -d ' ' -f 1 =
| xargs -n 1 jstack
   # Exit with error
   exit 1
 else
   echo "We're ok: there is no zombie test, but some tests took some time t=
o stop"
 fi
else
  echo "We're ok: there is no zombie test"
fi
Setting JDK_1_7_LATEST__HOME=3D/home/jenkins/tools/java/latest1.7
Setting JDK_1_8_LATEST__HOME=3D/home/jenkins/tools/java/latest1.8
[19ccb43d] $ /bin/bash -xe /tmp/jenkins686228240891961480.sh
+ pwd
<https://builds.apache.org/job/HBase-1.4/jdk=3DJDK_1_7,label=3DHadoop&&!H13=
/ws/>
+ ls
bin
CHANGES.txt
conf
dev-support
extra_env_var
hbase-annotations
hbase-archetypes
hbase-assembly
hbase-checkstyle
hbase-client
hbase-common
hbase-examples
hbase-external-blockcache
hbase-hadoop2-compat
hbase-hadoop-compat
hbase-it
hbase-metrics
hbase-metrics-api
hbase-native-client
hbase-prefix-tree
hbase-procedure
hbase-protocol
hbase-resource-bundle
hbase-rest
hbase-server
hbase-shaded
hbase-shell
hbase-testing-util
hbase-thrift
LICENSE.txt
NOTICE.txt
pom.xml
README.txt
src
target
++ jps -v
++ grep surefirebooter
++ grep -e -Dhbase.test
++ wc -l
+ ZOMBIE_TESTS_COUNT=3D1
+ [[ 1 !=3D 0 ]]
+ echo 'Suspicious java process found - waiting 30s to see if there are jus=
t slow to stop'
Suspicious java process found - waiting 30s to see if there are just slow t=
o stop
+ sleep 30
++ jps -v
++ grep surefirebooter
++ grep -e -Dhbase.test
++ wc -l
+ ZOMBIE_TESTS_COUNT=3D0
+ [[ 0 !=3D 0 ]]
+ echo 'We'\''re ok: there is no zombie test, but some tests took some time=
 to stop'
We're ok: there is no zombie test, but some tests took some time to stop
POST BUILD TASK : SUCCESS
END OF POST BUILD TASK : 0
Archiving artifacts
Setting JDK_1_7_LATEST__HOME=3D/home/jenkins/tools/java/latest1.7
Setting JDK_1_8_LATEST__HOME=3D/home/jenkins/tools/java/latest1.8
Recording test results
Setting JDK_1_7_LATEST__HOME=3D/home/jenkins/tools/java/latest1.7
Setting JDK_1_8_LATEST__HOME=3D/home/jenkins/tools/java/latest1.8
ERROR: Step ?Publish JUnit test result report? aborted due to exception:=20
java.lang.OutOfMemoryError: Java heap space
=09at com.sun.org.apache.xerces.internal.util.XMLStringBuffer.append(XMLStr=
ingBuffer.java:208)
=09at com.sun.org.apache.xerces.internal.impl.XMLEntityScanner.scanData(XML=
EntityScanner.java:1515)
=09at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImp=
l.scanCDATASection(XMLDocumentFragmentScannerImpl.java:1654)
=09at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImp=
l$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:3014)
=09at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(X=
MLDocumentScannerImpl.java:602)
=09at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next=
(XMLNSDocumentScannerImpl.java:112)
=09at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImp=
l.scanDocument(XMLDocumentFragmentScannerImpl.java:505)
=09at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(X=
ML11Configuration.java:841)
=09at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(X=
ML11Configuration.java:770)
=09at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.=
java:141)
=09at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Ab=
stractSAXParser.java:1213)
=09at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.p=
arse(SAXParserImpl.java:643)
=09at org.dom4j.io.SAXReader.read(SAXReader.java:465)
=09at org.dom4j.io.SAXReader.read(SAXReader.java:343)
=09at hudson.tasks.junit.SuiteResult.parse(SuiteResult.java:132)
=09at hudson.tasks.junit.TestResult.parse(TestResult.java:302)
=09at hudson.tasks.junit.TestResult.parsePossiblyEmpty(TestResult.java:244)
=09at hudson.tasks.junit.TestResult.parse(TestResult.java:175)
=09at hudson.tasks.junit.TestResult.parse(TestResult.java:154)
=09at hudson.tasks.junit.TestResult.<init>(TestResult.java:126)
=09at hudson.tasks.junit.JUnitParser$ParseResultCallable.invoke(JUnitParser=
.java:132)
=09at hudson.tasks.junit.JUnitParser$ParseResultCallable.invoke(JUnitParser=
.java:107)
=09at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2739)
=09at hudson.remoting.UserRequest.perform(UserRequest.java:153)
=09at hudson.remoting.UserRequest.perform(UserRequest.java:50)
=09at hudson.remoting.Request$2.run(Request.java:336)
=09at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecut=
orService.java:68)
=09at java.util.concurrent.FutureTask.run(FutureTask.java:266)
=09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.=
java:1142)
=09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor=
.java:617)
=09at java.lang.Thread.run(Thread.java:748)
=09at ......remote call to H0(Native Method)
=09at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1545)
=09at hudson.remoting.UserResponse.retrieve(UserRequest.java:253)
=09at hudson.remoting.Channel.call(Channel.java:830)
Caused: java.io.IOException: Remote call on H0 failed
=09at hudson.remoting.Channel.call(Channel.java:838)
=09at hudson.FilePath.act(FilePath.java:986)
Caused: java.io.IOException: remote file operation failed: <https://builds.=
apache.org/job/HBase-1.4/jdk=3DJDK_1_7,label=3DHadoop&&!H13/ws/> at hudson.=
remoting.Channel@61707ec5:H0
=09at hudson.FilePath.act(FilePath.java:993)
=09at hudson.FilePath.act(FilePath.java:975)
=09at hudson.tasks.junit.JUnitParser.parseResult(JUnitParser.java:103)
=09at hudson.tasks.junit.JUnitResultArchiver.parse(JUnitResultArchiver.java=
:128)
=09at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.ja=
va:149)
=09at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibili=
tyLayer.java:81)
=09at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
=09at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBui=
ld.java:735)
=09at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildStep=
s(AbstractBuild.java:676)
=09at hudson.model.Build$BuildExecution.post2(Build.java:186)
=09at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.=
java:621)
=09at hudson.model.Run.execute(Run.java:1760)
=09at hudson.matrix.MatrixRun.run(MatrixRun.java:146)
=09at hudson.model.ResourceController.execute(ResourceController.java:97)
=09at hudson.model.Executor.run(Executor.java:405)
Setting JDK_1_7_LATEST__HOME=3D/home/jenkins/tools/java/latest1.7
Setting JDK_1_8_LATEST__HOME=3D/home/jenkins/tools/java/latest1.8