I'm in the UK and get DVB/T and DVB/T2 transmissions. The tuner claims 100% signal strength and quality and I have good pictures on all
devices in the house.
The backend is a NUC dual i3, 4GB with SSD (root) and a USB drive (/var/lib/mythtv) running xubuntu 20.04/Myth 32.
Both are connected via ethernet to the same 1GB switch. I use EIT active grabbing on the first tuner only.
The HDHR is only accessed from this backend - I don't use it with other devices in the house.
About once every 10 days or so a recording on tuner 1 will fail. Thereafter, everything on tuner 1 fails but tuner 2 (and presumably 3 & 4) are ok. An hdhr reboot is needed to mend it.
Here is an extract from the log (with a column removed for readability).
Code: Select all
The previous recording was successful and it rescheduled the next recording.
Nov 3 15:47:57 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for MATCH 0 2 0 2022-11-10T23:32:30Z EITScanner
Nov 3 15:47:57 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 42 items in 0.1 = 0.04 match + 0.03 check + 0.02 place
Nov 3 15:50:00 myth2 : I TVRecEvent tv_rec.cpp:1077 (HandleStateChange) TVRec[1]: Changing from RecordingOnly to None
Nov 3 15:50:00 myth2 : N RecThread recorders/recorderbase.cpp:492 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (704x576 A/R: 3 25fps) Audio Codec: mp2
Nov 3 15:50:00 myth2 : I TVRecEvent recordinginfo.cpp:1251 (FinishedRecording) Finished recording I Escaped to the Country: channel 20001
Nov 3 15:50:00 myth2 : I CoreContext scheduler.cpp:717 (UpdateRecStatus) Updating status for "I Escaped to the Country" on cardid [1] (Recording => Recorded)
Nov 3 15:50:00 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for CHECK -3 4126 0 UpdateRecStatus2 | I Escaped to the Country | | Jules Hudson and Steve Brown revisit former house-hunters in the Midlands. One couple's home is now a lovely Grade II listed barn conversion. | fp.bbc.co.uk/m/rynq
Nov 3 15:50:00 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 41 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
Nov 3 15:50:02 myth2 : I ProcessRequest mainserver.cpp:1811 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 3 15:50:02 myth2 : I ProcessRequest mainserver.cpp:1813 (HandleAnnounce) MainServer: adding: myth2(558eed1e8880) as a client (events: 0)
Nov 3 15:50:02 myth2 : I ProcessRequest mainserver.cpp:1811 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 3 15:50:02 myth2 : I ProcessRequest mainserver.cpp:1813 (HandleAnnounce) MainServer: adding: myth2(558eed1ea7b0) as a client (events: 1)
Nov 3 15:50:10 myth2 : I MythSocketThread(81) mainserver.cpp:7889 (connectionClosed) Monitor sock(558eed1e8880) 'myth2' disconnected
Nov 3 15:50:10 myth2 : I MythSocketThread(84) mainserver.cpp:7889 (connectionClosed) Monitor sock(558eed1ea7b0) 'myth2' disconnected
Nov 3 15:50:16 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for MATCH 0 0 0 2022-11-03T16:20:00Z EITScanner
Nov 3 15:50:16 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 41 items in 0.1 = 0.03 match + 0.00 check + 0.02 place
Nov 3 15:51:09 myth2 : I TVRecEvent tv_rec.cpp:3600 (TuningFrequency) TVRec[1]: TuningFrequency
Nov 3 15:51:09 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:393 (Connect) HDHRSH[1](1252CD2F): Added 4 devices from 1252CD2F
Nov 3 15:51:09 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:410 (Connect) HDHRSH[1](1252CD2F): Connected to device(1252CD2F-0)
Nov 3 15:51:47 myth2 : N Expire autoexpire.cpp:240 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
Nov 3 15:51:47 myth2 : N Expire autoexpire.cpp:725 (ExpireEpisodesOverMax) Deleting 20001 at 2022-10-25T13:56:00Z => I Escaped to the Country. Too many episodes, we only want to keep 7.
Nov 3 15:51:47 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for CHECK -3 4126 0 ForgetHistory | I Escaped to the Country | | Jules Hudson revisits two pairs of home-buyers he previously showed around Norfolk. | fp.bbc.co.uk/m/rsgm
Nov 3 15:51:47 myth2 : N CoreContext recordinginfo.cpp:631 (ApplyRecordRecGroupChange) ApplyRecordRecGroupChange: Default to Deleted (3)
Nov 3 15:51:48 myth2 : E CoreContext mainserver.cpp:2158 (SendResponse) MainServer: SendResponse: Unable to write to client socket, as it's no longer there
Nov 3 15:51:48 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 41 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
Nov 3 15:51:48 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for CHECK 0 4126 0 DoHandleDelete1 | I Escaped to the Country | | Jules Hudson revisits two pairs of home-buyers he previously showed around Norfolk. | fp.bbc.co.uk/m/rsgm
Nov 3 15:51:48 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 41 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
[...]
Next recording failed and was rescheduling on a different channel and second tuner (dvb/t2 not dvb/t) which was successful.
Nov 3 22:35:00 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Nov 3 22:35:00 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 40 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
Nov 3 22:35:01 myth2 : I TVRecEvent tv_rec.cpp:3600 (TuningFrequency) TVRec[1]: TuningFrequency
Nov 3 22:35:01 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:393 (Connect) HDHRSH[1](1252CD2F): Added 4 devices from 1252CD2F
Nov 3 22:35:01 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:410 (Connect) HDHRSH[1](1252CD2F): Connected to device(1252CD2F-0)
Nov 3 22:35:11 myth2 : E TVRecEvent tv_rec.cpp:3891 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: SignalMonitor timed out
Nov 3 22:35:11 myth2 : I TVRecEvent tv_rec.cpp:3600 (TuningFrequency) TVRec[1]: TuningFrequency
Nov 3 22:35:11 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:393 (Connect) HDHRSH[1](1252CD2F): Added 4 devices from 1252CD2F
Nov 3 22:35:11 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:410 (Connect) HDHRSH[1](1252CD2F): Connected to device(1252CD2F-0)
Nov 3 22:35:11 myth2 : E TVRecEvent channelutil.cpp:1964 (GetChannelData) Could not find channel '' in DB for source '2'.
Nov 3 22:35:11 myth2 : E TVRecEvent recorders/dtvchannel.cpp:209 (SetChannelByString) DTVChan[1](1252CD2F): SetChannelByString(): Unable to find channel in database.
Nov 3 22:35:11 myth2 : E TVRecEvent tv_rec.cpp:3672 (TuningFrequency) TVRec[1]: Failed to set channel to . Reverting to kState_None
Nov 3 22:35:30 myth2 : I Scheduler mythdbcon.cpp:423 (PurgeIdleConnections) New DB connection, total: 10
Nov 3 22:35:30 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[7]: ASK_RECORDING 7 149 0 0
Nov 3 22:35:30 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[8]: ASK_RECORDING 8 149 0 0
Nov 3 22:35:30 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[6]: ASK_RECORDING 6 149 0 0
Nov 3 22:35:30 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[5]: ASK_RECORDING 5 149 0 0
Nov 3 22:35:30 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[1]: ASK_RECORDING 1 149 0 0
Nov 3 22:36:00 myth2 : I TVRecEvent tv_rec.cpp:1077 (HandleStateChange) TVRec[1]: Changing from None to RecordingOnly
Nov 3 22:36:00 myth2 : I CoreContext mythdbcon.cpp:423 (PurgeIdleConnections) New DB connection, total: 10
Nov 3 22:36:00 myth2 : E CoreContext programinfo.cpp:2619 (GetPlaybackURL) ProgramInfo(20001_20221103223600.ts): GetPlaybackURL: '20001_20221103223600.ts' should be local, but it can not be found.
Nov 3 22:36:00 myth2 : I CoreContext mythsystemevent.cpp:314 (customEvent) MythSystemEventHandler: Starting thread for command '/usr/local/bin/checkfilm.pl -v --chanid=20001 >>/var/log/mythtv/checkfilm.log 2>&1'
Nov 3 22:36:00 myth2 : I TVRecEvent tv_rec.cpp:3600 (TuningFrequency) TVRec[1]: TuningFrequency
Nov 3 22:36:00 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:393 (Connect) HDHRSH[1](1252CD2F): Added 4 devices from 1252CD2F
Nov 3 22:36:00 myth2 : I SystemEvent mythsystemevent.cpp:50 (run) MythSystemEventHandler: Finished '/usr/local/bin/checkfilm.pl -v --chanid=20001 >>/var/log/mythtv/checkfilm.log 2>&1' result 0
Nov 3 22:36:00 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:410 (Connect) HDHRSH[1](1252CD2F): Connected to device(1252CD2F-0)
Nov 3 22:36:00 myth2 : I Scheduler scheduler.cpp:2899 (HandleRecordingStatusChange) Tuning recording: "Question Time": channel 20001 on cardid [1], sourceid 2
Nov 3 22:36:07 myth2 : E TVRecEvent tv_rec.cpp:3904 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Hit pre-fail timeout
Nov 3 22:36:10 myth2 : W TVRecEvent tv_rec.cpp:3932 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: taking more than 10000 ms to get a lock. marking this recording as 'Failing'.
Nov 3 22:36:10 myth2 : W TVRecEvent tv_rec.cpp:3937 (TuningSignalCheck) TVRec[1]: See 'Tuning timeout' in mythtv-setup for this input
Nov 3 22:36:10 myth2 : I CoreContext scheduler.cpp:717 (UpdateRecStatus) Updating status for "Question Time" on cardid [1] (Tuning => Failing)
Nov 3 22:36:10 myth2 : I Scheduler scheduler.cpp:2330 (HandleReschedule) Reschedule requested for CHECK -14 5609 0 UpdateRecStatus2 | Question Time | | Fiona Bruce presents an hour of debate from Horsham in West Sussex. On the panel are Chris Philp MP, Peter Kyle MP, Zanny Minton Beddoes, George the Poet and Lord Stuart. | fp.bbc.co.uk/m/ryqm
Nov 3 22:36:10 myth2 : I Scheduler scheduler.cpp:2444 (HandleReschedule) Scheduled 40 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
Nov 3 22:36:10 myth2 : I TVRecEvent tv_rec.cpp:1077 (HandleStateChange) TVRec[2]: Changing from None to RecordingOnly
Nov 3 22:36:10 myth2 : I TVRecEvent mythdbcon.cpp:423 (PurgeIdleConnections) New DB connection, total: 10
Nov 3 22:36:10 myth2 : I TVRecEvent tv_rec.cpp:3600 (TuningFrequency) TVRec[2]: TuningFrequency
Nov 3 22:36:10 myth2 : E CoreContext programinfo.cpp:2619 (GetPlaybackURL) ProgramInfo(20101_20221103223600.ts): GetPlaybackURL: '20101_20221103223600.ts' should be local, but it can not be found.
Nov 3 22:36:10 myth2 : I CoreContext mythsystemevent.cpp:314 (customEvent) MythSystemEventHandler: Starting thread for command '/usr/local/bin/checkfilm.pl -v --chanid=20101 >>/var/log/mythtv/checkfilm.log 2>&1'
Nov 3 22:36:10 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:393 (Connect) HDHRSH[2](1252CD2F): Added 4 devices from 1252CD2F
Nov 3 22:36:10 myth2 : I TVRecEvent recorders/hdhrstreamhandler.cpp:410 (Connect) HDHRSH[2](1252CD2F): Connected to device(1252CD2F-1)
Nov 3 22:36:10 myth2 : I SystemEvent mythsystemevent.cpp:50 (run) MythSystemEventHandler: Finished '/usr/local/bin/checkfilm.pl -v --chanid=20101 >>/var/log/mythtv/checkfilm.log 2>&1' result 0
Nov 3 22:36:10 myth2 : I Scheduler scheduler.cpp:2899 (HandleRecordingStatusChange) Tuning recording: "Question Time": channel 20101 on cardid [2], sourceid 2
Nov 3 22:36:11 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[10]: ASK_RECORDING 10 108 0 0
Nov 3 22:36:11 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[9]: ASK_RECORDING 9 108 0 0
Nov 3 22:36:11 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[12]: ASK_RECORDING 12 108 0 0
Nov 3 22:36:11 myth2 : I TVRecEvent tv_rec.cpp:1648 (HandlePendingRecordings) TVRec[11]: ASK_RECORDING 11 108 0 0
Nov 3 22:36:12 myth2 : I CoreContext scheduler.cpp:717 (UpdateRecStatus) Updating status for "Question Time" on cardid [2] (Tuning => Recording)
Nov 3 22:36:12 myth2 : I TVRecEvent tv_rec.cpp:4146 (TuningNewRecorder) TVRec[2]: rec->GetPathname(): '/var/lib/mythtv/recordings/20101_20221103223600.ts'
Nov 3 22:36:12 myth2 : I TVRecEvent tv_rec.cpp:4179 (TuningNewRecorder) TVRec[2]: TuningNewRecorder - CreateRecorder()
Nov 3 22:36:20 myth2 : W TFWWrite threadedfilewriter.cpp:547 (DiskLoop) TFW(/var/lib/mythtv/recordings/20101_20221103223600.ts:85): write(376) cnt 38 total 2357708 -- took a long time, 6824 ms
Nov 3 22:36:48 myth2 : N Expire autoexpire.cpp:240 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 7 min
Thereafter, all test recordings on tuner 1 fail, all those on tuner 2 work.
Nov 4 07:56:10 myth2 : I (UpdateRecStatus) Updating status for "Spellbinders Wax Seals" on cardid [1] (Tuning => Failing)
Nov 4 09:01:50 myth2 : I (UpdateRecStatus) Updating status for "Spellbinders Wax Seals" on cardid [1] (Failing => Recorder Failed)
Nov 4 09:56:10 myth2 : I (UpdateRecStatus) Updating status for "Critical Incident" on cardid [1] (Tuning => Failing)
Nov 4 09:56:12 myth2 : I (UpdateRecStatus) Updating status for "Critical Incident" on cardid [2] (Tuning => Recording)
Nov 4 10:46:50 myth2 : I (UpdateRecStatus) Updating status for "Critical Incident" on cardid [1] (Failing => Recorder Failed)
Nov 4 10:50:00 myth2 : I (UpdateRecStatus) Updating status for "Critical Incident" on cardid [2] (Recording => Recorded)
Nov 4 14:56:11 myth2 : I (UpdateRecStatus) Updating status for "I Escaped to the Country" on cardid [1] (Tuning => Failing)
Nov 4 15:46:50 myth2 : I (UpdateRecStatus) Updating status for "I Escaped to the Country" on cardid [1] (Failing => Recorder Failed)
Nov 4 17:56:10 myth2 : I (UpdateRecStatus) Updating status for "Paper Boutique Mandala Magic..." on cardid [1] (Tuning => Failing)
Nov 4 18:26:10 myth2 : I (UpdateRecStatus) Updating status for "The Now Show" on cardid [2] (Tuning => Failing)
Nov 4 19:01:50 myth2 : I (UpdateRecStatus) Updating status for "Paper Boutique Mandala Magic..." on cardid [1] (Failing => Recorder Failed)
Nov 4 19:01:50 myth2 : I (UpdateRecStatus) Updating status for "The Now Show" on cardid [2] (Failing => Recorder Failed)
Nov 4 20:56:10 myth2 : I (UpdateRecStatus) Updating status for "Paper Boutique Mandala Magic..." on cardid [1] (Tuning => Failing)
Nov 4 22:01:50 myth2 : I (UpdateRecStatus) Updating status for "Paper Boutique Mandala Magic..." on cardid [1] (Failing => Recorder Failed)
Nov 4 22:36:10 myth2 : I (UpdateRecStatus) Updating status for "The Graham Norton Show" on cardid [2] (Tuning => Failing)
Nov 4 22:56:10 myth2 : I (UpdateRecStatus) Updating status for "Paper Boutique Mandala Magic..." on cardid [1] (Tuning => Failing)
Nov 4 23:31:50 myth2 : I (UpdateRecStatus) Updating status for "The Graham Norton Show" on cardid [2] (Failing => Recorder Failed)
hdhomerun_config FFFFFFFF set /sys/restart self A restart of the backend is not needed.
I'm also getting messages saying that writes are taking serveral seconds to start, presumably because the disk needs to spin up to speed.
A 'touch' at start recording system event makes little difference. I don't think this will be an issue though.
Any insights appreciated. Meanwhile, changes I'm considering or doing, all of which are probably wild desperate shots in the dark are:
- run that checkfilm job at another time (it looks for films split by news items - see UK forum).
- upgrade to latest firmware in the tuner (it was on February 2022. now Sept 2022).
- dedicated switch for these two devices
- attenuate the TV signal in case it is marginally overloading the hdhr
- reboot the hdhr regularly in case it's a memory leak.
Phil