[mythtv-users] Problems changing channel with PVR-250

[Rob Rosenfeld]

I have a PVR-250 and am using the ivtv driver from the 2.6.28 kernel
which according to dmesg loads drivers and firware fine. I can watch
live tv without a problem, but when I try to change channels, mythtv
errors out back to the main menu.  I am connected via s-video and an
external channel changer.  Below is a log from mythbackend -v
general,record,playback,channel,file,socket.  The only unusual item in
the dmesg output, which I don't know if it's applicable is:

CE: hpet increasing min_delta_ns to 22500 nsec.

Any help or suggestions appreciated.

----- Begin mythbackend log -----

2009-02-16 14:12:42.866 MythSocket(809540:35): socket is readable
2009-02-16 14:12:42.867 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:42.868 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:42.871 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.583 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.585 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.586 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.590 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.591 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.600 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.601 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.601 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.603 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.613 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.619 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.630 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.630 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.657 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.658 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.667 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.670 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.679 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.685 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.715 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.715 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.717 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.724 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.725 MPEGRec(/dev/video0): PauseAndWait pause
2009-02-16 14:12:43.730 MythSocket(809540:35): DownRef: 1
2009-02-16 14:12:43.731 MythSocket(809540:35): socket is readable
2009-02-16 14:12:43.739 MPEGRec(/dev/video0): StopEncoding
2009-02-16 14:12:43.748 MythSocket(809540:35): cb->readyRead()
2009-02-16 14:12:43.760 MythSocket(809540:35): UpRef: 2
2009-02-16 14:12:43.790 TVRec(5): SetChannel(28) -- begin
2009-02-16 14:12:43.791 TVRec(5): ClearFlags(RingBufferReady,) ->
FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2009-02-16 14:12:43.792 TVRec(5): Request: Program(no) channel(28)
input() flags(LiveTV,)
2009-02-16 14:12:43.799 ChannelBase(5): Looking for startchannel '28'
on input 'Could not open '/dev/video0' to '
2009-02-16 14:12:43.809 ChannelBase(5): Found startchannel '28' on
input 'Could not open '/dev/video0' to '
2009-02-16 14:12:43.812 TVRec(5): HW Tuner: 5->5
2009-02-16 14:12:43.817 TVRec(5): ClearFlags(PENDINGACTIONS,) ->
FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2009-02-16 14:12:43.823 TVRec(5): Waiting for recorder pause..
2009-02-16 14:12:43.848 TVRec(5): SetFlags(WaitingForRecPause,) ->
FrontendReady,RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning,
2009-02-16 14:12:43.849 TVRec(5): ClearFlags(WaitingForRecPause,) ->
FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,
2009-02-16 14:12:43.849 TVRec(5): Recorder paused, calling TuningFrequency
2009-02-16 14:12:43.858 Channel(/dev/video0)::SwitchToInput(in 7, '')
2009-02-16 14:12:43.865 Channel(/dev/video0): SetInputAndFormat() failed
2009-02-16 14:12:43.869 TVRec(5) Error: Failed to set channel to 28.
Reverting to kState_None
2009-02-16 14:12:43.875 TVRec(5): Changing from WatchingLiveTV to None
2009-02-16 14:12:43.881 TVRec(5):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RecorderRunning,
2009-02-16 14:12:43.915 TVRec(5): Request: Program(no) channel()
input() flags(KillRec,KillRingBuffer,)
2009-02-16 14:12:43.915 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:43.920 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:43.924 MythSocket(809540:35): UpRef: 3
2009-02-16 14:12:43.930 MythSocket(809540:35): DownRef: 2
2009-02-16 14:12:43.954 MPEGRec(/dev/video0): Encoding stopped
2009-02-16 14:12:44.081 MPEGRec(/dev/video0): StartRecording finishing up
2009-02-16 14:12:44.084 MPEGRec(/dev/video0): StopEncoding
2009-02-16 14:12:44.115 MPEGRec(/dev/video0): Encoding stopped
2009-02-16 14:12:44.175 MPEGRec(/dev/video0): SetStreamData(0x0) -- begin
2009-02-16 14:12:44.176 MPEGRec(/dev/video0): SetStreamData(0x0) -- end 0
2009-02-16 14:12:44.177 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,
2009-02-16 14:12:44.208 TVRec(5): FinishedRecording(News All Day) in
recgroup: LiveTV
2009-02-16 14:12:44.210 Chain: Updated endtime for
'3001_20090216141200' to 20090216141244
2009-02-16 14:12:44.210 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:44.211 TVRec(5): ClearFlags(RecorderRunning,) -> RunMainLoop,
2009-02-16 14:12:44.216 TVRec(5): Tearing down RingBuffer
2009-02-16 14:12:44.215 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:44.220 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
2009-02-16 14:12:44.224 MythSocket(809540:35): UpRef: 3
2009-02-16 14:12:44.255 MythSocket(809540:35): DownRef: 2
2009-02-16 14:12:44.281 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:44.282 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:44.287 MythSocket(809540:35): UpRef: 3
2009-02-16 14:12:44.291 MythSocket(809540:35): DownRef: 2
2009-02-16 14:12:44.783 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:44.787 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:44.788 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:44.789 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:44.789 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:44.801 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:44.807 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:44.824 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:50.801 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:50.804 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:50.805 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:50.806 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:50.806 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:50.820 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:50.821 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:50.832 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:50.856 MythSocket(977be0:37): new socket
2009-02-16 14:12:50.859 MythSocket(977be0:37): setSocket: 36
2009-02-16 14:12:50.860 MythSocket(977be0:36): state change Idle -> Connected
2009-02-16 14:12:50.861 MythSocket(977be0:36): UpRef: 1
2009-02-16 14:12:50.868 MythSocket(977be0:36): socket is readable
2009-02-16 14:12:50.875 MythSocket(977be0:36): cb->readyRead()
2009-02-16 14:12:50.906 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:50.907 MythSocket(977be0:36): DownRef: 1
2009-02-16 14:12:50.908 MythSocket(977be0:36): socket is readable
2009-02-16 14:12:50.914 MythSocket(977be0:36): cb->readyRead()
2009-02-16 14:12:50.924 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:50.925 MainServer::ANN Playback
2009-02-16 14:12:50.932 adding: tv as a client (events: 0)
2009-02-16 14:12:50.949 MythSocket(977be0:36): DownRef: 1
2009-02-16 14:12:50.949 MythSocket(977be0:36): socket is readable
2009-02-16 14:12:50.953 MythSocket(977be0:36): cb->readyRead()
2009-02-16 14:12:50.960 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:50.969 TVRec(5): Changing from None to WatchingLiveTV
2009-02-16 14:12:50.974 TVRec(5):
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2009-02-16 14:12:51.006 TVRec(5): Request: Program(no) channel()
input() flags(LiveTV,)
2009-02-16 14:12:51.008 TVRec(5): Start channel: 1.
2009-02-16 14:12:51.015 TVRec(5): HW Tuner: 5->5
2009-02-16 14:12:51.016 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
2009-02-16 14:12:51.017 TVRec(5): No recorder yet, calling TuningFrequency
2009-02-16 14:12:51.026 Channel(/dev/video0): Device name 'Hauppauge
WinTV PVR-250' driver 'ivtv'.
2009-02-16 14:12:51.240 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:51.241 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:51.242 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:51.243 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:51.243 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:51.257 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:51.258 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:51.264 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:51.403 ChannelBase(5): Input #7: 'Could not open
'/dev/video0' to ' schan(1) sourceid(3) ccid(5)
2009-02-16 14:12:51.406 ChannelBase(5): Input #8: 'S-Video 1' schan(1)
sourceid(3) ccid(5)
2009-02-16 14:12:51.407 ChannelBase(5): Current Input #7: 'Could not
open '/dev/video0' to '
2009-02-16 14:12:51.408 Global TVFormat Setting 'NTSC'
2009-02-16 14:12:51.416 Channel(/dev/video0): Input #7: 'Could not
open '/dev/video0' to ' schan(1) tun() v4l1(PAL) v4l2(Unknown)
2009-02-16 14:12:51.423 Channel(/dev/video0): Input #8: 'S-Video 1'
schan(1) tun() v4l1(NTSC) v4l2(NTSC)
2009-02-16 14:12:51.430 Channel(/dev/video0): SetFormat(Default)
fmt(Unknown) input(7)
2009-02-16 14:12:51.438 Channel(/dev/video0)::SwitchToInput(in 8, '')
2009-02-16 14:12:51.478 Channel(/dev/video0): SetInputAndFormat(8,
NTSC) (v4l v2)
2009-02-16 14:12:51.482 Channel(/dev/video0): SetChannelByString(1)
2009-02-16 14:12:51.490 Channel(/dev/video0): SetFormat(Default)
fmt(NTSC) input(8)
2009-02-16 14:12:51.491 External channel change: /usr/local/bin/MyBlaster.pl 1
2009-02-16 14:12:51.493 Waiting for External Tuning program to exit
2009-02-16 14:12:52.491 ret_pid(11053) child(11053) status(0x0)
2009-02-16 14:12:52.492 External Tuning program no longer running
2009-02-16 14:12:52.493 External Tuning program exited with no error
2009-02-16 14:12:52.496 Channel(/dev/video0):
InitPictureAttribute(brightness):  128
                        [    0,  255] dflt(  128, 0.50, 128)
2009-02-16 14:12:52.503 Channel(/dev/video0): InitPictureAttribute(
contrast):  63
                        [    0,  127] dflt(   64, 0.50, 257)
2009-02-16 14:12:52.510 Channel(/dev/video0): InitPictureAttribute(
colour):  63
                        [    0,  127] dflt(   64, 0.50, 257)
2009-02-16 14:12:52.516 Channel(/dev/video0): InitPictureAttribute(
   hue):  0
                        [ -128,  127] dflt(    0, 0.50, 128)
2009-02-16 14:12:52.521 TVRec(5): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,
2009-02-16 14:12:52.557 TVRec(5): SetFlags(NeedToStartRecorder,) ->
RunMainLoop,NeedToStartRecorder,
2009-02-16 14:12:52.558 TVRec(5): Starting Recorder
2009-02-16 14:12:52.608 Using profile 'Live TV' to record
2009-02-16 14:12:52.609 TVRec(5): CreateLiveTVRingBuffer()
2009-02-16 14:12:52.611 TVRec(5): GetProgramRingBufferForLiveTV()
2009-02-16 14:12:52.621 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:52.648 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:52.649 MythSocket(809540:35): UpRef: 3
2009-02-16 14:12:52.650 MythSocket(809540:35): DownRef: 2
2009-02-16 14:12:52.651 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:52.658 MythSocket(977be0:36): UpRef: 3
2009-02-16 14:12:52.665 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:52.671 MythSocket(977be0:36): DownRef: 2
2009-02-16 14:12:52.683 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:52.717 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:52.718 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:52.724 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:52.725 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:53.622 SG(LiveTV): FindNextDirMostFree: Starting'
2009-02-16 14:12:53.625 SG(LiveTV): FindNextDirMostFree: '/mnt/livetv'
has 600624976 KiB free
2009-02-16 14:12:53.626 SG(LiveTV): FindNextDirMostFree: Using '/mnt/livetv'
2009-02-16 14:12:53.639 ProgramInfo: StartedRecording: Recording to
'/mnt/livetv/3001_20090216141252.mpg'
2009-02-16 14:12:53.645 TVRec(5): StartedRecording(0x7f8a840d1de0)
fn(/mnt/livetv/3001_20090216141252.mpg)
2009-02-16 14:12:53.647 MythSocket(8e3610:30): UpRef: 3
2009-02-16 14:12:53.652 Chain: Appended at 0 '3001_20090216141252'
2009-02-16 14:12:53.657 TVRec(5): SetFlags(RingBufferReady,) ->
RunMainLoop,NeedToStartRecorder,RingBufferReady,
2009-02-16 14:12:53.685 RecBase(5:/dev/video0):
SetRingBuffer(0x88ebe0) '/mnt/livetv/3001_20090216141252.mpg'
2009-02-16 14:12:53.690 RecBase(5:/dev/video0): SetRecording(0x97a890)
title(News All Day)
2009-02-16 14:12:53.694 Channel(/dev/video0):
InitPictureAttribute(brightness):  128
                        [    0,  255] dflt(  128, 0.50, 128)
2009-02-16 14:12:53.701 Channel(/dev/video0): InitPictureAttribute(
contrast):  63
                        [    0,  127] dflt(   64, 0.50, 257)
2009-02-16 14:12:53.704 Channel(/dev/video0): InitPictureAttribute(
colour):  63
                        [    0,  127] dflt(   64, 0.50, 257)
2009-02-16 14:12:53.710 Channel(/dev/video0): InitPictureAttribute(
   hue):  0
                        [ -128,  127] dflt(    0, 0.50, 128)
2009-02-16 14:12:53.712 TVRec(5): SetChannel(28) -- end
2009-02-16 14:12:53.719 MythSocket(809540:35): state change Connected -> Idle
2009-02-16 14:12:53.726 MythSocket(809540:-1): cb->connectionClosed()
2009-02-16 14:12:53.712 MPEGRec(/dev/video0): usingv4l2(1)
has_v4l2_vbi(1) has_buggy_vbi(1)
2009-02-16 14:12:53.719 TVRec(5): SetFlags(CancelNextRecording,) ->
RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady,
2009-02-16 14:12:53.767 MythSocket(8e3610:30): DownRef: 2
2009-02-16 14:12:53.767 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:53.767 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:53.767 MythSocket(977be0:36): DownRef: 2
 *********************** WARNING ***********************
 ivtv drivers prior to 0.10.0 can cause lockups when
 reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
 properly capture VBI data on PVR-250 and PVR-350 cards.

2009-02-16 14:12:53.768 MythSocket(809540:-1): writeStringList: Error,
socket went unconnected.
2009-02-16 14:12:53.817 MythSocket(809540:-1): DownRef: 1
2009-02-16 14:12:53.772 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:53.771 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:53.835 MythSocket(977be0:36): DownRef: 1
2009-02-16 14:12:53.841 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:53.847 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:53.847 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:53.847 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:53.851 MPEGRec(/dev/video0): VBI service: 4096, io size: 0
2009-02-16 14:12:53.890 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:53.892 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:53.906 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:53.908 DevRdB(/dev/video0): buffer size 9400 KB
2009-02-16 14:12:53.914 MythSocket(977be0:36): DownRef: 1
2009-02-16 14:12:53.921 MPEGRec(/dev/video0): DRB ready
2009-02-16 14:12:53.956 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:53.958 MPEGRec(/dev/video0): Initial startup of recorder
2009-02-16 14:12:53.958 TVRec(5):
SetFlags(RecorderRunning,RingBufferReady,) ->
RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2009-02-16 14:12:53.967 MPEGRec(/dev/video0): StartEncoding
2009-02-16 14:12:53.975 TVRec(5): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
2009-02-16 14:12:53.985 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:53.985 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:53.994 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.004 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.011 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.011 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.048 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.049 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.053 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.053 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.064 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.071 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.078 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.078 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.115 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.116 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.120 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.120 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.131 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.132 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.139 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.140 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.152 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:54.182 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.183 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:54.307 MPEGRec(/dev/video0): Encoding started
2009-02-16 14:12:54.586 MythSocket(977be0:36): socket is readable
2009-02-16 14:12:54.588 MythSocket(977be0:36): cb->readyRead()
2009-02-16 14:12:54.589 MythSocket(977be0:36): UpRef: 2
2009-02-16 14:12:54.590 MythSocket(977be0:36): DownRef: 1
2009-02-16 14:12:54.591 MythSocket(977be0:36): socket is readable
2009-02-16 14:12:54.601 MythSocket(977be0:36): socket closed
2009-02-16 14:12:54.602 MythSocket(977be0:36): state change Connected -> Idle
2009-02-16 14:12:54.608 MythSocket(977be0:-1): cb->connectionClosed()
2009-02-16 14:12:54.618 TVRec(5): StopLiveTV(void) curRec:
0x7f8a840d1de0 pseudoRec: 0x0
2009-02-16 14:12:54.622 TVRec(5): Changing from WatchingLiveTV to None
2009-02-16 14:12:54.633 TVRec(5):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RecorderRunning,RingBufferReady,
2009-02-16 14:12:54.634 TVRec(5): Request: Program(no) channel()
input() flags(KillRec,KillRingBuffer,)
2009-02-16 14:12:54.665 MPEGRec(/dev/video0): StartRecording finishing up
2009-02-16 14:12:54.689 MPEGRec(/dev/video0): StopEncoding
2009-02-16 14:12:54.892 MPEGRec(/dev/video0): Encoding stopped
2009-02-16 14:12:54.936 MPEGRec(/dev/video0): SetStreamData(0x0) -- begin
2009-02-16 14:12:54.937 MPEGRec(/dev/video0): SetStreamData(0x0) -- end 0
2009-02-16 14:12:54.938 TVRec(5): ClearFlags(RecorderRunning,) ->
RunMainLoop,RingBufferReady,
2009-02-16 14:12:54.969 TVRec(5): FinishedRecording(News All Day) in
recgroup: LiveTV
2009-02-16 14:12:54.972 Chain: Updated endtime for
'3001_20090216141252' to 20090216141254
2009-02-16 14:12:54.975 Finished recording News All Day: channel 3001
2009-02-16 14:12:54.979 scheduler: Finished recording: News All Day:
channel 3001
2009-02-16 14:12:54.984 TVRec(5): ClearFlags(RecorderRunning,) ->
RunMainLoop,RingBufferReady,
2009-02-16 14:12:54.985 TVRec(5): Tearing down RingBuffer
2009-02-16 14:12:54.986 TVRec(5): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady,
2009-02-16 14:12:54.987 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:54.987 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:54.995 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.004 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:55.032 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.036 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.042 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.050 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.050 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.050 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:55.059 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.065 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:55.093 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.094 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.560 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:55.562 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:55.566 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.567 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:55.600 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:55.632 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:55.633 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:55.634 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:56.361 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:56.363 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:56.364 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:56.365 MythSocket(8e3610:30): DownRef: 1
2009-02-16 14:12:56.365 MythSocket(8e3610:30): socket is readable
2009-02-16 14:12:56.377 MythSocket(8e3610:30): cb->readyRead()
2009-02-16 14:12:56.383 MythSocket(8e3610:30): UpRef: 2
2009-02-16 14:12:56.391 MythSocket(8e3610:30): DownRef: 1