[
https://issues.apache.org/jira/browse/NIFI-916?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14723981#comment-14723981
]
Mark Payne commented on NIFI-916:
---------------------------------
And finally, running a thread dump on nifi provides the following information:
{noformat}
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
"pool-1-thread-2" #63116 prio=5 os_prio=0 tid=0x00007f64fc016800 nid=0x4404
waiting on condition [0x00007f64a5feb000]
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
java.lang.Thread.State: WAITING (parking)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at sun.misc.Unsafe.park(Native Method)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
- parking to wait for <0x00000000e0f5dd18> (a
java.util.concurrent.locks.ReentrantReadWriteLock$FairSync)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.lock(ReentrantReadWriteLock.java:943)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.apache.nifi.provenance.PersistentProvenanceRepository.close(PersistentProvenanceRepository.java:633)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
- locked <0x00000000e0f5dc20> (a
org.apache.nifi.provenance.PersistentProvenanceRepository)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.controller.FlowController.shutdown(FlowController.java:1114)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.apache.nifi.controller.StandardFlowService.stop(StandardFlowService.java:283)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.apache.nifi.web.contextlistener.ApplicationStartupContextListener.contextDestroyed(ApplicationStartupContextListener.java:132)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.server.handler.ContextHandler.callContextDestroyed(ContextHandler.java:808)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.servlet.ServletContextHandler.callContextDestroyed(ServletContextHandler.java:457)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:842)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:215)
2015-08-31 16:23:26,715 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.eclipse.jetty.webapp.WebAppContext.doStop(WebAppContext.java:529)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
- locked <0x00000000e07530a0> (a java.lang.Object)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:143)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:162)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
- locked <0x00000000e0753708> (a java.lang.Object)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:143)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:162)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.eclipse.jetty.server.Server.doStop(Server.java:456)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
- locked <0x00000000e0753350> (a java.lang.Object)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.web.server.JettyServer.stop(JettyServer.java:854)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.NiFi.shutdownHook(NiFi.java:154)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.BootstrapListener$Listener$1.run(BootstrapListener.java:192)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.lang.Thread.run(Thread.java:745)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
"pool-1-thread-1" #63115 prio=5 os_prio=0 tid=0x00007f64fc017800 nid=0x4403
waiting for monitor entry [0x00007f64a59e8000]
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
java.lang.Thread.State: BLOCKED (on object monitor)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:86)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
- waiting to lock <0x00000000e0753350> (a java.lang.Object)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.web.server.JettyServer.stop(JettyServer.java:854)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.NiFi.shutdownHook(NiFi.java:154)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at org.apache.nifi.BootstrapListener$Listener$1.run(BootstrapListener.java:192)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
at java.lang.Thread.run(Thread.java:745)
2015-08-31 16:23:26,716 INFO [NiFi logging handler] org.apache.nifi.StdOut
{noformat}
> 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)
