Network encoders missed show - delay to wait setting?

[jbuszkie]

I'm starting to miss a couple shows due to perceived "Halt Decected in Recording"

On my old setup I had

mmc/encoders/xxxxx/delay_to_wait_after_tuning=10000

For most of my encoders.

But on my new setup with unraid it's set to 0.
I also see this in the opendct properties file.

Where do I modify this property? in the OpenDCT file? or in sage?

And have any of you had to bump this up from 0 for the prime tuners?

Thanks,

Jim

[EnterNoEscape]

Originally Posted by jbuszkie

I'm starting to miss a couple shows due to perceived "Halt Decected in Recording"

On my old setup I had

mmc/encoders/xxxxx/delay_to_wait_after_tuning=10000

For most of my encoders.

But on my new setup with unraid it's set to 0.
I also see this in the opendct properties file.

Where do I modify this property? in the OpenDCT file? or in sage?

And have any of you had to bump this up from 0 for the prime tuners?

Thanks,

Jim

The value in the OpenDCT properties is passed to SageTV when the device is discovered, but I believe this only happens if it's new to SageTV. You will want to change the value on the SageTV side of things for it have any effect. I am however not certain that delays work on digital tuners.

[Fuzzy]

Originally Posted by EnterNoEscape

The value in the OpenDCT properties is passed to SageTV when the device is discovered, but I believe this only happens if it's new to SageTV. You will want to change the value on the SageTV side of things for it have any effect. I am however not certain that delays work on digital tuners.

Yes, this setting is only used on devices that have a separate tuning process (like using an IR blaster). It's the delay between sending the channel change, and actually asking the encoder to start recording.

[jbuszkie]

Here is the OpentDCT log for a failing show.. Why it's so small and in the archive is beyond me..

00:01:26.656 [SageTVRequestHandler-2045:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHomeRun Prime Tuner 13115570-0 Digital TV Tuner|214079676|835|3002256173308|/var/media/tv/Suits-S07E03-Mudmare-40510755-0.ts|Great'
00:01:26.885 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:26.885 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '214079676' to file name '/var/media/tv/Suits-S07E03-Mudmare-40510755-0.ts'.
00:01:26.885 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:27.022 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: '1062731234' lockKey: '1062731234' sendLength: 45 address: 192.168.2.92
00:01:27.022 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  HDHRNativeCaptureDevice - HDHomeRun is now locked.
00:01:27.022 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:27.022 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG BasicCaptureDevice - Consumer is not in progress.
00:01:27.023 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '835'
00:01:27.023 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  HDHRNativeCaptureDevice - Starting the encoding for the channel '835' from the device 'DCT-HDHomeRun Prime Tuner 13115570-0' to the file '/var/media/tv/Suits-S07E03-Mudmare-40510755-0.ts' via the upload id '214079676'...
00:01:27.023 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:27.024 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'none' lockKey: '1062731234' sendLength: 39 address: 192.168.2.92
00:01:27.024 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:27.024 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOHTTPProducerImpl - Connecting to source using the URL 'http://192.168.2.92:5004/tuner0/v835'
00:01:27.492 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HDHomeRun/1.0', 'Connection: close', 'Content-Type: video/mpeg', 'Cache-Control: no-cache', 'Pragma: no-cache', 'transferMode.dlna.org: Streaming', 'Date: Thu, 27 Jul 2017 04:01:26 GMT', 
00:01:27.493 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/program' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:27.493 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOHTTPProducerImpl - Producer thread is running.
00:01:27.494 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer...
00:01:27.494 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG NIOHTTPProducerImpl - Thread priority is 9.
00:01:27.494 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG BasicCaptureDevice - Consumer is not in progress.
00:01:27.495 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG HDHomeRunControl - key: '/tuner0/vstatus' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:27.495 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  MediaServerConsumerImpl - MediaServer thread started.
00:01:27.496 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG MediaServerConsumerImpl - Thread priority is 8.
00:01:27.496 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  MediaServerConsumerImpl - Opening file via MediaServer...
00:01:27.496 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOSageTVMediaServer - Connecting to SageTV server on socket /192.168.2.30:7818...
00:01:27.497 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/tv/Suits-S07E03-Mudmare-40510755-0.ts 214079676' to SageTV server...
00:01:27.906 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  MediaServerConsumerImpl - Setting up remuxing on MediaServer...
00:01:27.907 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO PS TRUE' to SageTV server...
00:01:27.913 [MediaServerConsumerImpl-2057:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  MediaServerConsumerImpl - Media Server consumer is now streaming...
00:01:28.538 [PowerMessageManagerShutdown] INFO  Main - Stopping power messages...
00:01:28.814 [SageTVManagerShutdown] INFO  Main - Stopping all SageTV socket servers...
00:01:28.814 [NanoHTTPDShutdown] INFO  Main - Stopping web server...
00:01:28.814 [DiscoveryManagerShutdown] INFO  Main - Stopping device discovery services...
00:01:28.814 [Shutdown] INFO  Main - OpenDCT has received a signal to stop.
00:01:28.814 [ChannelManagerShutdown] INFO  Main - Stopping all channel update threads...
00:01:28.815 [SageTVManagerShutdown] DEBUG SageTVSocketServer - Stopping listening thread...
00:01:28.814 [PowerMessageManagerShutdown] WARN  LinuxPowerMessagePump - System power state messages are currently not implemented for Linux.
00:01:28.815 [UPnPDiscovery-41] INFO  UpnpManager - UPnP discovery thread was interrupted => java.lang.InterruptedException: sleep interrupted
00:01:28.815 [SageTVTuningMonitor-67] INFO  SageTVTuningMonitor - Tuning monitor thread interrupted.
00:01:28.815 [NanoHTTPDShutdown] INFO  NanoHTTPDManager - Stopping webserver on port 9091...
00:01:28.815 [ChannelManagerShutdown] INFO  ChannelManager - Saving the channel lineup 'dct_hdhomerun'.
00:01:28.815 [Shutdown] INFO  Main - Saving current configuration...
00:01:28.816 [SageTVSocketServer-68:9000] INFO  SageTVSocketServer - Stopped listening on port 9000...
00:01:28.816 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-1' is being unloaded.
00:01:28.817 [SageTVManagerShutdown] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:28.817 [SageTVManagerShutdown] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:28.817 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.71
00:01:28.823 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.71
00:01:28.824 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-0' is being unloaded.
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:28.824 [SageTVManagerShutdown] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.71
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner0/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.71
00:01:28.824 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 131953B8-2' is being unloaded.
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:28.824 [SageTVManagerShutdown] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.71
00:01:28.824 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner2/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.71
00:01:28.824 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-2' is being unloaded.
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:28.825 [SageTVManagerShutdown] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner2/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.92
00:01:28.825 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-1' is being unloaded.
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked.
00:01:28.825 [SageTVManagerShutdown] DEBUG RTPCaptureDeviceServices - Producer was not running.
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:28.825 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.92
00:01:28.828 [SageTVManagerShutdown] INFO  SageTVManager - The capture device 'DCT-HDHomeRun Prime Tuner 13115570-0' is being unloaded.
00:01:28.828 [SageTVManagerShutdown] INFO  HDHRNativeCaptureDevice - Capture device was already locked.
00:01:28.828 [SageTVManagerShutdown] DEBUG HTTPCaptureDeviceServices - Stopping producer thread...
00:01:28.828 [SageTVManagerShutdown] DEBUG HTTPCaptureDeviceServices - Waiting for producer thread to stop...
00:01:28.828 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG NIOHTTPProducerImpl - The socket has been closed.
00:01:28.828 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOHTTPProducerImpl - Producer thread has stopped.
00:01:28.828 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 192.168.2.92
00:01:28.830 [SageTVManagerShutdown] DEBUG HDHomeRunControl - key: '/tuner0/target' value: 'none' lockKey: '0' sendLength: 32 address: 192.168.2.92
00:01:29.064 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG SageTVRequestHandler - Replied: 'OK'
00:01:29.064 [SageTVRequestHandler-2045:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG SageTVTuningMonitor - Tuning monitor is not running. This recording will not re-tune automatically.
00:01:29.148 [DiscoveryManagerShutdown] WARN  DiscoveryRegistryListener - Before UPnP shutdown, the registry did not contain any devices.
00:01:29.149 [DiscoveryManagerShutdown] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete.
00:01:29.149 [DiscoveryManagerShutdown] INFO  DiscoveryManager - UPnP discovery stopped.
00:01:29.149 [HDHomeRunDiscoveryReceive-42] DEBUG HDHomeRunDiscovery - Channel was closed while receiving HDHomeRun discovery packets from [/255.255.255.255:65001, /255.255.255.255:65001] => java.nio.channels.ClosedByInterruptException
	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
	at sun.nio.ch.DatagramChannelImpl.receive(DatagramChannelImpl.java:378)
	at opendct.tuning.hdhomerun.HDHomeRunDiscovery$ReceiveThread.run(HDHomeRunDiscovery.java:364)
	at java.lang.Thread.run(Thread.java:745)

00:01:29.295 [HDHomeRunDiscoveryReceive-42] DEBUG HDHomeRunDiscovery - Channel was closed while receiving HDHomeRun discovery packets from [/255.255.255.255:65001, /255.255.255.255:65001] => java.nio.channels.ClosedByInterruptException
	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
	at sun.nio.ch.DatagramChannelImpl.receive(DatagramChannelImpl.java:378)
	at opendct.tuning.hdhomerun.HDHomeRunDiscovery$ReceiveThread.run(HDHomeRunDiscovery.java:364)
	at java.lang.Thread.run(Thread.java:745)

00:01:29.295 [HDHomeRunDiscoverySend-42] DEBUG HDHomeRunDiscovery - Interrupted while waiting for next broadcast to be sent => java.lang.InterruptedException: sleep interrupted
	at java.lang.Thread.sleep(Native Method)
	at opendct.tuning.hdhomerun.HDHomeRunDiscovery.run(HDHomeRunDiscovery.java:307)
	at java.lang.Thread.run(Thread.java:745)

00:01:29.295 [DiscoveryManagerShutdown] INFO  DiscoveryManager - HDHomeRun discovery stopped.
00:01:29.295 [DiscoveryManagerShutdown] INFO  DiscoveryManager - Generic HTTP discovery stopped.
00:01:29.295 [DiscoveryManagerShutdown] INFO  DiscoveryManager - Generic Pipe discovery stopped.

It looks like it started just fine ..

Then a little later this:

00:01:28.828 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] DEBUG NIOHTTPProducerImpl - The socket has been closed.
00:01:28.828 [NIOHTTPProducerImpl-2055:DCT-HDHomeRun Prime Tuner 13115570-0] INFO  NIOHTTPProducerImpl - Producer thread has stopped.

If it's not the encoder delay.. Then where else should I look?

Jim

[EnterNoEscape]

Change the consumer to use FFmpeg. If things are set up the way I think they are for you, you should just need to change the following in opendct.properties:

consumer.dynamic.default=opendct.consumer.FFmpegTransSageTVConsumerImpl

[jbuszkie]

I don't think I changed anything from the default.

Why would I want to send it through ffmpeg if I'm not (at the moment) transcoding anything on the fly?

Is there an issue with

consumer.dynamic.default=opendct.consumer.MediaServerConsumerImpl

Is everyone sending it through ffmpeg?

[jbuszkie]

And thanks for the help so far!

[jbuszkie]

ok.. I changed it with opendct shutdown and it got changed back!

this is what the log says..

15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer default set to use opendct.consumer.MediaServerConsumerImpl
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.FFmpegTransSageTVConsumerImpl for []
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.MediaServerConsumerImpl for []
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.RawSageTVConsumerImpl for []
15:01:42.624 [WrapperSimpleAppMain] WARN  HDHomeRunDiscovery - Broadcast IP address was any local address. Using 255.255.255.255 instead.
15:01:42.624 [WrapperSimpleAppMain] WARN  HDHomeRunDiscovery - Broadcast IP address was any local address. Using 255.255.255.255 instead.

Why is it changing it back?

It physically changes it back to MediaServerConsumerImpl in the properties file.

Do I need to edit the opendct.properties.default file instead?

[EnterNoEscape]

Originally Posted by jbuszkie

I don't think I changed anything from the default.

Why would I want to send it through ffmpeg if I'm not (at the moment) transcoding anything on the fly?

Is there an issue with

consumer.dynamic.default=opendct.consumer.MediaServerConsumerImpl

Is everyone sending it through ffmpeg?

You're not transcoding, but my bet is that the internal remuxer that SageTV uses is tripping up on these channels. The FFmpeg remuxer even if you're not transcoding tends to deal with these kinds of streams better.

Originally Posted by jbuszkie

ok.. I changed it with opendct shutdown and it got changed back!

this is what the log says..

15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer default set to use opendct.consumer.MediaServerConsumerImpl
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.FFmpegTransSageTVConsumerImpl for []
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.MediaServerConsumerImpl for []
15:01:42.601 [DynamicAsyncInit-22] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.RawSageTVConsumerImpl for []
15:01:42.624 [WrapperSimpleAppMain] WARN  HDHomeRunDiscovery - Broadcast IP address was any local address. Using 255.255.255.255 instead.
15:01:42.624 [WrapperSimpleAppMain] WARN  HDHomeRunDiscovery - Broadcast IP address was any local address. Using 255.255.255.255 instead.

Why is it changing it back?

It physically changes it back to MediaServerConsumerImpl in the properties file.

Do I need to edit the opendct.properties.default file instead?

I was thinking that might happen. The OpenDCT Docker manipulates this value for you and the value it adds overrides your change. Until that is corrected in the Docker, I would just change all of the DynamicConsumerImpl for the devices to FFmpegTransSageTVConsumerImpl since I know it won't manipulate the per capture device settings.

[jbuszkie]

Originally Posted by EnterNoEscape

You're not transcoding, but my bet is that the internal remuxer that SageTV uses is tripping up on these channels. The FFmpeg remuxer even if you're not transcoding tends to deal with these kinds of streams better.

It doesn't always happen.. I haven't had that many fail yet to get a pattern. I do know that I very infrequently got any of these on my win7 V7 server using the win DCT or whatever it was called. It's only after I switched to V9 on Unraid Docker did I see some of these failures.

Originally Posted by EnterNoEscape

I was thinking that might happen. The OpenDCT Docker manipulates this value for you and the value it adds overrides your change. Until that is corrected in the Docker, I would just change all of the DynamicConsumerImpl for the devices to FFmpegTransSageTVConsumerImpl since I know it won't manipulate the per capture device settings.

Ok.. I changed all of them and now it seems to be using ffmpeg. It didn't break anything, at least. Time will tell if it's better.

Thanks,

Jim

[Deacon Crusher]

Did that fix things for you?

I'm starting to have some issues like this as well.

Thanks

[jbuszkie]

Yes.. I don't have those issues anymore!

Now I'm dealing with the Network encoder just stopping! It happened twice this past weekend. I did a full reboot of everything and hopefully that will fix it..

But yes, using FFMPEG seems to have fixed the halts.

Jim