[mythtv-users] Some recordings fail, others work OK

Sat Dec 12 08:08:42 UTC 2020

On Sat, 12 Dec 2020 at 00:15, Ram Ramesh <rramesh2400 at gmail.com> wrote:

> I had something similar. After several weeks of experiment, I found out
> that one of the channels from available channels had a poor reception.
> This also happened to be the first channel in the list (channel 2 for
> me). So, after a reboot of the backend the default will be to tune to
> channel 2 (all 4 tuners).  For whatever reason, backend could never tune
> to any other channel as the tuner was in broken state as it is stuck
> trying to tune channel 2. Since I do not watch or record channel 2, I
> simply removed it from the list of available channels and all my
> troubles went away.
>
> While this may not be your situation, just see if one (or more) of your
> channel is difficult to tune and if you do not need it, eliminate it
> from the list. This might help.
>
> Ramesh
>
>
> On 12/11/20 4:37 PM, Phill Edwards wrote:
> > Hi, I have a 4 tuner HD Homerun. A lot of the recordings work just
> > fine, but quite a lot also fail and end up with zero byte recordings.
> > I've checked if it's any particular one of the 4 tuners that fail and
> > it's not.
> >
> > I've noticed that when it fails there are always two recording entries
> > - I guess it fails once and tries again which creates a second entry
> > (which also fails).
> >
> > Here's some backend log file info for one of the failed recordings.
> > Can anyone identify what's causing the problem? There's a message
> > about "Failed to set channel" - could that be the root cause, and if
> > so what can I do to fix it?
> >
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:1057 (HandleStateChange) TVRec[42]: Changing from None to
> > RecordingOnly
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > mythdbcon.cpp:420 (PurgeIdleConnections) New DB connection, total: 17
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:3662 (TuningFrequency) TVRec[42]: TuningFrequency
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > recorders/hdhrstreamhandler.cpp:485 (TunerSet) HDHRSH[38](1251AA81):
> > DeviceSet(channel auto:543500000): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > recorders/dtvchannel.cpp:305 (SetChannelByString)
> > DTVChan[42](1251AA81): SetChannelByString(22): Tuning to frequency.
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > tv_rec.cpp:3737 (TuningFrequency) TVRec[42]: Failed to set channel to
> > 22. Reverting to kState_None
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:1057 (HandleStateChange) TVRec[42]: Changing from
> > RecordingOnly to None
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I CoreContext
> > scheduler.cpp:713 (UpdateRecStatus) Updating status for "Absolutely
> > Fabulous":Identity on cardid [42] (Pending => Recorder Failed)
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I Scheduler
> > scheduler.cpp:2882 (HandleRecordingStatusChange) Tuning recording:
> > "Absolutely Fabulous":Identity: channel 1022 on cardid [42], sourceid 1
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I Scheduler
> > scheduler.cpp:2311 (HandleReschedule) Reschedule requested for CHECK
> > -9 1714 0 UpdateRecStatus2 | Absolutely Fabulous | Identity | Twenty
> > years after Eddy, Patsy, Saffy, Bubble and Mother first staggered into
> > our lives, they're all back. One of them has been away for some time,
> > so there is lots of catching up to do. | EP17852045116
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: C CoreContext
> > programinfo.cpp:259 (ProgramInfo) ProgramInfo(): Failed to find
> > recorded entry for 0.
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E Scheduler
> > recordinginfo.cpp:1051 (InsertProgram)
> > RecordingInfo::InsertProgram(ProgramInfo(1022_20201211101500.ts):
> > channame(ABCComedy/Kids) startts(Fri. Dec. 11 10:17:00 2020 GMT)
> > endts(Fri. Dec. 11 10:48:00 2020 GMT)#012 recstartts(Fri. Dec. 11
> > 10:15:00 2020 GMT) recendts(Fri. Dec. 11 10:48:00 2020 GMT)#012
> >       title(Absolutely Fabulous)): recording already exists...
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:1057 (HandleStateChange) TVRec[42]: Changing from None to
> > RecordingOnly
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:3662 (TuningFrequency) TVRec[42]: TuningFrequency
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > recorders/hdhrstreamhandler.cpp:485 (TunerSet) HDHRSH[38](1251AA81):
> > DeviceSet(channel auto:543500000): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > recorders/dtvchannel.cpp:305 (SetChannelByString)
> > DTVChan[42](1251AA81): SetChannelByString(22): Tuning to frequency.
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E TVRecEvent
> > tv_rec.cpp:3737 (TuningFrequency) TVRec[42]: Failed to set channel to
> > 22. Reverting to kState_None
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I TVRecEvent
> > tv_rec.cpp:1057 (HandleStateChange) TVRec[42]: Changing from
> > RecordingOnly to None
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I CoreContext
> > scheduler.cpp:713 (UpdateRecStatus) Updating status for "Absolutely
> > Fabulous":Identity on cardid [42] (Tuning => Recorder Failed)
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E Scheduler
> > scheduler.cpp:775 (ChangeRecordingEnd) Failed to change end time on
> > card 42 to 2020-12-11T10:48:00Z
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I Scheduler
> > scheduler.cpp:2415 (HandleReschedule) Reschedule interrupted, will retry
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I Scheduler
> > scheduler.cpp:2311 (HandleReschedule) Reschedule requested for CHECK
> > -9 1714 0 UpdateRecStatus2 | Absolutely Fabulous | Identity | Twenty
> > years after Eddy, Patsy, Saffy, Bubble and Mother first staggered into
> > our lives, they're all back. One of them has been away for some time,
> > so there is lots of catching up to do. | EP17852045116
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: I Scheduler
> > scheduler.cpp:2311 (HandleReschedule) Reschedule requested for PLACE
> > Interrupted
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[38](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0011
> > 0x0014 0x0100-0x010f 0x0901 0x0903-0x0904 0x0906 0x0968
> > 0x157c-0x157d): ERROR: lock no longer held
> > Dec 11 21:15:00 MBE3 mythbackend: mythbackend[1016]: E
> > HDHRStreamHandler recorders/hdhrstreamhandler.cpp:485 (TunerSet)
> > HDHRSH[41](1251AA81): DeviceSet(filter 0x0000-0x0001 0x0010-0x0012
> > 0x0014 0x0087 0x0100-0x0109 0x0207 0x0247 0x02d0 0x0321-0x0323
> > 0x0f02): ERROR: lock no longer held
> >
>
> The "ERROR: lock no longer held" indicates that the HDHomeRun has
forgotten that MythTV is talking to it.
This would happen if you power cycle the HDHomeRun while it is being used.
On older HDHomeRuns quite often the power supply fails and this could be
your problem as well.
I suggest replacing the power supply.

Klaas.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20201212/4a753474/attachment-0001.htm>