[mythtv-users] Odd rewriting of program length

[Ross Boylan]

On Sun, May 23, 2021 at 2:49 PM Ross Boylan
<rossboylan at stanfordalumni.org> wrote:
>
> I have a rule to record Castle, which is being rebroadcast in the
> early morning a couple of times a week.
> For the last month or more it has been sporadically recording as if it
> were a 30 minute show; it's an hour.
>
> The program listing always shows the show as an hour, even when I
> check the day before.

Here are the results of checking all tables that seemed relevant:
program: some episodes end 8:30 instead of 9:00
recorded: some endtimes are 8:32 instead of 9:02; progend is always 9:00
recordedprogram and oldrecorded always show 9:00.
oldprogram: only has title and starttime--irrelevant

One other oddity is that oldrecorded shows the 04-26 progstart 8:35
and progend 9:35; the start and end times match with the 2 minute
buffer, so it recorded a full hour.  recorded and recordedprogram have
similar entries.  Reviewing the recording it looks as if it did start
late, though 8:30 was probably correct.  There's no entry for this
recording in program (I think that's expected, since it's no longer
current).

It may also be relevant that the signal quality is poor.  At first I
thought the recordings were just cut off because of that, but the
entries I've mentioned and the fact the terminations are exactly when
they'd be expected for a half hour show make me think something else
is going on.  The latest backend logs are below, which seem to show
quality problems discovered only after the recording ended.

Ross
The log times are my local times, UTC-7
2021-05-23 01:31:02.006101 I [4122/4213] Scheduler scheduler.cpp:2356
(HandleReschedule) - Reschedule requested for MATCH 0 2 1
2021-05-23T17:30:00Z EITScanner
2021-05-23 01:31:02.254879 I [4122/4213] Scheduler scheduler.cpp:2472
(HandleReschedule) - Scheduled 969 items in 0.2 = 0.10 match + 0.00
check + 0.05 place
2021-05-23 01:32:00.701657 I [4122/4206] TVRecEvent tv_rec.cpp:1091
(HandleStateChange) - TVRec[1]: Changing from RecordingOnly to None
2021-05-23 01:32:00.738275 N [4122/26578] RecThread
recorders/recorderbase.cpp:507 (FinishRecording) - Finished Recording:
Container: MPEG2-TS Video Codec: mpeg2video (1280x720 A/R: 3
59.9401fps) Audio Codec: ac3
2021-05-23 01:32:00.740517 I [4122/4206] TVRecEvent tv_rec.cpp:864
(FinishedRecording) - TVRec[1]:
FinishedRecording(20701_2021-05-23T08:00:00Z) damaged
recq:<RecordingQuality overall_score="0"
key="20701_2021-05-23T08:00:00Z" countinuity_error_count="0"
packet_count="10388048">
    <Gap start="2021-05-23T08:31:58Z" end="2021-05-23T09:00:00Z"
duration="1681" />
</RecordingQuality>

2021-05-23 01:32:00.758341 I [4122/4122] CoreContext scheduler.cpp:729
(UpdateRecStatus) - Updating status for Castle:Driven on cardid [1]
(Recording => Recorder Failed)
2021-05-23 01:32:00.761755 I [4122/4213] Scheduler scheduler.cpp:2356
(HandleReschedule) - Reschedule requested for CHECK -9 1974 0
UpdateRecStatus2 | Castle | Driven | Kate Beckett is faced with the
toughest case of her career after finding Castle's car engulfed in
flames. | EP010855880132