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

Phill Edwards philledwards at gmail.com
Fri Dec 11 22:37:40 UTC 2020


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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20201212/b680cfc4/attachment.htm>


More information about the mythtv-users mailing list