nifi-commits mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Mark Payne (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (NIFI-916) NiFi stop command occasionally results in Socket Timeout exception
Date Mon, 31 Aug 2015 20:25:45 GMT

    [ 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)

Mime
View raw message