Mailing List Archive

Some recordings fail, others work OK
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
Re: Some recordings fail, others work OK [ In reply to ]
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
>
> _______________________________________________
> mythtv-users mailing list
> mythtv-users@mythtv.org
> http://lists.mythtv.org/mailman/listinfo/mythtv-users
> http://wiki.mythtv.org/Mailing_List_etiquette
> MythTV Forums: https://forum.mythtv.org

_______________________________________________
mythtv-users mailing list
mythtv-users@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-users
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: Some recordings fail, others work OK [ In reply to ]
On Sat, 12 Dec 2020 at 00:15, Ram Ramesh <rramesh2400@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.