AvroRpcHttpTest fails on the plaform
- Dominant language
- Java
- Stars
- 302
- Forks
- 232
- Avg merge
- 1d 20h
- Merged PRs (30d)
- 114
Description
Happened on Java 8 in https://github.com/quarkusio/quarkus-platform/pull/214 https://github.com/quarkusio/quarkus-platform/pull/214/checks?check_run_id=2247278967
I cannot reproduce it locally. Let's
```
[INFO] [INFO] Running org.apache.camel.quarkus.component.avro.rpc.it.AvroRpcHttpTest
[INFO] 2021-04-01 16:05:49,849 WARN [io.qua.dep.QuarkusAugmentor] (main) Using Java versions older than 11 to build Quarkus applications is deprecated and will be disallowed in a future release!
[INFO] 2021-04-01 16:05:51,602 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 41993
[INFO] 2021-04-01 16:05:51,974 INFO [org.ecl.jet.uti.log] (pool-3-thread-1) Logging initialized @5629ms to org.eclipse.jetty.util.log.Slf4jLog
[INFO] 2021-04-01 16:05:52,033 INFO [org.ecl.jet.ser.Server] (pool-3-thread-1) jetty-9.4.36.v20210114; built: 2021-01-14T16:44:28.689Z; git: 238ec6997c7806b055319a6d11f8ae7564adc0de; jvm 1.8.0_282-b08
[INFO] 2021-04-01 16:05:52,065 INFO [org.ecl.jet.ser.han.ContextHandler] (pool-3-thread-1) Started o.e.j.s.ServletContextHandler@5955684b{/,null,AVAILABLE}
[INFO] 2021-04-01 16:05:52,080 INFO [org.ecl.jet.ser.AbstractConnector] (pool-3-thread-1) Started ServerConnector@11bd8e8{HTTP/1.1, (http/1.1)}{0.0.0.0:41993}
[INFO] 2021-04-01 16:05:52,080 INFO [org.ecl.jet.ser.Server] (pool-3-thread-1) Started @5734ms
[INFO] 2021-04-01 16:05:52,080 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 34279
[INFO] 2021-04-01 16:05:52,195 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 44741
[INFO] 2021-04-01 16:05:52,200 INFO [org.ecl.jet.ser.Server] (pool-3-thread-1) jetty-9.4.36.v20210114; built: 2021-01-14T16:44:28.689Z; git: 238ec6997c7806b055319a6d11f8ae7564adc0de; jvm 1.8.0_282-b08
[INFO] 2021-04-01 16:05:52,205 INFO [org.ecl.jet.ser.han.ContextHandler] (pool-3-thread-1) Started o.e.j.s.ServletContextHandler@66028f09{/,null,AVAILABLE}
[INFO] 2021-04-01 16:05:52,210 INFO [org.ecl.jet.ser.AbstractConnector] (pool-3-thread-1) Started ServerConnector@3365b724{HTTP/1.1, (http/1.1)}{0.0.0.0:44741}
[INFO] 2021-04-01 16:05:52,210 INFO [org.ecl.jet.ser.Server] (pool-3-thread-1) Started @5865ms
[INFO] 2021-04-01 16:05:52,210 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 34949
[INFO] 2021-04-01 16:05:52,213 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 40147
[INFO] 2021-04-01 16:05:52,213 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 36721
[INFO] 2021-04-01 16:05:52,213 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 43487
[INFO] 2021-04-01 16:05:52,213 INFO [org.apa.cam.qua.tes.AvailablePortFinder] (pool-3-thread-1) getNextAvailable() -> 40147
[INFO] 2021-04-01 16:05:52,707 INFO [org.apa.cam.qua.cor.CamelBootstrapRecorder] (main) bootstrap runtime: org.apache.camel.quarkus.main.CamelMainRuntime
[INFO] 2021-04-01 16:05:52,844 INFO [org.ecl.jet.ser.Server] (main) jetty-9.4.36.v20210114; built: 2021-01-14T16:44:28.689Z; git: 238ec6997c7806b055319a6d11f8ae7564adc0de; jvm 1.8.0_282-b08
[INFO] 2021-04-01 16:05:52,853 INFO [org.ecl.jet.ser.han.ContextHandler] (main) Started o.e.j.s.ServletContextHandler@596416c5{/,null,AVAILABLE}
[INFO] 2021-04-01 16:05:52,854 INFO [org.ecl.jet.ser.AbstractConnector] (main) Started ServerConnector@6d68257{HTTP/1.1, (http/1.1)}{0.0.0.0:40147}
[INFO] 2021-04-01 16:05:52,855 INFO [org.ecl.jet.ser.Server] (main) Started @6510ms
[INFO] 2021-04-01 16:05:52,862 INFO [org.ecl.jet.ser.Server] (main) jetty-9.4.36.v20210114; built: 2021-01-14T16:44:28.689Z; git: 238ec6997c7806b055319a6d11f8ae7564adc0de; jvm 1.8.0_282-b08
[INFO] 2021-04-01 16:05:52,866 INFO [org.ecl.jet.ser.han.ContextHandler] (main) Started o.e.j.s.ServletContextHandler@1dae615d{/,null,AVAILABLE}
[INFO] 2021-04-01 16:05:52,871 INFO [org.ecl.jet.ser.AbstractConnector] (main) Started ServerConnector@1fe9a01c{HTTP/1.1, (http/1.1)}{0.0.0.0:43487}
[INFO] 2021-04-01 16:05:52,871 INFO [org.ecl.jet.ser.Server] (main) Started @6526ms
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Routes startup summary (total:4 started:4)
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Started route1 (http:localhost:40147/setTestPojo)
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Started route2 (netty:localhost:36721/setTestPojo)
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Started route3 (http:localhost:43487/put)
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Started route4 (netty:localhost:40147/put)
[INFO] 2021-04-01 16:05:52,873 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Apache Camel 3.9.0 (camel-1) started in 88ms (build:0ms init:46ms start:42ms)
[INFO] 2021-04-01 16:05:52,919 INFO [io.quarkus] (main) Quarkus 1.13.0.Final on JVM started in 3.180s. Listening on: http://localhost:45693
[INFO] 2021-04-01 16:05:52,920 INFO [io.quarkus] (main) Profile test activated.
[INFO] 2021-04-01 16:05:52,920 INFO [io.quarkus] (main) Installed features: [camel-avro-rpc, camel-core, camel-support-common, cdi, resteasy]
[INFO] [INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.301 s - in org.apache.camel.quarkus.component.avro.rpc.it.AvroRpcHttpTest
[INFO] [INFO] Running org.apache.camel.quarkus.component.avro.rpc.it.AvroRpcNettyTest
[INFO] 2021-04-01 16:05:54,763 INFO [org.apa.avr.ipc.net.NettyTransceiver] (avro 1) Connection to localhost/127.0.0.1:40147 disconnected.
[INFO] 2021-04-01 16:05:54,899 INFO [org.apa.avr.ipc.net.NettyTransceiver] (avro 1) Connection to localhost/127.0.0.1:36721 disconnected.
[INFO] 2021-04-01 16:05:54,899 INFO [org.apa.avr.ipc.net.NettyServer] (nioEventLoopGroup-9-1) Connection to /127.0.0.1:41594 disconnected.
Error: [ERROR] Tests run: 4, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.217 s <<< FAILURE! - in org.apache.camel.quarkus.component.avro.rpc.it.AvroRpcNettyTest
Error: [ERROR] testSpecificConsumer Time elapsed: 0.02 s <<< ERROR!
[INFO] io.netty.handler.codec.DecoderException: org.apache.avro.AvroRuntimeException: Excessively large list allocation request detected: 791752241 items! Connection closed.
[INFO] at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:471)
[INFO] at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:276)
[INFO] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379)
[INFO] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365)
[INFO] at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357)
[INFO] at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410)
[INFO] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379)
[INFO] at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365)
[INFO] at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919)
[INFO] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
[INFO] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714)
[INFO] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:650)
[INFO] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:576)
[INFO] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493)
[INFO] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989)
[INFO] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
[INFO] at java.lang.Thread.run(Thread.java:748)
[INFO] Caused by: org.apache.avro.AvroRuntimeException: Excessively large list allocation request detected: 791752241 items! Connection closed.
[INFO] at org.apache.avro.ipc.netty.NettyTransportCodec$NettyFrameDecoder.decodePackHeader(NettyTransportCodec.java:158)
[INFO] at org.apache.avro.ipc.netty.NettyTransportCodec$NettyFrameDecoder.decode(NettyTransportCodec.java:136)
[INFO] at io.netty.handler.codec.ByteToMessageDecoder.decodeRemovalReentryProtection(ByteToMessageDecoder.java:501)
[INFO] at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:440)
[INFO] ... 16 more
[INFO]
[INFO] 2021-04-01 16:05:54,936 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Apache Camel 3.9.0 (camel-1) shutting down
[INFO] 2021-04-01 16:05:54,949 INFO [org.ecl.jet.ser.AbstractConnector] (main) Stopped ServerConnector@1fe9a01c{HTTP/1.1, (http/1.1)}{0.0.0.0:43487}
[INFO] 2021-04-01 16:05:54,950 INFO [org.ecl.jet.ser.han.ContextHandler] (main) Stopped o.e.j.s.ServletContextHandler@1dae615d{/,null,STOPPED}
[INFO] 2021-04-01 16:05:58,969 INFO [org.ecl.jet.ser.AbstractConnector] (main) Stopped ServerConnector@6d68257{HTTP/1.1, (http/1.1)}{0.0.0.0:40147}
[INFO] 2021-04-01 16:05:58,970 INFO [org.ecl.jet.ser.han.ContextHandler] (main) Stopped o.e.j.s.ServletContextHandler@596416c5{/,null,STOPPED}
[INFO] 2021-04-01 16:05:58,974 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Routes shutdown summary (total:4 stopped:4)
[INFO] 2021-04-01 16:05:58,974 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Stopped route1 (http:localhost:40147/setTestPojo)
[INFO] 2021-04-01 16:05:58,974 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Stopped route2 (netty:localhost:36721/setTestPojo)
[INFO] 2021-04-01 16:05:58,974 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Stopped route3 (http:localhost:43487/put)
[INFO] 2021-04-01 16:05:58,974 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Stopped route4 (netty:localhost:40147/put)
[INFO] 2021-04-01 16:05:58,977 INFO [org.apa.cam.imp.eng.AbstractCamelContext] (main) Apache Camel 3.9.0 (camel-1) shutdown in 4s41ms (uptime:6s146ms)
[INFO] 2021-04-01 16:05:58,989 INFO [io.quarkus] (main) Quarkus stopped in 4.085s
[INFO] [INFO]
[INFO] [INFO] Results:
[INFO] [INFO]
Error: [ERROR] Errors:
Error: [ERROR] AvroRpcNettyTest.testSpecificConsumer » Decoder org.apache.avro.AvroRuntimeExc...
[INFO] [INFO]
Error: [ERROR] Tests run: 8, Failures: 0, Errors: 1, Skipped: 0
```
Contributor guide
No contributing guide indexed for this repository
Research direction
Start by rerunning AvroRpcNettyTest.testSpecificConsumer on Java 8 and compare it with AvroRpcHttpTest, using the reported AvroRuntimeException in NettyFrameDecoder as the starting point. Trace why the Netty test requests an excessively large list allocation and confirm that the test completes without the decoder error.
Written by the indexing model from the issue text.
Assessment
- Tech stack
- java
- Domain
- testing-qa
- Issue type
- Bug
- Difficulty
- 4/5
- Estimated time
- 3-5 days
- Activity status
- Stale
- Clarity
- Needs clarification
- Newbie friendliness
- 35/100