# Logstash Log: Could not retrieve remote IP address for beats input

**URL:** https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280
**Category:** Logstash
**Created:** [May 25, 2018, 8:22am UTC](https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280 "2018-05-25T08:22:26Z")
**Posts on this page:** 4
**Page:** 1

<div class="post-metadata">

### Author: ![Maekee](https://sea2.discourse-cdn.com/elastic/user_avatar/discuss.elastic.co/maekee/32/21708_2.png) [@Maekee](https://discuss.elastic.co/u/Maekee)
#### Post date: [May 25, 2018, 8:22am UTC](https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280/1 "2018-05-25T08:22:26Z")

</div>

Hi,  
Need help!  
All of a sudden i started getting these in my Logstash log... ALOT!! Like 2 every millisecond

I see that events are coming in from my beat agents, but not in the pace i usually see.  
Have not changed any configuration on either beat or Logstash-side.  
Running Logstash and ElasticSearch on the same server.

Logstash Log:  
[2018-05-25T09:55:43,871][INFO][logstash.modules.scaffold] Initializing module {:module\_name=\>"fb\_apache", :directory=\>"D:/Bin/Logstash/modules/fb\_apache/configuration"}  
[2018-05-25T09:55:43,875][INFO][logstash.modules.scaffold] Initializing module {:module\_name=\>"netflow", :directory=\>"D:/Bin/Logstash/modules/netflow/configuration"}  
[2018-05-25T09:55:43,916][WARN][logstash.config.source.multilocal] Ignoring the 'pipelines.yml' file because modules or command line options are specified  
[2018-05-25T09:55:44,577][INFO][logstash.agent] Successfully started Logstash API endpoint {:port=\>9600}  
[2018-05-25T09:55:47,511][INFO][logstash.outputs.elasticsearch] Elasticsearch pool URLs updated {:changes=\>{:removed=\>[], :added=\>[[http://elkserver01:9200/](http://elkserver01:9200/)]}}  
[2018-05-25T09:55:47,514][INFO][logstash.outputs.elasticsearch] Running health check to see if an Elasticsearch connection is working {:healthcheck\_url=\>[http://elkserver01:9200/](http://elkserver01:9200/), :path=\>"/"}  
[2018-05-25T09:55:47,609][WARN][logstash.outputs.elasticsearch] Restored connection to ES instance {:url=\>"[http://elkserver01:9200/](http://elkserver01:9200/)"}  
[2018-05-25T09:55:47,644][INFO][logstash.outputs.elasticsearch] New Elasticsearch output {:class=\>"LogStash::Outputs::ElasticSearch", :hosts=\>["//elkserver01:9200"]}  
[2018-05-25T09:55:47,650][INFO][logstash.outputs.elasticsearch] Elasticsearch pool URLs updated {:changes=\>{:removed=\>[], :added=\>[[http://elkserver01:9200/](http://elkserver01:9200/)]}}  
[2018-05-25T09:55:47,650][INFO][logstash.outputs.elasticsearch] Running health check to see if an Elasticsearch connection is working {:healthcheck\_url=\>[http://elkserver01:9200/](http://elkserver01:9200/), :path=\>"/"}  
[2018-05-25T09:55:47,654][WARN][logstash.outputs.elasticsearch] Restored connection to ES instance {:url=\>"[http://elkserver01:9200/](http://elkserver01:9200/)"}  
[2018-05-25T09:55:47,664][INFO][logstash.outputs.elasticsearch] New Elasticsearch output {:class=\>"LogStash::Outputs::ElasticSearch", :hosts=\>["//elkserver01:9200"]}  
[2018-05-25T09:55:47,668][INFO][logstash.outputs.elasticsearch] Elasticsearch pool URLs updated {:changes=\>{:removed=\>[], :added=\>[[http://elkserver01:9200/](http://elkserver01:9200/)]}}  
[2018-05-25T09:55:47,670][INFO][logstash.outputs.elasticsearch] Running health check to see if an Elasticsearch connection is working {:healthcheck\_url=\>[http://elkserver01:9200/](http://elkserver01:9200/), :path=\>"/"}  
[2018-05-25T09:55:47,673][WARN][logstash.outputs.elasticsearch] Restored connection to ES instance {:url=\>"[http://elkserver01:9200/](http://elkserver01:9200/)"}  
[2018-05-25T09:55:47,680][INFO][logstash.outputs.elasticsearch] New Elasticsearch output {:class=\>"LogStash::Outputs::ElasticSearch", :hosts=\>["//elkserver01:9200"]}  
[2018-05-25T09:55:47,688][INFO][logstash.pipeline] Starting pipeline {:pipeline\_id=\>"main", "pipeline.workers"=\>2, "pipeline.batch.size"=\>125, "pipeline.batch.delay"=\>5, "pipeline.max\_inflight"=\>250, :thread=\>"#\<Thread:0x49754912@D:/Bin/Logstash/logstash-core/lib/logstash/pipeline.rb:290 run\>"}  
[2018-05-25T09:55:48,469][INFO][logstash.inputs.beats] Beats inputs: Starting input listener {:address=\>"10.50.121.50:5044"}  
[2018-05-25T09:55:48,534][INFO][logstash.pipeline] Pipeline started {"pipeline.id"=\>"main"}  
[2018-05-25T09:55:48,549][INFO][org.logstash.beats.Server] Starting server on port: 5044  
[2018-05-25T09:55:48,624][INFO][logstash.agent] Pipelines running {:count=\>1, :pipelines=\>["main"]}  
[2018-05-25T09:57:03,821][INFO][org.logstash.beats.BeatsHandler] [local: 0.0.0.0:5044, remote: 10.50.150.7:54638] Exception: An established connection was aborted by the software in your host machine  
[2018-05-25T09:57:03,899][WARN][logstash.inputs.beats] Could not retrieve remote IP address for beats input.  
.....

One of my beat logs:  
2018-05-25T10:01:38+02:00 INFO Home path: [C:\Elastic\winlogbeat] Config path: [C:\Elastic\winlogbeat] Data path: [C:\ProgramData\winlogbeat] Logs path: [C:\Elastic\winlogbeat\logs]  
2018-05-25T10:01:38+02:00 INFO Metrics logging every 30s  
2018-05-25T10:01:38+02:00 INFO Beat UUID: 97cab043-2f46-43b9-95a0-ccc6adfa661f  
2018-05-25T10:01:38+02:00 INFO Setup Beat: winlogbeat; Version: 6.0.0  
2018-05-25T10:01:38+02:00 INFO Beat name: SERVERX  
2018-05-25T10:01:38+02:00 INFO State will be read from and persisted to C:\ProgramData\winlogbeat.winlogbeat.yml  
2018-05-25T10:01:38+02:00 INFO winlogbeat start running.  
2018-05-25T10:02:08+02:00 INFO Non-zero metrics in the last 30s: beat.memstats.gc\_next=44020208 beat.memstats.memory\_alloc=41479008 beat.memstats.memory\_total=145583704 libbeat.config.module.running=0 libbeat.output.events.active=4096 libbeat.output.events.batches=3 libbeat.output.events.total=4096 libbeat.output.type=logstash libbeat.output.write.bytes=1136319 libbeat.pipeline.clients=4 libbeat.pipeline.events.active=4118 libbeat.pipeline.events.published=4116 libbeat.pipeline.events.retry=1600 libbeat.pipeline.events.total=4118 msg\_file\_cache.Microsoft-Windows-NTLM/OperationalHits=2224 msg\_file\_cache.Microsoft-Windows-NTLM/OperationalMisses=1 msg\_file\_cache.Microsoft-Windows-NTLM/OperationalSize=1 msg\_file\_cache.Microsoft-Windows-PowerShell/OperationalHits=1506 msg\_file\_cache.Microsoft-Windows-PowerShell/OperationalMisses=1 msg\_file\_cache.Microsoft-Windows-PowerShell/OperationalSize=1 msg\_file\_cache.SystemHits=423 msg\_file\_cache.SystemMisses=6 msg\_file\_cache.SystemSize=6 uptime={"server\_time":"2018-05-25T08:02:08.6577244Z","start\_time":"2018-05-25T08:01:38.6289945Z","uptime":"30.0287299s","uptime\_ms":"30028729"}  
2018-05-25T10:02:09+02:00 ERR Failed to publish events caused by: read tcp 10.50.150.7:52935-\>10.50.121.50:5044: i/o timeout  
2018-05-25T10:02:09+02:00 ERR Failed to publish events caused by: read tcp 10.50.150.7:52935-\>10.50.121.50:5044: i/o timeout  
2018-05-25T10:02:09+02:00 ERR Failed to publish events caused by: read tcp 10.50.150.7:52935-\>10.50.121.50:5044: i/o timeout  
2018-05-25T10:02:10+02:00 ERR Failed to publish events caused by: client is not connected  
2018-05-25T10:02:10+02:00 INFO retryer: send wait signal to consumer  
2018-05-25T10:02:10+02:00 INFO done  
2018-05-25T10:02:11+02:00 ERR Failed to publish events: client is not connected  
2018-05-25T10:02:11+02:00 INFO retryer: send unwait-signal to consumer  
2018-05-25T10:02:11+02:00 INFO done  
2018-05-25T10:02:11+02:00 INFO retryer: send wait signal to consumer

Dont understand why..  
If i run netstat -a, i see alot of established remote Connection to logstash (5044)

And as i said, i see data coming in. I saw this once Before, but it solved itself somehow. Now it came back again. My cluster indexes are green.

---

<div class="post-metadata">

### Author: ![Maekee](https://sea2.discourse-cdn.com/elastic/user_avatar/discuss.elastic.co/maekee/32/21708_2.png) [@Maekee](https://discuss.elastic.co/u/Maekee)
#### Post date: [May 25, 2018, 8:26am UTC](https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280/2 "2018-05-25T08:26:43Z")

</div>

Also saw these lines in my logstash logfile:  
[2018-05-25T10:06:15,459][WARN][io.netty.channel.AbstractChannelHandlerContext] An exception 'java.lang.NullPointerException' [enable DEBUG level for full stacktrace] was thrown by a user handler's exceptionCaught() method while handling the following exception:  
java.io.IOException: An existing connection was forcibly closed by the remote host  
at sun.nio.ch.SocketDispatcher.read0(Native Method) ~[?:1.8.0\_144]  
at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:43) ~[?:1.8.0\_144]  
at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) ~[?:1.8.0\_144]  
at sun.nio.ch.IOUtil.read(IOUtil.java:192) ~[?:1.8.0\_144]  
at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) ~[?:1.8.0\_144]  
at io.netty.buffer.PooledUnsafeDirectByteBuf.setBytes(PooledUnsafeDirectByteBuf.java:288) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1100) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:349) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:112) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:571) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:512) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:426) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:398) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:877) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:144) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at java.lang.Thread.run(Thread.java:748) [?:1.8.0\_144]  
[2018-05-25T10:06:15,475][WARN][logstash.inputs.beats] Could not retrieve remote IP address for beats input.  
[2018-05-25T10:06:15,474][WARN][logstash.inputs.beats] Could not retrieve remote IP address for beats input.  
[2018-05-25T10:06:15,475][WARN][io.netty.channel.AbstractChannelHandlerContext] An exception 'java.lang.NullPointerException' [enable DEBUG level for full stacktrace] was thrown by a user handler's exceptionCaught() method while handling the following exception:  
java.io.IOException: An existing connection was forcibly closed by the remote host  
at sun.nio.ch.SocketDispatcher.read0(Native Method) ~[?:1.8.0\_144]  
at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:43) ~[?:1.8.0\_144]  
at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) ~[?:1.8.0\_144]  
at sun.nio.ch.IOUtil.read(IOUtil.java:192) ~[?:1.8.0\_144]  
at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) ~[?:1.8.0\_144]  
at io.netty.buffer.PooledUnsafeDirectByteBuf.setBytes(PooledUnsafeDirectByteBuf.java:288) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1100) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:349) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:112) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:571) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:512) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:426) ~[netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:398) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:877) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:144) [netty-all-4.1.3.Final.jar:4.1.3.Final]  
at java.lang.Thread.run(Thread.java:748) [?:1.8.0\_144]  
[2018-05-25T10:06:15,474][WARN][logstash.inputs.beats] Could not retrieve remote IP address for beats input.

---

<div class="post-metadata">

### Author: ![Maekee](https://sea2.discourse-cdn.com/elastic/user_avatar/discuss.elastic.co/maekee/32/21708_2.png) [@Maekee](https://discuss.elastic.co/u/Maekee)
#### Post date: [June 14, 2018, 7:05pm UTC](https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280/3 "2018-06-14T19:05:55Z")

</div>

Heard from Elastic that this is a bug in the logbeat-plugin in logstash, upgraded to 6.2 version and problem disappeared

---

<div class="post-metadata">

### Author: ![system](https://us1.discourse-cdn.com/elastic/original/3X/1/a/1ac57faf039f6b580b3f104ef42a2a89e41014de.png) [@system](https://discuss.elastic.co/u/system)
#### Post date: [July 12, 2018, 7:05pm UTC](https://discuss.elastic.co/t/logstash-log-could-not-retrieve-remote-ip-address-for-beats-input/133280/4 "2018-07-12T19:05:57Z")

</div>

This topic was automatically closed 28 days after the last reply. New replies are no longer allowed.
