tl;dr recorder fails at some point after starting, logs don't show much, but truncated logs below.
I have MythTV set up with a Hauppauge 950Q USB tuner, hooked up to an antenna to capture local broadcasts. So far all is well except for recordings.
Occasionally, recordings will fail (around 80% of the time). I do not know why, and it doesn't seem related to a specific show. There's no specific place when it fails, but invariably, it just won't capture the entire episode, and MythTV marks the recording as failed.
Here are relevant logs from yesterday when I made a couple recordings:
The Good Doctor, recorded 9:52 out of 60 minutes
Code: Select all
Sep 23 22:00:05 media mythbackend: mythbackend[21652]: I Scheduler scheduler.cpp:2934 (HandleRecordingStatusChange) Tuning recording: "The Good Doctor":Disaster: channel 10901 on cardid [1], sourceid 1
Sep 23 22:00:06 media mythbackend: mythbackend[21652]: I CoreContext scheduler.cpp:729 (UpdateRecStatus) Updating status for "The Good Doctor":Disaster on cardid [1] (Tuning => Recording)
Sep 23 22:00:06 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:4231 (TuningNewRecorder) TVRec[1]: rec->GetPathname(): '/home/media/Recordings/10901_20190924020000.ts'
Sep 23 22:00:06 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:4263 (TuningNewRecorder) TVRec[1]: TuningNewRecorder - CreateRecorder()
Sep 23 22:02:32 media mythbackend: mythbackend[21652]: N Expire autoexpire.cpp:261 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
Sep 23 22:16:32 media mythbackend: mythbackend[21652]: N Expire autoexpire.cpp:261 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
... truncated ...
Sep 23 23:00:00 media mythbackend: mythbackend[21652]: I CoreContext scheduler.cpp:729 (UpdateRecStatus) Updating status for "The Good Doctor":Disaster on cardid [1] (Recording => Recorder Failed)
Sep 23 23:00:00 media mythbackend: mythbackend[21652]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -9 8 0 UpdateRecStatus2 | The Good Doctor | Disaster | Following what he believes was a disastrous first date with Carly, Dr. Shaun Murphy proposes a radical surgery to save a newlywed woman's life; Dr. Morgan Reznick and Dr. Alex Park compete for the chance to operate on an elderly patient. | EP026966280039
Because I noticed this pattern earlier, I had cleared out a bunch of junk from my hard drive and had over 80 GB free, but the episode still failed to record.
The Morning Show, recorded ~50 minutes out of 60
Code: Select all
Sep 24 09:00:00 media mythbackend: mythbackend[21652]: C CoreContext programinfo
.cpp:347 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Sep 24 09:00:05 media mythbackend: mythbackend[21652]: I Scheduler scheduler.cpp
:2934 (HandleRecordingStatusChange) Tuning recording: "The Morning Show": channel 14101 on cardid [1], sourceid 1
Sep 24 09:00:05 media mythbackend: mythbackend[21652]: I CoreContext scheduler.cpp:729 (UpdateRecStatus) Updating status for "The Morning Show" on cardid [1] (Tuning => Recording)
Sep 24 09:00:05 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:4231 (TuningNewRecorder) TVRec[1]: rec->GetPathname(): '/home/media/Recordings/14101_20190924130000.ts'
Sep 24 09:00:05 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:4263 (TuningNewRecorder) TVRec[1]: TuningNewRecorder - CreateRecorder()
... truncated ...
Sep 24 09:27:30 media mythbackend: mythbackend[21652]: N Expire autoexpire.cpp:2
61 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq
: 14 min
Sep 24 09:41:30 media mythbackend: mythbackend[21652]: N Expire autoexpire.cpp:2
61 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq
: 14 min
Sep 24 09:49:06 media mythbackend: mythbackend[21652]: I MythSocketThread(89) ma
inserver.cpp:7928 (connectionClosed) Playback sock(55ccb62e2ef0) 'media' disconn
ected
Sep 24 09:49:27 media mythbackend: mythbackend[21652]: I MythSocketThread(79) ba
ckendcontext.cpp:97 (SetFrontendDisconnected) BackendContext: Frontend 'media' d
isconnected.
Sep 24 09:49:27 media mythbackend: mythbackend[21652]: I MythSocketThread(79) ma
inserver.cpp:7928 (connectionClosed) Playback sock(55ccb6241db0) 'media' disconn
ected
Sep 24 09:49:27 media mythbackend: mythbackend[21652]: I MythSocketThread(75) ma
inserver.cpp:7928 (connectionClosed) Monitor sock(55ccb6242300) 'media' disconnected
Sep 24 09:55:30 media mythbackend: mythbackend[21652]: N Expire autoexpire.cpp:261 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
Sep 24 10:00:01 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:1091 (HandleStateChange) TVRec[1]: Changing from RecordingOnly to None
Sep 24 10:00:01 media mythbackend: mythbackend[21652]: N RecThread recorders/recorderbase.cpp:507 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (1920x1080 A/R: 3 29.97fps) Audio Codec: ac3
Sep 24 10:00:01 media mythbackend: mythbackend[21652]: I TVRecEvent tv_rec.cpp:864 (FinishedRecording) TVRec[1]: FinishedRecording(14101_2019-09-24T13:00:00Z) damaged recq:<RecordingQuality overall_score="0" key="14101_2019-09-24T13:00:00Z" countinuity_error_count="0" packet_count="38577695">#012 <Gap start="2019-09-24T13:50:43Z" end="2019-09-24T14:00:00Z" duration="556" />#012</RecordingQuality>
Sep 24 10:00:01 media mythbackend: mythbackend[21652]: E CoreContext mainserver.cpp:1199 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
Sep 24 10:00:01 media mythbackend: mythbackend[21652]: I CoreContext scheduler.cpp:729 (UpdateRecStatus) Updating status for "The Morning Show" on cardid [1] (Recording => Recorder Failed)
Anybody have thoughts on what I should do to debug? The logs don't seem to show enough information (or any at all, really) for the time period when recordings fail.
Thanks in advance!