[mythtv-users] TuningSignalCheck: Still waiting

Sun Sep 27 21:36:19 UTC 2015

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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20150927/a8eb2475/attachment-0001.html>