OpenDCT: An open source digital cable tuner.

[Greg2dot0]

I've done a bunch of recordings and all look good. I did have a small glitch when I first started my first recording after first starting the service yesterday and haven't seen it again. I'll continue to test recordings today.

[troll5501]

Originally Posted by EnterNoEscape

It looks like the first attempt could not get everything it needed about the video. Unfortunately that's all FFmpeg. If it can't find the video dimensions, usually that means something is particularly interesting about the video stream which might be why you are having playback issues with the file that was produced. If you want to chop off a chunk of that file, or just upload the whole thing and make it available to me, I am interested in what's so special about that stream.

I'll PM you with a link to the file. Thanks!

[EnterNoEscape]

Originally Posted by Greg2dot0

I've done a bunch of recordings and all look good. I did have a small glitch when I first started my first recording after first starting the service yesterday and haven't seen it again. I'll continue to test recordings today.

By small glitch, you mean a second of the recording was lost or SageTV actually reported there was a problem?

[Greg2dot0]

Originally Posted by EnterNoEscape

By small glitch, you mean a second of the recording was lost or SageTV actually reported there was a problem?

I'm attaching the log. I installed RC7 about 10:00, on tuner 0, I started to record Good Morning Vietnam and had an error about 10:04:20, which actually caused a halt in the recording. The stream fix stops about 10:06:05

The file had multiple parts and an halt was posted to the SageTV System Message. Since then, I've not had that happen again

10:03:58.054 [SageTVTuningMonitor-52] INFO  SageTVTuningMonitor - 'DCT-HDHomeRun Prime Tuner 13142E5A-0' produced first 8010492 packets.
10:03:58.054 [SageTVTuningMonitor-52] INFO  SageTVTuningMonitor - 'DCT-HDHomeRun Prime Tuner 13142E5A-0' recorded first 7970448 bytes.
10:04:20.799 [FFmpegTransSageTVConsumerImpl-76:DCT-HDHomeRun Prime Tuner 13142E5A-1] WARN  mpegts - Last message repeated 23 times.
10:04:20.799 [FFmpegTransSageTVConsumerImpl-76:DCT-HDHomeRun Prime Tuner 13142E5A-1] WARN  mpegts - [0x7f22b006f360] PES packet size mismatch
10:04:58.522 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR mpegts - Last message repeated 1 time.
10:04:58.523 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR mpegts - [0x7f22c8035ce0] pts () < dts ((null)) in stream 0
10:04:58.523 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR FFmpegTranscoder - Error from av_interleaved_write_frame: -22

Here's the entire log:

[EnterNoEscape]

Originally Posted by Greg2dot0

I'm attaching the log. I installed RC7 about 10:00, on tuner 0, I started to record Good Morning Vietnam and had an error about 10:04:20, which actually caused a halt in the recording. The stream fix stops about 10:06:05

The file had multiple parts and an halt was posted to the SageTV System Message. Since then, I've not had that happen again

10:03:58.054 [SageTVTuningMonitor-52] INFO  SageTVTuningMonitor - 'DCT-HDHomeRun Prime Tuner 13142E5A-0' produced first 8010492 packets.
10:03:58.054 [SageTVTuningMonitor-52] INFO  SageTVTuningMonitor - 'DCT-HDHomeRun Prime Tuner 13142E5A-0' recorded first 7970448 bytes.
10:04:20.799 [FFmpegTransSageTVConsumerImpl-76:DCT-HDHomeRun Prime Tuner 13142E5A-1] WARN  mpegts - Last message repeated 23 times.
10:04:20.799 [FFmpegTransSageTVConsumerImpl-76:DCT-HDHomeRun Prime Tuner 13142E5A-1] WARN  mpegts - [0x7f22b006f360] PES packet size mismatch
10:04:58.522 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR mpegts - Last message repeated 1 time.
10:04:58.523 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR mpegts - [0x7f22c8035ce0] pts () < dts ((null)) in stream 0
10:04:58.523 [FFmpegTransSageTVConsumerImpl-106:DCT-HDHomeRun Prime Tuner 13142E5A-0] ERROR FFmpegTranscoder - Error from av_interleaved_write_frame: -22

Here's the entire log:

The issue you ran into interestingly enough was for exactly the same reason troll5501 ran into a problem. You're not going to see it happen often since the channel needs to be wrapping around the dts. I'm working on fixing it, but FFmpeg really doesn't seem to understand what's going on when you limit the number to 33-bits yourself.

[EnterNoEscape]

Originally Posted by troll5501

I'll PM you with a link to the file. Thanks!

I really couldn't find anything particularly broken with this recording. My best guess is SageTV detected it incorrectly. I was able to import it into SageTV and play it back on the Android miniclient and HD300. Sorry I can't really get you any closer to an answer on this one.


On an unrelated note. I was talking with some friends at a get together last night about the local commercials and apparently I'm not the only person to notice that the way they are inserting them isn't even playing nicely with the cable company boxes. There's lots of tearing and stuttering especially on H.264 Comcast. I looked into this a little more when I found an infomercial that looked horrible. I immediately captured the raw video to verify it wasn't something new to fix in OpenDCT and it turned out the video was really just that bad. It was an H.264 720p channel, so I know FiOS was transcoding it (the first time I've discovered this). At any rate, if that was my commercial and I'd be pretty upset with the cable company. I understand that sometimes it's the source content, but I really doubt anyone would submit a 30 minute video that did not playback decently before submitting it to the broadcaster. I don't have a problem with them saving bandwidth, but I do have a problem with them butchering my recordings in the process.

[EnterNoEscape]

• Java heap size is now defined at 128MB minimum and 768MB maximum.
• Removed 33-bit corrections and improved decision making on what offset is the most correct.

[KeithAbbott]

Originally Posted by EnterNoEscape

• Java heap size is now defined at 128MB minimum and 768MB maximum.

For someone running OpenDCT in the same windows instance as the SageTV service, does OpenDCT have it's own Java heap, or would SageTV and OpenDCT share a single Java heap?

[nyplayer]

Originally Posted by KeithAbbott

For someone running OpenDCT in the same windows instance as the SageTV service, does OpenDCT have it's own Java heap, or would SageTV and OpenDCT share a single Java heap?

OpenDCT is separate from SageTV and does not run within the SageTV Java Heap ... it has it's own.

[jarredduq]

Originally Posted by EnterNoEscape

• Java heap size is now defined at 128MB minimum and 768MB maximum.
• Removed 33-bit corrections and improved decision making on what offset is the most correct.

Thank you for your hard work trying to perfect this software.

[EnterNoEscape]

Originally Posted by jarredduq

Thank you for your hard work trying to perfect this software.

Thanks! I especially find the timestamp discontinuity fixes absurd, but an unfortunate necessity. After playing around with different video from various providers, I'm starting to get the more complete picture of why so many need FFmpeg to even allow for even a really good player to be able to playback the video stream.

[troll5501]

Originally Posted by EnterNoEscape

I really couldn't find anything particularly broken with this recording. My best guess is SageTV detected it incorrectly. I was able to import it into SageTV and play it back on the Android miniclient and HD300. Sorry I can't really get you any closer to an answer on this one.

So I have an idea of what may have happened here, and I think you are right, but unfortunately my SageTV logs have rotated enough that I lost Friday's log so we can't tell exactly what SageTV was doing.

OpenDCT tried to start the recording and it encountered the errors "avformat_open_input returned error code -1094995529" and it eventually closed the "<name>-0.ts" file (not sure if anything was ever written to it or if it was even created) and then it restarted the stream again successfully using the filename "<name>-1.ts" as expected. But today when I looked closer, there was no "<name>-0.ts" file on the filesystem, only the "<name>-1.ts" file which is the one you downloaded from me.

In SageTV under Recordings, I looked at the View Recording Detail screen and for Format it only showed "MPEG2-TS". I don't remember if it showed one filename or two. The following is what Sage logged when I looked at the details today and tried to play the recording. Note the "format=MPEG2-TS 0:00:00 0 kbps []":

sagetv_1.txt:Sun 4/3 12:50:30.630 [AsyncWatch@40a8eec7] watchThisFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_1.txt:Sun 4/3 12:50:30.802 [AsyncWatch@40a8eec7] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null])
sagetv_1.txt:Sun 4/3 12:50:30.807 [VideoFrame-001d6a4bfae8@281bc614] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null] nPlayin=false
sagetv_1.txt:Sun 4/3 12:50:30.835 [VideoFrame-001d6a4bfae8@281bc614] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null]
sagetv_1.txt:Sun 4/3 12:50:30.835 [VideoFrame-001d6a4bfae8@281bc614] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null] nPlayin=false
sagetv_1.txt:Sun 4/3 12:50:30.847 [VideoFrame-001d6a4bfae8@281bc614] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null]
sagetv_1.txt:Sun 4/3 12:50:30.848 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame creating new media player for file:MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_1.txt:Sun 4/3 12:50:30.858 [VideoFrame-001d6a4bfae8@281bc614] VF file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] targetTime = Fri 4/1 19:00:16.212
sagetv_1.txt:Sun 4/3 12:50:30.858 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected(Fri 4/1 19:00:16.212, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_1.txt:Sun 4/3 12:50:31.185 [VideoFrame-001d6a4bfae8@281bc614] Opened MPEG-2 TS file: /sagedata/MyRecordingFile-38187431-1.ts firstPTS=3610166766 durationMsec=12583388
sagetv_1.txt:Sun 4/3 12:50:31.449 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected2(Fri 4/1 19:00:16.212, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] realDur=12583388
sagetv_1.txt:Sun 4/3 12:50:34.030 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected(Fri 4/1 19:02:48.174, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_1.txt:Sun 4/3 12:50:34.030 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected2(Fri 4/1 19:02:48.174, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] realDur=12583388
sagetv_1.txt:Sun 4/3 12:50:35.297 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected(Fri 4/1 19:02:49.444, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_1.txt:Sun 4/3 12:50:35.297 [VideoFrame-001d6a4bfae8@281bc614] VideoFrame.timeSelected2(Fri 4/1 19:02:49.444, true) currFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 0:00:00 0 kbps [] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] realDur=12583388

But then I restarted SageTV, and then that same recording was detected properly and played fine:

sagetv_0.txt:Sun 4/3 18:34:39.254 [Ministry@4325df72] Doing pre-emptive file format detection on recording MediaFile..../sagedata/MyRecordingFile-38187431-1.ts
sagetv_0.txt:Sun 4/3 18:34:39.274 [Ministry@4325df72] Using internal format detector first for: /sagedata/MyRecordingFile-38187431-1.ts
sagetv_0.txt:Sun 4/3 18:34:39.398 [Ministry@4325df72] File Format Parsed-2a /sagedata/MyRecordingFile-38187431-1.ts=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]]
sagetv_0.txt:Sun 4/3 18:34:39.416 [Ministry@4325df72] File Format Parsed-2b /sagedata/MyRecordingFile-38187431-1.ts=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]]
sagetv_0.txt:Sun 4/3 18:43:13.117 [AsyncWatch@7afb8c12] watchThisFile=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_0.txt:Sun 4/3 18:43:13.117 [AsyncWatch@7afb8c12] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null])
sagetv_0.txt:Sun 4/3 18:43:13.118 [VideoFrame-001d6a4bfae8@661ccc20] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null] nPlayin=false
sagetv_0.txt:Sun 4/3 18:43:13.118 [VideoFrame-001d6a4bfae8@661ccc20] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null]
sagetv_0.txt:Sun 4/3 18:43:13.126 [VideoFrame-001d6a4bfae8@661ccc20] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null] nPlayin=false
sagetv_0.txt:Sun 4/3 18:43:13.126 [VideoFrame-001d6a4bfae8@661ccc20] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] ifn=null]
sagetv_0.txt:Sun 4/3 18:43:13.126 [VideoFrame-001d6a4bfae8@661ccc20] VideoFrame creating new media player for file:MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]]
sagetv_0.txt:Sun 4/3 18:43:13.126 [VideoFrame-001d6a4bfae8@661ccc20] VF file=MediaFile[id=38397783 A[38187431,38185217,"My Show",32571@0401.19:00,150,T] mask=TV host=sagelinux encodedBy=DCT-HDHomeRun Prime Tuner 13191941-2 on 127.0.0.1:9000 CSNPHHD format=MPEG2-TS 3:29:42 3701 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=0100]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=0101 eng]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=0102 spa]] /sagedata/MyRecordingFile-38187431-1.ts, Seg0[Fri 4/1 19:00:16.212-Fri 4/1 22:30:00.006]] targetTime = Fri 4/1 19:00:16.212

So it seems like the initial recording failure (first file) made SageTV detect the recording as flawed and it cached that result without checking again, even though the second attempt (second file) was a good recording. I really wish I had the SageTV log to show what it was doing, because I don't know if this was an issue with OpenDCT or SageTV.

I'm guessing maybe one of these things happened: (1) SageTV deleted the first file at some point but it seems strange that it would delete one piece of a recording, or (2) OpenDCT never actually created the first file which could have confused SageTV, or (3) OpenDCT created the first file and notified SageTV but then deleted it because the stream was never detected properly which also could have confused SageTV. SageTV did detect the event as a halted recording and put a message into the System Messages log but the time only shows 7:00PM.

[troll5501]

I also noticed on 0.4.43 a minor issue related to the recent change for repeated log entries. The count and "time" vs "times" output appears to be misformatted:

21:03:35.108 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e41542c0] PES packet size mismatch time1: .
21:55:23.042 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time5: s.
22:12:42.193 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time5: s.
22:17:34.854 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time2: s.
22:17:35.041 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
22:32:23.205 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time3: s.
22:47:57.952 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
23:09:16.363 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
23:09:23.511 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time1: .
23:09:23.620 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .

It looks like the variables are out of order in the logger.log() call.

[EnterNoEscape]

Originally Posted by troll5501

I also noticed on 0.4.43 a minor issue related to the recent change for repeated log entries. The count and "time" vs "times" output appears to be misformatted:

21:03:35.108 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e41542c0] PES packet size mismatch time1: .
21:55:23.042 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time5: s.
22:12:42.193 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time5: s.
22:17:34.854 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time2: s.
22:17:35.041 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
22:32:23.205 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time3: s.
22:47:57.952 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
23:09:16.363 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .
23:09:23.511 [FFmpegTransSageTVConsumerImpl-474:DCT-HDHomeRun Prime Tuner 13191941-0] WARN  mpegts - Repeated [0x7f04e4fe7720] PES packet size mismatch time1: .
23:09:23.620 [FFmpegTransSageTVConsumerImpl-377:DCT-HDHomeRun Prime Tuner 13191941-2] WARN  mpegts - Repeated [0x7f04e50fd8a0] PES packet size mismatch time1: .

It looks like the variables are out of order in the logger.log() call.

You're right. I don't know how I missed that.

[EnterNoEscape]

Originally Posted by troll5501

OpenDCT tried to start the recording and it encountered the errors "avformat_open_input returned error code -1094995529" and it eventually closed the "<name>-0.ts" file (not sure if anything was ever written to it or if it was even created) and then it restarted the stream again successfully using the filename "<name>-1.ts" as expected. But today when I looked closer, there was no "<name>-0.ts" file on the filesystem, only the "<name>-1.ts" file which is the one you downloaded from me.

Based on what I saw in your log, FFmpeg wasn't able to detect the video dimensions after over 20MB of data. I never understand what's going on when that kind of thing happens. The -0.ts file is created the instant the consumer knows what the file will be which is fairly early in the tuning process. Since detection never succeeded, the file never grew beyond 0 bytes.

[jarredduq]

Since updating to RC8, recordings of Bravo channel are unwatchable. Let me know what you need to troubleshoot this problem. For now I've remapped that channel to record through my HDPVR, so I'm not dead on the water.

[waylo]

Thank you EnterNoEscape for your tireless updates on this project.

Just as an update, for me on my Comcast system, with the majority of channels (excepting major networks still on mpeg) with H264, I haven't had ANY hiccups for a while. As mentioned initially several pages back, the music stations won't play properly for the reasons you already explained, and I will await patiently if you choose to implement channel-dependent consumers in later builds.

[EnterNoEscape]

Originally Posted by jarredduq

Since updating to RC8, recordings of Bravo channel are unwatchable. Let me know what you need to troubleshoot this problem. For now I've remapped that channel to record through my HDPVR, so I'm not dead on the water.

Can you get me a raw clip of what you're seeing. Put the following URL into a browser. You'll need to figure out what the IP address of one of your HDHomeRuns is and put whatever the Bravo channel is for you in there directly next to the v. I think I know what's happening, but I would like to see it for myself.

http://<device_ip>:5004/auto/v<bravo_channel>?duration=120

[EnterNoEscape]

Originally Posted by waylo

Thank you EnterNoEscape for your tireless updates on this project.

Just as an update, for me on my Comcast system, with the majority of channels (excepting major networks still on mpeg) with H264, I haven't had ANY hiccups for a while. As mentioned initially several pages back, the music stations won't play properly for the reasons you already explained, and I will await patiently if you choose to implement channel-dependent consumers in later builds.

I have worked on that a little, but I don't want to put anything new into the mix until we get the current version to stable.

[jarredduq]

Originally Posted by EnterNoEscape

Can you get me a raw clip of what you're seeing. Put the following URL into a browser. You'll need to figure out what the IP address of one of your HDHomeRuns is and put whatever the Bravo channel is for you in there directly next to the v. I think I know what's happening, but I would like to see it for myself.

http://<device_ip>:5004/auto/v<bravo_channel>?duration=120

Uploaded file to Google Drive: https://drive.google.com/file/d/0ByM...ew?usp=sharing