[
https://issues.apache.org/jira/browse/NIFI-916?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14723977#comment-14723977
]
Mark Payne commented on NIFI-916:
---------------------------------
When I issue the command with DEBUG log status set, I see the following logs on
the nifi side:
{noformat}
2015-08-31 16:18:43,335 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:45,337 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:46,754 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Received connection from Bootstrap
2015-08-31 16:18:46,754 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:48,757 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:50,760 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:51,851 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Received connection from Bootstrap
2015-08-31 16:18:51,851 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:53,854 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:55,857 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:57,860 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:18:59,863 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
2015-08-31 16:19:01,865 DEBUG [Listen to Bootstrap]
org.apache.nifi.BootstrapListener Listening for Bootstrap Requests
{noformat}
and from the bootstrap side, I see:
{noformat}
[mark@centos-7 nifi-0.3.0-SNAPSHOT]$ bin/nifi.sh stop
Java home: /usr/java/latest
NiFi home: /opt/nifi-0.3.0-SNAPSHOT
Bootstrap Config File: /opt/nifi-0.3.0-SNAPSHOT/conf/bootstrap.conf
2015-08-31 16:18:46,745 DEBUG [main] org.apache.nifi.bootstrap.Command Status
File: /opt/nifi-0.3.0-SNAPSHOT/bin/nifi.pid
2015-08-31 16:18:46,749 DEBUG [main] org.apache.nifi.bootstrap.Command Status
File: /opt/nifi-0.3.0-SNAPSHOT/bin/nifi.pid
2015-08-31 16:18:46,749 DEBUG [main] org.apache.nifi.bootstrap.Command
Properties: {pid=6873, port=49560}
2015-08-31 16:18:46,749 DEBUG [main] org.apache.nifi.bootstrap.Command Port
defined in status file: 49560
2015-08-31 16:18:46,750 DEBUG [main] org.apache.nifi.bootstrap.Command Pinging
49560
2015-08-31 16:18:46,754 DEBUG [main] org.apache.nifi.bootstrap.Command Sent
PING command
2015-08-31 16:18:51,759 DEBUG [main] org.apache.nifi.bootstrap.Command PID in
status file is 6873
2015-08-31 16:18:51,844 DEBUG [main] org.apache.nifi.bootstrap.Command Process
with PID 6873 is running
2015-08-31 16:18:51,844 DEBUG [main] org.apache.nifi.bootstrap.Command Lock
File: /opt/nifi-0.3.0-SNAPSHOT/bin/nifi.lock
2015-08-31 16:18:51,845 DEBUG [main] org.apache.nifi.bootstrap.Command Status
File: /opt/nifi-0.3.0-SNAPSHOT/bin/nifi.pid
2015-08-31 16:18:51,850 DEBUG [main] org.apache.nifi.bootstrap.Command Status
File: /opt/nifi-0.3.0-SNAPSHOT/bin/nifi.pid
2015-08-31 16:18:51,851 DEBUG [main] org.apache.nifi.bootstrap.Command
Properties: {pid=6873, port=49560}
2015-08-31 16:18:51,851 DEBUG [main] org.apache.nifi.bootstrap.Command
Connecting to NiFi instance
2015-08-31 16:18:51,851 DEBUG [main] org.apache.nifi.bootstrap.Command
Established connection to NiFi instance.
2015-08-31 16:18:51,851 DEBUG [main] org.apache.nifi.bootstrap.Command Sending
SHUTDOWN Command to port 49560
2015-08-31 16:19:51,874 ERROR [main] org.apache.nifi.bootstrap.Command Failed
to send shutdown command to port 49560 due to java.net.SocketTimeoutException:
Read timed out
java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method) ~[na:1.8.0_45]
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
~[na:1.8.0_45]
at java.net.SocketInputStream.read(SocketInputStream.java:170)
~[na:1.8.0_45]
at java.net.SocketInputStream.read(SocketInputStream.java:141)
~[na:1.8.0_45]
at java.net.SocketInputStream.read(SocketInputStream.java:223)
~[na:1.8.0_45]
at org.apache.nifi.bootstrap.RunNiFi.stop(RunNiFi.java:536)
[nifi-bootstrap-0.3.0-SNAPSHOT.jar:0.3.0-SNAPSHOT]
at org.apache.nifi.bootstrap.RunNiFi.main(RunNiFi.java:208)
[nifi-bootstrap-0.3.0-SNAPSHOT.jar:0.3.0-SNAPSHOT]
{noformat}
So it certainly appears as if NiFi is receiving the connection but not reading
from the socket. Reading from the socket is done in another thread.
> NiFi stop command occasionally results in Socket Timeout exception
> ------------------------------------------------------------------
>
> Key: NIFI-916
> URL: https://issues.apache.org/jira/browse/NIFI-916
> Project: Apache NiFi
> Issue Type: Bug
> Components: Core Framework
> Reporter: Mark Payne
> Fix For: 0.3.0
>
>
> When I issue the bin/nifi.sh stop command, running in CentOS 7 in my VM, I
> occasionally get a timeout exception. If this happens, it will continue to
> happen until I manually kill the process.
> Below is the output that I receive, as well as the results of running netstat
> to verify that NiFi is in fact listening on the port reported.
> {noformat}
> [mark@centos-7 nifi-0.3.0-SNAPSHOT]$ bin/nifi.sh stop
> Java home: /usr/java/latest
> NiFi home: /opt/nifi-0.3.0-SNAPSHOT
> Bootstrap Config File: /opt/nifi-0.3.0-SNAPSHOT/conf/bootstrap.conf
> 2015-08-31 16:05:38,454 ERROR [main] org.apache.nifi.bootstrap.Command Failed
> to send shutdown command to port 49560 due to
> java.net.SocketTimeoutException: Read timed out
> java.net.SocketTimeoutException: Read timed out
> at java.net.SocketInputStream.socketRead0(Native Method) ~[na:1.8.0_45]
> at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
> ~[na:1.8.0_45]
> at java.net.SocketInputStream.read(SocketInputStream.java:170)
> ~[na:1.8.0_45]
> at java.net.SocketInputStream.read(SocketInputStream.java:141)
> ~[na:1.8.0_45]
> at java.net.SocketInputStream.read(SocketInputStream.java:223)
> ~[na:1.8.0_45]
> at org.apache.nifi.bootstrap.RunNiFi.stop(RunNiFi.java:536)
> [nifi-bootstrap-0.3.0-SNAPSHOT.jar:0.3.0-SNAPSHOT]
> at org.apache.nifi.bootstrap.RunNiFi.main(RunNiFi.java:208)
> [nifi-bootstrap-0.3.0-SNAPSHOT.jar:0.3.0-SNAPSHOT]
> [mark@centos-7 nifi-0.3.0-SNAPSHOT]$
> [mark@centos-7 nifi-0.3.0-SNAPSHOT]$
> [mark@centos-7 nifi-0.3.0-SNAPSHOT]$
> [mark@centos-7 nifi-0.3.0-SNAPSHOT]$ netstat -anp | grep 49560
> (Not all processes could be identified, non-owned process info
> will not be shown, you would have to be root to see it all.)
> tcp 0 0 127.0.0.1:49560 0.0.0.0:* LISTEN
> 6873/java
> tcp 0 0 127.0.0.1:49560 127.0.0.1:37907
> CLOSE_WAIT 6873/java
> tcp 0 0 127.0.0.1:49560 127.0.0.1:37905
> CLOSE_WAIT 6873/java
> tcp6 0 0 127.0.0.1:37907 127.0.0.1:49560 FIN_WAIT2
> -
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
