You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@hbase.apache.org by "Bharath Vissapragada (Jira)" <ji...@apache.org> on 2021/07/08 16:27:00 UTC
[jira] [Updated] (HBASE-26074)
testLogLevelByHttps/testLogLevelByHttpsWithSpnego consistently failing on
branch-1
[ https://issues.apache.org/jira/browse/HBASE-26074?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Bharath Vissapragada updated HBASE-26074:
-----------------------------------------
Labels: regression (was: )
> testLogLevelByHttps/testLogLevelByHttpsWithSpnego consistently failing on branch-1
> ----------------------------------------------------------------------------------
>
> Key: HBASE-26074
> URL: https://issues.apache.org/jira/browse/HBASE-26074
> Project: HBase
> Issue Type: Bug
> Components: test
> Affects Versions: 1.7.0
> Reporter: Bharath Vissapragada
> Assignee: Bharath Vissapragada
> Priority: Major
> Labels: regression
> Fix For: 1.7.1
>
>
> In prep-ing for 1.7.1, I noticed that this test has been consistently failing on branch-1 forever.
> {noformat}
> Regression
> health checks / yetus jdk8 hadoop2 checks / org.apache.hadoop.hbase.http.log.TestLogLevel.testLogLevelByHttpsWithSpnego
> Failing for the past 1 build (Since Failed#145 )
> Took 0.79 sec.
> Error Message
> Expected to find 'Unexpected end of file from server' but got unexpected exception:java.net.SocketException: Connection reset
> at java.net.SocketInputStream.read(SocketInputStream.java:210)
> at java.net.SocketInputStream.read(SocketInputStream.java:141)
> at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:743)
> at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:678)
> at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:702)
> at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1593)
> at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1498)
> at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480)
> at org.apache.hadoop.security.authentication.client.KerberosAuthenticator.authenticate(KerberosAuthenticator.java:189)
> at org.apache.hadoop.security.authentication.client.AuthenticatedURL.openConnection(AuthenticatedURL.java:347)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.connect(LogLevel.java:268)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.process(LogLevel.java:284)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.doGetLevel(LogLevel.java:227)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.sendLogLevelRequest(LogLevel.java:123)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.run(LogLevel.java:107)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.getLevel(TestLogLevel.java:349)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.access$000(TestLogLevel.java:68)
> at org.apache.hadoop.hbase.http.log.TestLogLevel$1.run(TestLogLevel.java:325)
> at org.apache.hadoop.hbase.http.log.TestLogLevel$1.run(TestLogLevel.java:322)
> at java.security.AccessController.doPrivileged(Native Method)
> at javax.security.auth.Subject.doAs(Subject.java:422)
> at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1844)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:322)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:277)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testLogLevelByHttpsWithSpnego(TestLogLevel.java:451)
> 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.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)
> Stacktrace
> java.lang.AssertionError:
> Expected to find 'Unexpected end of file from server' but got unexpected exception:java.net.SocketException: Connection reset
> at java.net.SocketInputStream.read(SocketInputStream.java:210)
> at java.net.SocketInputStream.read(SocketInputStream.java:141)
> at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
> at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
> at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
> at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:743)
> at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:678)
> at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:702)
> at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1593)
> at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1498)
> at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480)
> at org.apache.hadoop.security.authentication.client.KerberosAuthenticator.authenticate(KerberosAuthenticator.java:189)
> at org.apache.hadoop.security.authentication.client.AuthenticatedURL.openConnection(AuthenticatedURL.java:347)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.connect(LogLevel.java:268)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.process(LogLevel.java:284)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.doGetLevel(LogLevel.java:227)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.sendLogLevelRequest(LogLevel.java:123)
> at org.apache.hadoop.hbase.http.log.LogLevel$CLI.run(LogLevel.java:107)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.getLevel(TestLogLevel.java:349)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.access$000(TestLogLevel.java:68)
> at org.apache.hadoop.hbase.http.log.TestLogLevel$1.run(TestLogLevel.java:325)
> at org.apache.hadoop.hbase.http.log.TestLogLevel$1.run(TestLogLevel.java:322)
> at java.security.AccessController.doPrivileged(Native Method)
> at javax.security.auth.Subject.doAs(Subject.java:422)
> at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1844)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:322)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:277)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testLogLevelByHttpsWithSpnego(TestLogLevel.java:451)
> 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.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)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.exceptionShouldContains(TestLogLevel.java:479)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testLogLevelByHttpsWithSpnego(TestLogLevel.java:456)
> Caused by: java.net.SocketException: Connection reset
> at org.apache.hadoop.hbase.http.log.TestLogLevel.getLevel(TestLogLevel.java:349)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.access$000(TestLogLevel.java:68)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:322)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testDynamicLogLevel(TestLogLevel.java:277)
> at org.apache.hadoop.hbase.http.log.TestLogLevel.testLogLevelByHttpsWithSpnego(TestLogLevel.java:451)
> Standard Output
> Connecting to https://localhost:34625/logLevel?log=org.apache.hadoop.hbase.http.log.TestLogLevel
> Submitted Log Name: org.apache.hadoop.hbase.http.log.TestLogLevel
> Log Class: org.apache.commons.logging.impl.Log4JLogger
> Effective level: DEBUG
> Connecting to https://localhost:34625/logLevel?log=org.apache.hadoop.hbase.http.log.TestLogLevel&level=DEBUG
> Submitted Log Name: org.apache.hadoop.hbase.http.log.TestLogLevel
> Log Class: org.apache.commons.logging.impl.Log4JLogger
> Submitted Level: DEBUG
> Setting Level to DEBUG ...
> Effective level: DEBUG
> Connecting to http://localhost:42799/logLevel?log=org.apache.hadoop.hbase.http.log.TestLogLevel
> Standard Error
> 2021-07-08 13:56:31,715 INFO [main] hbase.ResourceChecker(178): after: http.log.TestLogLevel#testErrorLogLevel Thread=17 (was 13)
> Potentially hanging thread: NioProcessor-3
> sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
> org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
> org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> java.lang.Thread.run(Thread.java:748)
> Potentially hanging thread: Keep-Alive-Timer
> java.lang.Thread.sleep(Native Method)
> sun.net.www.http.KeepAliveCache.run(KeepAliveCache.java:172)
> java.lang.Thread.run(Thread.java:748)
> Potentially hanging thread: NioProcessor-2
> sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
> org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
> org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> java.lang.Thread.run(Thread.java:748)
> Potentially hanging thread: NioProcessor-1
> sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
> sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
> sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
> sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
> sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
> org.apache.mina.transport.socket.nio.NioProcessor.select(NioProcessor.java:72)
> org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1077)
> org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> java.lang.Thread.run(Thread.java:748)
> - Thread LEAK? -, OpenFileDescriptor=280 (was 279) - OpenFileDescriptor LEAK? -, MaxFileDescriptor=1048576 (was 1048576), SystemLoadAverage=513 (was 428) - SystemLoadAverage LEAK? -, ProcessCount=7 (was 7), AvailableMemoryMB=2522 (was 2579), ConnectionCount=0 (was 0)
> 2021-07-08 13:56:31,737 INFO [main] hbase.ResourceChecker(150): before: http.log.TestLogLevel#testLogLevelByHttpsWithSpnego Thread=17, OpenFileDescriptor=280, MaxFileDescriptor=1048576, SystemLoadAverage=513, ProcessCount=7, AvailableMemoryMB=2521, ConnectionCount=0
> 2021-07-08 13:56:31,744 INFO [Time-limited test] http.HttpRequestLog(69): Http request log for http.requests... is not defined
> 2021-07-08 13:56:31,748 INFO [Time-limited test] http.HttpServer(881): Added global filter 'safety' (class=org.apache.hadoop.hbase.http.HttpServer$QuotingInputFilter)
> 2021-07-08 13:56:31,748 INFO [Time-limited test] http.HttpServer(881): Added global filter 'clickjackingprevention' (class=org.apache.hadoop.hbase.http.ClickjackingPreventionFilter)
> 2021-07-08 13:56:31,749 INFO [Time-limited test] http.HttpServer(739): ASYNC_PROFILER_HOME environment variable and async.profiler.home system property not specified. Disabling /prof endpoint.
> 2021-07-08 13:56:31,749 INFO [Time-limited test] http.HttpServer(881): Added global filter 'SpnegoFilter' (class=org.apache.hadoop.security.authentication.server.AuthenticationFilter)
> 2021-07-08 13:56:31,801 INFO [Time-limited test] http.HttpServer(1112): Jetty bound to port 34625
> 2021-07-08 13:56:31,803 INFO [Time-limited test] log.Slf4jLog(67): jetty-6.1.26
> 2021-07-08 13:56:31,848 INFO [Time-limited test] log.Slf4jLog(67): Started SslSocketConnectorSecure@localhost:34625
> 2021-07-08 13:56:32,154 WARN [NioProcessor-2] protocol.KerberosProtocolHandler(241): KDC cannot accommodate requested option (13)
> 2021-07-08 13:56:32,154 WARN [NioProcessor-2] protocol.KerberosProtocolHandler(242): KDC cannot accommodate requested option (13)
> 2021-07-08 13:56:32,287 WARN [NioProcessor-1] protocol.KerberosProtocolHandler(241): KDC cannot accommodate requested option (13)
> 2021-07-08 13:56:32,287 WARN [NioProcessor-1] protocol.KerberosProtocolHandler(242): KDC cannot accommodate requested option (13)
> 2021-07-08 13:56:32,338 INFO [Time-limited test] log.Slf4jLog(67): Stopped SslSocketConnectorSecure@localhost:0
> 2021-07-08 13:56:32,445 INFO [Time-limited test] http.HttpRequestLog(69): Http request log for http.requests... is not defined
> 2021-07-08 13:56:32,445 INFO [Time-limited test] http.HttpServer(881): Added global filter 'safety' (class=org.apache.hadoop.hbase.http.HttpServer$QuotingInputFilter)
> 2021-07-08 13:56:32,445 INFO [Time-limited test] http.HttpServer(881): Added global filter 'clickjackingprevention' (class=org.apache.hadoop.hbase.http.ClickjackingPreventionFilter)
> 2021-07-08 13:56:32,446 INFO [Time-limited test] http.HttpServer(739): ASYNC_PROFILER_HOME environment variable and async.profiler.home system property not specified. Disabling /prof endpoint.
> 2021-07-08 13:56:32,447 INFO [Time-limited test] http.HttpServer(881): Added global filter 'SpnegoFilter' (class=org.apache.hadoop.security.authentication.server.AuthenticationFilter)
> 2021-07-08 13:56:32,448 INFO [Time-limited test] http.HttpServer(1112): Jetty bound to port 42799
> 2021-07-08 13:56:32,448 INFO [Time-limited test] log.Slf4jLog(67): jetty-6.1.26
> 2021-07-08 13:56:32,483 INFO [Time-limited test] log.Slf4jLog(67): Started SslSocketConnectorSecure@localhost:42799
> 2021-07-08 13:56:32,506 WARN [1853592968@qtp-1232647843-0] log.Slf4jLog(89): EXCEPTION
> javax.net.ssl.SSLException: Unsupported or unrecognized SSL message
> at sun.security.ssl.SSLSocketInputRecord.handleUnknownRecord(SSLSocketInputRecord.java:440)
> at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:175)
> at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110)
> at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1197)
> at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1106)
> at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:398)
> at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:370)
> at org.mortbay.jetty.security.SslSocketConnector$SslConnection.run(SslSocketConnector.java:708)
> at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
> 2021-07-08 13:56:32,510 WARN [1853592968@qtp-1232647843-0] log.Slf4jLog(89): EXCEPTION
> javax.net.ssl.SSLException: Unsupported or unrecognized SSL message
> at sun.security.ssl.SSLSocketInputRecord.handleUnknownRecord(SSLSocketInputRecord.java:440)
> at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:175)
> at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110)
> at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1197)
> at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1106)
> at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:398)
> at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:370)
> at org.mortbay.jetty.security.SslSocketConnector$SslConnection.run(SslSocketConnector.java:708)
> at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
> 2021-07-08 13:56:32,511 INFO [Time-limited test] log.Slf4jLog(67): Stopped SslSocketConnectorSecure@localhost:0
> REST API
> CloudBees CI Client Master 2.263.4.2-rolling
> {noformat}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)