Twice now I've noticed that OpenDCT was stopped. After looking through the logs I saw this:
Code:
INFO | jvm 2 | 2017/07/24 07:39:58.102 | 07:39:58.053 [ChannelManager-57] INFO HDHomeRunChannels - Connecting to HDHomeRun using the URL 'http://192.168.2.92:80/lineup.xml'
INFO | jvm 2 | 2017/07/24 07:49:51.789 | [GC pause (G1 Evacuation Pause) (young) 84M->9695K(128M), 0.0039382 secs]
ERROR | wrapper | 2017/07/24 08:01:58.031 | JVM appears hung: Timed out waiting for signal from JVM.
INFO | jvm 2 | 2017/07/24 08:02:00.586 | 08:02:00.486 [NIOHTTPProducerImpl-19742:DCT-HDHomeRun Prime Tuner 13115570-0] WARN NIOHTTPProducerImpl - An exception occurred while receiving data => java.io.IOException: Connection reset by peer
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at sun.nio.ch.FileDispatcherImpl.read0(Native Method)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at sun.nio.ch.IOUtil.read(IOUtil.java:192)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at opendct.video.http.NIOHttpDownloader.read(NIOHttpDownloader.java:218)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at opendct.producer.NIOHTTPProducerImpl.run(NIOHTTPProducerImpl.java:263)
INFO | jvm 2 | 2017/07/24 08:02:00.586 | at java.lang.Thread.run(Thread.java:745)
INFO | jvm 2 | 2017/07/24 08:02:00.586 |
INFO | wrapper | 2017/07/24 08:02:00.587 | Wrapper Process has not received any CPU time for 75 seconds. Extending timeouts.
INFO | jvm 2 | 2017/07/24 08:02:09.597 | 08:02:09.504 [NIOHTTPProducerImpl-19742:DCT-HDHomeRun Prime Tuner 13115570-0] ERROR NIOHTTPProducerImpl - Producer thread created an unexpected exception => java.nio.channels.AlreadyConnectedException
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at sun.nio.ch.SocketChannelImpl.ensureOpenAndUnconnected(SocketChannelImpl.java:610)
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:621)
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at opendct.video.http.NIOHttpDownloader.connect(NIOHttpDownloader.java:88)
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at opendct.video.http.NIOHttpDownloader.connect(NIOHttpDownloader.java:65)
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at opendct.producer.NIOHTTPProducerImpl.run(NIOHTTPProducerImpl.java:229)
INFO | jvm 2 | 2017/07/24 08:02:09.597 | at java.lang.Thread.run(Thread.java:745)
INFO | jvm 2 | 2017/07/24 08:02:09.597 |
INFO | jvm 2 | 2017/07/24 08:02:09.597 | 08:02:09.504 [NIOHTTPProducerImpl-19742:DCT-HDHomeRun Prime Tuner 13115570-0] INFO NIOHTTPProducerImpl - Producer thread has stopped.
INFO | jvm 2 | 2017/07/24 08:02:28.816 | Wrapper Manager: The Wrapper code did not ping the JVM for 30 seconds. Quit and let the Wrapper resynch.
STATUS | wrapper | 2017/07/24 08:02:28.816 | JVM requested a restart.
INFO | jvm 2 | 2017/07/24 08:02:38.426 | 08:02:38.331 [SageTVManagerShutdown] INFO Main - Stopping all SageTV socket servers...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.333 [NanoHTTPDShutdown] INFO Main - Stopping web server...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.333 [PowerMessageManagerShutdown] INFO Main - Stopping power messages...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.333 [DiscoveryManagerShutdown] INFO Main - Stopping device discovery services...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.334 [NanoHTTPDShutdown] INFO NanoHTTPDManager - Stopping webserver on port 9091...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.334 [SageTVTuningMonitor-66] INFO SageTVTuningMonitor - Tuning monitor thread interrupted.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.334 [Shutdown] INFO Main - OpenDCT has received a signal to stop.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.334 [ChannelManagerShutdown] INFO Main - Stopping all channel update threads...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.334 [PowerMessageManagerShutdown] WARN LinuxPowerMessagePump - System power state messages are currently not implemented for Linux.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.335 [SageTVSocketServer-67:9000] INFO SageTVSocketServer - Stopped listening on port 9000...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.336 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-1' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.337 [ChannelManagerShutdown] INFO ChannelManager - Saving the channel lineup 'dct_hdhomerun'.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.337 [Shutdown] INFO Main - Saving current configuration...
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.340 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-0' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.342 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-2' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.343 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-2' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.344 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-1' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.345 [SageTVManagerShutdown] INFO SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-0' is being unloaded.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.345 [SageTVManagerShutdown] INFO HDHRNativeCaptureDevice - Capture device was already locked.
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.347 [UPnPDiscovery-40] INFO UpnpManager - UPnP discovery thread was interrupted => java.lang.InterruptedException: sleep interrupted
INFO | jvm 2 | 2017/07/24 08:02:38.427 | 08:02:38.351 [DiscoveryManagerShutdown] WARN DiscoveryRegistryListener - Before UPnP shutdown, the registry did not contain any devices.
INFO | jvm 2 | 2017/07/24 08:02:38.627 | 08:02:38.555 [DiscoveryManagerShutdown] INFO DiscoveryManager - UPnP discovery stopped.
INFO | jvm 2 | 2017/07/24 08:02:38.627 | 08:02:38.556 [DiscoveryManagerShutdown] INFO DiscoveryManager - HDHomeRun discovery stopped.
INFO | jvm 2 | 2017/07/24 08:02:38.627 | 08:02:38.556 [DiscoveryManagerShutdown] INFO DiscoveryManager - Generic HTTP discovery stopped.
INFO | jvm 2 | 2017/07/24 08:02:38.627 | 08:02:38.556 [DiscoveryManagerShutdown] INFO DiscoveryManager - Generic Pipe discovery stopped.
ERROR | wrapper | 2017/07/24 08:02:54.946 | Shutdown failed: Timed out waiting for the JVM to terminate.
ERROR | wrapper | 2017/07/24 08:02:55.046 | JVM did not exit on request, terminated
STATUS | wrapper | 2017/07/24 08:02:58.954 | JVM exited in response to signal SIGKILL (9).
ERROR | wrapper | 2017/07/24 08:02:58.954 | Unable to start a JVM
STATUS | wrapper | 2017/07/24 08:02:59.054 | <-- Wrapper Stopped
I've seen a couple times in the log where it said:
Code:
Wrapper Process has not received any CPU time for xxx seconds
Has anyone seen this before? Is there some way to bump it's priority? The only other docker (other than sage) is the crash plan one.
Jim