[mythtv-users] TuningSignalCheck: Still waiting

Hika van den Hoven hikavdh at gmail.com
Sun Sep 27 21:58:41 UTC 2015 

Hoi Guillaume,

Sunday, September 27, 2015, 11:36:19 PM, you wrote:

> Hi,

> I have a strange problem since a few weeks : my recordings are, most of the
> time, empty :'(
> Mythweb shows the recording being recorded but when the time is over, there
> is no file at all.
> I changed the order of the priority of the tuner to use during recording,
> check the signal reception but it gaves me no result.
> When a recording fail, if I launch the live TV just after the beginning on
> the other tuner, livetv is working...

> Do you have any clue ?

> Here is my setup :
> $ mythbackend -v
> 2015-09-27 23:26:32.743188 C  mythbackend version: fixes/0.27 [ad97d24]
> www.mythtv.org
> 2015-09-27 23:26:32.743259 C  Qt version: compile: 4.8.6, runtime: 4.8.2

> My tuner is a Pinnacle PCTV 2000e : double tuner DVB-T in USB.

> logs :
> Sep 27 20:33:53 mythbackend  mythbackend[8447]: I CoreContext
> housekeeper.cpp:674 (Run) Queueing HouseKeeperTask 'MythFillDB'.
> Sep 27 20:33:53 mythbackend  mythbackend[8447]: I HouseKeeping
> mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 7
> Sep 27 20:33:53 mythbackend  mythbackend[8447]: I HouseKeeping
> housekeeper.cpp:133 (Run) Running HouseKeeperTask 'MythFillDB'.
> Sep 27 20:33:58 mythbackend  mythbackend[8447]: I ProcessRequest
> mainserver.cpp:1446 (HandleAnnounce) MainServer::ANN Playback
> Sep 27 20:33:58 mythbackend  mythbackend[8447]: I ProcessRequest
> mainserver.cpp:1448 (HandleAnnounce) adding: mythbackend as a client
> (events: 0)
> Sep 27 20:34:55 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2140 (HandleReschedule) Reschedule requested for MATCH 0 0 0
> - MythFillDatabase
> Sep 27 20:34:55 mythbackend  mythbackend[8447]: I Scheduler
> mythdbcon.cpp:241 (Reconnect) MySQL reconnected successfully
> Sep 27 20:34:55 mythbackend  mythbackend[8447]: I CoreContext
> mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 7
> Sep 27 20:34:56 mythbackend  mythbackend[8447]: I HouseKeeping
> housekeeper.cpp:151 (Run) HouseKeeperTask 'MythFillDB' Finished
> Successfully.
> Sep 27 20:34:56 mythbackend  mythbackend[8447]: I Scheduler
> mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 7
> Sep 27 20:34:56 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2253 (HandleReschedule) Scheduled 28 items in 0.6 = 0.37
> match + 0.09 check + 0.14 place
> Sep 27 20:39:12 mythbackend  mythbackend[8447]: N Expire autoexpire.cpp:264
> (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB
> w/freq: 15 min
> Sep 27 20:42:30 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2140 (HandleReschedule) Reschedule requested for PLACE
> PrepareToRecord
> Sep 27 20:42:30 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2253 (HandleReschedule) Scheduled 28 items in 0.1 = 0.00
> match + 0.00 check + 0.09 place
> Sep 27 20:42:55 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:42:55 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:42:55 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:42:57 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:302
> (RecordPending) TVRec[1]: RecordPending on inputid 10
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:302
> (RecordPending) TVRec[2]: RecordPending on inputid 10
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:302
> (RecordPending) TVRec[3]: RecordPending on inputid 10
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1567 (HandlePendingRecordings) TVRec[2]: ASK_RECORDING 2 119 0 0
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1567 (HandlePendingRecordings) TVRec[3]: ASK_RECORDING 3 119 0 0
> Sep 27 20:43:00 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1567 (HandlePendingRecordings) TVRec[1]: ASK_RECORDING 1 119 0 0
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1681 (GetConflictingCards) CardUtil:   Group ID 5
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 2
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler
> cardutil.cpp:1701 (GetConflictingCards) CardUtil:   Card ID 3
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:416
> (StartRecording) TVRec[1]: StartRecording("Fast & Furious 6")
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:701
> (SetRecordingStatus) TVRec[1]:
> SetRecordingStatus(Inconnu->Interrompu) on
> line 421
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:485
> (StartRecording) TVRec[1]: Checking input group recorders - begin
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:562
> (StartRecording) TVRec[1]: Checking input group recorders - done
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:800
> (StartedRecording) TVRec[1]:
> StartedRecording(1051_2015-09-27T18:44:00Z)
> fn(/mnt/media/enregistrements/1051_20150927184400.mpg)
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:4081
> (load_profile) Using profile 'Default' to record
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:4329
> (ClearFlags) TVRec[1]: ClearFlags(CancelNextRecording,) -> RunMainLoop,
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I Scheduler tv_rec.cpp:701
> (SetRecordingStatus) TVRec[1]:
> SetRecordingStatus(Interrompu->Syntonisation) on line 607
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1050 (HandleStateChange) TVRec[1]: Changing from None to
> RecordingOnly
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4329 (ClearFlags) TVRec[1]:
> ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3514 (HandleTuning) TVRec[1]: HandleTuning Request:
> Program(ProgramInfo(1051_20150927184400.mpg): channame(TF1 HD) startts(Sun
> Sep 27 18:55:00 2015) endts(Sun Sep 27 21:25:00 2015)
>              recstartts(Sun Sep 27 18:44:00 2015) recendts(Sun Sep 27
> 21:42:00 2015)
>              title(Fast & Furious 6)) channel() input() flags(Recording,)
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 7
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3603 (TuningCheckForHWChange) TVRec[1]: HW Tuner: 1->1
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4329 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
> RunMainLoop,
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3536 (HandleTuning) TVRec[1]: No recorder yet, calling
> TuningFrequency
> Sep 27 20:43:30 mythbackend  mythbackend[8447]: I TVRecEvent
> recorders/dvbchannel.cpp:267 (Open)
> DVBChan[1](/dev/dvb/adapter0/frontend0): Using DVB card
> /dev/dvb/adapter0/frontend0, with frontend 'DiBcom 7000PC'.
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3842 (TuningFrequency) TVRec[1]: Starting Signal Monitor
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1985 (SetupSignalMonitor) TVRec[1]: SetupSignalMonitor(1, 0)
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:2007 (SetupSignalMonitor) TVRec[1]: Signal monitor successfully
> created
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1805 (SetupDTVSignalMonitor) TVRec[1]: Setting up table
> monitoring.
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4081 (load_profile) Using profile 'Live TV' to record
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1919 (SetupDTVSignalMonitor) TVRec[1]: MPEG program number: 1281
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> mpeg/mpegstreamdata.cpp:118 (SetDesiredProgram)
> MPEGStream[1](0xffffffffab4abc48): SetDesiredProgram(1281)
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:1940 (SetupDTVSignalMonitor) TVRec[1]: Successfully set up MPEG
> table monitoring.
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4320 (SetFlags) TVRec[1]:
> SetFlags(SignalMonitorRunning,) ->
> RunMainLoop,SignalMonitorRunning,
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4329 (ClearFlags) TVRec[1]: ClearFlags(WaitingForSignal,) ->
> RunMainLoop,SignalMonitorRunning,
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4320 (SetFlags) TVRec[1]: SetFlags(WaitingForSignal,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4329 (ClearFlags) TVRec[1]:
> ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:4320 (SetFlags) TVRec[1]: SetFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3955 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
> waiting.  Will timeout @ 23:42:00.000
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I SignalMonitor
> recorders/streamhandler.cpp:53 (AddListener)
> SH(/dev/dvb/adapter0/frontend0): AddListener(0xffffffffab4abc48) -- begin
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I SignalMonitor
> recorders/streamhandler.cpp:65 (AddListener)
> SH(/dev/dvb/adapter0/frontend0): AddListener(0xffffffffab4abc48) -- locked
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:113 (run)
> DVBSH(/dev/dvb/adapter0/frontend0): run(): begin
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:608 (Open)
> PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter for pid 0x0
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: N Scheduler
> autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free
> Space: 4.0 GB w/freq: 14 min
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2690 (HandleRecordingStatusChange) Tuning recording: "Fast &
> Furious 6": channel 1051 on cardid 1, sourceid 1
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:702 (Close)
> PIDInfo(/dev/dvb/adapter0/frontend0): Closing filter for pid 0x0
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2140 (HandleReschedule) Reschedule requested for PLACE
> PrepareToRecord
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:197 (RunTS)
> DVBSH(/dev/dvb/adapter0/frontend0): RunTS(): begin
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:608 (Open)
> PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter for pid 0x0
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I DVBRead
> recorders/dvbstreamhandler.cpp:608 (Open)
> PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter for pid 0x1
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I SignalMonitor
> recorders/streamhandler.cpp:99 (AddListener)
> SH(/dev/dvb/adapter0/frontend0): AddListener(0xffffffffab4abc48) -- end
> Sep 27 20:43:31 mythbackend  mythbackend[8447]: I Scheduler
> scheduler.cpp:2253 (HandleReschedule) Scheduled 28 items in 0.1 = 0.00
> match + 0.00 check + 0.10 place
> Sep 27 20:43:36 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3955 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
> waiting.  Will timeout @ 23:42:00.000
> Sep 27 20:43:42 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3955 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
> waiting.  Will timeout @ 23:42:00.000
> Sep 27 20:43:48 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3955 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
> waiting.  Will timeout @ 23:42:00.000
> Sep 27 20:43:54 mythbackend  mythbackend[8447]: I TVRecEvent
> tv_rec.cpp:3955 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
> waiting.  Will timeout @ 23:42:00.000

> Thanks a lot
> Guillaume

Do you have fast tuning set? If only for live TV try setting to
always.

Tot mails,
  Hika                            mailto:hikavdh at gmail.com

"Zonder hoop kun je niet leven
Zonder leven is er geen hoop
Het eeuwige dilemma
Zeker als je hoop moet vernietigen om te kunnen overleven!"

De lerende Mens

More information about the mythtv-users mailing list