[
https://issues.apache.org/jira/browse/PLC4X-163?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17009771#comment-17009771
]
Sebastian Wiendl commented on PLC4X-163:
----------------------------------------
[~julian.feinauer] I just retried my Scraper Example with the 0.6.0-SNAPSHOT
but can't get it to work. Starting the scraper causes the following log
output:{code}2020-01-07 15:27:40.951 DEBUG 21204 --- [nio-8080-exec-8]
c.e.plc4jscraperdemo.ScraperService : start()
2020-01-07 15:27:40.951 INFO 21204 --- [nio-8080-exec-8]
o.a.p.j.s.t.TriggeredScraperImpl : Starting jobs...
2020-01-07 15:27:40.951 DEBUG 21204 --- [nio-8080-exec-8]
o.a.p.j.s.t.TriggeredScraperImpl : Register task for job example for
conn S7NG (s7ng://172.30.74.65/0/0) at rate 100 ms
2020-01-07 15:27:40.951 DEBUG 21204 --- [nio-8080-exec-8]
o.a.p.j.s.t.t.TriggerConfiguration : Strategy: SCHEDULED, scheduled ms:
100
2020-01-07 15:27:40.951 INFO 21204 --- [nio-8080-exec-8]
o.a.p.j.s.t.TriggeredScraperImpl : Task
TriggeredScraperTask{driverManager=org.apache.plc4x.java.utils.connectionpool.PooledPlcDriverManager@49f815a3,
jobName='example', connectionAlias='S7NG',
connectionString='s7ng://172.30.74.65/0/0', requestTimeoutMs=2000,
executorService=java.util.concurrent.ThreadPoolExecutor@e384576[Running, pool
size = 2, active threads = 0, queued tasks = 0, completed tasks = 2],
resultHandler=ScraperResultHandler(latest=,
sessions=[StandardWebSocketSession[id=667792a6-2f30-225a-0a51-ebac441e217e,
uri=ws://localhost:8080/scraper-service]]),
triggerHandler=org.apache.plc4x.java.scraper.triggeredscraper.triggerhandler.TriggerHandlerImpl@478289b2}
added to scheduling
2020-01-07 15:27:40.951 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:40.956 ERROR 21204 --- [ntLoopGroup-2-1]
o.a.p.j.s.r.types.DataTransportSize : No DataTransportSize for value -1
2020-01-07 15:27:40.957 WARN 21204 --- [ntLoopGroup-2-1]
.p.j.s.GeneratedDriverByteToMessageCodec : Error decoding package with content
[0300014d02f08032030000000c0002013800000409ff05002000000003ff0300010000ff0500100002ff05001016f6ff0300010000ff050010fffbff0300010100ff070004c10a3a1dff090100fe0a5465737473797374656d00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000]:
Unable to use the parse Method!
java.lang.IllegalStateException: Unable to use the parse Method!
at
org.apache.plc4x.java.spi.connection.ReflectionBasedIo.parse(ReflectionBasedIo.java:52)
~[plc4j-spi-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.spi.connection.GeneratedProtocolMessageCodec$1.parse(GeneratedProtocolMessageCodec.java:54)
~[plc4j-spi-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.spi.connection.GeneratedProtocolMessageCodec$1.parse(GeneratedProtocolMessageCodec.java:50)
~[plc4j-spi-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.spi.GeneratedDriverByteToMessageCodec.decode(GeneratedDriverByteToMessageCodec.java:71)
~[plc4j-spi-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
io.netty.handler.codec.ByteToMessageCodec$1.decode(ByteToMessageCodec.java:42)
[netty-codec-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.decodeRemovalReentryProtection(ByteToMessageDecoder.java:503)
[netty-codec-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:442)
[netty-codec-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:281)
[netty-codec-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.handler.codec.ByteToMessageCodec.channelRead(ByteToMessageCodec.java:103)
[netty-codec-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:360)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:352)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1422)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:360)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:931)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
[netty-transport-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
[netty-common-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
[netty-common-4.1.43.Final.jar:4.1.43.Final]
at
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
[netty-common-4.1.43.Final.jar:4.1.43.Final]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
Caused by: java.lang.reflect.InvocationTargetException: null
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
~[na:1.8.0_201]
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
~[na:1.8.0_201]
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
~[na:1.8.0_201]
at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_201]
at
org.apache.plc4x.java.spi.connection.ReflectionBasedIo.parse(ReflectionBasedIo.java:50)
~[plc4j-spi-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
... 24 common frames omitted
Caused by: java.lang.NullPointerException: null
at
org.apache.plc4x.java.s7.readwrite.io.S7VarPayloadDataItemIO.parse(S7VarPayloadDataItemIO.java:57)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.s7.readwrite.io.S7PayloadReadVarResponseIO.parse(S7PayloadReadVarResponseIO.java:57)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.s7.readwrite.io.S7PayloadIO.parse(S7PayloadIO.java:55)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.s7.readwrite.io.S7MessageIO.parse(S7MessageIO.java:91)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.s7.readwrite.io.COTPPacketIO.parse(COTPPacketIO.java:92)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
org.apache.plc4x.java.s7.readwrite.io.TPKTPacketIO.parse(TPKTPacketIO.java:64)
~[test-java-s7-driver-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
... 29 common frames omitted
2020-01-07 15:27:41.015 DEBUG 21204 --- [duling-thread-1]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:41.016 DEBUG 21204 --- [duling-thread-1]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 1 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:41.952 DEBUG 21204 --- [uling-thread-18]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:41.952 DEBUG 21204 --- [uling-thread-18]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 1 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:42.016 DEBUG 21204 --- [uling-thread-15]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:42.016 DEBUG 21204 --- [uling-thread-15]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 1 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:42.953 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:42.953 DEBUG 21204 --- [duling-thread-2]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:42.953 DEBUG 21204 --- [duling-thread-2]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 1 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:42.953 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:42.954 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:43.016 DEBUG 21204 --- [uling-thread-17]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:43.016 DEBUG 21204 --- [uling-thread-17]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:43.952 DEBUG 21204 --- [duling-thread-4]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:43.952 DEBUG 21204 --- [duling-thread-4]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:44.016 DEBUG 21204 --- [uling-thread-20]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:44.016 DEBUG 21204 --- [uling-thread-20]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:44.951 DEBUG 21204 --- [duling-thread-7]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:44.951 DEBUG 21204 --- [duling-thread-7]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:44.955 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:44.955 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:44.956 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:45.016 DEBUG 21204 --- [uling-thread-13]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:45.016 DEBUG 21204 --- [uling-thread-13]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 3 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:45.951 DEBUG 21204 --- [uling-thread-12]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:45.952 DEBUG 21204 --- [uling-thread-12]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 3 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:46.017 DEBUG 21204 --- [duling-thread-3]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:46.017 DEBUG 21204 --- [duling-thread-3]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 3 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:46.951 DEBUG 21204 --- [uling-thread-19]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:46.951 DEBUG 21204 --- [uling-thread-19]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 3 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:46.958 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:46.959 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:46.959 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:47.015 DEBUG 21204 --- [duling-thread-8]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:47.016 DEBUG 21204 --- [duling-thread-8]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 4 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:47.951 DEBUG 21204 --- [uling-thread-16]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:47.951 DEBUG 21204 --- [uling-thread-16]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 4 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:48.016 DEBUG 21204 --- [duling-thread-6]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:48.016 DEBUG 21204 --- [duling-thread-6]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 4 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:48.951 DEBUG 21204 --- [duling-thread-1]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:48.951 DEBUG 21204 --- [duling-thread-1]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 4 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:48.961 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:48.962 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:48.962 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:49.016 DEBUG 21204 --- [uling-thread-18]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:49.016 DEBUG 21204 --- [uling-thread-18]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 5 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:49.951 DEBUG 21204 --- [uling-thread-15]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:49.951 DEBUG 21204 --- [uling-thread-15]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 5 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:50.016 DEBUG 21204 --- [uling-thread-11]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:50.016 DEBUG 21204 --- [uling-thread-11]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 5 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:50.951 DEBUG 21204 --- [uling-thread-10]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:50.952 DEBUG 21204 --- [uling-thread-10]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 5 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:50.963 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:50.963 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:50.963 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:51.015 DEBUG 21204 --- [uling-thread-17]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:51.015 DEBUG 21204 --- [uling-thread-17]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 6 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:51.952 DEBUG 21204 --- [duling-thread-4]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:51.952 DEBUG 21204 --- [duling-thread-4]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 6 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:52.015 DEBUG 21204 --- [duling-thread-7]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:52.016 DEBUG 21204 --- [duling-thread-7]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 6 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:52.952 DEBUG 21204 --- [uling-thread-14]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:52.952 DEBUG 21204 --- [uling-thread-14]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 6 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:52.964 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:52.964 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.util.concurrent.TimeoutException: null
at
java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1771)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
2020-01-07 15:27:52.965 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Trigger for job example and device
S7NG is met ... scraping desired data
2020-01-07 15:27:53.016 DEBUG 21204 --- [uling-thread-13]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 2 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:53.016 DEBUG 21204 --- [uling-thread-13]
o.a.p.j.s.t.TriggeredScraperImpl : Job statistics (example, S7NG)
number of requests: 7 (0 success, 100.0 % failed, NaN % too slow), min latency:
NaN ms, mean latency: NaN ms, median: NaN ms
2020-01-07 15:27:53.833 DEBUG 21204 --- [nio-8080-exec-9]
c.e.plc4jscraperdemo.ScraperService : stop()
2020-01-07 15:27:53.833 INFO 21204 --- [nio-8080-exec-9]
o.a.p.j.s.t.TriggeredScraperImpl : Stopping scraper...
2020-01-07 15:27:53.833 DEBUG 21204 --- [nio-8080-exec-9]
o.a.p.j.s.t.TriggeredScraperImpl : Stopping task
TriggeredScraperTask{driverManager=org.apache.plc4x.java.utils.connectionpool.PooledPlcDriverManager@49f815a3,
jobName='example', connectionAlias='S7NG',
connectionString='s7ng://172.30.74.65/0/0', requestTimeoutMs=2000,
executorService=java.util.concurrent.ThreadPoolExecutor@e384576[Running, pool
size = 5, active threads = 0, queued tasks = 0, completed tasks = 9],
resultHandler=ScraperResultHandler(latest=,
sessions=[StandardWebSocketSession[id=667792a6-2f30-225a-0a51-ebac441e217e,
uri=ws://localhost:8080/scraper-service]]),
triggerHandler=org.apache.plc4x.java.scraper.triggeredscraper.triggerhandler.TriggerHandlerImpl@478289b2}...
2020-01-07 15:27:53.834 WARN 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Exception during scraping of Job
example, Connection-Alias S7NG: Error-message: null - for stack-trace change
logging to DEBUG
2020-01-07 15:27:53.834 DEBUG 21204 --- [duling-thread-9]
o.a.p.j.s.t.TriggeredScraperTask : Detailed exception occurred at
scraping
java.lang.InterruptedException: null
at
java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:347)
~[na:1.8.0_201]
at
java.util.concurrent.CompletableFuture.get(CompletableFuture.java:1915)
~[na:1.8.0_201]
at
org.apache.plc4x.java.scraper.triggeredscraper.TriggeredScraperTask.run(TriggeredScraperTask.java:131)
~[plc4j-scraper-0.6.0-SNAPSHOT.jar:0.6.0-SNAPSHOT]
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[na:1.8.0_201]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[na:1.8.0_201]
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[na:1.8.0_201]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[na:1.8.0_201]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
{code} My Job config is as follows: !screenshot-1.png!
I will also updated the example code (see first comment for repo), it is in the
feature/ng-driver branch. I just changed the scheme to s7ng and added the
dependency on the new driver.
> Netty OutOfDirectMemoryError
> ----------------------------
>
> Key: PLC4X-163
> URL: https://issues.apache.org/jira/browse/PLC4X-163
> Project: Apache PLC4X
> Issue Type: Bug
> Components: Util-Scraper
> Affects Versions: 0.5.0
> Environment: Ubuntu 16.04 LTS
> Java 1.8.0_201
> {code}$ java -XX:+PrintFlagsFinal -version | grep -iE
> 'HeapSize|PermSize|ThreadStackSize'
> intx CompilerThreadStackSize = 0
> {pd product}
> uintx ErgoHeapSizeLimit = 0
> {product}
> uintx HeapSizePerGCThread = 87241520
> {product}
> uintx InitialHeapSize := 132120576
> {product}
> uintx LargePageHeapSizeThreshold = 134217728
> {product}
> uintx MaxHeapSize := 2092957696
> {product}
> intx ThreadStackSize = 1024
> {pd product}
> intx VMThreadStackSize = 1024
> {pd product}
> java version "1.8.0_201"
> Java(TM) SE Runtime Environment (build 1.8.0_201-b09)
> Java HotSpot(TM) 64-Bit Server VM (build 25.201-b09, mixed mode){code}
> Reporter: Sebastian Wiendl
> Assignee: Julian Feinauer
> Priority: Blocker
> Attachments: screenshot-1.png
>
>
> After roughly 5-7 days of continued scraper operation (about 10 PLC tags,
> 100ms sampling rate), the scraper stops working with the following log
> output:{code}2019-12-12 18:08:29.569 WARN 23031 --- [nioEventLoopGroup-2-1]
> io.netty.channel.DefaultChannelPipeline : An exceptionCaught() event was
> fired, and it reached at the tail of the pipeline. It usually means the last
> handler in the pipeline did not handle the exception.
> io.netty.util.internal.OutOfDirectMemoryError: failed to allocate 16777216
> byte(s) of direct memory (used: 1845493760, max: 1860698112)
> at
> io.netty.util.internal.PlatformDependent.incrementMemoryCounter(PlatformDependent.java:726)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.PlatformDependent.allocateDirectNoCleaner(PlatformDependent.java:681)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PoolArena$DirectArena.allocateDirect(PoolArena.java:758)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena$DirectArena.newChunk(PoolArena.java:734)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocateNormal(PoolArena.java:245)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:215)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:147)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:342)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:187)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:178)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.ioBuffer(AbstractByteBufAllocator.java:139)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultMaxMessagesRecvByteBufAllocator$MaxMessageHandle.allocate(DefaultMaxMessagesRecvByteBufAllocator.java:114)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:147)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
> 2019-12-12 18:08:29.569 WARN 23031 --- [nioEventLoopGroup-2-1]
> io.netty.channel.DefaultChannelPipeline : An exceptionCaught() event was
> fired, and it reached at the tail of the pipeline. It usually means the last
> handler in the pipeline did not handle the exception.
> io.netty.util.internal.OutOfDirectMemoryError: failed to allocate 16777216
> byte(s) of direct memory (used: 1845493760, max: 1860698112)
> at
> io.netty.util.internal.PlatformDependent.incrementMemoryCounter(PlatformDependent.java:726)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.PlatformDependent.allocateDirectNoCleaner(PlatformDependent.java:681)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PoolArena$DirectArena.allocateDirect(PoolArena.java:758)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena$DirectArena.newChunk(PoolArena.java:734)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocateNormal(PoolArena.java:245)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:215)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:147)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:342)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:187)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:178)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.ioBuffer(AbstractByteBufAllocator.java:139)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultMaxMessagesRecvByteBufAllocator$MaxMessageHandle.allocate(DefaultMaxMessagesRecvByteBufAllocator.java:114)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:147)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
> 2019-12-12 18:08:29.570 WARN 23031 --- [nioEventLoopGroup-2-1]
> io.netty.channel.DefaultChannelPipeline : An exceptionCaught() event was
> fired, and it reached at the tail of the pipeline. It usually means the last
> handler in the pipeline did not handle the exception.
> io.netty.util.internal.OutOfDirectMemoryError: failed to allocate 16777216
> byte(s) of direct memory (used: 1845493760, max: 1860698112)
> at
> io.netty.util.internal.PlatformDependent.incrementMemoryCounter(PlatformDependent.java:726)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.PlatformDependent.allocateDirectNoCleaner(PlatformDependent.java:681)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PoolArena$DirectArena.allocateDirect(PoolArena.java:758)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena$DirectArena.newChunk(PoolArena.java:734)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocateNormal(PoolArena.java:245)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:215)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:147)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:342)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:187)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:178)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.ioBuffer(AbstractByteBufAllocator.java:139)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultMaxMessagesRecvByteBufAllocator$MaxMessageHandle.allocate(DefaultMaxMessagesRecvByteBufAllocator.java:114)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:147)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
> 2019-12-12 18:08:29.570 WARN 23031 --- [nioEventLoopGroup-2-1]
> io.netty.channel.DefaultChannelPipeline : An exceptionCaught() event was
> fired, and it reached at the tail of the pipeline. It usually means the last
> handler in the pipeline did not handle the exception.
> io.netty.util.internal.OutOfDirectMemoryError: failed to allocate 16777216
> byte(s) of direct memory (used: 1845493760, max: 1860698112)
> at
> io.netty.util.internal.PlatformDependent.incrementMemoryCounter(PlatformDependent.java:726)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.PlatformDependent.allocateDirectNoCleaner(PlatformDependent.java:681)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PoolArena$DirectArena.allocateDirect(PoolArena.java:758)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena$DirectArena.newChunk(PoolArena.java:734)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocateNormal(PoolArena.java:245)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:215)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:147)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:342)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:187)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:178)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.ioBuffer(AbstractByteBufAllocator.java:139)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultMaxMessagesRecvByteBufAllocator$MaxMessageHandle.allocate(DefaultMaxMessagesRecvByteBufAllocator.java:114)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:147)
> ~[netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
> 2019-12-12 18:08:29.570 WARN 23031 --- [nioEventLoopGroup-2-1]
> io.netty.channel.DefaultChannelPipeline : An exceptionCaught() event was
> fired, and it reached at the tail of the pipeline. It usually means the last
> handler in the pipeline did not handle the exception.
> io.netty.util.internal.OutOfDirectMemoryError: failed to allocate 16777216
> byte(s) of direct memory (used: 1845493760, max: 1860698112)
> at
> io.netty.util.internal.PlatformDependent.incrementMemoryCounter(PlatformDependent.java:726)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.PlatformDependent.allocateDirectNoCleaner(PlatformDependent.java:681)
> ~[netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PoolArena$DirectArena.allocateDirect(PoolArena.java:758)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena$DirectArena.newChunk(PoolArena.java:734)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocateNormal(PoolArena.java:245)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:215)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.PoolArena.allocate(PoolArena.java:147)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:342)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:187)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.buffer.AbstractByteBufAllocator.buffer(AbstractByteBufAllocator.java:123)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.buffer.AbstractByteBuf.readBytes(AbstractByteBuf.java:886)
> ~[netty-buffer-4.1.43.Final.jar!/:4.1.43.Final]
> at
> org.apache.plc4x.java.isoontcp.protocol.IsoOnTcpProtocol.decode(IsoOnTcpProtocol.java:100)
> ~[plc4j-protocol-iso-on-tcp-0.5.0.jar!/:0.5.0]
> at
> io.netty.handler.codec.ByteToMessageCodec$1.decode(ByteToMessageCodec.java:42)
> ~[netty-codec-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.handler.codec.ByteToMessageDecoder.decodeRemovalReentryProtection(ByteToMessageDecoder.java:503)
> ~[netty-codec-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:442)
> ~[netty-codec-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:281)
> ~[netty-codec-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.handler.codec.ByteToMessageCodec.channelRead(ByteToMessageCodec.java:103)
> ~[netty-codec-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:360)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:352)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1422)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:360)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:931)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:700)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:635)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:552)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:514)
> [netty-transport-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1050)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at
> io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
> [netty-common-4.1.43.Final.jar!/:4.1.43.Final]
> at java.lang.Thread.run(Thread.java:748) [na:1.8.0_201]
> 2019-12-12 18:08:31.563 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:33.564 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:35.565 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:37.566 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:39.566 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:41.567 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:43.568 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:45.568 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:47.569 WARN 23031 ---
> [triggeredscraper-scheduling-thread-20] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:49.569 WARN 23031 ---
> [triggeredscraper-scheduling-thread-7] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG
> 2019-12-12 18:08:51.570 WARN 23031 ---
> [triggeredscraper-scheduling-thread-7] o.a.p.j.s.t.TriggeredScraperTask
> : Exception during scraping of Job example, Connection-Alias S7:
> Error-message: null - for stack-trace change logging to DEBUG{code}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
