[mythtv-users] mythtv-users Digest, Vol 97, Issue 48

Mon Apr 18 02:44:41 UTC 2011

I just upgraded from a 24-fixes 4/4 release to today's 4/17 version.

Livetv still is broken.  I was actually able to change channels a couple of times, this time on the ATSC tuners, but once I was on an NTSC channel, it lost the ability to change channels.  It would go through the motions, but end up back on the same channel.

Backend log:
...
2011-04-17 15:20:35.220 TVRec(3): Found channel (36) on current card(3).
2011-04-17 15:20:35.289 TVRec(3): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
2011-04-17 15:20:35.290 TVRec(3): HandleTuning Request: Program(no) channel(36) input() flags(LiveTV,)
2011-04-17 15:20:35.295 TVRec(3): HW Tuner: 3->3
2011-04-17 15:20:35.296 TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
2011-04-17 15:20:35.296 TVRec(3): Waiting for recorder pause..
2011-04-17 15:20:35.297 TVRec(3): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning,
2011-04-17 15:20:35.324 TVRec(3): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning,
2011-04-17 15:20:35.325 TVRec(3): Recorder paused, calling TuningFrequency
2011-04-17 15:20:35.326 TVRec(3): SwitchLiveTVRingBuffer(discont 1, set_rec 0)
2011-04-17 15:20:35.327 TVRec(3): GetProgramRingBufferForLiveTV()
2011-04-17 15:20:35.328 LoadFromScheduler(): Error, called from backend.
2011-04-17 15:20:35.332 Scheduler: FindNextLiveTVDir: next dir is '/mnt/store2'
2011-04-17 15:20:35.332 RecordingInfo(2036_20110417152035.nuv): StartedRecording: Recording to '/mnt/store2/2036_20110417152035.nuv'
2011-04-17 15:20:35.333 AutoExpire: Cardid 3: is starting a recording on an unknown fsID soon.
2011-04-17 15:20:35.342 AutoExpire: CalcParams()
2011-04-17 15:20:35.344 TVRec(3): StartedRecording(0x8cfad0) fn(/mnt/store2/2036_20110417152035.nuv)
2011-04-17 15:20:35.346 Cardid 3: max bitrate 73828 KB/min
2011-04-17 15:20:35.348 Maximal bitrate of busy encoders is 73828 KB/min
2011-04-17 15:20:35.348 fsID #1: Total:   912.6 GB   Used:   756.8 GB   Free:   155.9 GB
2011-04-17 15:20:35.350 TVRec(3): FinishedRecording(Book TV) in recgroup: LiveTV
2011-04-17 15:20:35.355 Chain: Updated endtime for '2004_20110417151959' to 20110417152035
2011-04-17 15:20:35.356     Cardid 3: max bitrate 9843 Kb/sec, fsID 1 max is now 73828 KB/min
2011-04-17 15:20:35.357   Max of 73828 KB/min for fsID 1 is higher than the existing Max of 0 so we'll use this Max instead
2011-04-17 15:20:35.358 AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
2011-04-17 15:20:35.358 Finished recording Book TV "Dave Cullen": channel 2004
2011-04-17 15:20:35.362 scheduler: Last message repeated 1 times: Finished recording: Willa's Wild Life "Unbearable Bear; Willa's Fun Raiser": channel 2012
2011-04-17 15:20:35.369 scheduler: Finished recording: Book TV "Dave Cullen": channel 2004
2011-04-17 15:20:35.371 SG(LiveTV): FindRecordingFile: Searching for '2004_20110417151959.nuv'
2011-04-17 15:20:35.371 SG(LiveTV): FindRecordingDir: Checking '/mnt/store2' for '/mnt/store2/2004_20110417151959.nuv'
2011-04-17 15:20:35.372 SG(LiveTV): FindRecordingFile: Found '/mnt/store2/2004_20110417151959.nuv'
2011-04-17 15:20:35.372 ProgramInfo(2004_20110417151959.nuv): GetPlaybackURL: File is local: '/mnt/store2/2004_20110417151959.nuv'
2011-04-17 15:20:35.375 PreviewQueue: Requesting preview for '2004_20110417151959.nuv_0x0_-1s'
2011-04-17 15:20:35.377 PreviewQueue: Requested preview for '2004_20110417151959.nuv_0x0_-1s'
2011-04-17 15:20:35.379 Preview Queued: '2004_2011-04-17T15:19:59' ''
2011-04-17 15:20:35.384 Chain: Appended at 9 '2036_20110417152035'
2011-04-17 15:20:35.526 Channel(/dev/video0) Error: SetInputAndFormat(3, NTSC) 
			while setting input (v4l v2)
			eno: Invalid argument (22)
2011-04-17 15:20:35.526 Channel(/dev/video0) Error: SetInputAndFormat(3, NTSC) 
			while setting format (v4l v2)
			eno: Invalid argument (22)
2011-04-17 15:20:35.527 Channel(/dev/video0) Error: SetInputAndFormat(3, ATSC) 
			while setting input (v4l v2)
			eno: Invalid argument (22)
2011-04-17 15:20:35.527 Channel(/dev/video0) Error: SetInputAndFormat(3, ATSC) 
			while setting format (v4l v2)
			eno: Invalid argument (22)
2011-04-17 15:20:35.528 Channel(/dev/video0): SetInputAndFormat() failed
2011-04-17 15:20:35.528 TVRec(3): Starting Signal Monitor
2011-04-17 15:20:35.547 TVRec(3): SetupSignalMonitor(1, 1)
2011-04-17 15:20:35.547 TVRec(3): Signal monitor successfully created
2011-04-17 15:20:35.548 TVRec(3): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning,
2011-04-17 15:20:35.548 TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning,
2011-04-17 15:20:35.549 TVRec(3): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning,
2011-04-17 15:20:35.549 RecBase(3:/dev/video0): SetRingBuffer(0x0)
2011-04-17 15:20:35.550 TVRec(3): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,
2011-04-17 15:20:35.550 DummyDTVRecorder -- started
2011-04-17 15:20:35.551 TVRec(3): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2011-04-17 15:20:35.551 TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2011-04-17 15:20:35.558 TVRec(3): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady,
2011-04-17 15:20:35.559 TVRec(3): Got good signal
2011-04-17 15:20:35.559 TVRec(3): TeardownSignalMonitor() -- begin
2011-04-17 15:20:35.604 TVRec(3): TeardownSignalMonitor() -- end
2011-04-17 15:20:35.605 TVRec(3): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2011-04-17 15:20:35.613 TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady,
2011-04-17 15:20:35.614 TVRec(3): Restarting Recorder
2011-04-17 15:20:35.614 TVRec(3): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2011-04-17 15:20:35.616 TVRec(3): FinishedRecording(CNN Newsroom) in recgroup: LiveTV
2011-04-17 15:20:35.617 Chain: Updated endtime for '2036_20110417152035' to 20110417152035
2011-04-17 15:20:35.619 Finished recording CNN Newsroom: channel 2036
2011-04-17 15:20:35.622 scheduler: Finished recording: CNN Newsroom: channel 2036
2011-04-17 15:20:35.623 TVRec(3): SwitchLiveTVRingBuffer(discont 1, set_rec 0)
2011-04-17 15:20:35.625 TVRec(3): GetProgramRingBufferForLiveTV()
2011-04-17 15:20:35.626 Scheduler: FindNextLiveTVDir: next dir is '/mnt/store2'
2011-04-17 15:20:35.627 AutoExpire: Cardid 3: is starting a recording on an unknown fsID soon.
2011-04-17 15:20:35.628 AutoExpire: CalcParams()
2011-04-17 15:20:35.632 Cardid 3: max bitrate 73828 KB/min
2011-04-17 15:20:35.632 Maximal bitrate of busy encoders is 73828 KB/min
2011-04-17 15:20:35.633 fsID #1: Total:   912.6 GB   Used:   756.8 GB   Free:   155.9 GB
2011-04-17 15:20:35.635     Cardid 3: max bitrate 9843 Kb/sec, fsID 1 max is now 73828 KB/min
2011-04-17 15:20:35.641   Max of 73828 KB/min for fsID 1 is higher than the existing Max of 0 so we'll use this Max instead
2011-04-17 15:20:35.642 AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
2011-04-17 15:20:35.628 LoadFromScheduler(): Error, called from backend.
2011-04-17 15:20:35.649 RecordingInfo(2004_20110417152035.nuv): StartedRecording: Recording to '/mnt/store2/2004_20110417152035.nuv'
2011-04-17 15:20:35.652 TVRec(3): StartedRecording(0x89cb40) fn(/mnt/store2/2004_20110417152035.nuv)
2011-04-17 15:20:35.677 TVRec(3): FinishedRecording(CNN Newsroom) in recgroup: LiveTV
2011-04-17 15:20:35.681 Chain: Updated endtime for '2036_20110417152035' to 20110417152035
2011-04-17 15:20:35.683 Finished recording CNN Newsroom: channel 2036
2011-04-17 15:20:35.686 Chain: Appended at 10 '2004_20110417152035'
2011-04-17 15:20:35.688 RecBase(3:/dev/video0): SetRingBuffer(0x8b8360) '/mnt/store2/2004_20110417152035.nuv'
2011-04-17 15:20:35.688 MainServer::ANN Monitor
2011-04-17 15:20:35.696 adding: myth as a client (events: 0)
2011-04-17 15:20:35.690 RecBase(3:/dev/video0): SetRecording(0x8a6150) title(Book TV)
2011-04-17 15:20:35.697 MainServer::ANN Monitor
2011-04-17 15:20:35.699 adding: myth as a client (events: 1)
2011-04-17 15:20:35.704 NVR(/dev/video0) Error: Resetting and re-queueing
2011-04-17 15:20:35.704 TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
2011-04-17 15:20:35.982 Preview: Preview process returned 0.
2011-04-17 15:20:35.983 Preview: Preview process ran ok.
2011-04-17 15:20:36.867 About to delete file: /mnt/store2/3121_20110417151859.mpg
2011-04-17 15:20:42.877 About to delete file: /mnt/store2/3121_20110417151900.mpg
2011-04-17 15:20:44.884 About to delete file: /mnt/store2/3121_20110417151900.mpg.png
2011-04-17 15:20:53.554 TVRec(3): StopLiveTV(void) curRec: 0x89cb40 pseudoRec: 0x0
2011-04-17 15:20:53.555 TVRec(3): Changing from WatchingLiveTV to None
2011-04-17 15:20:53.556 TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady,
2011-04-17 15:20:53.557 TVRec(3): HandleTuning Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,)
2011-04-17 15:20:54.030 TVRec(3): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
2011-04-17 15:20:54.051 RecBase(3:/dev/video0): SetRecording(0x0)
2011-04-17 15:20:54.052 TVRec(3): FinishedRecording(Book TV) in recgroup: LiveTV
2011-04-17 15:20:54.053 Chain: Updated endtime for '2004_20110417152035' to 20110417152054
2011-04-17 15:20:54.055 TVRec(3): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
2011-04-17 15:20:54.055 TVRec(3): Tearing down RingBuffer
2011-04-17 15:20:54.148 TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
2011-04-17 15:20:54.149 TVRec(3): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady,

frontend
...
2011-04-17 15:20:05.623 AFD Warning: ScanATSCCaptionStreams() called with no PMT
2011-04-17 15:20:05.623 AFD: Opened codec 0x7efee4fa10c0, id(MPEG4) type(Video)
2011-04-17 15:20:05.627 AFD: Resetting byte context eof (livetv 1 was eof 0)
2011-04-17 15:20:14.283 pause_active: 0
2011-04-17 15:20:30.726 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:30.726 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:30.726 [mpeg4 @ 0x7efef73c86e0]header damaged
2011-04-17 15:20:30.726 AFD Error: Unknown decoding error
2011-04-17 15:20:36.004 ScreenSaverX11Private: DPMS Deactivated 1
2011-04-17 15:20:36.088 RingBuf(/mnt/store2/2004_20110417152035.nuv) Warning: Not starting read ahead thread, already running
2011-04-17 15:20:40.881 RingBuf(/mnt/store2/2004_20110417152035.nuv): Waited 0.2 seconds for data 
			to become available... 32365 < 32768
2011-04-17 15:20:41.368 AFD Warning: ScanATSCCaptionStreams() called with no PMT
2011-04-17 15:20:41.369 AFD: Opened codec 0x20dff30, id(MPEG4) type(Video)
2011-04-17 15:20:41.375 AFD: Resetting byte context eof (livetv 1 was eof 0)
2011-04-17 15:20:46.446 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:46.446 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:46.446 [mpeg4 @ 0x7efef73c86e0]header damaged
2011-04-17 15:20:46.446 AFD Error: Unknown decoding error
2011-04-17 15:20:48.081 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:48.081 [mpeg4 @ 0x7efef73c86e0]time_base.den==0
2011-04-17 15:20:48.081 [mpeg4 @ 0x7efef73c86e0]header damaged
2011-04-17 15:20:48.081 AFD Error: Unknown decoding error
2011-04-17 15:20:53.521 TV: Attempting to change from WatchingLiveTV to None
2011-04-17 15:20:53.526 MythPainter: 6 images not yet de-allocated.
2011-04-17 15:20:54.149 TV: Changing from WatchingLiveTV to None
2011-04-17 15:20:54.151 ScreenSaverX11Private: DPMS Reactivated 1