Mailing List Archive

TuningSignalCheck: SignalMonitor failed ----- Wrong timeout
v32-Pre-2807-g8a7505a0ad

I have recently resurrected a USB tuner (Hauppauge Dual HD) which
performs very well. However it is failing every few days with the
following error:-

Three instances:-

Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: E TVRecEvent
tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
SignalMonitor failed
Sep 19 20:00:01 tv mythbackend: mythbackend[4247]: E TVRecEvent
tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
SignalMonitor failed
Sep 12 20:00:01 tv mythbackend: mythbackend[1854]: E TVRecEvent
tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
SignalMonitor failed

EIT scanning is running on this mux.

The fact that they all occur at the same time is pretty weird. The
backend boots up at 17:00 and does a couple of single recordings from
then until about 18:30. At 20:00 the main recording period starts. The
above failure is not fatal, later recordings on this mux do work.

The fact that the timeout is only one second is strange as well - the
timeouts for the tuner are the defaults 3000 signal 6000 tune.

Can anybody give any guidance on what logging is required to debug this
and I will raise it as a proper issue.

The following log snipit shows the fail at 20:01 for one recording and
another succeeding on the same mux at the same time?

Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:402 (StartRecording) TVRec[9]: StartRecording("The Great
British Bake Off")
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:692 (SetRecordingStatus) TVRec[9]:
SetRecordingStatus(Recording->Aborted) on line 408
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:472 (StartRecording) TVRec[9]: Checking input group recorders
- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:546 (StartRecording) TVRec[9]: Checking input group recorders
- done
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: W Scheduler
recordinginfo.cpp:998 (InsertRecording) RecordingInfo(TV):
InsertRecording: m_pathname was '/media/library/TV'. This is usually blank.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:794 (StartedRecording) TVRec[9]:
StartedRecording(20104_2021-09-28T19:00:00Z)
fn(/media/library/TV/20104_20210928190000.ts)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:4144 (LoadProfile) TVRec[9]: Using profile 'Default' to record
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(CancelNextRecording,)
-> RunMainLoop,RingBufferReady, @ tv_rec.cpp:591
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:692 (SetRecordingStatus) TVRec[9]:
SetRecordingStatus(Aborted->Tuning) on line 594
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1060 (HandleStateChange) TVRec[9]: Changing from None to
RecordingOnly
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]:
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:1299
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3509 (HandleTuning) TVRec[9]: HandleTuning Request:
Program(ProgramInfo(20104_20210928190000.ts): channame(Channel 4
HD)#012???????????? startts(Tue Sep 28 19:00:00 2021 GMT) endts(Tue Sep
28 20:30:00 2021 GMT)#012 recstartts(Tue Sep 28 19:00:00 2021 GMT)
recendts(Tue Sep 28 20:30:00 2021 GMT)#012???????????? title(The Great
British Bake Off)) channel() input() flags(Recording,)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3578 (TuningShutdowns) TVRec[9]:
TuningShutdowns(Program(ProgramInfo(20104_20210928190000.ts):
channame(Channel 4 HD)#012???????????? startts(Tue Sep 28 19:00:00 2021
GMT) endts(Tue Sep 28 20:30:00 2021 GMT)#012???????????? recstartts(Tue
Sep 28 19:00:00 2021 GMT) recendts(Tue Sep 28 20:30:00 2021
GMT)#012???????????? title(The Great British Bake Off)) channel(4)
input(DVB-T2) flags(Recording,))
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3645
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3531 (HandleTuning) TVRec[9]: No recorder yet, calling
TuningFrequency
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3667 (TuningFrequency) TVRec[9]: TuningFrequency
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
cardutil.cpp:2093 (GetConflictingInputs) CardUtil[9]:
GetConflictingInputs(9) 11,12,13
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3803 (TuningFrequency) TVRec[9]: Starting Signal Monitor
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2044 (SetupSignalMonitor) TVRec[9]: SetupSignalMonitor(1, 0)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dvbstreamhandler.cpp:46 (Get) DVBSH[9]: Creating new stream
handler /dev/dvb/adapter0/frontend0
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2068 (SetupSignalMonitor) TVRec[9]: Signal monitor
successfully created
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1864 (SetupDTVSignalMonitor) TVRec[9]: Setting up table
monitoring.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4144 (LoadProfile) TVRec[9]: Using profile 'Live TV' to record
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1939 (SetupDTVSignalMonitor) TVRec[9]: DVB service_id 17664
on net_id 9018 tsid 16520
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1961 (SetupDTVSignalMonitor) TVRec[9]: Successfully set up
DVB table monitoring.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[9]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3830
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3831
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[9]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @
tv_rec.cpp:3836
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(NeedToStartRecorder,)
-> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @
tv_rec.cpp:3897
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[9]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
@ tv_rec.cpp:3899
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4014 (TuningSignalCheck) TVRec[9]: TuningSignalCheck: Still
waiting.? Will timeout @ 21:29:50.000
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2876 (HandleRecordingStatusChange) Tuning recording: "The
Great British Bake Off": channel 20104 on cardid [9], sourceid 2
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:402 (StartRecording) TVRec[11]: StartRecording("Love Your
Garden":"Woodland Wonders")
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:692 (SetRecordingStatus) TVRec[11]:
SetRecordingStatus(Unknown->Aborted) on line 408
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:472 (StartRecording) TVRec[11]: Checking input group
recorders - begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:546 (StartRecording) TVRec[11]: Checking input group
recorders - done
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: W Scheduler
recordinginfo.cpp:998 (InsertRecording) RecordingInfo(TV):
InsertRecording: m_pathname was '/media/library/TV'. This is usually blank.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:794 (StartedRecording) TVRec[11]:
StartedRecording(20103_2021-09-28T19:00:00Z)
fn(/media/library/TV/20103_20210928190000.ts)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:4144 (LoadProfile) TVRec[11]: Using profile 'Default' to record
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(CancelNextRecording,)
-> RunMainLoop, @ tv_rec.cpp:591
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
tv_rec.cpp:692 (SetRecordingStatus) TVRec[11]:
SetRecordingStatus(Aborted->Tuning) on line 594
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1060 (HandleStateChange) TVRec[11]: Changing from None to
RecordingOnly
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]:
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @
tv_rec.cpp:1299
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3509 (HandleTuning) TVRec[11]: HandleTuning Request:
Program(ProgramInfo(20103_20210928190000.ts): channame(ITV
HD)#012???????????? startts(Tue Sep 28 19:00:00 2021 GMT) endts(Tue Sep
28 20:00:00 2021 GMT)#012 recstartts(Tue Sep 28 19:00:00 2021 GMT)
recendts(Tue Sep 28 20:00:00 2021 GMT)#012???????????? title(Love Your
Garden)) channel() input() flags(Recording,)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3578 (TuningShutdowns) TVRec[11]:
TuningShutdowns(Program(ProgramInfo(20103_20210928190000.ts):
channame(ITV HD)#012???????????? startts(Tue Sep 28 19:00:00 2021 GMT)
endts(Tue Sep 28 20:00:00 2021 GMT)#012 recstartts(Tue Sep 28 19:00:00
2021 GMT) recendts(Tue Sep 28 20:00:00 2021 GMT)#012????????????
title(Love Your Garden)) channel(3) input(DVB-T2) flags(Recording,))
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop, @ tv_rec.cpp:3645
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3531 (HandleTuning) TVRec[11]: No recorder yet, calling
TuningFrequency
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3667 (TuningFrequency) TVRec[11]: TuningFrequency
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
cardutil.cpp:2093 (GetConflictingInputs) CardUtil[11]:
GetConflictingInputs(11) 9,12,13
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3803 (TuningFrequency) TVRec[11]: Starting Signal Monitor
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2044 (SetupSignalMonitor) TVRec[11]: SetupSignalMonitor(1, 0)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dvbstreamhandler.cpp:54 (Get) DVBSH[11]: Using existing stream
handler for /dev/dvb/adapter0/frontend0 (2 in use)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2068 (SetupSignalMonitor) TVRec[11]: Signal monitor
successfully created
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1864 (SetupDTVSignalMonitor) TVRec[11]: Setting up table
monitoring.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4144 (LoadProfile) TVRec[11]: Using profile 'Live TV' to record
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1978 (SetupDTVSignalMonitor) TVRec[11]: MPEG program number:
17608
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
mpeg/mpegstreamdata.cpp:73 (SetDesiredProgram)
MPEGStream[11](0x7fbc6401ae90): SetDesiredProgram(17608)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:1998 (SetupDTVSignalMonitor) TVRec[11]: Successfully set up
MPEG table monitoring.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[11]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3830
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3831
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[11]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3836
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(NeedToStartRecorder,)
-> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3897
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[11]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @
tv_rec.cpp:3899
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4014 (TuningSignalCheck) TVRec[11]: TuningSignalCheck: Still
waiting.? Will timeout @ 20:59:50.000
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2876 (HandleRecordingStatusChange) Tuning recording: "Love
Your Garden":"Woodland Wonders": channel 20103 on cardid [11], sourceid 2
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: N Scheduler
scheduler.cpp:3234 (HandleIdleShutdown) Blocking shutdown because of an
active encoder
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: N Scheduler
scheduler.cpp:3245 (HandleIdleShutdown) Blocking shutdown because of
delay request from external application
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2309 (HandleReschedule) Reschedule requested for MATCH
8001 0 0 - SaveRule World's Most Scenic Railway
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: C CoreContext
programinfo.cpp:256 (ProgramInfo) ProgramInfo(): Failed to find recorded
entry for 0.
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2423 (HandleReschedule) Scheduled 79 items in 0.1 = 0.01
match + 0.00 check + 0.07 place
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:40 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:52 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- locked
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:74 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:40 (AddListener) SH[9]:
AddListener(0x7fbc7402abe8) -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:52 (AddListener) SH[9]:
AddListener(0x7fbc7402abe8) -- locked
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I SignalMonitor
recorders/streamhandler.cpp:74 (AddListener) SH[9]:
AddListener(0x7fbc7402abe8) -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:3925 (TuningSignalCheck) TVRec[11]: TuningSignalCheck: Good
signal
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:692 (SetRecordingStatus) TVRec[11]:
SetRecordingStatus(Tuning->Recording) on line 4023
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2101 (TeardownSignalMonitor) TVRec[11]:
TeardownSignalMonitor() -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I CoreContext
scheduler.cpp:706 (UpdateRecStatus) Updating status for "Love Your
Garden":"Woodland Wonders" on cardid [11] (Tuning => Recording)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:82 (RemoveListener) SH[9]:
RemoveListener(0x7fbc6401ae90) -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:94 (RemoveListener) SH[9]:
RemoveListener(0x7fbc6401ae90) -- locked
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:111 (RemoveListener) SH[9]:
RemoveListener(0x7fbc6401ae90) -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2120 (TeardownSignalMonitor) TVRec[11]:
TeardownSignalMonitor() -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]:
ClearFlags(SignalMonitorRunning,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder, @ tv_rec.cpp:4049
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,NeedToStartRecorder, @ tv_rec.cpp:4051
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4176 (TuningNewRecorder) TVRec[11]: Starting Recorder
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4144 (LoadProfile) TVRec[11]: Using profile 'Default' to record
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4214 (TuningNewRecorder) TVRec[11]: rec->GetPathname():
'/media/library/TV/20103_20210928190000.ts'
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4247 (TuningNewRecorder) TVRec[11]: TuningNewRecorder -
CreateRecorder()
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dtvrecorder.cpp:140 (ResetForNewFile) DTVRec[11]:
ResetForNewFile(void)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/recorderbase.cpp:81 (SetRingBuffer)
RecBase[11](/dev/dvb/adapter0/frontend0): SetRingBuffer(0x7fbc64004c00)
'/media/library/TV/20103_20210928190000.ts'
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/recorderbase.cpp:92 (SetRecording)
RecBase[11](/dev/dvb/adapter0/frontend0): SetRecording(0x7fbc4403da60)
title(Love Your Garden)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
mpeg/mpegstreamdata.cpp:73 (SetDesiredProgram)
MPEGStream[11](0x7fbc6401ae90): SetDesiredProgram(17608)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dtvrecorder.cpp:1281 (HandlePAT) DTVRec[11]: SetPAT(17608 on
pid 0xc8)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(17608, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/dtvrecorder.cpp:1307 (HandlePMT) DTVRec[11]: SetPMT(17608)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/dtvrecorder.cpp:140 (ResetForNewFile) DTVRec[11]:
ResetForNewFile(void)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/dvbstreamhandler.cpp:54 (Get) DVBSH[11]: Using existing stream
handler for /dev/dvb/adapter0/frontend0 (2 in use)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/dvbrecorder.cpp:68 (Open)
DVBRec[11](/dev/dvb/adapter0/frontend0): Card opened successfully
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4429 (SetFlags) TVRec[11]:
SetFlags(RecorderRunning,RingBufferReady,) ->
RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, @
tv_rec.cpp:4318
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[11]: ClearFlags(NeedToStartRecorder,)
-> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4320
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/streamhandler.cpp:40 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/streamhandler.cpp:52 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- locked
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/streamhandler.cpp:124 (Start) SH[9]: Restarting StreamHandler
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/DeviceReadBuffer.cpp:99 (Setup)
DevRdB(/dev/dvb/adapter0/frontend0): buffer size 18800 KB
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/DeviceReadBuffer.cpp:106 (Start)
DevRdB(/dev/dvb/adapter0/frontend0): Start() -- begin
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/DeviceReadBuffer.cpp:124 (Start)
DevRdB(/dev/dvb/adapter0/frontend0): Start() -- middle
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/DeviceReadBuffer.cpp:129 (Start)
DevRdB(/dev/dvb/adapter0/frontend0): Start() -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(20256, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(18112, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(17472, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(17664, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I RecThread
recorders/streamhandler.cpp:74 (AddListener) SH[9]:
AddListener(0x7fbc6401ae90) -- end
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(17728, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(17540, valid)
Sep 28 20:00:00 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[11]: SetPMT(20160, valid)
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: E TVRecEvent
tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
SignalMonitor failed
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(NeedToStartRecorder,)
-> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @
tv_rec.cpp:3962
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:692 (SetRecordingStatus) TVRec[9]:
SetRecordingStatus(Tuning->Recorder Failed) on line 4023
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2101 (TeardownSignalMonitor) TVRec[9]:
TeardownSignalMonitor() -- begin
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I CoreContext
scheduler.cpp:706 (UpdateRecStatus) Updating status for "The Great
British Bake Off" on cardid [9] (Tuning => Recorder Failed)
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2309 (HandleReschedule) Reschedule requested for CHECK -9
7973 0 UpdateRecStatus2 | The Great British Bake Off | | It's crunch
time for the bakers in Biscuit Week, as they put their twist on brandy
snaps, make a jammy childhood favourite and create an interactive toy
made entirely from biscuit. (S5 Ep2) | www.channel4.com/70301/002
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:82 (RemoveListener) SH[9]:
RemoveListener(0x7fbc7402abe8) -- begin
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:94 (RemoveListener) SH[9]:
RemoveListener(0x7fbc7402abe8) -- locked
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
recorders/streamhandler.cpp:111 (RemoveListener) SH[9]:
RemoveListener(0x7fbc7402abe8) -- end
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:2120 (TeardownSignalMonitor) TVRec[9]:
TeardownSignalMonitor() -- end
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(SignalMonitorRunning,)
-> RunMainLoop,WaitingForSignal,RingBufferReady, @ tv_rec.cpp:4049
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I TVRecEvent
tv_rec.cpp:4438 (ClearFlags) TVRec[9]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:4051
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I Scheduler
scheduler.cpp:2423 (HandleReschedule) Scheduled 79 items in 0.1 = 0.00
match + 0.01 check + 0.06 place
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1018 (FindH2645Keyframes) DTVRec[11]:
FindH2645Keyframes: timescale: 50, tick: 1, framerate: 25000
Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I DVBRead
recorders/dtvrecorder.cpp:1634 (ProcessAVTSPacket) DTVRec[11]: PID 0xc9
Found Payload Start

_______________________________________________
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: TuningSignalCheck: SignalMonitor failed ----- Wrong timeout [ In reply to ]
On Wed, 29 Sep 2021 12:44:19 +0100, you wrote:

>v32-Pre-2807-g8a7505a0ad
>
>I have recently resurrected a USB tuner (Hauppauge Dual HD) which
>performs very well. However it is failing every few days with the
>following error:-
>
>Three instances:-
>
>Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: E TVRecEvent
>tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>SignalMonitor failed
>Sep 19 20:00:01 tv mythbackend: mythbackend[4247]: E TVRecEvent
>tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>SignalMonitor failed
>Sep 12 20:00:01 tv mythbackend: mythbackend[1854]: E TVRecEvent
>tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>SignalMonitor failed
>
>EIT scanning is running on this mux.
>
>The fact that they all occur at the same time is pretty weird. The
>backend boots up at 17:00 and does a couple of single recordings from
>then until about 18:30. At 20:00 the main recording period starts. The
>above failure is not fatal, later recordings on this mux do work.
>
>The fact that the timeout is only one second is strange as well - the
>timeouts for the tuner are the defaults 3000 signal 6000 tune.
>
>Can anybody give any guidance on what logging is required to debug this
>and I will raise it as a proper issue.
>

Here in New Zealand, we have one DVB-T channel ("Three") which
requires a much longer timeout for some reason. So if the recordings
that fail are all on one channel, that is a likely cause. Three takes
7000-8000 ms to tune normally, and sometimes even longer, so I have
set all my capturecard.channel_timeout values to 10000 ms and that
fixed the problem. I have only 1000 ms for capturecard.signal_timeout
- I have never had any problem with that setting.

I had to make that change to my settings after one MythTV version
upgrade where the tuning was changed so that it does not automatically
retry and instead obeyed the timeouts. Before that change, tuning of
Three worked, apparently by timing out and retrying on the same tuner,
but without restarting the tuning process completely. So the tuner
got set to receive the mux containing Three and mythbackend started
receiving data, but failed to find the header packets for the Three
channel and timed out. But it left the tuner running and restarted
looking for the header packets, and then found them on the second try,
before a second timeout happened.
_______________________________________________
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: TuningSignalCheck: SignalMonitor failed ----- Wrong timeout [ In reply to ]
On 29/09/2021 13:17, Stephen Worthington wrote:
> On Wed, 29 Sep 2021 12:44:19 +0100, you wrote:
>
>> v32-Pre-2807-g8a7505a0ad
>>
>> I have recently resurrected a USB tuner (Hauppauge Dual HD) which
>> performs very well. However it is failing every few days with the
>> following error:-
>>
>> Three instances:-
>>
>> Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: E TVRecEvent
>> tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>> SignalMonitor failed
>> Sep 19 20:00:01 tv mythbackend: mythbackend[4247]: E TVRecEvent
>> tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>> SignalMonitor failed
>> Sep 12 20:00:01 tv mythbackend: mythbackend[1854]: E TVRecEvent
>> tv_rec.cpp:3959 (TuningSignalCheck) TVRec[9]: TuningSignalCheck:
>> SignalMonitor failed
>>
>> EIT scanning is running on this mux.
>>
>> The fact that they all occur at the same time is pretty weird. The
>> backend boots up at 17:00 and does a couple of single recordings from
>> then until about 18:30. At 20:00 the main recording period starts. The
>> above failure is not fatal, later recordings on this mux do work.
>>
>> The fact that the timeout is only one second is strange as well - the
>> timeouts for the tuner are the defaults 3000 signal 6000 tune.
>>
>> Can anybody give any guidance on what logging is required to debug this
>> and I will raise it as a proper issue.
>>
> Here in New Zealand, we have one DVB-T channel ("Three") which
> requires a much longer timeout for some reason. So if the recordings
> that fail are all on one channel, that is a likely cause. Three takes
> 7000-8000 ms to tune normally, and sometimes even longer, so I have
> set all my capturecard.channel_timeout values to 10000 ms and that
> fixed the problem. I have only 1000 ms for capturecard.signal_timeout
> - I have never had any problem with that setting.
>
> I had to make that change to my settings after one MythTV version
> upgrade where the tuning was changed so that it does not automatically
> retry and instead obeyed the timeouts. Before that change, tuning of
> Three worked, apparently by timing out and retrying on the same tuner,
> but without restarting the tuning process completely. So the tuner
> got set to receive the mux containing Three and mythbackend started
> receiving data, but failed to find the header packets for the Three
> channel and timed out. But it left the tuner running and restarted
> looking for the header packets, and then found them on the second try,
> before a second timeout happened.
> _______________________________________________
> 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

Thanks for the response it sounds like a reasonable theory but I can't
understand why the backend is failing the recording after just one second.

Sep 28 20:00:01 tv mythbackend: mythbackend[1523]: I CoreContext
scheduler.cpp:706 (UpdateRecStatus) Updating status for "The Great
British Bake Off" on cardid [9] (Tuning => Recorder Failed)

_______________________________________________
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