search

ballerina-platform / ballerina-library

The Ballerina Library
https://ballerina.io/learn/api-docs/ballerina/
Apache License 2.0
137 stars 64 forks source link

[Windows] Intermittent transport test failures #317

Open chamil321 opened 4 years ago

chamil321 commented 4 years ago

Description: org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase#postTest logs_644.zip

Observed another intermittent failure

Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase STANDARD_OUT
2020-10-12T04:18:46.2509485Z     Start Running Chunk Always Http One Point Zero Client Test Case ...
2020-10-12T04:18:46.2515779Z 
2020-10-12T04:18:46.2519553Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase STANDARD_ERROR
2020-10-12T04:18:46.2522965Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.server.HttpServer start
2020-10-12T04:18:46.2524951Z     INFO: HttpServer started on port 9000
2020-10-12T04:18:46.2526196Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestNGListener onTestStart
2020-10-12T04:18:46.2527336Z     INFO: Test running: postTest
2020-10-12T04:18:46.2527767Z 
2020-10-12T04:18:46.2557107Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase > postTest STANDARD_ERROR
2020-10-12T04:18:46.2561394Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.contractimpl.sender.states.ReceivingEntityBody writeOutboundRequestEntity
2020-10-12T04:18:46.3071793Z     WARNING: writeOutboundRequestEntity is not a valid action of this state
2020-10-12T04:18:46.3074088Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestUtil handleException
2020-10-12T04:18:46.3075814Z     SEVERE: Exception occurred while running postTest
2020-10-12T04:18:46.3077590Z     io.netty.handler.codec.EncoderException: java.io.IOException: Inbound response message already received
2020-10-12T04:18:46.3081706Z        at org.ballerinalang.net.transport.message.HttpMessageDataStreamer$ByteBufferOutputStream.close(HttpMessageDataStreamer.java:170)
2020-10-12T04:18:46.3087273Z        at org.ballerinalang.net.transport.chunkdisable.ChunkClientTemplate.sendRequest(ChunkClientTemplate.java:79)
2020-10-12T04:18:46.3095326Z        at org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase.postTest(ChunkAlwaysHttpOnePointZeroClientTestCase.java:46)
2020-10-12T04:18:46.3101814Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3105239Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3109379Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3112260Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3114935Z        at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
2020-10-12T04:18:46.3117781Z        at org.testng.internal.Invoker.invokeMethod(Invoker.java:583)
2020-10-12T04:18:46.3119964Z        at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:719)
2020-10-12T04:18:46.3122278Z        at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:989)
2020-10-12T04:18:46.3124510Z        at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:125)
2020-10-12T04:18:46.3126283Z        at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:109)
2020-10-12T04:18:46.3127382Z        at org.testng.TestRunner.privateRun(TestRunner.java:648)
2020-10-12T04:18:46.3128204Z        at org.testng.TestRunner.run(TestRunner.java:505)
2020-10-12T04:18:46.3129005Z        at org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
2020-10-12T04:18:46.3130024Z        at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
2020-10-12T04:18:46.3131055Z        at org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
2020-10-12T04:18:46.3132138Z        at org.testng.SuiteRunner.run(SuiteRunner.java:364)
2020-10-12T04:18:46.3133197Z        at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
2020-10-12T04:18:46.3134500Z        at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
2020-10-12T04:18:46.3135653Z        at org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
2020-10-12T04:18:46.3136708Z        at org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
2020-10-12T04:18:46.3137618Z        at org.testng.TestNG.runSuites(TestNG.java:1049)
2020-10-12T04:18:46.3138300Z        at org.testng.TestNG.run(TestNG.java:1017)
2020-10-12T04:18:46.3140491Z        at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:141)
2020-10-12T04:18:46.3143714Z        at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:90)
2020-10-12T04:18:46.3146809Z        at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
2020-10-12T04:18:46.3149140Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3151263Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3153663Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3155414Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3156969Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T04:18:46.3159095Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T04:18:46.3161630Z        at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33)
2020-10-12T04:18:46.3164359Z        at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94)
2020-10-12T04:18:46.3165927Z        at com.sun.proxy.$Proxy5.stop(Unknown Source)
2020-10-12T04:18:46.3167284Z        at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:132)
2020-10-12T04:18:46.3169348Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3171241Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3173462Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3175381Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3176942Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T04:18:46.3179057Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T04:18:46.3182118Z        at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:182)
2020-10-12T04:18:46.3186019Z        at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:164)
2020-10-12T04:18:46.3188818Z        at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:413)
2020-10-12T04:18:46.3190857Z        at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:64)
2020-10-12T04:18:46.3192936Z        at org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48)
2020-10-12T04:18:46.3194844Z        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2020-10-12T04:18:46.3196573Z        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2020-10-12T04:18:46.3198432Z        at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56)
2020-10-12T04:18:46.3199849Z        at java.base/java.lang.Thread.run(Thread.java:834)
2020-10-12T04:18:46.3200652Z     Caused by: java.io.IOException: Inbound response message already received
2020-10-12T04:18:46.3203844Z        at org.ballerinalang.net.transport.contractimpl.sender.states.SendingEntityBody.readInboundResponseHeaders(SendingEntityBody.java:90)
2020-10-12T04:18:46.3209879Z        at org.ballerinalang.net.transport.contractimpl.common.states.SenderReqRespStateManager.readInboundResponseHeaders(SenderReqRespStateManager.java:54)
2020-10-12T04:18:46.3215219Z        at org.ballerinalang.net.transport.contractimpl.sender.TargetHandler.channelRead(TargetHandler.java:85)
2020-10-12T04:18:46.3218378Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3221371Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3224273Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3226771Z        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286)
2020-10-12T04:18:46.3229454Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3232431Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3235311Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3237938Z        at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:102)
2020-10-12T04:18:46.3240605Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3243606Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3246486Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3249528Z        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436)
2020-10-12T04:18:46.3252385Z        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:321)
2020-10-12T04:18:46.3254644Z        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:295)
2020-10-12T04:18:46.3256992Z        at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251)
2020-10-12T04:18:46.3259752Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3262688Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3265589Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3267912Z        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410)
2020-10-12T04:18:46.3270373Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3273365Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3275935Z        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919)
2020-10-12T04:18:46.3277980Z        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
2020-10-12T04:18:46.3279917Z        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714)
2020-10-12T04:18:46.3282067Z        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:650)
2020-10-12T04:18:46.3284126Z        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:576)
2020-10-12T04:18:46.3285670Z        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493)
2020-10-12T04:18:46.3287299Z        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2020-10-12T04:18:46.3289054Z        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2020-10-12T04:18:46.3290842Z        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2020-10-12T04:18:46.3292025Z        ... 1 more
2020-10-12T04:18:46.3292239Z 
2020-10-12T04:18:46.3293229Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestNGListener onTestFailure
2020-10-12T04:18:46.3294448Z     SEVERE: Test failed: postTest-> Exception occurred while running postTest
2020-10-12T04:18:46.3294831Z 
2020-10-12T04:18:46.3297183Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase > postTest FAILED
2020-10-12T04:18:46.3300246Z     java.lang.AssertionError at ChunkAlwaysHttpOnePointZeroClientTestCase.java:59
2020-10-12T04:18:48.3077432Z 
2020-10-12T04:18:48.3078756Z Transport test suite > Transport HTTP test STANDARD_ERROR
2020-10-12T04:18:48.3082131Z     Oct 12, 2020 4:18:48 AM org.ballerinalang.net.transport.contractimpl.common.states.StateUtil handleIncompleteInboundMessage
2020-10-12T04:18:48.3085649Z     WARNING: Remote host closed the connection while reading inbound response body
2020-10-12T04:18:50.3146035Z     Oct 12, 2020 4:18:50 AM org.ballerinalang.net.transport.util.server.HttpServer shutdown
2020-10-12T04:18:50.3147187Z     INFO: HttpServer shutdown
2020-10-12T04:18:50.3147458Z
chamil321 commented 4 years ago

logs_694.zip

Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase STANDARD_ERROR
2020-10-12T14:19:32.2281981Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.server.HttpServer start
2020-10-12T14:19:32.2283317Z     INFO: HttpServer started on port 9000
2020-10-12T14:19:32.2936874Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestNGListener onTestStart
2020-10-12T14:19:32.2938323Z     INFO: Test running: postTest
2020-10-12T14:19:32.2939131Z 
2020-10-12T14:19:32.2940954Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase > postTest STANDARD_ERROR
2020-10-12T14:19:32.2945785Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.contractimpl.sender.states.ReceivingEntityBody writeOutboundRequestEntity
2020-10-12T14:19:32.2948121Z     WARNING: writeOutboundRequestEntity is not a valid action of this state
2020-10-12T14:19:32.2949344Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestUtil handleException
2020-10-12T14:19:32.2950389Z     SEVERE: Exception occurred while running postTest
2020-10-12T14:19:32.2951472Z     io.netty.handler.codec.EncoderException: java.io.IOException: Inbound response message already received
2020-10-12T14:19:32.2953934Z        at org.ballerinalang.net.transport.message.HttpMessageDataStreamer$ByteBufferOutputStream.close(HttpMessageDataStreamer.java:170)
2020-10-12T14:19:32.2957323Z        at org.ballerinalang.net.transport.chunkdisable.ChunkClientTemplate.sendRequest(ChunkClientTemplate.java:79)
2020-10-12T14:19:32.2961018Z        at org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase.postTest(ChunkEnableClientTestCase.java:45)
2020-10-12T14:19:32.2964313Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.2966403Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.2968895Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.2970685Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.2972322Z        at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
2020-10-12T14:19:32.2974242Z        at org.testng.internal.Invoker.invokeMethod(Invoker.java:583)
2020-10-12T14:19:32.2975547Z        at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:719)
2020-10-12T14:19:32.2976956Z        at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:989)
2020-10-12T14:19:32.2978704Z        at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:125)
2020-10-12T14:19:32.2980487Z        at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:109)
2020-10-12T14:19:32.2981683Z        at org.testng.TestRunner.privateRun(TestRunner.java:648)
2020-10-12T14:19:32.2982577Z        at org.testng.TestRunner.run(TestRunner.java:505)
2020-10-12T14:19:32.2983480Z        at org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
2020-10-12T14:19:32.2984578Z        at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
2020-10-12T14:19:32.2985718Z        at org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
2020-10-12T14:19:32.2986672Z        at org.testng.SuiteRunner.run(SuiteRunner.java:364)
2020-10-12T14:19:32.2987755Z        at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
2020-10-12T14:19:32.2988993Z        at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
2020-10-12T14:19:32.2990168Z        at org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
2020-10-12T14:19:32.2991259Z        at org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
2020-10-12T14:19:32.2992149Z        at org.testng.TestNG.runSuites(TestNG.java:1049)
2020-10-12T14:19:32.2992852Z        at org.testng.TestNG.run(TestNG.java:1017)
2020-10-12T14:19:32.2995017Z        at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:141)
2020-10-12T14:19:32.2998560Z        at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:90)
2020-10-12T14:19:32.3001662Z        at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
2020-10-12T14:19:32.3004085Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.3006176Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.3008609Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.3010394Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.3011947Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T14:19:32.3014146Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T14:19:32.3016692Z        at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33)
2020-10-12T14:19:32.3019486Z        at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94)
2020-10-12T14:19:32.3021085Z        at com.sun.proxy.$Proxy5.stop(Unknown Source)
2020-10-12T14:19:32.3022435Z        at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:132)
2020-10-12T14:19:32.3024470Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.3026622Z        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.3029752Z        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.3031749Z        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.3033477Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T14:19:32.3035779Z        at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T14:19:32.3039503Z        at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:182)
2020-10-12T14:19:32.3043687Z        at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:164)
2020-10-12T14:19:32.3046677Z        at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:413)
2020-10-12T14:19:32.3048793Z        at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:64)
2020-10-12T14:19:32.3050962Z        at org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48)
2020-10-12T14:19:32.3053071Z        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2020-10-12T14:19:32.3055803Z        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2020-10-12T14:19:32.3057856Z        at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56)
2020-10-12T14:19:32.3059419Z        at java.base/java.lang.Thread.run(Thread.java:834)
2020-10-12T14:19:32.3060301Z     Caused by: java.io.IOException: Inbound response message already received
2020-10-12T14:19:32.3063669Z        at org.ballerinalang.net.transport.contractimpl.sender.states.SendingEntityBody.readInboundResponseHeaders(SendingEntityBody.java:90)
2020-10-12T14:19:32.3070040Z        at org.ballerinalang.net.transport.contractimpl.common.states.SenderReqRespStateManager.readInboundResponseHeaders(SenderReqRespStateManager.java:54)
2020-10-12T14:19:32.3075805Z        at org.ballerinalang.net.transport.contractimpl.sender.TargetHandler.channelRead(TargetHandler.java:85)
2020-10-12T14:19:32.3081839Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3085736Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3088788Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3092774Z        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286)
2020-10-12T14:19:32.3095802Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3098865Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3101840Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3104568Z        at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:102)
2020-10-12T14:19:32.3109971Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3113044Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3116698Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3119807Z        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436)
2020-10-12T14:19:32.3123815Z        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:321)
2020-10-12T14:19:32.3126174Z        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:295)
2020-10-12T14:19:32.3128570Z        at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251)
2020-10-12T14:19:32.3131366Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3134404Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3138005Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3140387Z        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410)
2020-10-12T14:19:32.3142845Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3146306Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3148980Z        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919)
2020-10-12T14:19:32.3151080Z        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
2020-10-12T14:19:32.3152942Z        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714)
2020-10-12T14:19:32.3155816Z        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:650)
2020-10-12T14:19:32.3157951Z        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:576)
2020-10-12T14:19:32.3159491Z        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493)
2020-10-12T14:19:32.3161141Z        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2020-10-12T14:19:32.3162943Z        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2020-10-12T14:19:32.3164780Z        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2020-10-12T14:19:32.3165981Z        ... 1 more
2020-10-12T14:19:32.3166203Z 
2020-10-12T14:19:32.3167198Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestNGListener onTestFailure
2020-10-12T14:19:32.3168441Z     SEVERE: Test failed: postTest-> Exception occurred while running postTest
2020-10-12T14:19:32.3168840Z 
2020-10-12T14:19:32.3170495Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase > postTest FAILED
2020-10-12T14:19:32.3172665Z     java.lang.AssertionError at ChunkEnableClientTestCase.java:52
2020-10-12T14:19:34.2936252Z 
2020-10-12T14:19:34.2937245Z Transport test suite > Transport HTTP test STANDARD_ERROR
2020-10-12T14:19:34.2939255Z     Oct 12, 2020 2:19:34 PM org.ballerinalang.net.transport.contractimpl.common.states.StateUtil handleIncompleteInboundMessage
2020-10-12T14:19:34.2942386Z     WARNING: Remote host closed the connection while reading inbound response body
2020-10-12T14:19:36.2938583Z     Oct 12, 2020 2:19:36 PM org.ballerinalang.net.transport.util.server.HttpServer shutdown
2020-10-12T14:19:36.2940081Z     INFO: HttpServer shutdown
2020-10-12T14:19:36.2940478Z
chamil321 commented 4 years ago 
2020-10-20T08:41:25.5848048Z    [fail] testCBTrialRunFailure:
2020-10-20T08:41:25.5848675Z        Found unexpected output
2020-10-20T08:41:25.5849155Z            expected: '500'
2020-10-20T08:41:25.5849570Z            actual  : '503'
2020-10-20T08:41:25.5850248Z            at ballerina.test.0_0_0:createBallerinaError(assert.bal:42)
2020-10-20T08:41:25.5851091Z            ballerina.test.0_0_0:assertEquals(assert.bal:75)
2020-10-20T08:41:25.5853154Z            ballerina.http-tests.1_0_1.tests.integration-tests.service.resiliency.00-commons:invokeApiAndVerifyResponse(tests/integration-tests/service/resiliency/00-commons.bal:44)
2020-10-20T08:41:25.5857117Z            ballerina.http-tests.1_0_1.tests.integration-tests.service.resiliency.13-http-circuit-breaker-test:testCBTrialRunFailure(tests/integration-tests/service/resiliency/13-http-circuit-breaker-test.bal:105)
2020-10-20T08:41:25.5859324Z
chamil321 commented 3 years ago

2020-11-25T16:19:21.9308937Z Nov 25, 2020 4:19:21 PM org.ballerinalang.net.transport.util.TestNGListener onTestFailure 2020-11-25T16:19:21.9310155Z SEVERE: Test failed: postTest-> Exception occurred while running postTest 2020-11-25T16:19:21.9310595Z 2020-11-25T16:19:21.9312106Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkAutoClientTestCase > postTest FAILED 2020-11-25T16:19:21.9313995Z java.lang.AssertionError at ChunkAutoClientTestCase.java:52

chamil321 commented 3 years ago

this test failed in ubuntu

2021-04-09T09:58:50.2657191Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.proxyserver.HttpsProxyServerTestCase > testHttpsProxyServer STANDARD_ERROR
2021-04-09T09:58:50.2660468Z     Apr 09, 2021 9:58:50 AM org.ballerinalang.net.transport.util.TestNGListener onTestStart
2021-04-09T09:58:50.2662047Z     INFO: Test running: testHttpsProxyServer
2021-04-09T09:58:50.2664885Z     Apr 09, 2021 9:58:50 AM org.ballerinalang.net.transport.contractimpl.listener.ServerConnectorBootstrap$HttpServerConnector lambda$start$0
2021-04-09T09:58:50.2667404Z     INFO: HTTP(S) Interface starting on host 0.0.0.0 and port 9001
2021-04-09T09:58:55.3650674Z 
2021-04-09T09:58:55.3652405Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.proxyserver.HttpsProxyServerTestCase > testHttpsProxyServer FAILED
2021-04-09T09:58:55.3654147Z     java.lang.AssertionError at HttpsProxyServerTestCase.java:80
2021-04-09T09:58:55.3654702Z 
2021-04-09T09:58:55.3655972Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.proxyserver.HttpsProxyServerTestCase STANDARD_ERROR
2021-04-09T09:58:55.3657850Z     Apr 09, 2021 9:58:55 AM org.ballerinalang.net.transport.util.TestNGListener onTestFailure
2021-04-09T09:58:55.3659362Z     SEVERE: Test failed: testHttpsProxyServer-> 
2021-04-09T09:58:55.3659700Z 
2021-04-09T09:58:55.3660091Z Transport test suite > Transport HTTP test STANDARD_ERROR
2021-04-09T09:58:55.3661791Z     Apr 09, 2021 9:58:55 AM org.ballerinalang.net.transport.contractimpl.listener.ServerConnectorBootstrap$HttpServerConnector unBindInterface
2021-04-09T09:58:55.3663541Z     INFO: HttpConnectorListener stopped listening on host 0.0.0.0 and port 9001
2021-04-09T09:58:59.3656329Z     Apr 09, 2021 9:58:59 AM org.ballerinalang.net.transport.contractimpl.listener.HttpExceptionHandler exceptionCaught
2021-04-09T09:58:59.3660383Z     SEVERE: Exception occurred in HTTP inbound channel pipeline : {}
2021-04-09T09:58:59.3662880Z     io.netty.handler.proxy.ProxyConnectException: http, none, localhost/127.0.0.1:9002 => localhost/127.0.0.1:9001, disconnected
2021-04-09T09:58:59.3666463Z        at io.netty.handler.proxy.ProxyHandler.channelInactive(ProxyHandler.java:234)
2021-04-09T09:58:59.3669454Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:262)
2021-04-09T09:58:59.3672981Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:248)
2021-04-09T09:58:59.3676199Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:241)
2021-04-09T09:58:59.3679500Z        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelInactive(CombinedChannelDuplexHandler.java:418)
2021-04-09T09:58:59.3682653Z        at io.netty.handler.codec.ByteToMessageDecoder.channelInputClosed(ByteToMessageDecoder.java:389)
2021-04-09T09:58:59.3685246Z        at io.netty.handler.codec.ByteToMessageDecoder.channelInactive(ByteToMessageDecoder.java:354)
2021-04-09T09:58:59.3687625Z        at io.netty.handler.codec.http.HttpClientCodec$Decoder.channelInactive(HttpClientCodec.java:288)
2021-04-09T09:58:59.3690370Z        at io.netty.channel.CombinedChannelDuplexHandler.channelInactive(CombinedChannelDuplexHandler.java:221)
2021-04-09T09:58:59.3692952Z        at io.netty.handler.proxy.HttpProxyHandler$HttpClientCodecWrapper.channelInactive(HttpProxyHandler.java:267)
2021-04-09T09:58:59.3695877Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:262)
2021-04-09T09:58:59.3699159Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:248)
2021-04-09T09:58:59.3702394Z        at io.netty.channel.AbstractChannelHandlerContext.fireChannelInactive(AbstractChannelHandlerContext.java:241)
2021-04-09T09:58:59.3705005Z        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelInactive(DefaultChannelPipeline.java:1405)
2021-04-09T09:58:59.3707716Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:262)
2021-04-09T09:58:59.3710991Z        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelInactive(AbstractChannelHandlerContext.java:248)
2021-04-09T09:58:59.3714110Z        at io.netty.channel.DefaultChannelPipeline.fireChannelInactive(DefaultChannelPipeline.java:901)
2021-04-09T09:58:59.3716226Z        at io.netty.channel.AbstractChannel$AbstractUnsafe$8.run(AbstractChannel.java:818)
2021-04-09T09:58:59.3718220Z        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164)
2021-04-09T09:58:59.3721049Z        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasksFrom(SingleThreadEventExecutor.java:428)
2021-04-09T09:58:59.3724169Z        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:377)
2021-04-09T09:58:59.3727098Z        at io.netty.util.concurrent.SingleThreadEventExecutor.confirmShutdown(SingleThreadEventExecutor.java:763)
2021-04-09T09:58:59.3729302Z        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:525)
2021-04-09T09:58:59.3731037Z        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2021-04-09T09:58:59.3732865Z        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2021-04-09T09:58:59.3734722Z        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2021-04-09T09:58:59.3736155Z        at java.base/java.lang.Thread.run(Thread.java:834)
2021-04-09T09:58:59.3736602Z 
2021-04-09T09:59:01.3653100Z     Apr 09, 2021 9:59:01 AM org.mockserver.proxy.relay.RelayConnectHandler failure
2021-04-09T09:59:01.3655359Z     WARNING: Connection failed to 0.0.0.0/0.0.0.0:9002
2021-04-09T09:59:01.3657005Z     java.nio.channels.ClosedChannelException
2021-04-09T09:59:01.3659909Z        at io.netty.channel.nio.AbstractNioChannel.doClose(AbstractNioChannel.java:502)
2021-04-09T09:59:01.3663634Z        at io.netty.channel.socket.nio.NioSocketChannel.doClose(NioSocketChannel.java:342)
2021-04-09T09:59:01.3667134Z        at io.netty.channel.AbstractChannel$AbstractUnsafe.doClose0(AbstractChannel.java:759)
2021-04-09T09:59:01.3670083Z        at io.netty.channel.AbstractChannel$AbstractUnsafe.close(AbstractChannel.java:736)
2021-04-09T09:59:01.3672973Z        at io.netty.channel.AbstractChannel$AbstractUnsafe.close(AbstractChannel.java:607)
2021-04-09T09:59:01.3675853Z        at io.netty.channel.nio.NioEventLoop.closeAll(NioEventLoop.java:762)
2021-04-09T09:59:01.3678571Z        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:524)
2021-04-09T09:59:01.3682246Z        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2021-04-09T09:59:01.3686112Z        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2021-04-09T09:59:01.3689765Z        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2021-04-09T09:59:01.3692651Z        at java.base/java.lang.Thread.run(Thread.java:834)

logs_4647.zip

chamil321 commented 3 years ago

logs_4789.zip

2021-04-21T10:42:49.9655680Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.connectionpool.ConnectionPoolWaitingTimeoutTestCase > testWaitingForConnectionTimeout STANDARD_ERROR 2021-04-21T10:42:49.9658909Z Apr 21, 2021 10:42:49 AM org.ballerinalang.net.transport.util.TestNGListener onTestFailure 2021-04-21T10:42:50.0307157Z SEVERE: Test failed: testWaitingForConnectionTimeout-> Cannot have more than one error 2021-04-21T10:42:50.0308136Z 2021-04-21T10:42:50.0310559Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.connectionpool.ConnectionPoolWaitingTimeoutTestCase > testWaitingForConnectionTimeout FAILED 2021-04-21T10:42:50.0313459Z java.lang.AssertionError at ConnectionPoolWaitingTimeoutTestCase.java:77 2021-04-21T10:42:54.0315546Z

chamil321 commented 3 years ago

Failed in ubuntu + windows

2021-09-22T16:56:12.5987532Z INFO: Test running: testretrievingResponseFromWeb 2021-09-22T16:56:12.7949254Z 2021-09-22T16:56:12.7951996Z Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.certificatevalidation.OCSPStaplingTest > testretrievingResponseFromWeb STANDARD_ERROR 2021-09-22T16:56:12.7954836Z Sep 22, 2021 4:56:12 PM io.ballerina.stdlib.http.transport.util.TestNGListener onTestFailure 2021-09-22T16:56:12.7957224Z SEVERE: Test failed: testretrievingResponseFromWeb-> Could not get revocation status from OCSP. 2021-09-22T16:56:12.8949851Z 2021-09-22T16:56:12.8951971Z Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.certificatevalidation.OCSPStaplingTest > testretrievingResponseFromWeb FAILED 2021-09-22T16:56:12.8957610Z io.ballerina.stdlib.http.transport.contractimpl.common.certificatevalidation.CertificateVerificationException at OCSPStaplingTest.java:96

logs_6084.zip

chamil321 commented 3 years ago

In windows

2021-09-27T04:17:37.8135622Z Start Running Timeout After Request Received ... 2021-09-27T04:17:37.8136027Z 2021-09-27T04:17:37.8138885Z Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.servertimeout.TimeoutAfterRequestReceived > testServerTimeout STANDARD_ERROR 2021-09-27T04:17:37.8141902Z Sep 27, 2021 4:17:37 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestStart 2021-09-27T04:17:37.8143081Z INFO: Test running: testServerTimeout 2021-09-27T04:17:37.8144803Z Sep 27, 2021 4:17:37 AM io.ballerina.stdlib.http.transport.http2.listeners.Http2NoResponseListener onMessage 2021-09-27T04:17:37.8146425Z INFO: Message received 2021-09-27T04:17:38.1298314Z Sep 27, 2021 4:17:37 AM io.ballerina.stdlib.http.transport.http2.listeners.Http2NoResponseListener onMessage 2021-09-27T04:17:38.1566843Z INFO: Message received 2021-09-27T04:17:38.1576713Z Sep 27, 2021 4:17:38 AM io.ballerina.stdlib.http.transport.contractimpl.common.states.StateUtil handleIncompleteInboundMessage 2021-09-27T04:17:38.1579348Z WARNING: Idle timeout triggered while reading inbound request entity body 2021-09-27T04:17:38.3129485Z 2021-09-27T04:17:38.3132610Z Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.servertimeout.TimeoutAfterRequestReceived > testServerTimeout FAILED 2021-09-27T04:17:38.3135858Z java.lang.AssertionError at TimeoutAfterRequestReceived.java:108 2021-09-27T04:17:38.3136845Z 2021-09-27T04:17:38.3139106Z Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.servertimeout.TimeoutAfterRequestReceived STANDARD_ERROR 2021-09-27T04:17:38.3142307Z Sep 27, 2021 4:17:38 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestFailure 2021-09-27T04:17:38.3144043Z SEVERE: Test failed: testServerTimeout-> Expected response not received expected [Stream timed out] but found [] logs_6112.zip

chamil321 commented 2 years ago 
        [fail] testRetryWithMultiPart:

            java.lang.NullPointerException

                at ballerina.http_tests.2.tests.http_retry_test:externTestMultiPart(tests/http_retry_test.bal:594)
                ballerina.http_tests.2.tests.http_retry_test:testRetryWithMultiPart(tests/http_retry_test.bal:547)
TharmiganK commented 2 years ago

Failed in ubuntu + windows

2021-09-22T16:56:12.5987532Z INFO: Test running: testretrievingResponseFromWeb 2021-09-22T16:56:12.7949254Z 2021-09-22T16:56:12.7951996Z Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.certificatevalidation.OCSPStaplingTest > testretrievingResponseFromWeb STANDARD_ERROR 2021-09-22T16:56:12.7954836Z Sep 22, 2021 4:56:12 PM io.ballerina.stdlib.http.transport.util.TestNGListener onTestFailure 2021-09-22T16:56:12.7957224Z SEVERE: Test failed: testretrievingResponseFromWeb-> Could not get revocation status from OCSP. 2021-09-22T16:56:12.8949851Z 2021-09-22T16:56:12.8951971Z Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.certificatevalidation.OCSPStaplingTest > testretrievingResponseFromWeb FAILED 2021-09-22T16:56:12.8957610Z io.ballerina.stdlib.http.transport.contractimpl.common.certificatevalidation.CertificateVerificationException at OCSPStaplingTest.java:96

logs_6084.zip

This failure is reproduced when there is no internet connection. Otherwise the test passes in both ubuntu and windows even when the tests are ran multiple times.

TharmiganK commented 2 years ago

Description: org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase#postTest logs_644.zip

Observed another intermittent failure

Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase STANDARD_OUT
2020-10-12T04:18:46.2509485Z     Start Running Chunk Always Http One Point Zero Client Test Case ...
2020-10-12T04:18:46.2515779Z 
2020-10-12T04:18:46.2519553Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase STANDARD_ERROR
2020-10-12T04:18:46.2522965Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.server.HttpServer start
2020-10-12T04:18:46.2524951Z     INFO: HttpServer started on port 9000
2020-10-12T04:18:46.2526196Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestNGListener onTestStart
2020-10-12T04:18:46.2527336Z     INFO: Test running: postTest
2020-10-12T04:18:46.2527767Z 
2020-10-12T04:18:46.2557107Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase > postTest STANDARD_ERROR
2020-10-12T04:18:46.2561394Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.contractimpl.sender.states.ReceivingEntityBody writeOutboundRequestEntity
2020-10-12T04:18:46.3071793Z     WARNING: writeOutboundRequestEntity is not a valid action of this state
2020-10-12T04:18:46.3074088Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestUtil handleException
2020-10-12T04:18:46.3075814Z     SEVERE: Exception occurred while running postTest
2020-10-12T04:18:46.3077590Z     io.netty.handler.codec.EncoderException: java.io.IOException: Inbound response message already received
2020-10-12T04:18:46.3081706Z      at org.ballerinalang.net.transport.message.HttpMessageDataStreamer$ByteBufferOutputStream.close(HttpMessageDataStreamer.java:170)
2020-10-12T04:18:46.3087273Z      at org.ballerinalang.net.transport.chunkdisable.ChunkClientTemplate.sendRequest(ChunkClientTemplate.java:79)
2020-10-12T04:18:46.3095326Z      at org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase.postTest(ChunkAlwaysHttpOnePointZeroClientTestCase.java:46)
2020-10-12T04:18:46.3101814Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3105239Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3109379Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3112260Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3114935Z      at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
2020-10-12T04:18:46.3117781Z      at org.testng.internal.Invoker.invokeMethod(Invoker.java:583)
2020-10-12T04:18:46.3119964Z      at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:719)
2020-10-12T04:18:46.3122278Z      at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:989)
2020-10-12T04:18:46.3124510Z      at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:125)
2020-10-12T04:18:46.3126283Z      at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:109)
2020-10-12T04:18:46.3127382Z      at org.testng.TestRunner.privateRun(TestRunner.java:648)
2020-10-12T04:18:46.3128204Z      at org.testng.TestRunner.run(TestRunner.java:505)
2020-10-12T04:18:46.3129005Z      at org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
2020-10-12T04:18:46.3130024Z      at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
2020-10-12T04:18:46.3131055Z      at org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
2020-10-12T04:18:46.3132138Z      at org.testng.SuiteRunner.run(SuiteRunner.java:364)
2020-10-12T04:18:46.3133197Z      at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
2020-10-12T04:18:46.3134500Z      at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
2020-10-12T04:18:46.3135653Z      at org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
2020-10-12T04:18:46.3136708Z      at org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
2020-10-12T04:18:46.3137618Z      at org.testng.TestNG.runSuites(TestNG.java:1049)
2020-10-12T04:18:46.3138300Z      at org.testng.TestNG.run(TestNG.java:1017)
2020-10-12T04:18:46.3140491Z      at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:141)
2020-10-12T04:18:46.3143714Z      at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:90)
2020-10-12T04:18:46.3146809Z      at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
2020-10-12T04:18:46.3149140Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3151263Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3153663Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3155414Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3156969Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T04:18:46.3159095Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T04:18:46.3161630Z      at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33)
2020-10-12T04:18:46.3164359Z      at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94)
2020-10-12T04:18:46.3165927Z      at com.sun.proxy.$Proxy5.stop(Unknown Source)
2020-10-12T04:18:46.3167284Z      at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:132)
2020-10-12T04:18:46.3169348Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T04:18:46.3171241Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T04:18:46.3173462Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T04:18:46.3175381Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T04:18:46.3176942Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T04:18:46.3179057Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T04:18:46.3182118Z      at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:182)
2020-10-12T04:18:46.3186019Z      at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:164)
2020-10-12T04:18:46.3188818Z      at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:413)
2020-10-12T04:18:46.3190857Z      at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:64)
2020-10-12T04:18:46.3192936Z      at org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48)
2020-10-12T04:18:46.3194844Z      at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2020-10-12T04:18:46.3196573Z      at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2020-10-12T04:18:46.3198432Z      at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56)
2020-10-12T04:18:46.3199849Z      at java.base/java.lang.Thread.run(Thread.java:834)
2020-10-12T04:18:46.3200652Z     Caused by: java.io.IOException: Inbound response message already received
2020-10-12T04:18:46.3203844Z      at org.ballerinalang.net.transport.contractimpl.sender.states.SendingEntityBody.readInboundResponseHeaders(SendingEntityBody.java:90)
2020-10-12T04:18:46.3209879Z      at org.ballerinalang.net.transport.contractimpl.common.states.SenderReqRespStateManager.readInboundResponseHeaders(SenderReqRespStateManager.java:54)
2020-10-12T04:18:46.3215219Z      at org.ballerinalang.net.transport.contractimpl.sender.TargetHandler.channelRead(TargetHandler.java:85)
2020-10-12T04:18:46.3218378Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3221371Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3224273Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3226771Z      at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286)
2020-10-12T04:18:46.3229454Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3232431Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3235311Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3237938Z      at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:102)
2020-10-12T04:18:46.3240605Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3243606Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3246486Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3249528Z      at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436)
2020-10-12T04:18:46.3252385Z      at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:321)
2020-10-12T04:18:46.3254644Z      at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:295)
2020-10-12T04:18:46.3256992Z      at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251)
2020-10-12T04:18:46.3259752Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3262688Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3265589Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T04:18:46.3267912Z      at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410)
2020-10-12T04:18:46.3270373Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T04:18:46.3273365Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T04:18:46.3275935Z      at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919)
2020-10-12T04:18:46.3277980Z      at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
2020-10-12T04:18:46.3279917Z      at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714)
2020-10-12T04:18:46.3282067Z      at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:650)
2020-10-12T04:18:46.3284126Z      at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:576)
2020-10-12T04:18:46.3285670Z      at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493)
2020-10-12T04:18:46.3287299Z      at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2020-10-12T04:18:46.3289054Z      at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2020-10-12T04:18:46.3290842Z      at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2020-10-12T04:18:46.3292025Z      ... 1 more
2020-10-12T04:18:46.3292239Z 
2020-10-12T04:18:46.3293229Z     Oct 12, 2020 4:18:46 AM org.ballerinalang.net.transport.util.TestNGListener onTestFailure
2020-10-12T04:18:46.3294448Z     SEVERE: Test failed: postTest-> Exception occurred while running postTest
2020-10-12T04:18:46.3294831Z 
2020-10-12T04:18:46.3297183Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.http1point0test.ChunkAlwaysHttpOnePointZeroClientTestCase > postTest FAILED
2020-10-12T04:18:46.3300246Z     java.lang.AssertionError at ChunkAlwaysHttpOnePointZeroClientTestCase.java:59
2020-10-12T04:18:48.3077432Z 
2020-10-12T04:18:48.3078756Z Transport test suite > Transport HTTP test STANDARD_ERROR
2020-10-12T04:18:48.3082131Z     Oct 12, 2020 4:18:48 AM org.ballerinalang.net.transport.contractimpl.common.states.StateUtil handleIncompleteInboundMessage
2020-10-12T04:18:48.3085649Z     WARNING: Remote host closed the connection while reading inbound response body
2020-10-12T04:18:50.3146035Z     Oct 12, 2020 4:18:50 AM org.ballerinalang.net.transport.util.server.HttpServer shutdown
2020-10-12T04:18:50.3147187Z     INFO: HttpServer shutdown
2020-10-12T04:18:50.3147458Z

logs_694.zip

Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase STANDARD_ERROR
2020-10-12T14:19:32.2281981Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.server.HttpServer start
2020-10-12T14:19:32.2283317Z     INFO: HttpServer started on port 9000
2020-10-12T14:19:32.2936874Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestNGListener onTestStart
2020-10-12T14:19:32.2938323Z     INFO: Test running: postTest
2020-10-12T14:19:32.2939131Z 
2020-10-12T14:19:32.2940954Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase > postTest STANDARD_ERROR
2020-10-12T14:19:32.2945785Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.contractimpl.sender.states.ReceivingEntityBody writeOutboundRequestEntity
2020-10-12T14:19:32.2948121Z     WARNING: writeOutboundRequestEntity is not a valid action of this state
2020-10-12T14:19:32.2949344Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestUtil handleException
2020-10-12T14:19:32.2950389Z     SEVERE: Exception occurred while running postTest
2020-10-12T14:19:32.2951472Z     io.netty.handler.codec.EncoderException: java.io.IOException: Inbound response message already received
2020-10-12T14:19:32.2953934Z      at org.ballerinalang.net.transport.message.HttpMessageDataStreamer$ByteBufferOutputStream.close(HttpMessageDataStreamer.java:170)
2020-10-12T14:19:32.2957323Z      at org.ballerinalang.net.transport.chunkdisable.ChunkClientTemplate.sendRequest(ChunkClientTemplate.java:79)
2020-10-12T14:19:32.2961018Z      at org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase.postTest(ChunkEnableClientTestCase.java:45)
2020-10-12T14:19:32.2964313Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.2966403Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.2968895Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.2970685Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.2972322Z      at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
2020-10-12T14:19:32.2974242Z      at org.testng.internal.Invoker.invokeMethod(Invoker.java:583)
2020-10-12T14:19:32.2975547Z      at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:719)
2020-10-12T14:19:32.2976956Z      at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:989)
2020-10-12T14:19:32.2978704Z      at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:125)
2020-10-12T14:19:32.2980487Z      at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:109)
2020-10-12T14:19:32.2981683Z      at org.testng.TestRunner.privateRun(TestRunner.java:648)
2020-10-12T14:19:32.2982577Z      at org.testng.TestRunner.run(TestRunner.java:505)
2020-10-12T14:19:32.2983480Z      at org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
2020-10-12T14:19:32.2984578Z      at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
2020-10-12T14:19:32.2985718Z      at org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
2020-10-12T14:19:32.2986672Z      at org.testng.SuiteRunner.run(SuiteRunner.java:364)
2020-10-12T14:19:32.2987755Z      at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
2020-10-12T14:19:32.2988993Z      at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
2020-10-12T14:19:32.2990168Z      at org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
2020-10-12T14:19:32.2991259Z      at org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
2020-10-12T14:19:32.2992149Z      at org.testng.TestNG.runSuites(TestNG.java:1049)
2020-10-12T14:19:32.2992852Z      at org.testng.TestNG.run(TestNG.java:1017)
2020-10-12T14:19:32.2995017Z      at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:141)
2020-10-12T14:19:32.2998560Z      at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:90)
2020-10-12T14:19:32.3001662Z      at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
2020-10-12T14:19:32.3004085Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.3006176Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.3008609Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.3010394Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.3011947Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T14:19:32.3014146Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T14:19:32.3016692Z      at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33)
2020-10-12T14:19:32.3019486Z      at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94)
2020-10-12T14:19:32.3021085Z      at com.sun.proxy.$Proxy5.stop(Unknown Source)
2020-10-12T14:19:32.3022435Z      at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:132)
2020-10-12T14:19:32.3024470Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2020-10-12T14:19:32.3026622Z      at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2020-10-12T14:19:32.3029752Z      at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2020-10-12T14:19:32.3031749Z      at java.base/java.lang.reflect.Method.invoke(Method.java:566)
2020-10-12T14:19:32.3033477Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2020-10-12T14:19:32.3035779Z      at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2020-10-12T14:19:32.3039503Z      at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:182)
2020-10-12T14:19:32.3043687Z      at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:164)
2020-10-12T14:19:32.3046677Z      at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:413)
2020-10-12T14:19:32.3048793Z      at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:64)
2020-10-12T14:19:32.3050962Z      at org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48)
2020-10-12T14:19:32.3053071Z      at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2020-10-12T14:19:32.3055803Z      at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2020-10-12T14:19:32.3057856Z      at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56)
2020-10-12T14:19:32.3059419Z      at java.base/java.lang.Thread.run(Thread.java:834)
2020-10-12T14:19:32.3060301Z     Caused by: java.io.IOException: Inbound response message already received
2020-10-12T14:19:32.3063669Z      at org.ballerinalang.net.transport.contractimpl.sender.states.SendingEntityBody.readInboundResponseHeaders(SendingEntityBody.java:90)
2020-10-12T14:19:32.3070040Z      at org.ballerinalang.net.transport.contractimpl.common.states.SenderReqRespStateManager.readInboundResponseHeaders(SenderReqRespStateManager.java:54)
2020-10-12T14:19:32.3075805Z      at org.ballerinalang.net.transport.contractimpl.sender.TargetHandler.channelRead(TargetHandler.java:85)
2020-10-12T14:19:32.3081839Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3085736Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3088788Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3092774Z      at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286)
2020-10-12T14:19:32.3095802Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3098865Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3101840Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3104568Z      at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:102)
2020-10-12T14:19:32.3109971Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3113044Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3116698Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3119807Z      at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436)
2020-10-12T14:19:32.3123815Z      at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:321)
2020-10-12T14:19:32.3126174Z      at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:295)
2020-10-12T14:19:32.3128570Z      at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251)
2020-10-12T14:19:32.3131366Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3134404Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3138005Z      at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:355)
2020-10-12T14:19:32.3140387Z      at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410)
2020-10-12T14:19:32.3142845Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:377)
2020-10-12T14:19:32.3146306Z      at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:363)
2020-10-12T14:19:32.3148980Z      at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919)
2020-10-12T14:19:32.3151080Z      at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
2020-10-12T14:19:32.3152942Z      at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714)
2020-10-12T14:19:32.3155816Z      at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:650)
2020-10-12T14:19:32.3157951Z      at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:576)
2020-10-12T14:19:32.3159491Z      at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493)
2020-10-12T14:19:32.3161141Z      at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
2020-10-12T14:19:32.3162943Z      at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
2020-10-12T14:19:32.3164780Z      at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2020-10-12T14:19:32.3165981Z      ... 1 more
2020-10-12T14:19:32.3166203Z 
2020-10-12T14:19:32.3167198Z     Oct 12, 2020 2:19:32 PM org.ballerinalang.net.transport.util.TestNGListener onTestFailure
2020-10-12T14:19:32.3168441Z     SEVERE: Test failed: postTest-> Exception occurred while running postTest
2020-10-12T14:19:32.3168840Z 
2020-10-12T14:19:32.3170495Z Transport test suite > Transport HTTP test > org.ballerinalang.net.transport.chunkdisable.ChunkEnableClientTestCase > postTest FAILED
2020-10-12T14:19:32.3172665Z     java.lang.AssertionError at ChunkEnableClientTestCase.java:52
2020-10-12T14:19:34.2936252Z 
2020-10-12T14:19:34.2937245Z Transport test suite > Transport HTTP test STANDARD_ERROR
2020-10-12T14:19:34.2939255Z     Oct 12, 2020 2:19:34 PM org.ballerinalang.net.transport.contractimpl.common.states.StateUtil handleIncompleteInboundMessage
2020-10-12T14:19:34.2942386Z     WARNING: Remote host closed the connection while reading inbound response body
2020-10-12T14:19:36.2938583Z     Oct 12, 2020 2:19:36 PM org.ballerinalang.net.transport.util.server.HttpServer shutdown
2020-10-12T14:19:36.2940081Z     INFO: HttpServer shutdown
2020-10-12T14:19:36.2940478Z

Al three tests intermittently fails when a POST request is send with the large payload. This happens when readInboundResponseHeaders is called before Request Complete state. Only occurred in windows.

TharmiganK commented 2 years ago

Intermittent failures summary :

Test Case Reported in Already Enabled Reproducable Possible Reason Additional comments
ChunkAlwaysHttpOnePointZeroClient : postTest Windows NO YES (Only in Windows) Headers received before completing the request  
ChunkEnableClient : postTest Windows NO YES (Only in Windows) Headers received before completing the request  
ChunkAutoClient : postTest Windows NO YES (Only in Windows) Headers received before completing the request  
HttpsProxyServer : testHttpsProxyServer Ubuntu NO NO   Ran with all the test cases multiple times could not reproduce
ConnectionPoolWaitingTimeout : testWaitingForConnectionTimeout Windows NO NO   Could not reproduce by running the test multiple times
OCSPStapling : testretrievingResponseFromWeb Windows and Ubuntu NO YES (in both) Due to intermittent network failure  
TimeoutAfterRequestReceived : testServerTimeout Windows YES NO    
testCBTrialRunFailure Windows YES NO    
testRetryWithMultiPart Windows YES NO   Already observed in ubuntu but could not reproduce it
chamil321 commented 2 years ago

Saw this in ubuntu build as well

Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.SSLProtocolsTest STANDARD_ERROR
    May 13, 2022 8:21:46 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestStart
    INFO: Test running: setup
Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.SSLProtocolsTest > setup[1](TLSv1.1, TLSv1.2, true, 9002) STANDARD_ERROR
    Exception in thread "pool-28-thread-1" java.lang.NullPointerException
        at io.ballerina.stdlib.http.transport.message.DefaultListener.onRemove(DefaultListener.java:62)
        at io.ballerina.stdlib.http.transport.message.DefaultObservable.notifyGetListener(DefaultObservable.java:50)
        at io.ballerina.stdlib.http.transport.message.HttpCarbonMessage.getHttpContent(Unknown Source)
        at io.ballerina.stdlib.http.transport.util.server.listeners.Continue100Listener.lambda$onMessage$0(Continue100Listener.java:74)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
        at java.base/java.lang.Thread.run(Thread.java:829)
    May 13, 2022 8:21:51 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestSuccess
    INFO: Test successful: setup
Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.SSLProtocolsTest > setup[1](TLSv1.1, TLSv1.2, true, 9002) PASSED
Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.MutualSSLTestCase STANDARD_OUT
    Start Running Mutual S S L Test Case ...
Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.MutualSSLTestCase STANDARD_ERROR
    May 13, 2022 8:21:57 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestStart
    INFO: Test running: testHttpsPost
Transport test suite > Transport Security Tests > io.ballerina.stdlib.http.transport.https.MutualSSLTestCase > testHttpsPost STANDARD_ERROR
    May 13, 2022 8:21:57 AM io.netty.util.ResourceLeakDetector reportTracedLeak
    SEVERE: LEAK: ByteBuf.release() was not called before it's garbage-collected. See https://netty.io/wiki/reference-counted-objects.html for more information.
    Recent access records: 
    Created at:
        io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:403)
        io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:188)
        io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:179)
        io.netty.buffer.AbstractByteBufAllocator.ioBuffer(AbstractByteBufAllocator.java:140)
        io.netty.channel.DefaultMaxMessagesRecvByteBufAllocator$MaxMessageHandle.allocate(DefaultMaxMessagesRecvByteBufAllocator.java:120)
        io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:150)
        io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722)
        io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658)
        io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584)
        io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496)
        io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:995)
        io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
        io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
        java.base/java.lang.Thread.run(Thread.java:829)
    May 13, 2022 8:21:57 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestSuccess
    INFO: Test successful: testHttpsPost
TharmiganK commented 2 years ago

Http2ForwardedEnableWithoutForceHttp2 : This transport test seems to be failing intermittently

Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.http2forwardedextension.Http2ForwardedEnableWithoutForceHttp2 STANDARD_OUT
    Start Running Http2 Forwarded Enable Without Force Http2 ...

Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.http2forwardedextension.Http2ForwardedEnableWithoutForceHttp2 > setUp FAILED
    java.net.BindException at Net.java:-2

Transport test suite > Transport HTTP2 Tests > io.ballerina.stdlib.http.transport.http2.http2forwardedextension.Http2ForwardedEnableWithoutForceHttp2 > testMultipleHeader STANDARD_ERROR
    Jul 22, 2022 9:06:18 AM io.ballerina.stdlib.http.transport.util.TestNGListener onTestStart
    INFO: Test running: testMultipleHeader

Failure Logs : https://drive.google.com/file/d/1nsv4sVAnmSrQFIork0QBUidijDcF1nvD/view?usp=sharing