I apologise in advance - this is all going to be a bit vague. But I hope with the guidance of some more knowledgeable folks, I'll be able to provide some more concrete information...
I have a problem with some programmes randomly not recording; possibly related, every so often I find that my tuners (one HDHomeRun Connect HDHR4-2DT, containing two tuners) seem to have become unavailable - as though they're not being released after Myth has been using them.
For example, I had a timer set to record 'The White Princess' last night - here's the mythbackend log for the period in question:
Code: Select all
2020-03-01 01:02:38.634785 I [1070/17411] Scheduler scheduler.cpp:2357 (HandleReschedule) - Reschedule requested for MATCH 0 1 0 2020-03-01T06:00:00Z EITScanner
2020-03-01 01:02:39.331262 I [1070/9731] TVRecEvent tv_rec.cpp:3665 (TuningFrequency) - TVRec[1]: TuningFrequency
2020-03-01 01:02:40.844429 I [1070/17411] Scheduler scheduler.cpp:2470 (HandleReschedule) - Scheduled 45 items in 1.9 = 1.83 match + 0.00 check + 0.10 place
2020-03-01 01:04:00.135028 I [1070/17411] Scheduler scheduler.cpp:2357 (HandleReschedule) - Reschedule requested for PLACE PrepareToRecord
2020-03-01 01:04:00.512840 I [1070/17411] Scheduler scheduler.cpp:2470 (HandleReschedule) - Scheduled 45 items in 0.1 = 0.00 match + 0.00 check + 0.10 place
2020-03-01 01:04:01.141146 I [1070/17411] Scheduler scheduler.cpp:2357 (HandleReschedule) - Reschedule requested for MATCH 0 1 0 2020-03-01T07:00:00Z EITScanner
2020-03-01 01:04:01.809515 I [1070/10243] TVRecEvent tv_rec.cpp:3665 (TuningFrequency) - TVRec[2]: TuningFrequency
2020-03-01 01:04:03.346942 I [1070/17411] Scheduler scheduler.cpp:2470 (HandleReschedule) - Scheduled 45 items in 1.9 = 1.83 match + 0.00 check + 0.10 place
2020-03-01 01:04:30.282271 I [1070/9731] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[1]: ASK_RECORDING 1 148 0 0
2020-03-01 01:04:30.561380 I [1070/9731] TVRecEvent tv_rec.cpp:3665 (TuningFrequency) - TVRec[1]: TuningFrequency
2020-03-01 01:04:30.561396 E [1070/9731] TVRecEvent tv_rec.cpp:3743 (TuningFrequency) - TVRec[1]: Failed to set channel to . Reverting to kState_None
2020-03-01 01:04:31.022802 I [1070/9731] TVRecEvent tv_rec.cpp:3665 (TuningFrequency) - TVRec[1]: TuningFrequency
2020-03-01 01:04:31.023283 E [1070/9731] TVRecEvent tv_rec.cpp:3743 (TuningFrequency) - TVRec[1]: Failed to set channel to . Reverting to kState_None
2020-03-01 01:04:31.064883 I [1070/10755] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[3]: ASK_RECORDING 3 148 0 0
2020-03-01 01:04:31.064883 I [1070/11011] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[4]: ASK_RECORDING 4 148 0 0
2020-03-01 01:04:31.064906 I [1070/16387] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[9]: ASK_RECORDING 9 148 0 0
2020-03-01 01:04:31.064921 I [1070/16131] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[8]: ASK_RECORDING 8 148 0 0
2020-03-01 01:04:31.064922 I [1070/11779] TVRecEvent tv_rec.cpp:1639 (HandlePendingRecordings) - TVRec[7]: ASK_RECORDING 7 148 0 0
2020-03-01 01:05:00.196611 I [1070/9731] TVRecEvent tv_rec.cpp:1090 (HandleStateChange) - TVRec[1]: Changing from None to RecordingOnly
2020-03-01 01:05:00.360926 I [1070/9731] TVRecEvent tv_rec.cpp:3665 (TuningFrequency) - TVRec[1]: TuningFrequency
2020-03-01 01:05:00.392860 I [1070/17411] Scheduler scheduler.cpp:2924 (HandleRecordingStatusChange) - Tuning recording: "White Princess":"In Bed with the Enemy": channel 1020 on cardid [1], sourceid 1
2020-03-01 01:05:00.489758 C [1070/775] CoreContext programinfo.cpp:340 (ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
2020-03-01 01:05:07.265020 E [1070/9731] TVRecEvent tv_rec.cpp:3962 (TuningSignalCheck) - TVRec[1]: TuningSignalCheck: Hit pre-fail timeout
2020-03-01 01:05:08.098437 I [1070/17411] Scheduler scheduler.cpp:2357 (HandleReschedule) - Reschedule requested for MATCH 0 0 0 2020-03-01T06:00:00Z EITScanner
2020-03-01 01:05:10.285620 I [1070/17411] Scheduler scheduler.cpp:2470 (HandleReschedule) - Scheduled 45 items in 1.9 = 1.81 match + 0.00 check + 0.10 place
2020-03-01 01:05:10.706670 W [1070/9731] TVRecEvent tv_rec.cpp:3993 (TuningSignalCheck) - TVRec[1]: TuningSignalCheck: taking more than 10000 ms to get a lock. marking this recording as 'Failing'.
2020-03-01 01:05:10.706689 W [1070/9731] TVRecEvent tv_rec.cpp:3995 (TuningSignalCheck) - TVRec[1]: See 'Tuning timeout' in mythtv-setup for this input
2020-03-01 01:05:10.707123 I [1070/775] CoreContext scheduler.cpp:735 (UpdateRecStatus) - Updating status for "White Princess":"In Bed with the Enemy" on cardid [1] (Tuning => Failing)
2020-03-01 01:05:10.707901 I [1070/17411] Scheduler scheduler.cpp:2357 (HandleReschedule) - Reschedule requested for CHECK -14 1917 0 UpdateRecStatus2 | White Princess | In Bed with the Enemy | Historical drama series adapted from Philippa Gregory's novel. Henry Tudor is set to ascend to the throne, which puts Elizabeth of York in grave danger. | bds.tv/e9mrrp
2020-03-01 01:05:11.087736 I [1070/17411] Scheduler scheduler.cpp:2470 (HandleReschedule) - Scheduled 45 items in 0.1 = 0.00 match + 0.00 check + 0.10 place
2020-03-01 01:05:15.018265 E [1070/17923] JobQueue programinfo.cpp:2608 (GetPlaybackURL) - ProgramInfo(1020_20200301010500.ts): GetPlaybackURL: '1020_20200301010500.ts' should be local, but it can not be found.
2020-03-01 01:05:15.019865 E [1070/17923] JobQueue programinfo.cpp:2608 (GetPlaybackURL) - ProgramInfo(1020_20200301010500.ts): GetPlaybackURL: '1020_20200301010500.ts' should be local, but it can not be found.
Code: Select all
20200301-00:50:55 Tuner: tuner1 rtp stream ended (stop request)
20200301-00:51:59 Tuner: tuner0 rtp stream ended (stop request)
20200301-00:51:59 Tuner: tuner0 streaming rtp to 192.168.101.5:50170
20200301-00:53:54 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-00:57:19 Tuner: tuner0 rtp stream ended (stop request)
20200301-00:57:22 Tuner: tuner0 streaming rtp to 192.168.101.5:50170
20200301-00:58:57 Tuner: tuner1 rtp stream ended (stop request)
20200301-00:58:58 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:02:40 Tuner: tuner0 rtp stream ended (stop request)
20200301-01:02:40 Tuner: tuner0 streaming rtp to 192.168.101.5:50170
20200301-01:04:02 Tuner: tuner1 rtp stream ended (stop request)
20200301-01:04:05 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:04:31 Tuner: tuner0 rtp stream ended (stop request)
20200301-01:09:07 Tuner: tuner1 rtp stream ended (stop request)
20200301-01:09:07 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:14:11 Tuner: tuner1 rtp stream ended (stop request)
20200301-01:17:25 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:22:35 Tuner: tuner1 rtp stream ended (stop request)
20200301-01:22:35 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:27:45 Tuner: tuner1 rtp stream ended (stop request)
20200301-01:27:47 Tuner: tuner1 streaming rtp to 192.168.101.5:56083
20200301-01:32:55 Tuner: tuner1 rtp stream ended (stop request)
I'm running Myth 29.1-ish on Mac OS X.
Any thoughts on what this might be, or what I can log more deeply to find out what's going on?
Thanks!