[mythtv-users] HDPVR unable to record after upgrading to r20007 - in trunk

john jppoet at gmail.com
Wed Feb 18 05:21:45 UTC 2009


On Feb 17, 2009, at 7:05 PM, John Hoyt wrote:

> Ok, so I rebuilt under r2009 with the fixed patch and had no luck.   
> Turning on verbose logging for record gave me the following output:
>
> 2009-02-17 20:40:28.598 mythbackend version: trunk [20009M] www.mythtv.org
> 2009-02-17 20:40:28.601 Using runtime prefix = /usr
> 2009-02-17 20:40:28.602 Empty LocalHostName.
> 2009-02-17 20:40:28.603 Using localhost value of MythTV
> 2009-02-17 20:40:28.607 mythbackend version: trunk [20009M] www.mythtv.org
> 2009-02-17 20:40:28.613 Using runtime prefix = /usr
> 2009-02-17 20:40:28.614 Empty LocalHostName.
> 2009-02-17 20:40:28.615 Using localhost value of MythTV
> 2009-02-17 20:40:28.618 New DB connection, total: 1
> 2009-02-17 20:40:28.627 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.629 New DB connection, total: 1
> 2009-02-17 20:40:28.631 Closing DB connection named 'DBManager0'
> 2009-02-17 20:40:28.638 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.642 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.647 Current Schema Version: 1228
> 2009-02-17 20:40:28.648 Closing DB connection named 'DBManager0'
> 2009-02-17 20:40:28.651 New DB connection, total: 2
> 2009-02-17 20:40:28.657 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.672 Current Schema Version: 1228
> 2009-02-17 20:40:28.657 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.675 New DB connection, total: 2
> 2009-02-17 20:40:28.690 Connected to database 'mythconverg' at host:  
> localhost
> 2009-02-17 20:40:28.692 Preview Error: Previewer file '/media/DVR/ 
> recordings/2024_20090217194000.mpg' is not valid.
> 2009-02-17 20:40:28.706 Preview Error: Run() file not local: '/media/ 
> DVR/recordings/2024_20090217194000.mpg'
> 2009-02-17 20:40:28.707 Preview Error: Previewer file '/media/DVR/ 
> recordings/2024_20090217183800.mpg' is not valid.
> 2009-02-17 20:40:28.718 Preview Error: Preview process not ok.
>             fileinfo(/media/DVR/recordings/ 
> 2024_20090217194000.mpg.png) exists: 0 readable: 0 size: 0
> 2009-02-17 20:40:28.719 Preview Error: Run() file not local: '/media/ 
> DVR/recordings/2024_20090217183800.mpg'
> 2009-02-17 20:40:28.747 Preview Error: Preview process not ok.
>             fileinfo(/media/DVR/recordings/ 
> 2024_20090217183800.mpg.png) exists: 0 readable: 0 size: 0
> 2009-02-17 20:40:29.107 MainServer::ANN Monitor
> 2009-02-17 20:40:29.113 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:32.996 MainServer::ANN Monitor
> 2009-02-17 20:40:32.998 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:33.692 MainServer::ANN Monitor
> 2009-02-17 20:40:33.693 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:33.724 MainServer::ANN Monitor
> 2009-02-17 20:40:33.732 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:38.618 MainServer::ANN Monitor
> 2009-02-17 20:40:38.621 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:39.323 MainServer::ANN Monitor
> 2009-02-17 20:40:39.324 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:39.353 MainServer::ANN Monitor
> 2009-02-17 20:40:39.355 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:40.759 Reschedule requested for id 0.
> 2009-02-17 20:40:41.357 Scheduled 176 items in 0.6 = 0.01 match +  
> 0.57 place
> 2009-02-17 20:40:42.309 MainServer::ANN Monitor
> 2009-02-17 20:40:42.312 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:42.489 MainServer::ANN Monitor
> 2009-02-17 20:40:42.490 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:43.707 MainServer::ANN Monitor
> 2009-02-17 20:40:43.709 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:46.379 Reschedule requested for id 0.
> 2009-02-17 20:40:46.985 Scheduled 176 items in 0.6 = 0.01 match +  
> 0.58 place
> 2009-02-17 20:40:56.538 MainServer::ANN Monitor
> 2009-02-17 20:40:56.541 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:57.239 MainServer::ANN Monitor
> 2009-02-17 20:40:57.241 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:57.242 Reschedule requested for id 393.
> 2009-02-17 20:40:57.490 MainServer::ANN Monitor
> 2009-02-17 20:40:57.494 adding: mythtv as a client (events: 0)
> 2009-02-17 20:40:57.842 Scheduled 176 items in 0.6 = 0.01 match +  
> 0.58 place
> 2009-02-17 20:41:08.707 Reschedule requested for id 394.
> 2009-02-17 20:41:09.307 Scheduled 177 items in 0.6 = 0.05 match +  
> 0.54 place
> 2009-02-17 20:41:09.311 TVRec(3): RecordPending on inputid 1
> 2009-02-17 20:41:09.313 TVRec(3): StartRecording(ESPNEWS)
> 2009-02-17 20:41:09.314 TVRec(3): ASK_RECORDING 3 0 0 0
> 2009-02-17 20:41:09.378 TVRec(3): StartedRecording(0x1c3ae40) fn(/ 
> media/DVR/recordings/2072_20090217204100.mpg)
> 2009-02-17 20:41:09.386 TVRec(3): ClearFlags(CancelNextRecording,) - 
> > RunMainLoop,
> 2009-02-17 20:41:09.386 TVRec(3): Changing from None to RecordingOnly
> 2009-02-17 20:41:09.391 TVRec(3):  
> ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
> 2009-02-17 20:41:09.392 TVRec(3): Request: Program(yes) channel()  
> input() flags(Recording,)
> 2009-02-17 20:41:09.395 TVRec(3): HW Tuner: 3->3
> 2009-02-17 20:41:09.396 TVRec(3): ClearFlags(PENDINGACTIONS,) ->  
> RunMainLoop,
> 2009-02-17 20:41:09.397 TVRec(3): No recorder yet, calling  
> TuningFrequency
> 2009-02-17 20:41:10.478 ret_pid(0) child(12017) status(0x0)
> 2009-02-17 20:41:11.479 ret_pid(0) child(12017) status(0x0)
> 2009-02-17 20:41:11.754 ret_pid(12017) child(12017) status(0x0)
> 2009-02-17 20:41:11.754 External Tuning program exited with no error
> 2009-02-17 20:41:11.835 TVRec(3): ClearFlags(NeedToStartRecorder,) - 
> > RunMainLoop,
> 2009-02-17 20:41:11.836 TVRec(3): SetFlags(NeedToStartRecorder,) ->  
> RunMainLoop,NeedToStartRecorder,
> 2009-02-17 20:41:11.836 TVRec(3): Starting Recorder
> 2009-02-17 20:41:11.873 Using profile 'Default' to record
> 2009-02-17 20:41:11.881 RecBase(3:/dev/video0):  
> SetRingBuffer(0x1a9c400) '/media/DVR/recordings/ 
> 2072_20090217204100.mpg'
> 2009-02-17 20:41:11.882 RecBase(3:/dev/video0):  
> SetRecording(0x1c3ae40) title(ESPNEWS)
> 2009-02-17 20:41:11.969 MPEGRec(/dev/video0): usingv4l2(1)  
> has_v4l2_vbi(0) has_buggy_vbi(1)
> 2009-02-17 20:41:11.980 AutoExpire: CalcParams(): Max required Free  
> Space: 6.0 GB w/freq: 15 min
> 2009-02-17 20:41:11.985 Started recording: ESPNEWS: channel 2072 on  
> cardid 3, sourceid 2
> 2009-02-17 20:41:12.065 DevRdB(/dev/video0): buffer size 9400 KB
> 2009-02-17 20:41:12.089 MPEGRec(/dev/video0): DRB ready
> 2009-02-17 20:41:12.090 MPEGRec(/dev/video0):  
> SetStreamData(0x1b9f8b0) -- begin
> 2009-02-17 20:41:12.091 MPEGRec(/dev/video0):  
> SetStreamData(0x1b9f8b0) -- end 1
> 2009-02-17 20:41:12.096 MPEGRec(/dev/video0):  
> HandleSingleProgramPAT(NULL)
> 2009-02-17 20:41:12.108 MPEGRec(/dev/video0): Initial startup of  
> recorder
> 2009-02-17 20:41:12.109 MPEGRec(/dev/video0): Checking Resolution
> 2009-02-17 20:41:12.111 TVRec(3):  
> SetFlags(RecorderRunning,RingBufferReady,) ->  
> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
> 2009-02-17 20:41:12.116 TVRec(3): ClearFlags(NeedToStartRecorder,) - 
> > RunMainLoop,RecorderRunning,RingBufferReady,
> 2009-02-17 20:41:17.483 MPEGRec(/dev/video0): Got Resolution 1280x720
> 2009-02-17 20:41:17.488 MPEGRec(/dev/video0): StartEncoding
> 2009-02-17 20:41:17.489 MPEGRec(/dev/video0): Encoding started
> 2009-02-17 20:41:17.743 DevRdB(/dev/video0) Error: Poll timeout (1)
> 2009-02-17 20:41:18.259 DevRdB(/dev/video0) Error: Poll timeout (3)
> 2009-02-17 20:41:18.771 DevRdB(/dev/video0) Error: Poll timeout (5)
> 2009-02-17 20:41:19.287 DevRdB(/dev/video0) Error: Poll timeout (7)
> 2009-02-17 20:41:19.795 DevRdB(/dev/video0) Error: Poll timeout (9)
> 2009-02-17 20:41:20.051 DevRdB(/dev/video0) Error: Poll giving up
> 2009-02-17 20:41:20.052 DevRdB(/dev/video0): fill_ringbuffer: error  
> state
> 2009-02-17 20:41:20.055 MPEGRec(/dev/video0) Error: Device error  
> detected
> 2009-02-17 20:41:20.060 MPEGRec(/dev/video0): RestartEncoding
> 2009-02-17 20:41:20.061 DevRdB(/dev/video0): Stop(): Not running.
> 2009-02-17 20:41:20.062 MPEGRec(/dev/video0): StopEncoding
> 2009-02-17 20:41:21.475 MPEGRec(/dev/video0): Encoding stopped
> 2009-02-17 20:41:21.476 MPEGRec(/dev/video0): Checking Resolution
> 2009-02-17 20:41:27.091 MPEGRec(/dev/video0): Got Resolution 1280x720
> 2009-02-17 20:41:27.093 MPEGRec(/dev/video0): StartEncoding
> 2009-02-17 20:41:27.093 MPEGRec(/dev/video0): Encoding started
> 2009-02-17 20:41:27.347 DevRdB(/dev/video0) Error: Poll timeout (1)
> 2009-02-17 20:41:27.863 DevRdB(/dev/video0) Error: Poll timeout (3)
> 2009-02-17 20:41:28.375 DevRdB(/dev/video0) Error: Poll timeout (5)
> 2009-02-17 20:41:28.887 DevRdB(/dev/video0) Error: Poll timeout (7)
> 2009-02-17 20:41:29.399 DevRdB(/dev/video0) Error: Poll timeout (9)
> 2009-02-17 20:41:29.655 DevRdB(/dev/video0) Error: Poll giving up
> 2009-02-17 20:41:29.656 DevRdB(/dev/video0): fill_ringbuffer: error  
> state
> 2009-02-17 20:41:29.659 MPEGRec(/dev/video0) Error: Device error  
> detected
> 2009-02-17 20:41:29.664 MPEGRec(/dev/video0): RestartEncoding
> 2009-02-17 20:41:29.665 DevRdB(/dev/video0): Stop(): Not running.
> 2009-02-17 20:41:29.672 MPEGRec(/dev/video0): StopEncoding
> 2009-02-17 20:41:31.091 MPEGRec(/dev/video0): Encoding stopped
>
> It looks like I am getting all sorts of Poll timeout errors.  I do  
> have the latest "DeviceReadBuffer-polltimeout.2.patch" applied and  
> have verified it took by parsing the code manually.  I've also tried  
> another power-cycle of the HD-PVR with no success.  Any other  
> suggestions?
>
> Thanks again for the help!
> John
>

I am running trunk with the polltimeout patch and the  
audio_codec_selection patch, and my pair of HD-PVRs are working  
perfectly.  So, I don't think there is a problem with the code.

You probably should investigate Chris' theory.

John


-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20090217/36de256d/attachment.htm>


More information about the mythtv-users mailing list