[mythtv-users] Request for help making live TV useable

Mon Apr 18 17:25:47 UTC 2011

{Ooops:  forgot to set the subject line to route my followup to the correct thread.}
Subject was: Re: [mythtv-users] mythtv-users Digest, Vol 97, Issue 48

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

------------------------------

Message: 21
Date: Sun, 17 Apr 2011 22:43:57 -0500
From: Kenneth Emerson <kenneth.emerson at gmail.com>
Subject: Re: [mythtv-users] Playback problem -- random short pauses
To: Discussion about MythTV <mythtv-users at mythtv.org>
Message-ID: <BANLkTim4wQB3PPj2pH9vV=57UopcmkTDkQ at mail.gmail.com>
Content-Type: text/plain; charset="iso-8859-1"

On Sun, Apr 17, 2011 at 4:03 PM, Tom Lichti <redpepperracing at gmail.com>wrote:

> - if you have your backend storage directory nfs mounted on your
> remote frontend, try unmounting it to force the backend to stream it.
> Since I have done that, I have not see one hiccup (aside from when the
> OSD is present, but I can live with that). When there were hiccups,
> they would show as a series of these messages in the frontend log:
>
> 2011-04-17 16:34:58.674 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:58.683 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:58.692 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:58.809 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:58.818 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:59.059 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
> 2011-04-17 16:34:59.068 Player(0): Waited 100ms for video frames from
> decoder AAAAAAAAAAAAAAAAA
>
> I know, it's probably obvious to everyone else, but if it helps
> someone else, great!\
>
> Certainly not obvious to me.  IIRC, there have been several threads over
the past two years discussing different switches to use when using
nfs-mounted file systems for recordings.  A search of the archives can
probably locate them; however, nfs isn't the only reason (evidently) that
causes these errors to crop up, but there may be a common thread between the
nfs issue and what I am seeing (I only have a single box -- combined FE/BE
so no nfs issues).

I reported before that I was going to test to see if background jobs
(transcoding, commflagging, other recordings) could be causing these
"hiccups".  Sadly, they don't seem to have any affect on my problem.  While
I was watching an mpeg file with no other myth-jobs running, there were
several instances of messeages similar to the above mentioned errors.
Specifically, mine look like:

2011-04-16 21:52:31.692 Player(0): Waited 100ms for video buffers
ALAAAAAAAAAAAAaaA

At this point, I am out of ideas.  Very annoying.  If anyone has other ideas
to try to help further narrow the search, I'm all ears.

-- Ken Emerson
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.mythtv.org/pipermail/mythtv-users/attachments/20110417/2c8fe23f/attachment-0001.html 

------------------------------

Message: 22
Date: Sun, 17 Apr 2011 21:57:46 -0600
From: Greg Grotsky <spikeygg.mythbox at gmail.com>
Subject: [mythtv-users] User Job Progress Meter?
To: Discussion about MythTV <mythtv-users at mythtv.org>
Message-ID: <BANLkTin-XQXGbL3tkS6QX38gaee-vR22ng at mail.gmail.com>
Content-Type: text/plain; charset="iso-8859-1"

I like how commercial detection jobs show the progress status in
percentage on the "System Information" screen and in mythweb.  Is there any
way for me to have my user jobs feed back this kind of progress meter?

Thanks,
-Greg
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.mythtv.org/pipermail/mythtv-users/attachments/20110417/5cc20a59/attachment.html 

------------------------------

_______________________________________________
mythtv-users mailing list
mythtv-users at mythtv.org
http://www.mythtv.org/mailman/listinfo/mythtv-users

End of mythtv-users Digest, Vol 97, Issue 49
********************************************