[mythtv-users] LiveTV works, recording does not

me at blaue0.net me at blaue0.net
Wed Feb 14 08:47:01 UTC 2007 

Hello,

I recently rebuilt my mythtv system because my structure here changed  
a bit. I have a frontend that has a PVR-500 card (boots from a CF  
card). The frontend mounts the storage via NFS from the main server  
(that is also the master backend). The master backend has no tuners.

I can watch LiveTV without problems, the only thing that I noticed is  
that the file doesn't get streamed to the master backend *and* then  
back to the frontend, the frontend seems to access it directly, I  
suppose this is a feature, not a bug.

The problem with recordings is that they fail. I raised the loglevel  
on both backends but I don't see why it shouldn't work.

Below are the two log files of the backends. I don't see why it should  
get an invald file. cat /dev/video0 > /share/TV/foo.mpg on the slave  
backend works ok...

Maybe someone saw this issue before?

Log of the master backend:
   +Wetterkanal                            1 1  14 07:30-09:30  1 1  S 1   1/0
2007-02-14 09:06:55.892 ClearListMaps...
2007-02-14 09:06:55.893 Sort by time...
2007-02-14 09:06:55.903 PruneRedundants...
2007-02-14 09:06:55.903 Sort by time...
--- print list start ---
Title - Subtitle                    Chan ChID Day Start  End   C I  T N   Pri
Wetterkanal                            1 1  14 07:30-09:30  1 1  S 1   1/0
---  print list end  ---
2007-02-14 09:06:55.906 Scheduled 1 items in 0.2 = 0.09 match + 0.07 place
2007-02-14 09:06:56.143 Started recording: Wetterkanal: channel 1 on  
cardid 1, sourceid 1
2007-02-14 09:06:57.942 MainServer::HandleAnnounce Monitor
2007-02-14 09:06:57.943 adding: gentux as a client (events: 0)  
gentux[]:[]/media/Video/TV/1_20070214080700.mpg.png
2007-02-14 09:06:58.038 MainServer::HandleAnnounce FileTransfer
2007-02-14 09:06:58.039 adding: gentux as a remote file transfer
2007-02-14 09:06:58.043 Local file path:  
/media/Video/TV/1_20070214080700.mpg.png
2007-02-14 09:06:59.035 MainServer::HandleAnnounce Monitor
2007-02-14 09:06:59.036 adding: gentux as a client (events: 0)
2007-02-14 09:07:04.543  
RingBuf(/media/Video/TV/1_20070214080700.mpg.png): Could not open  
/media/Video/TV/1_20070214080700.mpg.png.
2007-02-14 09:07:04.583 MythSocket(8197510:-1): writeStringList:  
Error, socket went unconnected.
2007-02-14 09:07:16.399 JobQueue: Commercial Flagging Starting for  
Wetterkanal recorded from channel 1 at Wed Feb 14 08:07:00 2007
2007-02-14 09:07:16.502 Using runtime prefix = /usr
2007-02-14 09:07:16.525 New DB connection, total: 1
2007-02-14 09:07:16.539 Connected to database 'mythconverg' at host: localhost
2007-02-14 09:07:16.544 New DB connection, total: 2
2007-02-14 09:07:16.546 Connected to database 'mythconverg' at host: localhost
2007-02-14 09:07:23.054 RingBuf(/media/Video/TV/1_20070214080700.mpg):  
Invalid file (fd -1) when opening  
'/media/Video/TV/1_20070214080700.mpg'.
2007-02-14 09:07:23.078 Connecting to backend server:  
192.168.1.100:6543 (try 1 of 5)
2007-02-14 09:07:23.124 Using protocol version 31
2007-02-14 09:07:23.144 MainServer::HandleAnnounce Monitor
2007-02-14 09:07:23.145 adding: gentux as a client (events: 0)
2007-02-14 09:07:23.195 MainServer::HandleAnnounce Monitor
2007-02-14 09:07:23.204 adding: gentux as a client (events: 1)
2007-02-14 09:07:23.325 NVP::OpenFile(): Error, file not found:  
/media/Video/TV/1_20070214080700.mpg
2007-02-14 09:07:23.417 JobQueue: Commercial Flagging Finished, 0  
break(s) found.
2007-02-14 09:07:23.934 RingBuf(/media/Video/TV/1_20070214080700.mpg):  
Invalid file (fd -1) when opening  
'/media/Video/TV/1_20070214080700.mpg'.
2007-02-14 09:07:23.942 Preview Error: Previewer could not open file:  
'/media/Video/TV/1_20070214080700.mpg'

The log of the slave backend:

2007-02-14 09:06:32.772 TVRec(1): StartRecording(Wetterkanal)
2007-02-14 09:06:32.772 TVRec(1): ClearFlags(AskAllowRecording,) ->  
RunMainLoop,CancelNextRecording,
2007-02-14 09:06:32.798 TVRec(1): StartedRecording(0x8189c88)  
fn(/share/Video/TV//1_20070214080700.mpg)
2007-02-14 09:06:32.798 TVRec(1): ClearFlags(CancelNextRecording,) ->  
RunMainLoop,
2007-02-14 09:06:32.798 TVRec(1): Changing from None to RecordingOnly
2007-02-14 09:06:32.798 TVRec(1):  
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2007-02-14 09:06:32.798 TVRec(1): SetFlags(AskAllowRecording,) ->  
RunMainLoop,AskAllowRecording,
2007-02-14 09:06:32.798 TVRec(1): Request: Program(yes) channel()  
input() flags(Recording,)
2007-02-14 09:06:32.799 TVRec(1): HW Tuner: 1->1
2007-02-14 09:06:32.799 TVRec(1): ClearFlags(PENDINGACTIONS,) ->  
RunMainLoop,AskAllowRecording,
2007-02-14 09:06:32.800 Channel(/dev/v4l/video0): Device name 'WinTV  
PVR 500 (unit #1)' driver 'ivtv'.
2007-02-14 09:06:32.807 ChannelBase(1): Input #1: 'Tuner 1' schan(23)  
sourceid(1) ccid(1)
2007-02-14 09:06:32.807 ChannelBase(1): Current Input #1: 'Tuner 1'
2007-02-14 09:06:32.807 Global TVFormat Setting 'PAL'
2007-02-14 09:06:32.807 Channel(/dev/v4l/video0): Input #1: 'Tuner 1'  
schan(23) tun() v4l1(PAL) v4l2(Unknown)
2007-02-14 09:06:32.807 Channel(/dev/v4l/video0): SetFormat(Default)  
fmt(PAL) input(1)
2007-02-14 09:06:32.807 Channel(/dev/v4l/video0)::SwitchToInput(in 1, '')
2007-02-14 09:06:32.807 Channel(/dev/v4l/video0): SetInputAndFormat(1,  
PAL) (v4l v2)
2007-02-14 09:06:32.807 Channel(/dev/v4l/video0): SetChannelByString(1)
2007-02-14 09:06:32.808  
ChannelBase(/dev/v4l/video0)::SetCachedATSCInfo(): 0--1
2007-02-14 09:06:32.808 Channel(/dev/v4l/video0): SetFormat(Default)  
fmt(PAL) input(1)
2007-02-14 09:06:32.809 Channel(/dev/v4l/video0)::TuneTo(E11):  
curList[12].freq(217250)
2007-02-14 09:06:32.809 Channel(/dev/v4l/video0)::Tune(217250000, , analog)
2007-02-14 09:06:32.939 Channel(/dev/v4l/video0)::Tune(): Frequency is  
now 217250000
2007-02-14 09:06:32.939  
ChannelBase(/dev/v4l/video0)::SetCachedATSCInfo(1_0): 1--1
2007-02-14 09:06:32.939 TVRec(1): ClearFlags(NeedToStartRecorder,) ->  
RunMainLoop,AskAllowRecording,
2007-02-14 09:06:32.939 TVRec(1): SetFlags(NeedToStartRecorder,) ->  
RunMainLoop,AskAllowRecording,NeedToStartRecorder,
2007-02-14 09:06:32.939 TVRec(1): Starting Recorder
2007-02-14 09:06:32.955 Using profile 'Default' to record
2007-02-14 09:06:32.959 RecBase(/dev/v4l/video0):  
SetRingBuffer(0x81a2fa8) '/share/Video/TV//1_20070214080700.mpg'
2007-02-14 09:06:32.960 RecBase(/dev/v4l/video0):  
SetRecording(0x8189c88) title(Wetterkanal)
2007-02-14 09:06:32.961 Channel(/dev/v4l/video0):  
InitPictureAttribute(brightness):
                         [    0,  255] dflt(  128, 0.50, 128)
2007-02-14 09:06:32.964 Channel(/dev/v4l/video0):  
InitPictureAttribute(  contrast):
                         [    0,  127] dflt(   64, 0.50, 257)
2007-02-14 09:06:32.966 Channel(/dev/v4l/video0):  
InitPictureAttribute(    colour):
                         [    0,  127] dflt(   64, 0.50, 257)
2007-02-14 09:06:32.969 Channel(/dev/v4l/video0):  
InitPictureAttribute(       hue):
                         [ -128,  127] dflt(    0, 0.50, 128)
2007-02-14 09:06:32.987 MPEGRec(/dev/v4l/video0) Warning: Error  
getting codec params using old IVTV ioctl
                         eno: Invalid argument (22)
2007-02-14 09:06:33.121 MPEGRec(/dev/v4l/video0): VBI service:17409, io size:0
2007-02-14 09:06:33.140 TVRec(1):  
SetFlags(RecorderRunning,RingBufferReady,) ->  
RunMainLoop,AskAllowRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2007-02-14 09:06:33.140 TVRec(1): ClearFlags(NeedToStartRecorder,) ->  
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2007-02-14 09:06:33.141 TVRec(1): ClearFlags(FinishRecording,) ->  
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2007-02-14 09:06:33.141 TVRec(1): Changing from RecordingOnly to None
2007-02-14 09:06:33.141 TVRec(1):  
ClearFlags(FrontendReady,CancelNextRecording,) ->  
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2007-02-14 09:06:33.141 TVRec(1): SetFlags(AskAllowRecording,) ->  
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2007-02-14 09:06:33.141 TVRec(1): Request: Program(no) channel()  
input() flags(CloseRec,KillRingBuffer,)
2007-02-14 09:06:33.143 TVRec(1): FinishedRecording(Wetterkanal) in  
recgroup: Default
2007-02-14 09:06:33.144 Finished recording Wetterkanal: channel 1
2007-02-14 09:06:33.869 TVRec(1): ClearFlags(RecorderRunning,) ->  
RunMainLoop,AskAllowRecording,RingBufferReady,
2007-02-14 09:06:33.891 TVRec(1): FinishedRecording(Wetterkanal) in  
recgroup: Default
2007-02-14 09:06:33.892 Finished recording Wetterkanal: channel 1
2007-02-14 09:06:33.894 TVRec(1): ClearFlags(RecorderRunning,) ->  
RunMainLoop,AskAllowRecording,RingBufferReady,
2007-02-14 09:06:33.894 TVRec(1): Tearing down RingBuffer
2007-02-14 09:06:33.895 TVRec(1): ClearFlags(PENDINGACTIONS,) ->  
RunMainLoop,AskAllowRecording,RingBufferReady,
2007-02-14 09:06:34.395  
RingBuf(/share/Video/TV//1_20070214080700.mpg): Invalid file (fd -1)  
when opening '/share/Video/TV//1_20070214080700.mpg'.
2007-02-14 09:06:34.395 Preview Error: Previewer could not open file:  
'/share/Video/TV//1_20070214080700.mpg'
2007-02-14 09:06:34.881 Preview Error: Previewer file  
'/media/Video/TV/1_20070214080700.mpg' is not valid.
2007-02-14 09:06:34.881 MainServer: Failed to make preview image.

More information about the mythtv-users mailing list