[mythtv-users] Recording Functionality Breaks Regularly Since Upgrading to 0.25
Brian and Lisa
brian.n.lisa at gmail.com
Sun Jun 10 13:12:07 UTC 2012
On 6/9/2012 1:26 AM, Michael Harnden wrote:
> On Friday, June 08, 2012 07:10:30 PM Brian and Lisa wrote:
>> On 6/7/2012 11:21 PM, Nick Rout wrote:
>>> On Fri, Jun 8, 2012 at 7:30 AM, Brian and Lisa<brian.n.lisa at gmail.com>
> wrote:
>>>> Greetings (and apologies for original message which included html, I hope
>>>> this is better),
>>>>
>>>>
>>>> Ever since upgrading to 0.25 from 0.24 my system spontaneously fails to
>>>> make recordings.
>>>>
>>>> Generally 12 hours or so after the last reboot, after having made
>>>> scheduled
>>>> recordings normally in the interim, the system will attempt to make
>>>> scheduled TV recording(s) but fail. After a reboot, the system will
>>>> resume
>>>> recording scheduled programs but once the backend breaks, it will remain
>>>> broken until the host is reboot (stopping/restarting the backend makes no
>>>> difference). The recordings menu (Watch Recording-->All Programs) will
>>>> display normal menu entries for all recordings which were attempted
>>>> (successful or not), but no HDD recording will have been made once the
>>>> backend breaks and of course, no thumbnail image will be evident in the
>>>> menu. Aside from the regular breaks in recording capability, the system
>>>> performs more or less identically to when it was running 0.24.
>>>>
>>>> Whether the recording functions are working or not, there is no issue
>>>> with
>>>> playback of successful recordings (suggesting this is not a harddrive I/O
>>>> issue).
>>>>
>>>>
>>>> I followed Mike's advice to another user with a similar issue
>>>> (http://www.mythtv.org/pipermail/mythtv-users/2012-May/333505.html):"'Del
>>>> ete all capture cards' (not 'Delete all capture cards on<hostname>'),
>>>> then re-create cards and re-connect inputs" but this made no difference.
>>>> I've also physically reseated my digitizer card after cleaning the
>>>> contacts.
>>>>
>>>> Portions of my backend.log during a failure are attached.
>>> Sorry if I missed it, but what capture device?
>> Nick,
>>
>> Sorry I left this out - it's a tried and true Hauppauge WinTV PVR-150.
>>
>>
>> _______________________________________________
>> mythtv-users mailing list
>> mythtv-users at mythtv.org
>> http://www.mythtv.org/mailman/listinfo/mythtv-users
> As someone wrestling with the same problem (using a PVR150 and PVR350) I have
> a cron job set up to unload/load the ivtv modules as a temporary workaround
> (and save a bunch of rebooting). I now average only 2 or 3 failed recordings
> per week.
>
> #!/bin/bash
> modprobe -r ivtv
> sleep 5
> modprobe ivtv debug=0x4f
>
>
> Mike
>
Mike,
Thanks for your suggestion. My effort to try your fix didn't work, but
given my lack of experience with cron jobs it's possible that my
implementation is is error.
I copied your script into /etc/cron.hourly (-rwxr-xr-x 1 root root 126
2012-06-09 07:32 ivtv_reload_MythTV_0.25_bug.sh) .
Brian
Recordings worked well from o/a 0800 9 June until next failure at 0330
10June: Selecting the failed recording pop-up reads "Recording
Unavailable 'The Chris Mathews Show' The file for this recording is empty."
Trying to "Watch TV" takes some time to error out with pop-up "Error
openeing jump program file buffer"
administrator at mediacenter:/etc/cron.hourly$ ps -ef |grep ivtv
root 958 2 0 Jun09 ? 00:00:00 [ivtv0]
1000 5639 5564 0 06:57 pts/1 00:00:00 grep --color=auto ivtv
Jun 10 07:01:49 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jun 10 07:01:49 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1362 (HandleAnnounce) adding: mediacenter as a client
(events: 0)
Jun 10 07:01:49 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from None to
WatchingLiveTV
Jun 10 07:01:49 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:3459 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jun 10 07:01:49 mediacenter mythbackend[1848]: I TVRecEvent
v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0):
SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jun 10 07:01:49 mediacenter mythbackend[1848]: N CoreContext
autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required
Free Space: 6.0 GB w/freq: 15 min
Jun 10 07:01:50 mediacenter mythbackend[1848]: N CoreContext
autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required
Free Space: 6.0 GB w/freq: 15 min
Jun 10 07:01:50 mediacenter mythbackend[1848]: W RecThread
mpegrecorder.cpp:635 (GetFilteredAudioSampleRate) MPEGRec(/dev/video0):
Audio sample rate 32000 Hz#012#011#011#011is not supported by ivtv
driver, using 48000 Hz instead.
Jun 10 07:02:00 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from
WatchingLiveTV to None
Jun 10 07:02:07 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jun 10 07:02:07 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1362 (HandleAnnounce) adding: mediacenter as a client
(events: 0)
Jun 10 07:02:07 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jun 10 07:02:07 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1362 (HandleAnnounce) adding: mediacenter as a client
(events: 0)
Jun 10 07:02:43 mediacenter mythbackend[1848]: W RecThread
mpegrecorder.cpp:1315 (StartEncoding) MPEGRec(/dev/video0):
StartEncoding failed#012#011#011#011eno: Input/output error (5)
Opened second terminal window and ran cron manually
administrator at mediacenter:/etc/cron.hourly$ sudo
./ivtv_reload_MythTV_0.25_bug.sh
[sudo] password for administrator:
FATAL: Module ivtv is in use.
And log read:
Jun 10 07:03:35 mediacenter mythbackend[1848]: W DeviceReadBuffer
DeviceReadBuffer.cpp:525 (Poll) DevRdB(/dev/video0): Poll took an
unusually long time 52144 ms
Jun 10 07:03:35 mediacenter mythbackend[1848]: E RecThread
mpegrecorder.cpp:1017 (run) MPEGRec(/dev/video0): Device EOF detected
Jun 10 07:03:35 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from None to
WatchingLiveTV
Jun 10 07:03:35 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:3459 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jun 10 07:03:35 mediacenter mythbackend[1848]: I TVRecEvent
v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0):
SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jun 10 07:03:35 mediacenter mythbackend[1848]: N CoreContext
autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required
Free Space: 6.0 GB w/freq: 15 min
Jun 10 07:03:35 mediacenter mythbackend[1848]: W ProcessRequest
mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket
closing MythSocket(0xffffffff9c303af8)
Jun 10 07:03:35 mediacenter mythbackend[1848]: W ProcessRequest
mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket
closing MythSocket(0xffffffffa6a7dfc8)
Jun 10 07:03:35 mediacenter mythbackend[1848]: E ProcessRequest
mythsocket.cpp:358 (writeStringList) MythSocket(ffffffff9c303af8:-1):
writeStringList: Error, socket went unconnected.#012#011#011#011We wrote
0 of 10 bytes with 1 errors#012#011#011#011starts with: 2 ok
Jun 10 07:03:35 mediacenter mythbackend[1848]: E ProcessRequest
mythsocket.cpp:358 (writeStringList) MythSocket(ffffffffa6a7dfc8:-1):
writeStringList: Error, socket went unconnected.#012#011#011#011We wrote
0 of 10 bytes with 1 errors#012#011#011#011starts with: 2 ok
Jun 10 07:03:35 mediacenter mythbackend[1848]: W RecThread
mpegrecorder.cpp:635 (GetFilteredAudioSampleRate) MPEGRec(/dev/video0):
Audio sample rate 32000 Hz#012#011#011#011is not supported by ivtv
driver, using 48000 Hz instead.
Jun 10 07:03:35 mediacenter mythbackend[1848]: I HouseKeeping
housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
Jun 10 07:04:02 mediacenter mythbackend[1848]: N Expire
autoexpire.cpp:640 (SendDeleteMessages) Expiring 0 MB for 1008 at
2012-06-10T07:01:49 => FNG
administrator at mediacenter:/etc/cron.hourly$ ps -ef |grep ivtv
root 958 2 0 Jun09 ? 00:00:00 [ivtv0]
1000 5824 5748 0 07:04 pts/2 00:00:00 grep --color=auto ivtv
Reboot the machine (always temporarily repairs the problem) and can
watch live TV now.
administrator at mediacenter:~$ ps -ef |grep ivtv
root 1000 2 0 07:08 ? 00:00:00 [ivtv0]
1000 3000 2977 0 07:10 pts/1 00:00:00 grep --color=auto ivtv
Tail -f of the backend log yields this during a good Watch TV start and
stop a few seconds latter:
Jun 10 07:11:57 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jun 10 07:11:57 mediacenter mythbackend[1848]: I ProcessRequest
mainserver.cpp:1362 (HandleAnnounce) adding: mediacenter as a client
(events: 0)
Jun 10 07:11:57 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from None to
WatchingLiveTV
Jun 10 07:11:57 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:3459 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jun 10 07:11:57 mediacenter mythbackend[1848]: I TVRecEvent
v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0):
SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jun 10 07:11:57 mediacenter mythbackend[1848]: N CoreContext
autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required
Free Space: 6.0 GB w/freq: 15 min
Jun 10 07:11:58 mediacenter mythbackend[1848]: N CoreContext
autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required
Free Space: 6.0 GB w/freq: 15 min
Jun 10 07:11:58 mediacenter mythbackend[1848]: W RecThread
mpegrecorder.cpp:635 (GetFilteredAudioSampleRate) MPEGRec(/dev/video0):
Audio sample rate 32000 Hz#012#011#011#011is not supported by ivtv
driver, using 48000 Hz instead.
Jun 10 07:12:00 mediacenter mythbackend[1848]: I ProcessRequest
recorderbase.cpp:386 (GetKeyframePositions) RecBase(1:/dev/video0):
GetKeyframePositions(31,9223372036854775807,#1) out of 3
Jun 10 07:12:03 mediacenter mythbackend[1848]: I TVRecEvent
tv_rec.cpp:1014 (HandleStateChange) TVRec(1): Changing from
WatchingLiveTV to None
Jun 10 07:12:03 mediacenter mythbackend[1848]: E DeviceReadBuffer
DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jun 10 07:12:03 mediacenter mythbackend[1848]: E RecThread
mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
Jun 10 07:12:06 mediacenter mythbackend[1848]: E DeviceReadBuffer
DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
More information about the mythtv-users
mailing list