[mythtv-users] Post upgrade 0.24 to 0.25 PVR-150/250/350 fail to record at some point

Michael Harnden mike at rochestervball.com
Wed May 9 09:14:09 UTC 2012 

On Tuesday, May 08, 2012 06:29:04 PM Michael Harnden wrote:
> On Tuesday, May 08, 2012 05:19:26 PM Kenan Ezal wrote:
> > ---- Michael Harnden <mike at rochestervball.com> wrote:
> > > On Tuesday, May 08, 2012 11:36:35 AM Michael T. Dean wrote:
> > > > On 05/08/2012 07:44 AM, Michael Harnden wrote:
> > > > > Quoting Kenan Ezal:
> > > > >> Summary of problem:
> > > > >> 
> > > > >> After I reboot both my master backend and slave backend I schedule
> > > > >> recordings (that are currently on) to test my three tuners
> > > > >> (PVR-250/PVR-150 on master backend and PVR-350 on slave backend).
> > > > >> All
> > > > >> three tuners record their respective shows and I am able to play
> > > > >> back
> > > > >> during recording. I delete the shows and try again for a different
> > > > >> set of programs on different channels. Once again everything works
> > > > >> and I am pleased thinking my kids will be happy with the new Mickey
> > > > >> Mouse Clubhouse show that is scheduled to be recorded the next
> > > > >> morning.
> > > > >> 
> > > > >> The following morning I wake up to find that the first show that
> > > > >> was
> > > > >> scheduled for recording at 2am (Phineas and Ferb) recorded fine
> > > > >> although the backend did report a database error (see below).
> > > > >> However, the second show (Doc McStuffins at 7:30am) and the third
> > > > >> show (Mickey Mouse Club House at 8am) did not record, although they
> > > > >> show up in grey on my list of recordings. All three shows were
> > > > >> scheduled to use Tuner 1 (PVR-250).
> > > > >> 
> > > > >> I am puzzled so I sellect three other shows (such as the highly
> > > > >> provocative "Inside Edition: Butt Girl" and "Brazil Butt Lift") for
> > > > >> immediate recording. All three appear to be recording. However,
> > > > >> when
> > > > >> I check Tuner 1 I see that it is not. I stop the recording on Tuner
> > > > >> 1
> > > > >> (leaving the others recording) and try LiveTV and receive the
> > > > >> following error: "Error opening jump program buffer." The other two
> > > > >> tuners are in fact recording. However, I am able to recreate the
> > > > >> same
> > > > >> issue with any of the tuners. For example, the other night I
> > > > >> decided
> > > > >> to disable Tuner 1 so that Tuner 2 was the first in line to record.
> > > > >> The next morning same problem. I then disabled Tuners 1 and 2, and
> > > > >> the same problem occurred with Tuner 3 (on slave backend). They all
> > > > >> initially record fine, but fail during a later scheduled recording,
> > > > >> usually after successfully recording the first show.
> > > > >> 
> > > > >> Extended Discussion:
> > > > >> 
> > > > >> Unfortunately, this started about two weeks ago when I upgraded
> > > > >> from
> > > > >> 0.24 to 0.25 on Fedora 16. I use ATrpms to access the packages. At
> > > > >> first I thought it was a power supply problem because we had been
> > > > >> having some power problems in our area and I found my master
> > > > >> backend
> > > > >> completely off one morning. However, I believe that was a false
> > > > >> alarm
> > > > >> and now it appears to be a software problem. The system was very
> > > > >> stable and recording away happily before the upgrade.
> > > > >> 
> > > > >> I have noted that other people have been reporting similar problems
> > > > >> with their PVRs although the descriptons vary a some and many seem
> > > > >> to
> > > > >> be focused on LiveTV. We never watch LiveTV, always recorded shows
> > > > >> so
> > > > >> I know it isn't a problem solely related to LiveTV.
> > > > >> 
> > > > >> See for exampe:
> > > > >> 
> > > > >> http://www.mythtv.org/pipermail/mythtv-users/2012-May/333121.html
> > > > >> https://bugs.archlinux.org/task/29627
> > > > >> 
> > > > >> I did discover that there is a possible fix, but I"m not sure when
> > > > >> its going to get into the packages:
> > > > >> 
> > > > >> https://github.com/MythTV/mythtv/commit/f81f712537b63502814d1f274c7
> > > > >> da
> > > > >> 1419
> > > > >> 6cedd8c
> > > > >> 
> > > > >> 
> > > > >> However, I'm not completely convinced that is the only problem on
> > > > >> my
> > > > >> system since I can't seem to make it fail on will. It's repeatable
> > > > >> in
> > > > >> that after a reboot, it will happen (probably overnight).
> > > > >> 
> > > > >> 1. I'm curious if anyone else has had similar issues and was able
> > > > >> to
> > > > >> fix it without the above mentioned fix (I did try deleting all the
> > > > >> tuners and inputs and adding them back in and it did not help).
> > > > >> 
> > > > >> snip
> > > > > 
> > > > > 
> > > > > I will dig into this a bit more when I get home from work tonight.
> > > > 
> > > > Did you guys do a "Delete all capture cards" (not "Delete all capture
> > > > cards on <hostname>"), then re-create cards and re-connect inputs? 
> > > > (Any
> > > > time you change cards or drivers, you should probably do that--and
> > > > should definitely do that if you have failures after the upgrade.)  It
> > > > won't affect Video Sources or channels, so, it's a quick 30-second
> > > > process that allows MythTV to configure your cards for the current
> > > > drivers.
> > > > 
> > > > Mike
> > > > _______________________________________________
> > > > mythtv-users mailing list
> > > > mythtv-users at mythtv.org
> > > > http://www.mythtv.org/mailman/listinfo/mythtv-users
> > > 
> > > Hi Mike,
> > > I ran "Delete all capture cards" and had the same results (recording
> > > failure). After some more googling, I shut my server down (not just a
> > > reboot), waited a minute or so and restarted. So far I have been able to
> > > record 5 separate times on that tuner. So I think I am good. I'll
> > > observe
> > > for a couple of days and report back.
> > > 
> > > I managed to break live TV in my Delete all cards process, so I am off
> > > to
> > > investigate that.
> > > Mike
> > 
> > Mike,
> > 
> > When you say you were able to record 5 seperate times, do you mean that
> > you
> > manually told the tuner to to record a show, stopped the recording, and
> > told it to record another show, possibly on another channel? [Or were
> > these
> > previously scheduled recordings?]
> > 
> > If so, I was able to do the same thing, but after leaving the machine with
> > previously scheduled programs, the first scheduled program fails to
> > record.
> > For example, after a reboot, I went into Manage Recordings and selected a
> > currently running program for recording. It would successfully record. I
> > would stop the recording and go to another channel and do the same thing
> > with another program. Once again no problem. However, after thinking
> > everything is Ok I would come back the next morning to find an empty file
> > for a previously scheduled recording using that same tuner.
> > 
> > I was wondering if there is something related to the scheduler going
> > on...?
> > 
> > However, tonight I will try complete shutdown of both machines after I
> > remove my cards from setup. I will then re-select the cards after starting
> > the machine and see what happens. I may also shutdown after re-selecting.
> > I'll try anything now...
> > 
> > -Kenan
> > 
> > -Kenan
> 
> Hi Kenan,
> Now you have me concerned, because that is exactly what I did. Told it to
> record a show, stopped it, picked another on a different channel, etc.
> 
> I am using a PVR-350 and the interesting thing is that it is only the
> S-Video feed into the card that I was having problems with. I have the
> tuner portion of the card connected directly to my cable, and the S-Video
> input I am using to record from my cable box. The tuner portion has worked
> fine all along.
> 
> I will schedule some shows on the S-Video feed overnight and report back.
> Mike
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-users

Looks like I still have the problem. I was able to record a show at 11:00 pm, then the 
card failed at the end of recording.

May  8 22:59:29 mythtv-server mythbackend[1950]: I TVRecEvent tv_rec.cpp:1521 (HandlePendingRecordings) TVRec(1): ASK_RECORDING 1 20 0 0
May  8 22:59:49 mythtv-server mythbackend[1950]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from None to RecordingOnly
May  8 22:59:49 mythtv-server mythbackend[1950]: I TVRecEvent tv_rec.cpp:3459 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
May  8 22:59:49 mythtv-server mythbackend[1950]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
May  8 22:59:50 mythtv-server mythbackend[1950]: N Scheduler autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
May  8 22:59:50 mythtv-server mythbackend[1950]: I Scheduler scheduler.cpp:2459 (HandleRecordingStatusChange) Tuning recording: Pocoyo: channel 1519 on cardid 1, sourceid 1
May  8 22:59:50 mythtv-server mythbackend[1950]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(1519_20120508230000.mpg): GetPlaybackURL: '1519_20120508230000.mpg' should be local, but it can not be found.
May  8 22:59:50 mythtv-server mythbackend[1950]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(1519_20120508230000.mpg): GetPlaybackURL: '1519_20120508230000.mpg' should be local, but it can not be found.
May  8 22:59:51 mythtv-server mythbackend[1950]: I Scheduler scheduler.cpp:2459 (HandleRecordingStatusChange) Started recording: Pocoyo: channel 1519 on cardid 1, sourceid 1
May  8 22:59:51 mythtv-server mythbackend[1950]: I TVRecEvent tv_rec.cpp:3953 (TuningNewRecorder) TVRec(1): rec->GetPathname(): '/mnt/store/d4/video/1519_20120508230000.mpg'
May  8 23:00:00 mythtv-server mythbackend[1950]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
May  8 23:00:00 mythtv-server mythbackend[1950]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: mythtv-server.site as a client (events: 0)
May  8 23:00:00 mythtv-server mythbackend[1950]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
May  8 23:00:00 mythtv-server mythbackend[1950]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: mythtv-server.site as a client (events: 1)
May  8 23:02:50 mythtv-server mythbackend[1950]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
May  8 23:04:08 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:09:13 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:14:18 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:17:50 mythtv-server mythbackend[1950]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
May  8 23:19:24 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:24:24 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:29:25 mythtv-server mythbackend[1950]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
May  8 23:30:10 mythtv-server mythbackend[1950]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from RecordingOnly to None
May  8 23:30:11 mythtv-server mythbackend[1950]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
May  8 23:30:11 mythtv-server mythbackend[1950]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
May  8 23:30:13 mythtv-server mythbackend[1950]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
May  8 23:30:13 mythtv-server mythbackend[1950]: I CoreContext scheduler.cpp:634 (UpdateRecStatus) Updating status for Pocoyo on cardid 1 (Recording => Recorded)
May  8 23:30:13 mythtv-server mythbackend[1950]: I TVRecEvent recordinginfo.cpp:1113 (FinishedRecording) Finished recording Pocoyo: channel 1519
May  8 23:30:13 mythtv-server mythbackend[1950]: E CoreContext mainserver.cpp:871 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.

More information about the mythtv-users mailing list