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

Wed May 9 09:48:20 UTC 2012

---- Michael Harnden <mike at rochestervball.com> wrote: 
> 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.

Darn. I was hoping your shutdown suggestion would work. I went home tonight and did exactly that. 

However, in your earlier message you stated that you were not having the same problem with your
tuner, only your S-Video feed. That is different from my experience because I'm having the problem
with my tuner. I don't use my S-Video feed. 

I'm convinced that there is a software problem, but because of how we are able to record 
on-demand, but not on-schedule, I think the problem has to do with the scheduler state and
the PVR command requirements. The fact that your system is working with the tuner but not 
S-Video also seems to imply that the problem isn't tuner-related exactly. 

I may submit a bug report but I want to comb through my now volumous logs to see if I can
spot a trend. 

Unfortunately my wife is getting a little worried that we won't be recording the end of the
season episodes of her favorite shows... so the clock is ticking and I may have to revert
to 0.24.

-Kenan