Some Eclipse Foundation services are deprecated, or will be soon. Please ensure you've read this important communication.
Bug 363788 - Build failure: ExternalKeyStoreAsyncSslHttpExchangeTest.testBigPostWithContentExchange()
Summary: Build failure: ExternalKeyStoreAsyncSslHttpExchangeTest.testBigPostWithConten...
Status: RESOLVED FIXED
Alias: None
Product: Jetty
Classification: RT
Component: client (show other bugs)
Version: 7.5.4   Edit
Hardware: PC Linux
: P3 normal (vote)
Target Milestone: 7.5.x   Edit
Assignee: Simone Bordet CLA
QA Contact:
URL:
Whiteboard:
Keywords:
Depends on:
Blocks:
 
Reported: 2011-11-15 04:33 EST by Simone Bordet CLA
Modified: 2011-11-27 21:01 EST (History)
2 users (show)

See Also:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Simone Bordet CLA 2011-11-15 04:33:49 EST
Failed tests: 
  testBigPostWithContentExchange(org.eclipse.jetty.client.ExternalKeyStoreAsyncSslHttpExchangeTest): expected:<7> but was:<9>

Running org.eclipse.jetty.client.ExternalKeyStoreAsyncSslHttpExchangeTest
2011-11-15 10:05:41.373:INFO:oejs.Server:jetty-7.6.0-SNAPSHOT
2011-11-15 10:05:41.886:INFO:oejus.SslContextFactory:Enabled Protocols [SSLv2Hello, TLSv1, SSLv3] of [SSLv2Hello, SSLv3, TLSv1]
2011-11-15 10:05:41.901:INFO:oejs.AbstractConnector:Started SslSocketConnector@0.0.0.0:53974 STARTING
2011-11-15 10:05:41.926:INFO:oejus.SslContextFactory:Enabled Protocols [SSLv2Hello, TLSv1, SSLv3] of [SSLv2Hello, SSLv3, TLSv1]
2011-11-15 10:09:02.456:WARN:oejc.HttpExchange:EXCEPTION @6401d98a=POST//localhost:53974/#WAITING(200274ms)->EXCEPTED(0ms)sent=200300ms
org.eclipse.jetty.io.EofException: early EOF
	at org.eclipse.jetty.client.AbstractHttpConnection$Handler.earlyEOF(AbstractHttpConnection.java:352)
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:331)
	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
	at org.eclipse.jetty.client.AsyncHttpConnection.handle(AsyncHttpConnection.java:129)
	at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:186)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:583)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:45)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533)
	at java.lang.Thread.run(Thread.java:662)

Test set: org.eclipse.jetty.client.ExternalKeyStoreAsyncSslHttpExchangeTest
-------------------------------------------------------------------------------
Tests run: 11, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 205.123 sec <<< FAILURE!
testBigPostWithContentExchange(org.eclipse.jetty.client.ExternalKeyStoreAsyncSslHttpExchangeTest)  Time elapsed: 201.303 sec  <<< FAILURE!
java.lang.AssertionError: expected:<7> but was:<9>
	at org.junit.Assert.fail(Assert.java:91)
	at org.junit.Assert.failNotEquals(Assert.java:645)
	at org.junit.Assert.assertEquals(Assert.java:126)
	at org.junit.Assert.assertEquals(Assert.java:470)
	at org.junit.Assert.assertEquals(Assert.java:454)
	at org.eclipse.jetty.client.HttpExchangeTest.testBigPostWithContentExchange(HttpExchangeTest.java:462)
	at org.eclipse.jetty.client.ExternalKeyStoreAsyncSslHttpExchangeTest.testBigPostWithContentExchange(ExternalKeyStoreAsyncSslHttpExchangeTest.java:38)
Comment 1 Greg Wilkins CLA 2011-11-16 01:26:46 EST
I've tried to reproduce, but have not seen it on 1000's iteration on unix - also tried on windows XP and on OSX.

I did fix https://bugs.eclipse.org/bugs/show_bug.cgi?id=363757, which may be related.

can you monitor and look for a repeat.
Comment 2 Simone Bordet CLA 2011-11-22 19:19:09 EST
Dump from a failure... can't find a server-side connection ?

2011-11-23 00:52:09.754:INFO:oejus.SslContextFactory:Enabled Protocols [SSLv2Hello, TLSv1, SSLv3] of [SSLv2Hello, SSLv3, TLSv1]
TEST IS TAKING TOOOOO LONG!!!!!!!!!!!!!!!!!!!!
CLIENT:
org.eclipse.jetty.client.HttpClient@4e2016b0#STARTED
 +- HttpDestination@1cb1a4e2//localhost:31237(2/2,1,0/2147483647)
idle=1 pending=0
     +- HttpConnection@1388314661//localhost:31237,g=0,p=-14
     |   +- Upgradable:SSL:SCEP@1633127083{localhost/127.0.0.1:31237->/127.0.0.1:57026,open,1R} NOT_HANDSHAKING i/u/o=0/0/0
     +- HttpConnection@1620640756//localhost:31237,g=3,p=-14
         +- Upgradable:SSL:SCEP@1723642430{localhost/127.0.0.1:31237->/127.0.0.1:57027,open,1} NOT_HANDSHAKING i/u/o=0/0/0

SERVER:
org.eclipse.jetty.server.Server@5dd2b9b7 STARTED
 +- org.eclipse.jetty.client.helperClasses.GenericServerHandler@359ecd80#STARTED STARTED
 |
 +- qtp100568296{8<=5<=8/254,0}#STARTED
 |   +- 113 qtp100568296-113 Acceptor0 SslSocketConnector@0.0.0.0:31237 STARTING RUNNABLE @ java.net.PlainSocketImpl.socketAccept(Native Method)
 |   +- 114 qtp100568296-114 RUNNABLE @ java.net.SocketInputStream.socketRead0(Native Method)
 |   +- 115 qtp100568296-115 RUNNABLE @ java.net.SocketInputStream.socketRead0(Native Method)
 |   +- 116 qtp100568296-116 TIMED_WAITING @ sun.misc.Unsafe.park(Native Method) IDLE
 |   +- 117 qtp100568296-117 TIMED_WAITING @ sun.misc.Unsafe.park(Native Method) IDLE
 |   +- 118 qtp100568296-118 TIMED_WAITING @ sun.misc.Unsafe.park(Native Method) IDLE
 |   +- 119 qtp100568296-119 TIMED_WAITING @ sun.misc.Unsafe.park(Native Method) IDLE
 |   +- 120 qtp100568296-120 TIMED_WAITING @ sun.misc.Unsafe.park(Native Method) IDLE
 |
 +- SslSocketConnector@0.0.0.0:31237 STARTED
java.lang.Throwable
	at org.eclipse.jetty.client.HttpExchangeTest.testBigPostWithContentExchange(HttpExchangeTest.java:484)
	at org.eclipse.jetty.client.AsyncSslHttpExchangeTest.testBigPostWithContentExchange(AsyncSslHttpExchangeTest.java:45)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
	at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:53)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:119)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at org.apache.maven.surefire.booter.ProviderFactory$ClassLoaderProxy.invoke(ProviderFactory.java:103)
	at $Proxy0.invoke(Unknown Source)
	at org.apache.maven.surefire.booter.SurefireStarter.invokeProvider(SurefireStarter.java:150)
	at org.apache.maven.surefire.booter.SurefireStarter.runSuitesInProcess(SurefireStarter.java:91)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:69)
2011-11-23 00:55:30.767:WARN:oejc.HttpExchange:EXCEPTION @5159e154=POST//localhost:31237/#WAITING(200112ms)->EXCEPTED(0ms)sent=200112ms
org.eclipse.jetty.io.EofException: early EOF
	at org.eclipse.jetty.client.AbstractHttpConnection$Handler.earlyEOF(AbstractHttpConnection.java:352)
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:334)
	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
	at org.eclipse.jetty.client.AsyncHttpConnection.handle(AsyncHttpConnection.java:129)
	at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:185)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:585)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:45)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533)
	at java.lang.Thread.run(Thread.java:662)
Comment 3 Greg Wilkins CLA 2011-11-27 21:01:04 EST
fixed by avoiding hasProgressed being overwritten