Login | Register For Free | Help
Search for: (Advanced)

Mailing List Archive: MythTV: Dev

NFS shared livetv log

  

 
MythTV dev RSS feed   Index | Next | Previous | View Threaded


irish at irishmark

Feb 20, 2006, 4:22 PM

Post #1 of 11 (3279 views)
Permalink
NFS shared livetv log
I've put a a couple of logs up for my failed livetv.
I noticed some extra debug so I upgraded to 9068.
As I mentioned, streaming is working fine only a problem with a shared dir.

They were created with
mythbackend -v channel,record
and a mythfrontend -v playback,channel

here:
http://irishmark.co.uk/myth-backtraces/frontend.log
and
http://irishmark.co.uk/myth-backtraces/backend.log

hope thats correct
thanks
Mark
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


ijr at case

Feb 20, 2006, 4:53 PM

Post #2 of 11 (3172 views)
Permalink
Re: NFS shared livetv log [In reply to]
On Monday 20 February 2006 19:51, Mark wrote:
> I've put a a couple of logs up for my failed livetv.
> I noticed some extra debug so I upgraded to 9068.
> As I mentioned, streaming is working fine only a problem with a shared dir.

I need you to do some (really, really easy) extra debugging work, so:

in libs/libmythtv/NuppelVideoPlayer.cpp, StartPlaying(), line 2417.

For some reason, it's taking too long to start playing. Need to figure out
why. It thinks it's started playing when it sets 'playing' to true, at line
2508.

So, here's what I'd like you to do. Everywhere there's a return, add a
verbose, before every top level 'if' statement, add a verbose: ie:

VERBOSE(VB_IMPORTANT, "pre-openfile");
if (OpenFile() < 0)
{
VERBOSE(VB_IMPORTANT, "openfile returned");
return;
}

up until you get to the 'playing = true;' line at 2508. Put a verbose at that
line as well. Obviously, change the text to be unique/obvious where each one
is for each added verbose. =)

That'll help give me an idea of what's going on in there.

Isaac
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


irish at irishmark

Feb 20, 2006, 4:55 PM

Post #3 of 11 (3184 views)
Permalink
Re: NFS shared livetv log [In reply to]
Mark wrote:
> I've put a a couple of logs up for my failed livetv.
> I noticed some extra debug so I upgraded to 9068.
> As I mentioned, streaming is working fine only a problem with a shared dir.
>
> They were created with
> mythbackend -v channel,record
> and a mythfrontend -v playback,channel
>
> here:
> http://irishmark.co.uk/myth-backtraces/frontend.log
> and
> http://irishmark.co.uk/myth-backtraces/backend.log
>
> hope thats correct
> thanks
> Mark

Oh dear, how embarrassing, I just discovered that a large time
difference between front and backend will break livetv if using a shared
directory.

Sorry for the noise.
Mark
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


tom at redpepperracing

Feb 20, 2006, 5:34 PM

Post #4 of 11 (3155 views)
Permalink
Re: NFS shared livetv log [In reply to]
Mark wrote:
> Mark wrote:
>
>> I've put a a couple of logs up for my failed livetv.
>> I noticed some extra debug so I upgraded to 9068.
>> As I mentioned, streaming is working fine only a problem with a shared dir.
>>
>> They were created with
>> mythbackend -v channel,record
>> and a mythfrontend -v playback,channel
>>
>> here:
>> http://irishmark.co.uk/myth-backtraces/frontend.log
>> and
>> http://irishmark.co.uk/myth-backtraces/backend.log
>>
>> hope thats correct
>> thanks
>> Mark
>>
>
> Oh dear, how embarrassing, I just discovered that a large time
> difference between front and backend will break livetv if using a shared
> directory.
>
> Sorry for the noise.
> Mark
>
My 3 systems (frontend, master and slave backend) clocks are all within
less than a second of each other, and I don't think I can use shared
dirs for Live TV. I think it works ok with recordings, but I'd like to
double check my setup. Can someone tell me if I have it setup right for
shared dirs? On my master, it records to a local directory, /myth/tv,
and my slave records to a local directory /myth/tvs. On my frontend I
mount my master to the local path /myth/tv and the slave mounts to
/myth/tvs. What should I put it 'RecordFilePrefix' in the db? Or do I
need to?

If I try to watch Live TV in this situation I get the following in the
frontend log:

2006-02-20 20:05:48.609 TV: Attempting to change from None to WatchingLiveTV
2006-02-20 20:05:48.617 Using protocol version 27
2006-02-20 20:05:49.612 LiveTVChain(live-htpc-2006-02-20T20:05:48):
ReloadAll(): Added new recording
2006-02-20 20:05:49.618 RingBuf(/myth/tvs/1065_20060220200549.mpg):
OpenFile(/myth/tvs/1065_20060220200549.mpg, 12)
2006-02-20 20:05:49.619 RingBuf(/myth/tvs/1065_20060220200549.mpg):
Invalid file (fd 17) when opening '/myth/tvs/1065_20060220200549.mpg'.
12 retries remaining.
2006-02-20 20:05:50.154 TV: StartRecorder(): took 10 ms to start recorder.
2006-02-20 20:05:52.266 RingBuf(/myth/tvs/1065_20060220200549.mpg):
Waited 2 seconds for data to become available...
2006-02-20 20:05:52.267 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:05:54.284 RingBuf(/myth/tvs/1065_20060220200549.mpg):
Waited 4 seconds for data to become available...
2006-02-20 20:05:54.284 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:05:58.316 RingBuf(/myth/tvs/1065_20060220200549.mpg):
Waited 8 seconds for data to become available...
2006-02-20 20:05:58.316 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:06:06.383 RingBuf(/myth/tvs/1065_20060220200549.mpg)
Error: Waited 16 seconds for data, aborting.
2006-02-20 20:06:06.383 NVP::OpenFile(): Error, couldn't read file:
/myth/tvs/1065_20060220200549.mpg
2006-02-20 20:06:06.383 TV: StartPlayer(): took 16198 ms to start player.
2006-02-20 20:06:06.383 TV Error: StartPlayer(): NVP is not playing
after 20000 msec
2006-02-20 20:06:06.383 TV: StopStuff() -- begin
2006-02-20 20:06:06.383 TV: StopStuff(): stopping ring buffer[s]
2006-02-20 20:06:06.415 TV: StopStuff(): stopping player[s] (1/2)
2006-02-20 20:06:06.415 TV: StopStuff(): stopping recorder[s]
2006-02-20 20:06:06.899 TV: StopStuff(): stopping player[s] (2/2)
2006-02-20 20:06:06.948 TV: StopStuff() -- end
2006-02-20 20:06:06.948 TV Error: LiveTV not successfully started

On the frontend, I can see the file:

root [at] htp:/myth/tvs# ls -l /myth/tvs/1065_20060220200549.mpg
-rw-r--r-- 1 mythtv mythtv 7661568 Feb 20 20:06
/myth/tvs/1065_20060220200549.mpg

and I can touch the file, so it's not a permissions problem, that I can
see. The slave backend shows this:

2006-02-20 20:05:48.622 MainServer::HandleAnnounce Playback
2006-02-20 20:05:48.625 adding: htpc as a client (events: 0)
2006-02-20 20:05:48.639 TVRec(4): Changing from None to WatchingLiveTV
2006-02-20 20:05:48.656 TVRec(4):
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2006-02-20 20:05:48.659 TVRec(4): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,
2006-02-20 20:05:48.662 TVRec(4): Request: Program(no) channel() input()
flags(LiveTV,)
2006-02-20 20:05:48.677 TVRec(4): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,
2006-02-20 20:05:48.835 TVRec(4): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,
2006-02-20 20:05:48.847 TVRec(4): SetFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,
2006-02-20 20:05:48.850 TVRec(4): Starting Recorder
2006-02-20 20:05:48.876 TVRec(4): Using profile 'Live TV' to record
2006-02-20 20:05:48.888 TVRec(4): CreateLiveTVRingBuffer()
2006-02-20 20:05:48.892 TVRec(4): GetProgramRingBufferForLiveTV()
2006-02-20 20:05:49.492 TVRec(4): StartedRecording(0x8159408)
fn(/myth/tvs/1065_20060220200549.mpg)
2006-02-20 20:05:49.523 Chain: Appended@0 '1065_20060220200549'
2006-02-20 20:05:49.543 TVRec(4): SetFlags(RingBufferReady,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-02-20 20:05:49.552 RecBase(/dev/video1): SetRingBuffer(0x816e328)
'/myth/tvs/1065_20060220200549.mpg'
2006-02-20 20:05:49.567 RecBase(/dev/video1): SetRecording(0x816e098)
title(Little Bear)
2006-02-20 20:05:49.580 TVRec(4): SetFlags(CancelNextRecording,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-02-20 20:05:49.593 Can't enable VBI recording (2)
vbi: Invalid argument
2006-02-20 20:05:49.616 VBI service:4, packet size:0, io size:0
2006-02-20 20:05:49.635 TVRec(4):
SetFlags(RecorderRunning,RingBufferReady,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2006-02-20 20:05:49.638 TVRec(4): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:06:06.421 StopLiveTV(void) curRec: 0x8159408 pseudoRec: 0
2006-02-20 20:06:06.563 TVRec(4): Changing from WatchingLiveTV to None
2006-02-20 20:06:06.584 TVRec(4):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:06:06.588 TVRec(4): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:06:06.593 TVRec(4): Request: Program(no) channel() input()
flags(KillRec,KillRingBuffer,)
2006-02-20 20:06:06.772 TVRec(4): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:06:06.839 TVRec(4): FinishedRecording(Little Bear) in
recgroup: LiveTV
2006-02-20 20:06:06.846 Chain: Updated endtime for '1065_20060220200549'
to 20060220200606
2006-02-20 20:06:06.851 Finished recording Little Bear "Lemonade; Good
Night, Little Bear; Silly Billy": channel 1065
2006-02-20 20:06:06.874 TVRec(4): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:06:06.878 TVRec(4): Tearing down RingBuffer
2006-02-20 20:06:06.895 TVRec(4): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
[mpeg2video @ 0xb7616970]mb incr damaged
[mpeg2video @ 0xb7616970]Warning MVs not available

I can successfully watch recordings via the shared directory.

I get a slightly different error, although the same result, when trying
the same thing from the master backend. Frontend shows this:

2006-02-20 20:28:50.518 TV: Attempting to change from None to WatchingLiveTV
2006-02-20 20:28:50.526 Using protocol version 27
2006-02-20 20:28:51.243 LiveTVChain(live-htpc-2006-02-20T20:28:50):
ReloadAll(): Added new recording
2006-02-20 20:28:51.247 RingBuf(/myth/tv/1008_20060220202851.mpg):
OpenFile(/myth/tv/1008_20060220202851.mpg, 12)
2006-02-20 20:28:51.248 RingBuf(/myth/tv/1008_20060220202851.mpg):
Invalid file (fd 17) when opening '/myth/tv/1008_20060220202851.mpg'. 12
retries remaining.
2006-02-20 20:28:51.751 RingBuf(/myth/tv/1008_20060220202851.mpg):
Invalid file (fd 17) when opening '/myth/tv/1008_20060220202851.mpg'. 11
retries remaining.
2006-02-20 20:28:52.268 TV: StartRecorder(): took 11 ms to start recorder.
2006-02-20 20:28:54.446 RingBuf(/myth/tv/1008_20060220202851.mpg):
Waited 2 seconds for data to become available...
2006-02-20 20:28:54.446 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:28:56.463 RingBuf(/myth/tv/1008_20060220202851.mpg):
Waited 4 seconds for data to become available...
2006-02-20 20:28:56.464 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:29:00.497 RingBuf(/myth/tv/1008_20060220202851.mpg):
Waited 8 seconds for data to become available...
2006-02-20 20:29:00.497 Checking to see if there's a new livetv program
to switch to..
2006-02-20 20:29:08.561 RingBuf(/myth/tv/1008_20060220202851.mpg) Error:
Waited 16 seconds for data, aborting.
0: start_time: -9223372036854.775 duration: -9223372036854.775
1: start_time: -9223372036854.775 duration: -9223372036854.775
2: start_time: -9223372036854.775 duration: -9223372036854.775
stream: start_time: -9223372036854.775 duration: -9223372036854.775
bitrate=0 kb/s
2006-02-20 20:29:08.585 AFD: Stream #0, has id 0x480 codec id
MPEG2VIDEO, type Video at 0x0x81eac30
2006-02-20 20:29:08.587 detectInterlace(Detect Scan, Detect Scan, 29.97,
480) ->Interlaced Scan
2006-02-20 20:29:08.587 Interlaced: Interlaced Scan video_height: 480
fps: 29.97
2006-02-20 20:29:08.587 AFD: Looking for decoder for MPEG2VIDEO
2006-02-20 20:29:08.587 AFD: Opened codec 0x8224040, id(MPEG2VIDEO)
type(Video)
2006-02-20 20:29:08.587 AFD: Stream #1, has id 0x448 codec id MP2, type
Audio at 0x0x8241b20
2006-02-20 20:29:08.588 AFD: Looking for decoder for MP2
2006-02-20 20:29:08.588 AFD: Opened codec 0x881c2a0, id(MP2) type(Audio)
2006-02-20 20:29:08.588 AFD: Stream #2, has id 0x105 codec id MPEG2VBI,
type Data at 0x0x826ca40
2006-02-20 20:29:08.588 AFD: data codec (Data)
2006-02-20 20:29:08.588 RingBuf(/myth/tv/1008_20060220202851.mpg):
CalcReadAheadThresh(4384 KB)
-> threshhold(160 KB) min read(32 KB) blk
size(128 KB)
2006-02-20 20:29:08.588 Opening audio device 'default'.
2006-02-20 20:29:08.591 AudioOutput Warning: Mixer attach error -2: No
such file or directory
Check Mixer Name in Setup: '/dev/mixer'
2006-02-20 20:29:08.592 Resyncing position map. posmapStarted = 0
livetv(1) watchingRec(0)
2006-02-20 20:29:08.595 Position map filled from DB to: 26
2006-02-20 20:29:08.595 SyncPositionMap watchingrecording, from DB: 26
entries
2006-02-20 20:29:08.602 Filling position map from 27 to 33
2006-02-20 20:29:08.607 Position map filled from Encoder to: 33
2006-02-20 20:29:08.607 SyncPositionMap watchingrecording total: 33 entries
2006-02-20 20:29:08.608 SyncPositionMap, new totframes: 495, new length:
16, posMap size: 33
2006-02-20 20:29:08.608 AFD: Partial position map found
2006-02-20 20:29:08.608 AFD: Successfully opened decoder for file:
"/myth/tv/1008_20060220202851.mpg". novideo(0)
2006-02-20 20:29:08.610 VideoOutputXv: ctor
2006-02-20 20:29:08.611 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2006-02-20 20:29:08.614 Image size. dispxoff 0, dispyoff: 0, dispwoff:
800, disphoff: 600, dispaspect: 1.33333
2006-02-20 20:29:08.615 Image size. imgx 0, imgy: 0, imgw: 720, imgh:
480, aspect: 1.33333
2006-02-20 20:29:08.615 VideoOutputXv: Pixel dimensions: Screen 800x600,
window 800x600
2006-02-20 20:29:08.615 VideoOutputXv: Estimated display dimensions:
203x154 mm Aspect: 1.31818
2006-02-20 20:29:08.615 VideoOutputXv: Estimated window dimensions:
203x154 mm Aspect: 1.31818
2006-02-20 20:29:08.617 VideoOutputXv: @ j=3 Looking for flag[s]:
XvInputMask XvImageMask
2006-02-20 20:29:08.617 VideoOutputXv: Adaptor: 0 has flag[s]:
XvInputMask XvImageMask
2006-02-20 20:29:08.618 VideoOutputXv: Grabbed xv port 270
2006-02-20 20:29:08.618 VideoOutputXv: XVideo surface found on port 270
2006-02-20 20:29:08.618 VideoOutputXv: XVideo Format #0 is 'YUY2'
2006-02-20 20:29:08.618 VideoOutputXv: XVideo Format #1 is 'YV12'
2006-02-20 20:29:08.618 VideoOutputXv: XVideo Format #2 is 'UYVY'
2006-02-20 20:29:08.619 VideoOutputXv: XVideo Format #3 is 'I420'
2006-02-20 20:29:08.619 VideoOutputXv: Using XVideo Format 'I420'
2006-02-20 20:29:08.619 VideoOutputXv: CreateShmImages(32): XJ: (720,480)
2006-02-20 20:29:08.715 VideoOutputXv Error: Couldn't get the color key
color,
probably due to a driver bug or limitation.
You might not get any video, but we'll try anyway.
2006-02-20 20:29:08.715 Image size. dispxoff 0, dispyoff: 0, dispwoff:
800, disphoff: 600, dispaspect: 1.33333
2006-02-20 20:29:08.715 Image size. imgx 0, imgy: 0, imgw: 720, imgh:
480, aspect: 1.33333
2006-02-20 20:29:09.099 OSDTypePositionImage::AddPosition[0](95x281 1, 1)
2006-02-20 20:29:09.100 OSDTypePositionImage::AddPosition[1](95x311 1, 1)
2006-02-20 20:29:09.100 OSDTypePositionImage::AddPosition[2](95x341 1, 1)
2006-02-20 20:29:09.105 OSDTypePositionImage::AddPosition[3](95x371 1, 1)
2006-02-20 20:29:09.710 TV: StartPlayer(): took 17427 ms to start player.
2006-02-20 20:29:09.710 TV: Changing from None to WatchingLiveTV
2006-02-20 20:29:09.710 NVP: ClearAfterSeek()
2006-02-20 20:29:09.715 VideoOutputXv: ClearAfterSeek()
2006-02-20 20:29:09.716 VideoBuffers::DiscardFrames(0):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:09.716 VideoBuffers::DiscardFrames(0):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-02-20 20:29:09.716 Realtime priority would require SUID as root.
2006-02-20 20:29:09.725 Using deinterlace method bobdeint
2006-02-20 20:29:09.732 Ignoring livetv eof in decoder loop
2006-02-20 20:29:09.787 Ignoring livetv eof in decoder loop
2006-02-20 20:29:09.827 nVidiaVideoSync: VBlank ioctl did not work,
unimplemented in this driver?
2006-02-20 20:29:09.862 DRMVideoSync: Could not open device
/dev/dri/card0, No such device or address
2006-02-20 20:29:09.862 RTCVideoSync: Could not set RTC frequency,
Permission denied.
2006-02-20 20:29:09.862 Set video sync frame interval to 33366
2006-02-20 20:29:09.863 Using audio as timebase
2006-02-20 20:29:09.863 Video timing method: USleep with busy wait
2006-02-20 20:29:09.863 Refresh rate: 16579, frame interval: 33366
2006-02-20 20:29:09.863 NVP: Waiting for prebuffer.. 0
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:09.864 Ignoring livetv eof in decoder loop
2006-02-20 20:29:09.916 Ignoring livetv eof in decoder loop
2006-02-20 20:29:09.968 Ignoring livetv eof in decoder loop
2006-02-20 20:29:09.998 NVP: Waiting for prebuffer.. 1
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:10.020 Ignoring livetv eof in decoder loop
2006-02-20 20:29:10.072 Ignoring livetv eof in decoder loop
2006-02-20 20:29:10.124 Ignoring livetv eof in decoder loop
2006-02-20 20:29:10.133 NVP: Waiting for prebuffer.. 2
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
.
.
.
this continues looping until I hit ESC
.
.
.
2006-02-20 20:29:13.372 NVP: Waiting for prebuffer.. 6
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:13.399 Ignoring livetv eof in decoder loop
2006-02-20 20:29:13.451 Ignoring livetv eof in decoder loop
2006-02-20 20:29:13.493 TV: Attempting to change from WatchingLiveTV to None
2006-02-20 20:29:13.494 TV: StopStuff() -- begin
2006-02-20 20:29:13.494 TV: StopStuff(): stopping ring buffer[s]
2006-02-20 20:29:13.494 TV: StopStuff(): stopping player[s] (1/2)
2006-02-20 20:29:13.494 TV: StopStuff(): stopping recorder[s]
2006-02-20 20:29:13.503 NVP: Exited decoder loop.
2006-02-20 20:29:13.507 VideoOutputXv: dtor
2006-02-20 20:29:13.507 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:13.508 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-02-20 20:29:13.508 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-02-20 20:29:13.508 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-20 20:29:13.508 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-02-20 20:29:13.509 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-02-20 20:29:13.513 VideoOutputXv: Closing XVideo port 270
2006-02-20 20:29:13.646 TV: StopStuff(): stopping player[s] (2/2)
2006-02-20 20:29:13.670 TV: StopStuff() -- end
2006-02-20 20:29:13.670 TV: Changing from WatchingLiveTV to None

The file was on the backend, but it's since expired.

The master backend log shows this:

2006-02-20 20:28:50.464 Getting next free recorder after : -1
2006-02-20 20:28:50.469 Checking card 1. Best card so far 1
2006-02-20 20:28:50.470 Checking card 2. Best card so far 1
2006-02-20 20:28:50.471 Checking card 3. Best card so far 1
2006-02-20 20:28:50.473 Checking card 4. Best card so far 1
2006-02-20 20:28:50.488 MainServer::HandleAnnounce Playback
2006-02-20 20:28:50.491 adding: htpc as a client (events: 0)
2006-02-20 20:28:50.501 TVRec(1): Changing from None to WatchingLiveTV
2006-02-20 20:28:50.504 TVRec(1):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:28:50.505 TVRec(1): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:28:50.507 TVRec(1): Request: Program(no) channel() input()
flags(LiveTV,)
2006-02-20 20:28:50.509 TVRec(1): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:28:51.015 TVRec(1): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:28:51.018 TVRec(1): SetFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-02-20 20:28:51.019 TVRec(1): Starting Recorder
2006-02-20 20:28:51.031 TVRec(1): Using profile 'Live TV' to record
2006-02-20 20:28:51.032 TVRec(1): CreateLiveTVRingBuffer()
2006-02-20 20:28:51.034 TVRec(1): GetProgramRingBufferForLiveTV()
2006-02-20 20:28:51.117 TVRec(1): StartedRecording(0x8239070)
fn(/myth/tv/1008_20060220202851.mpg)
2006-02-20 20:28:51.152 Chain: Appended@0 '1008_20060220202851'
2006-02-20 20:28:51.155 TVRec(1): SetFlags(RingBufferReady,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-02-20 20:28:51.160 RecBase(/dev/video1): SetRingBuffer(0x8281010)
'/myth/tv/1008_20060220202851.mpg'
2006-02-20 20:28:51.162 RecBase(/dev/video1): SetRecording(0x8319930)
title(Corner Gas)
2006-02-20 20:28:51.165 TVRec(1): SetFlags(CancelNextRecording,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RingBufferReady,
2006-02-20 20:28:51.197 VBI service:4, packet size:52, io size:1872
2006-02-20 20:28:51.203 TVRec(1):
SetFlags(RecorderRunning,RingBufferReady,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2006-02-20 20:28:51.205 TVRec(1): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:29:06.209 Running HouseKeeping
2006-02-20 20:29:09.678 TVRec(1): SetFlags(FrontendReady,) ->
FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:29:13.457 StopLiveTV(void) curRec: 0x8239070 pseudoRec: 0
2006-02-20 20:29:13.462 TVRec(1): Changing from WatchingLiveTV to None
2006-02-20 20:29:13.465 TVRec(1):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:29:13.468 TVRec(1): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-02-20 20:29:13.469 TVRec(1): Request: Program(no) channel() input()
flags(KillRec,KillRingBuffer,)
2006-02-20 20:29:13.532 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:29:13.582 TVRec(1): FinishedRecording(Corner Gas) in
recgroup: LiveTV
2006-02-20 20:29:13.584 Chain: Updated endtime for '1008_20060220202851'
to 20060220202913
2006-02-20 20:29:13.588 Finished recording Corner Gas "Block Party":
channel 1008
2006-02-20 20:29:13.594 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-02-20 20:29:13.597 TVRec(1): Tearing down RingBuffer
2006-02-20 20:29:13.600 TVRec(1): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,

Strangely enough, in all this testing I managed to crash the master
backend, but I wasn't running under gdb at the time.

Is this information helpful at all?

Tom
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


cpinkham at bc2va

Feb 20, 2006, 6:23 PM

Post #5 of 11 (3156 views)
Permalink
Re: NFS shared livetv log [In reply to]
> My 3 systems (frontend, master and slave backend) clocks are all within
> less than a second of each other, and I don't think I can use shared
> dirs for Live TV. I think it works ok with recordings, but I'd like to
> double check my setup. Can someone tell me if I have it setup right for

Please try remounting your nfs-shared directories on the client end
with the "-o actimeo=0" option and see if that fixes the problem.

I think the issue may be related to nfs file attribute caching on the
client end. Using actimeo=0 on my system got rid of a lot of warnings
about prebuffering that I was seeing when trying to watch LiveTV on
my system with a dedicated NFS server and dedicated frontends.

--
Chris

_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


tom at redpepperracing

Feb 20, 2006, 7:40 PM

Post #6 of 11 (3148 views)
Permalink
Re: NFS shared livetv log [In reply to]
Chris Pinkham wrote:
>> My 3 systems (frontend, master and slave backend) clocks are all within
>> less than a second of each other, and I don't think I can use shared
>> dirs for Live TV. I think it works ok with recordings, but I'd like to
>> double check my setup. Can someone tell me if I have it setup right for
>>
>
> Please try remounting your nfs-shared directories on the client end
> with the "-o actimeo=0" option and see if that fixes the problem.
>
> I think the issue may be related to nfs file attribute caching on the
> client end. Using actimeo=0 on my system got rid of a lot of warnings
> about prebuffering that I was seeing when trying to watch LiveTV on
> my system with a dedicated NFS server and dedicated frontends.
>
>
I saw your post about that earlier, but what if I'm using CIFS? Will
that option still work?

Tom
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


cpinkham at bc2va

Feb 20, 2006, 7:51 PM

Post #7 of 11 (3169 views)
Permalink
Re: NFS shared livetv log [In reply to]
> > Please try remounting your nfs-shared directories on the client end
> > with the "-o actimeo=0" option and see if that fixes the problem.

> I saw your post about that earlier, but what if I'm using CIFS? Will
> that option still work?

Not sure, check the manpage on your system to see if there is an
option to disable file attribute caching.

--
Chris

_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


tom at redpepperracing

Feb 21, 2006, 6:54 AM

Post #8 of 11 (3155 views)
Permalink
Re: NFS shared livetv log [In reply to]
Chris Pinkham wrote:
>>> Please try remounting your nfs-shared directories on the client end
>>> with the "-o actimeo=0" option and see if that fixes the problem.
>>>
>
>
>> I saw your post about that earlier, but what if I'm using CIFS? Will
>> that option still work?
>>
>
> Not sure, check the manpage on your system to see if there is an
> option to disable file attribute caching.
>
This seems like it would be the one, but at the default 1 second, it
should still work, no? In my logs, it tries for 17 seconds, and I know
there is data there, I can see it even from the frontend. Perhaps the
retry method isn't working right?

ttl=<arg>
sets how long a directory listing is cached in
milliseconds (also affects visibility of file size and date
changes). A higher value means that changes on the server
take longer to be noticed but it can give better
performance on large directories, especially over long
distances. Default is 1000ms but something like
10000ms (10 seconds) is probably more reasonable in many
cases. (Note: only kernel 2.4.2 or later)

I won't be able to test it until tonight.

Thanks
Tom
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


tom at redpepperracing

Feb 21, 2006, 3:29 PM

Post #9 of 11 (3127 views)
Permalink
Re: NFS shared livetv log [In reply to]
Chris Pinkham wrote:
>>> Please try remounting your nfs-shared directories on the client end
>>> with the "-o actimeo=0" option and see if that fixes the problem.
>>>
>
>
>> I saw your post about that earlier, but what if I'm using CIFS? Will
>> that option still work?
>>
>
> Not sure, check the manpage on your system to see if there is an
> option to disable file attribute caching.
>
>
I had to change my mount type to smb, and added ttl=100, and now live tv
works with shared directories! It starts WAY faster than streaming as
well. Channel changes are just a hair faster.

Thanks for the tip! I'll experiment with different values to see where
it breaks.

Tom
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


ijr at case

Feb 21, 2006, 4:30 PM

Post #10 of 11 (3153 views)
Permalink
Re: NFS shared livetv log [In reply to]
On Tuesday 21 February 2006 18:29, Tom Lichti wrote:
> Chris Pinkham wrote:
> >>> Please try remounting your nfs-shared directories on the client end
> >>> with the "-o actimeo=0" option and see if that fixes the problem.
> >>
> >> I saw your post about that earlier, but what if I'm using CIFS? Will
> >> that option still work?
> >
> > Not sure, check the manpage on your system to see if there is an
> > option to disable file attribute caching.
>
> I had to change my mount type to smb, and added ttl=100, and now live tv
> works with shared directories! It starts WAY faster than streaming as
> well. Channel changes are just a hair faster.
>
> Thanks for the tip! I'll experiment with different values to see where
> it breaks.

I'd recommend adding your findings to the wiki, too.

Isaac
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


tom at redpepperracing

Feb 21, 2006, 7:28 PM

Post #11 of 11 (3132 views)
Permalink
Re: NFS shared livetv log [In reply to]
Isaac Richards wrote:
> On Tuesday 21 February 2006 18:29, Tom Lichti wrote:
>
>> Chris Pinkham wrote:
>>
>>>>> Please try remounting your nfs-shared directories on the client end
>>>>> with the "-o actimeo=0" option and see if that fixes the problem.
>>>>>
>>>> I saw your post about that earlier, but what if I'm using CIFS? Will
>>>> that option still work?
>>>>
>>> Not sure, check the manpage on your system to see if there is an
>>> option to disable file attribute caching.
>>>
>> I had to change my mount type to smb, and added ttl=100, and now live tv
>> works with shared directories! It starts WAY faster than streaming as
>> well. Channel changes are just a hair faster.
>>
>> Thanks for the tip! I'll experiment with different values to see where
>> it breaks.
>>
>
> I'd recommend adding your findings to the wiki, too.
>
> Isaac
> _______________________________________________
>
I already had that in mind, once I figure out the best setting.

Tom
_______________________________________________
mythtv-dev mailing list
mythtv-dev [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev

MythTV dev RSS feed   Index | Next | Previous | View Threaded
 
 


Interested in having your list archived? Contact Gossamer Threads
 
  Web Applications & Managed Hosting Powered by Gossamer Threads Inc.