[mythtv-users] MythTV stops/doesn't start recording

Jan Schneider jan at horde.org
Wed Apr 8 22:20:19 UTC 2009 

Zitat von Nick Morrott <knowledgejunkie at gmail.com>:

> On 07/04/2009, Jan Schneider <jan at horde.org> wrote:
>> Zitat von "Michael T. Dean" <mtdean at thirdcontact.com>:
>>
>>
>>
>> > On 04/06/2009 10:05 AM, Jan Schneider wrote:
>> >
>> > > Zitat von Nick Morrott:
>> > >
>> > > > On 06/04/2009, Jan Schneider wrote:
>> > > >
>> > > > > 2009-04-06 03:02:01.891 Started recording: The Mentalist "Tisch 43":
>> > > > > channel 110
>> > > > > 4 on cardid 1, sourceid 1
>> > > > > 2009-04-06 03:02:02.322 MainServer: HandleRemoteEncoder(cmd
>> GET_STATE)
>> > > > > Unknown encoder: 4
>> > > > > 2009-04-06 03:02:02.352 MainServer: HandleRemoteEncoder(cmd
>> GET_STATE)
>> > > > > Unknown encoder: 4
>> > > > >
>> > > > <snip>
>> > > >
>> > > > >  As you can see, nothing suspicious, it looks just like a successful
>> > > > >
>> > > > Those error messages look suspicious to me :)
>> > > >
>> > >
>> > > I get them on recordings that work too. This is a card that is currently
>> not bound to any source.
>> > >
>> >
>> > Really, Myth doesn't support a configuration that includes a card with no
>> connected inputs.  Often, it works when you set up Myth, then--for no
>> apparent reason--at some point in the future, things just stop working.
>> >
>> >
>> > >
>> > > > Have you been adding/removing capture cards recently? You could try
>> > > > Mike Dean's often-used suggestions (start with the capture card
>> > > > section) at
>> http://www.gossamer-threads.com/lists/mythtv/users/264034#264034.
>> > > >
>> > > > You can also increase the logging level with the -v option (see
>> > > > mythbackend's help for all log options) for mythbackend  whilst
>> > > > debugging. You should probably turn on verbose logging for at least
>> > > > record and file.
>> > > >
>> > > I will try that.
>> > >
>> >
>> > And, yeah, if the problem is the defined-but-not-used card, that's the
>> right fix (no need to do the Video Sources portion--just like Nick said).
>> The only qualification is that this time you should /not/ define the card
>> that Myth isn't using.
>> >
>>
>>  Besides that it took me over two hours to get everything up again after
>> deleting the cards (and sources, because mythv ceased to work completely
>> after only re-installing the cards), it didn't change anything.
>>  Sometimes the recordings work, sometimes not. This is really frustrating.
>
> It's more than possible that it could be a distro upgrade causing your
> problems - can you roll back (or at least identidy) the updates you
> have installed since the system started to give you problem? As Mike
> suggested, another possible cause would be a sudden change to the
> signal quality reaching your capture cards.

No, you know how that game goes. There are updates coming in from the  
distro all the time. No idea when exactly this happened (since it  
doesn't happen always) or which packages have been updated before that.

> Do you have multiple capture cards of different types (e.g. ATSC, V4L,
> DVB-T)? If so, is it possible that their device numbers are changing
> after each boot? MythTV will have problems if this is the case - but I
> would have thought this issue would have caused problems before now.

Yes, this was a problem when I installed the machine, but I fixed it  
long ago, and the symptoms are completely different.

> You certainly shouldn't have had to reconfigure your video sources,
> only reconnect them to the newly created capture card inputs on the
> Input Connections page in mythtv-setup.

Tell that Myth. I couldn't tune in to *any* channel, not even run a  
new scan without completely starting from scratch.

> Did the extra 'record' and 'file' mythbackend logging reveal any
> additional information to help?

No, see my logs below. This is a recording session with 3 recordings  
in the row, all right next after each on the same channel. The first  
two worked flawlessly, the 3rd didn't. There is also another recording  
in parallel to the 3rd, on the same transponder, which didn't record  
anything either. And no, this is not the problem, it also happens if  
there is only a single recording on the card.
The logs starts at the end of recording 2, and ends somewhere after  
the start of recording 4. Maybe anyone else can see something that I  
don't see:


2009-04-08 22:14:29.117 TVRec(1): RecordPending on inputid 1
2009-04-08 22:14:29.118 CardUtil:   Group ID 1
2009-04-08 22:14:29.119 CardUtil:   Card ID 2
2009-04-08 22:14:29.120 TVRec(2): RecordPending on inputid 1
2009-04-08 22:14:29.121 CardUtil:   Group ID 1
2009-04-08 22:14:29.121 CardUtil:   Card ID 1
2009-04-08 22:14:29.123 TVRec(2): RecordPending on inputid 2
2009-04-08 22:14:29.123 CardUtil:   Group ID 1
2009-04-08 22:14:29.132 CardUtil:   Card ID 1
2009-04-08 22:14:29.138 TVRec(1): RecordPending on inputid 2
2009-04-08 22:14:29.158 TVRec(1): ASK_RECORDING 1 29 0 0
2009-04-08 22:14:29.175 TVRec(1): ASK_RECORDING 1 29 0 0
2009-04-08 22:14:29.634 TVRec(2): ASK_RECORDING 2 29 0 0
2009-04-08 22:14:29.638 TVRec(2): ASK_RECORDING 2 29 0 0
2009-04-08 22:14:30.147 CardUtil:   Group ID 1
2009-04-08 22:14:30.150 CardUtil:   Card ID 1
2009-04-08 22:14:31.152 CardUtil:   Group ID 1
2009-04-08 22:14:31.153 CardUtil:   Card ID 1
2009-04-08 22:14:32.155 CardUtil:   Group ID 1
2009-04-08 22:14:32.155 CardUtil:   Card ID 1
2009-04-08 22:14:33.157 CardUtil:   Group ID 1
2009-04-08 22:14:33.158 CardUtil:   Card ID 1
2009-04-08 22:14:34.171 CardUtil:   Group ID 1
2009-04-08 22:14:34.172 CardUtil:   Card ID 1
2009-04-08 22:14:35.174 CardUtil:   Group ID 1
2009-04-08 22:14:35.175 CardUtil:   Card ID 1
2009-04-08 22:14:36.176 CardUtil:   Group ID 1
2009-04-08 22:14:36.177 CardUtil:   Card ID 1
2009-04-08 22:14:37.179 CardUtil:   Group ID 1
2009-04-08 22:14:37.180 CardUtil:   Card ID 1
2009-04-08 22:14:38.182 CardUtil:   Group ID 1
2009-04-08 22:14:38.183 CardUtil:   Card ID 1
2009-04-08 22:14:39.184 CardUtil:   Group ID 1
2009-04-08 22:14:39.185 CardUtil:   Card ID 1
2009-04-08 22:14:40.191 CardUtil:   Group ID 1
2009-04-08 22:14:40.192 CardUtil:   Card ID 1
2009-04-08 22:14:41.194 CardUtil:   Group ID 1
2009-04-08 22:14:41.195 CardUtil:   Card ID 1
2009-04-08 22:14:42.197 CardUtil:   Group ID 1
2009-04-08 22:14:42.197 CardUtil:   Card ID 1
2009-04-08 22:14:43.207 CardUtil:   Group ID 1
2009-04-08 22:14:43.208 CardUtil:   Card ID 1
2009-04-08 22:14:44.222 CardUtil:   Group ID 1
2009-04-08 22:14:44.223 CardUtil:   Card ID 1
2009-04-08 22:14:45.224 CardUtil:   Group ID 1
2009-04-08 22:14:45.225 CardUtil:   Card ID 1
2009-04-08 22:14:46.227 CardUtil:   Group ID 1
2009-04-08 22:14:46.228 CardUtil:   Card ID 1
2009-04-08 22:14:47.230 CardUtil:   Group ID 1
2009-04-08 22:14:47.231 CardUtil:   Card ID 1
2009-04-08 22:14:48.233 CardUtil:   Group ID 1
2009-04-08 22:14:48.234 CardUtil:   Card ID 1
2009-04-08 22:14:49.247 CardUtil:   Group ID 1
2009-04-08 22:14:49.248 CardUtil:   Card ID 1
2009-04-08 22:14:50.250 CardUtil:   Group ID 1
2009-04-08 22:14:50.251 CardUtil:   Card ID 1
2009-04-08 22:14:51.253 CardUtil:   Group ID 1
2009-04-08 22:14:51.254 CardUtil:   Card ID 1
2009-04-08 22:14:52.256 CardUtil:   Group ID 1
2009-04-08 22:14:52.257 CardUtil:   Card ID 1
2009-04-08 22:14:53.263 CardUtil:   Group ID 1
2009-04-08 22:14:53.265 CardUtil:   Card ID 1
2009-04-08 22:14:54.267 CardUtil:   Group ID 1
2009-04-08 22:14:54.268 CardUtil:   Card ID 1
2009-04-08 22:14:55.270 CardUtil:   Group ID 1
2009-04-08 22:14:55.271 CardUtil:   Card ID 1
2009-04-08 22:14:56.272 CardUtil:   Group ID 1
2009-04-08 22:14:56.273 CardUtil:   Card ID 1
2009-04-08 22:14:57.275 CardUtil:   Group ID 1
2009-04-08 22:14:57.276 CardUtil:   Card ID 1
2009-04-08 22:14:58.278 CardUtil:   Group ID 1
2009-04-08 22:14:58.279 CardUtil:   Card ID 1
2009-04-08 22:14:59.281 CardUtil:   Group ID 1
2009-04-08 22:14:59.282 CardUtil:   Card ID 1
2009-04-08 22:15:00.291 CardUtil:   Group ID 1
2009-04-08 22:15:00.292 CardUtil:   Card ID 1
2009-04-08 22:15:01.294 CardUtil:   Group ID 1
2009-04-08 22:15:01.295 CardUtil:   Card ID 1
2009-04-08 22:15:02.296 TVRec(1): StartRecording(Emergency Room - Die  
Notaufnahme)
2009-04-08 22:15:02.300 TVRec(1): Checking input group recorders - begin
2009-04-08 22:15:02.301 TVRec(1): Checking input group recorders - done
2009-04-08 22:15:02.302 TVRec(1): Changing from RecordingOnly to None
2009-04-08 22:15:02.307 TVRec(1):  
ClearFlags(FrontendReady,CancelNextRecording,) ->  
RunMainLoop,RecorderRunning,RingBufferReady,
2009-04-08 22:15:02.307 TVRec(1): Request: Program(no) channel()  
input() flags(CloseRec,KillRingBuffer,)
2009-04-08 22:15:02.312 TVRec(1): FinishedRecording(Private Practice)  
in recgroup: Default
2009-04-08 22:15:02.313 Finished recording Private Practice "Es ist,  
wie es ist": channel 5040
2009-04-08 22:15:02.347 DVBSH(0): RemoveListener(0xb07f1850) -- begin
2009-04-08 22:15:02.350 DVBSH(0): RemoveListener(0xb07f1850) -- locked
2009-04-08 22:15:02.370 DVBSH(0): RunTS(): shutdown
2009-04-08 22:15:02.374 DVBSH(0): RemovePIDFilter(0x0)
2009-04-08 22:15:02.374 PIDInfo(0): Closing filter for pid 0x0
2009-04-08 22:15:02.381 DVBSH(0): RemovePIDFilter(0x10)
2009-04-08 22:15:02.382 PIDInfo(0): Closing filter for pid 0x10
2009-04-08 22:15:02.401 DVBSH(0): RemovePIDFilter(0x11)
2009-04-08 22:15:02.414 PIDInfo(0): Closing filter for pid 0x11
2009-04-08 22:15:02.437 DVBSH(0): RemovePIDFilter(0x14)
2009-04-08 22:15:02.438 PIDInfo(0): Closing filter for pid 0x14
2009-04-08 22:15:02.465 DVBSH(0): RemovePIDFilter(0x101)
2009-04-08 22:15:02.470 PIDInfo(0): Closing filter for pid 0x101
2009-04-08 22:15:02.477 DVBSH(0): RemovePIDFilter(0x102)
2009-04-08 22:15:02.478 PIDInfo(0): Closing filter for pid 0x102
2009-04-08 22:15:02.509 DVBSH(0): RemovePIDFilter(0x103)
2009-04-08 22:15:02.514 PIDInfo(0): Closing filter for pid 0x103
2009-04-08 22:15:02.525 DVBSH(0): RemovePIDFilter(0x104)
2009-04-08 22:15:02.526 PIDInfo(0): Closing filter for pid 0x104
2009-04-08 22:15:02.541 DVBSH(0): RemovePIDFilter(0x105)
2009-04-08 22:15:02.543 PIDInfo(0): Closing filter for pid 0x105
2009-04-08 22:15:02.557 DVBSH(0): RemovePIDFilter(0x106)
2009-04-08 22:15:02.569 PIDInfo(0): Closing filter for pid 0x106
2009-04-08 22:15:02.577 DVBSH(0): RemovePIDFilter(0x107)
2009-04-08 22:15:02.581 PIDInfo(0): Closing filter for pid 0x107
2009-04-08 22:15:02.593 DVBSH(0): RemovePIDFilter(0x108)
2009-04-08 22:15:02.594 PIDInfo(0): Closing filter for pid 0x108
2009-04-08 22:15:02.617 DVBSH(0): RemovePIDFilter(0x109)
2009-04-08 22:15:02.618 PIDInfo(0): Closing filter for pid 0x109
2009-04-08 22:15:02.633 DVBSH(0): RemovePIDFilter(0x10a)
2009-04-08 22:15:02.634 PIDInfo(0): Closing filter for pid 0x10a
2009-04-08 22:15:02.649 DVBSH(0): RemovePIDFilter(0x10b)
2009-04-08 22:15:02.650 PIDInfo(0): Closing filter for pid 0x10b
2009-04-08 22:15:02.673 DVBSH(0): RemovePIDFilter(0x215)
2009-04-08 22:15:02.681 PIDInfo(0): Closing filter for pid 0x215
2009-04-08 22:15:02.689 DVBSH(0): RemovePIDFilter(0x216)
2009-04-08 22:15:02.690 PIDInfo(0): Closing filter for pid 0x216
2009-04-08 22:15:02.705 DVBSH(0): RemovePIDFilter(0x217)
2009-04-08 22:15:02.713 PIDInfo(0): Closing filter for pid 0x217
2009-04-08 22:15:02.729 DVBSH(0): RemovePIDFilter(0x218)
2009-04-08 22:15:02.736 PIDInfo(0): Closing filter for pid 0x218
2009-04-08 22:15:02.743 DVBSH(0): RunTS(): end
2009-04-08 22:15:02.743 DVBSH(0): RemoveListener(0xb07f1850) -- end
2009-04-08 22:15:02.750 DVBRec(1:0): Close() fd(-1) -- begin
2009-04-08 22:15:02.769 DVBRec(1:0): Close() fd(-1) -- end
2009-04-08 22:15:02.924 TVRec(1): ClearFlags(RecorderRunning,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:02.980 TVRec(1): FinishedRecording(Private Practice)  
in recgroup: Default
2009-04-08 22:15:02.983 AutoExpire: Adding Programs to 'Do Not Expire' List
2009-04-08 22:15:02.983 Finished recording Private Practice "Es ist,  
wie es ist": channel 5040
2009-04-08 22:15:02.986     5040 @ 2009-04-08T21:15:00 in use by  
recorder on htpc
2009-04-08 22:15:03.041 TVRec(1): ClearFlags(RecorderRunning,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.074 TVRec(1): Tearing down RingBuffer
2009-04-08 22:15:03.073 Using runtime prefix = /usr
2009-04-08 22:15:03.075 TVRec(1): ClearFlags(PENDINGACTIONS,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.094 TVRec(1): ClearFlags(CancelNextRecording,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.094 Empty LocalHostName.
2009-04-08 22:15:03.147 Using localhost value of htpc
2009-04-08 22:15:03.148 ProgramInfo: StartedRecording: Recording to  
'/var/lib/mythtv/recordings/5040_20090408221500.mpg'
2009-04-08 22:15:03.153 New DB connection, total: 1
2009-04-08 22:15:03.219 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:03.222 Closing DB connection named 'DBManager0'
2009-04-08 22:15:03.232 TVRec(1): StartedRecording(0xb077e768)  
fn(/var/lib/mythtv/recordings/5040_20090408221500.mpg)
2009-04-08 22:15:03.233 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:03.257 TVRec(1): ClearFlags(CancelNextRecording,) ->  
RunMainLoo
p,RingBufferReady,
2009-04-08 22:15:03.266 New DB connection, total: 2
2009-04-08 22:15:03.266 TVRec(1): Changing from None to RecordingOnly
2009-04-08 22:15:03.279 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:03.291 TVRec(1):  
ClearFlags(FrontendReady,CancelNextRecording,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.323 TVRec(1): Request: Program(yes) channel()  
input() flags(Recording,)
2009-04-08 22:15:03.325 TVRec(1): HW Tuner: 1->1
2009-04-08 22:15:03.295 Current Schema Version: 1214
2009-04-08 22:15:03.335 TVRec(1): ClearFlags(PENDINGACTIONS,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.368 TVRec(1): No recorder yet, calling TuningFrequency
2009-04-08 22:15:03.487 TVRec(1): Starting Signal Monitor
2009-04-08 22:15:03.487 TVRec(1): SetupSignalMonitor(1, 0)
2009-04-08 22:15:03.494 TVRec(1): Signal monitor successfully created
2009-04-08 22:15:03.512 TVRec(1): Setting up table monitoring.
2009-04-08 22:15:03.525 Using profile 'Live TV' to record
2009-04-08 22:15:03.526 TVRec(1): DVB service_id 12103 on net_id 9999 tsid 121
2009-04-08 22:15:03.534 TVRec(1): Successfully set up DVB table monitoring.
2009-04-08 22:15:03.556 TVRec(1): SetFlags(SignalMonitorRunning,) ->  
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:03.557 TVRec(1): ClearFlags(WaitingForSignal,) ->  
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:03.621 TVRec(1): SetFlags(WaitingForSignal,) ->  
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:03.623 TVRec(1): ClearFlags(NeedToStartRecorder,) ->  
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:03.630 TVRec(1): SetFlags(NeedToStartRecorder,) ->  
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:03.639 AutoExpire: Cardid 1: is starting a recording  
on an unknown fsID soon.
2009-04-08 22:15:03.658 AutoExpire: CalcParams()
2009-04-08 22:15:03.679 Cardid 1: max bitrate 142089 KB/min
2009-04-08 22:15:03.681 Cardid 2: max bitrate 142089 KB/min
2009-04-08 22:15:03.700 Maximal bitrate of busy encoders is 284178 KB/min
--- GetFilesystemInfos directory list start ---
Dir: htpc:/var/lib/mythtv/recordings
      Location: Local
      fsID    : 1
      dirID   : 6
      TotalKB : 475537480
      UsedKB  : 113493124
      FreeKB  : 362044356

--- GetFilesystemInfos directory list end ---
2009-04-08 22:15:03.738 fsID #1: Total:   453.5 GB   Used:   108.2 GB   
  Free:345.3 GB
2009-04-08 22:15:03.757     Cardid 1: max bitrate 18945 Kb/sec, fsID 1  
max is now 142089 KB/min
2009-04-08 22:15:03.767   Max of 142089 KB/min for fsID 1 is higher  
than the existing Max of 0 so we'll use this Max instead
2009-04-08 22:15:03.779 AutoExpire: CalcParams(): Max required Free  
Space: 3.0 GB w/freq: 15 min
2009-04-08 22:15:03.787 Started recording: Emergency Room - Die  
Notaufnahme "Nur ein kleines M<E4>dchen": channel 5040 on cardid 1,  
sourceid 1
2009-04-08 22:15:03.801 CardUtil:   Group ID 1
2009-04-08 22:15:03.846 CardUtil:   Card ID 1
2009-04-08 22:15:03.848 TVRec(2): StartRecording(Snatch - Schweine und  
Diamanten)
2009-04-08 22:15:03.849 TVRec(2): Checking input group recorders - begin
2009-04-08 22:15:03.855 TVRec(2): Checking input group recorders - done
2009-04-08 22:15:03.879 ProgramInfo: StartedRecording: Recording to  
'/var/lib/mythtv/recordings/5042_20090408221500.mpg'
2009-04-08 22:15:03.882 TVRec(2): StartedRecording(0xb077e640)  
fn(/var/lib/mythtv/recordings/5042_20090408221500.mpg)
2009-04-08 22:15:03.883 TVRec(2): ClearFlags(CancelNextRecording,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.883 TVRec(2): Changing from None to RecordingOnly
2009-04-08 22:15:03.890 TVRec(2):  
ClearFlags(FrontendReady,CancelNextRecording,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.901 TVRec(2): Request: Program(yes) channel()  
input() flags(Recording,)
2009-04-08 22:15:03.907 TVRec(2): HW Tuner: 2->2
2009-04-08 22:15:03.934 TVRec(2): ClearFlags(PENDINGACTIONS,) ->  
RunMainLoop,RingBufferReady,
2009-04-08 22:15:03.945 TVRec(2): No recorder yet, calling TuningFrequency
2009-04-08 22:15:04.127 TVRec(2): Starting Signal Monitor
2009-04-08 22:15:04.129 TVRec(2): SetupSignalMonitor(1, 0)
2009-04-08 22:15:04.136 TVRec(2): Signal monitor successfully created
2009-04-08 22:15:04.155 TVRec(2): Setting up table monitoring.
2009-04-08 22:15:04.157 Using profile 'Live TV' to record
2009-04-08 22:15:04.167 TVRec(2): DVB service_id 12106 on net_id 9999 tsid 121
2009-04-08 22:15:04.178 TVRec(2): Successfully set up DVB table monitoring.
2009-04-08 22:15:04.200 TVRec(2): SetFlags(SignalMonitorRunning,) ->  
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:04.200 TVRec(2): ClearFlags(WaitingForSignal,) ->  
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:04.222 TVRec(2): SetFlags(WaitingForSignal,) ->  
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:04.222 TVRec(2): ClearFlags(NeedToStartRecorder,) ->  
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:04.234 TVRec(2): SetFlags(NeedToStartRecorder,) ->  
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2009-04-08 22:15:04.246 AutoExpire: Cardid 2: is starting a recording  
on an unknown fsID soon.
2009-04-08 22:15:04.266 AutoExpire: CalcParams()
2009-04-08 22:15:04.268 Cardid 1: max bitrate 142089 KB/min
2009-04-08 22:15:04.279 Cardid 2: max bitrate 142089 KB/min
2009-04-08 22:15:04.286 Maximal bitrate of busy encoders is 284178 KB/min
--- GetFilesystemInfos directory list start ---
Dir: htpc:/var/lib/mythtv/recordings
      Location: Local
      fsID    : 1
      dirID   : 6
      TotalKB : 475537480
      UsedKB  : 113493124
      FreeKB  : 362044356

--- GetFilesystemInfos directory list end ---
2009-04-08 22:15:04.388 fsID #1: Total:   453.5 GB   Used:   108.2 GB   
  Free:345.3 GB
2009-04-08 22:15:04.389     Cardid 1: max bitrate 18945 Kb/sec, fsID 1  
max is now 142089 KB/min
2009-04-08 22:15:04.401     Cardid 2: max bitrate 18945 Kb/sec, fsID 1  
max is now 284178 KB/min
2009-04-08 22:15:04.406   Max of 284178 KB/min for fsID 1 is higher  
than the existing Max of 0 so we'll use this Max instead
2009-04-08 22:15:04.414 AutoExpire: CalcParams(): Max required Free  
Space: 3.0 GB w/freq: 8 min
2009-04-08 22:15:04.433 Started recording: Snatch - Schweine und  
Diamanten: channel 5042 on cardid 2, sourceid 1
2009-04-08 22:15:05.441 Reschedule requested for id 0.
2009-04-08 22:15:05.687 Scheduled 129 items in 0.2 = 0.02 match + 0.23 place
2009-04-08 22:15:05.799 AFD: Opened codec 0x8a66d80, id(MPEG2VIDEO)  
type(Video)
2009-04-08 22:15:05.944 AFD: codec MP3 has 2 channels
2009-04-08 22:15:05.949 AFD: Opened codec 0x8a67370, id(MP3) type(Audio)
2009-04-08 22:15:05.950 AFD: codec AC3 has 6 channels
2009-04-08 22:15:05.951 AFD: Opened codec 0x8a67960, id(AC3) type(Audio)
2009-04-08 22:15:06.135 Preview: Grabbed preview  
'/var/lib/mythtv/recordings/5040_20090408211500.mpg' 720x576 at 360s
2009-04-08 22:15:07.232 DVBSH(0): AddListener(0xaa57b750) -- begin
2009-04-08 22:15:07.240 DVBSH(0): AddListener(0xaa57b750) -- locked
2009-04-08 22:15:07.262 DVBSH(0): AddListener(0xaa57b750) -- end
2009-04-08 22:15:07.266 DVBSH(0): RunTS(): begin
2009-04-08 22:15:07.270 DVBSH(0): AddPIDFilter(0x0) priority 2
2009-04-08 22:15:07.270 PIDInfo(0): Opening filter for pid 0x0
2009-04-08 22:15:07.277 DVBSH(0): AddPIDFilter(0x10) priority 2
2009-04-08 22:15:07.278 PIDInfo(0): Opening filter for pid 0x10
2009-04-08 22:15:07.285 DVBSH(0): AddPIDFilter(0x11) priority 2
2009-04-08 22:15:07.290 DVBSH(0): AddListener(0xb07b8c50) -- begin
2009-04-08 22:15:07.356 PIDInfo(0): Opening filter for pid 0x11
2009-04-08 22:15:07.363 DVBSH(0): AddListener(0xb07b8c50) -- locked
2009-04-08 22:15:07.378 DVBSH(0): AddListener(0xb07b8c50) -- end
2009-04-08 22:15:07.373 DVBSH(0): AddPIDFilter(0x14) priority 2
2009-04-08 22:15:07.382 PIDInfo(0): Opening filter for pid 0x14
2009-04-08 22:15:52.075 JobQueue: Commercial Flagging Starting for  
Private Practice "Es ist, wie es ist" recorded from channel 5040 at Mi  
Apr 8 21:15:00 2009
2009-04-08 22:15:52.188 Using runtime prefix = /usr
2009-04-08 22:15:52.192 Empty LocalHostName.
2009-04-08 22:15:52.204 Using localhost value of htpc
2009-04-08 22:15:52.211 New DB connection, total: 1
2009-04-08 22:15:52.220 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:52.221 Closing DB connection named 'DBManager0'
2009-04-08 22:15:52.222 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:52.226 New DB connection, total: 2
2009-04-08 22:15:52.226 Connected to database 'mythconverg' at host: localhost
2009-04-08 22:15:52.228 SG(Default): FindRecordingFile: Searching for  
'5040_20090408211500.mpg'
2009-04-08 22:15:52.229 SG(Default): FindRecordingDir: Checking  
'/var/lib/mythtv/recordings'
2009-04-08 22:15:52.229 SG(Default): FindRecordingFile: Found  
'/var/lib/mythtv/recordings/5040_20090408211500.mpg'
2009-04-08 22:15:52.230 ProgramInfo: GetPlaybackURL: File is local:  
'/var/lib/mythtv/recordings/5040_20090408211500.mpg'
2009-04-08 22:15:52.238 Connecting to backend server:  
192.168.60.10:6543 (try 1of 5)
2009-04-08 22:15:52.260 Using protocol version 40
2009-04-08 22:15:52.261 MainServer::HandleAnnounce Monitor
2009-04-08 22:15:52.262 adding: htpc as a client (events: 0)
2009-04-08 22:15:52.263 MainServer::HandleAnnounce Monitor
2009-04-08 22:15:52.264 adding: htpc as a client (events: 1)
2009-04-08 22:15:54.338  
RingBuf(/var/lib/mythtv/recordings/5040_20090408211500.mpg): Waited  
2.0 seconds for data to become available...
2009-04-08 22:15:54.740 AFD: Opened codec 0x8dfc170, id(MPEG2VIDEO)  
type(Video)
2009-04-08 22:15:54.740 AFD: codec MP3 has 2 channels
2009-04-08 22:15:54.767 AFD: Opened codec 0x8dfc760, id(MP3) type(Audio)
2009-04-08 22:15:54.767 AFD: codec AC3 has 6 channels
2009-04-08 22:15:54.779 AFD: Opened codec 0x8dfcd50, id(AC3) type(Audio)
2009-04-08 22:15:59.017 [mpeg2video @ 0xb736d744]ac-tex damaged at 24 31
2009-04-08 22:15:59.038 [mpeg2video @ 0xb736d744]Warning MVs not available
2009-04-08 22:16:03.045 AutoExpire: Adding Programs to 'Do Not Expire' List
2009-04-08 22:16:03.048     5040 @ 2009-04-08T21:15:00 in use by  
flagger on htpc
2009-04-08 22:16:03.049     5042 @ 2009-04-08T22:15:00 in use by  
recorder on htpc
2009-04-08 22:16:03.052     5040 @ 2009-04-08T22:15:00 in use by  
recorder on htpc
2009-04-08 22:16:03.053 AutoExpire: ExpireLiveTV(10000)
2009-04-08 22:16:03.078 AutoExpire: FillDBOrdered: Adding Short LiveTV  
programsin starttime order
2009-04-08 22:16:03.079 AutoExpire: SendDeleteMessages. Nothing to expire.
2009-04-08 22:17:03.080 AutoExpire: Adding Programs to 'Do Not Expire' List
2009-04-08 22:17:03.086     5040 @ 2009-04-08T21:15:00 in use by  
flagger on htpc
2009-04-08 22:17:03.087     5042 @ 2009-04-08T22:15:00 in use by  
recorder on htpc
2009-04-08 22:17:03.089     5040 @ 2009-04-08T22:15:00 in use by  
recorder on htpc
2009-04-08 22:17:03.137 [mpeg2video @ 0xb736d744]ac-tex damaged at 24 31
2009-04-08 22:17:57.457 [mpeg2video @ 0xb736d744]mb incr damaged
2009-04-08 22:17:57.464 [mpeg2video @ 0xb736d744]Warning MVs not available


It then continues with AutoExpire calls every minute and random  
mpeg2video messages from the commercial flagging of recording 2.

Jan.

-- 
Do you need professional PHP or Horde consulting?
http://horde.org/consulting/

More information about the mythtv-users mailing list