Login | Register For Free | Help
Search for: (Advanced)

Mailing List Archive: MythTV: Users

MythTV stops/doesn't start recording

 

 

MythTV users RSS feed   Index | Next | Previous | View Threaded


jan at horde

Apr 6, 2009, 1:36 AM

Post #1 of 11 (1773 views)
Permalink
MythTV stops/doesn't start recording

Since a few day I have issues with MythTV sometimes either stopping a
recording after a short time, or not even starting a recording. The
recording appears under recordings, but either it cuts off after a few
minutes, because it's only a few MB large, or the file isn't found at
all.
The backend logs show nothing but a running recording. This is a log
example of an empty recording, i.e. one that doesn't even create a
recording file:

2009-04-06 03:02:01.821 TVRec(1): Changing from None to RecordingOnly
2009-04-06 03:02:01.828 TVRec(1): HW Tuner: 1->1
2009-04-06 03:02:01.887 AutoExpire: CalcParams(): Max required Free
Space: 3.0 G
B w/freq: 15 min
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 e
ncoder: 4
2009-04-06 03:02:02.352 MainServer: HandleRemoteEncoder(cmd GET_STATE)
Unknown e
ncoder: 4
2009-04-06 03:10:01.821 MainServer::HandleAnnounce Monitor
2009-04-06 03:10:01.825 adding: htpc as a client (events: 0)
2009-04-06 03:13:58.814 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/var/lib/mythtv/videos:
2009-04-06 03:13:58.839 UPnpMedia: BuildMediaMap Done. Found 77 objects
2009-04-06 03:13:58.840 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/media/TREKSTOR:
2009-04-06 03:13:58.841 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-04-06 03:13:58.842 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/media/cdrom:
2009-04-06 03:13:58.843 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-04-06 03:16:29.193 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-04-06 03:20:02.396 MainServer::HandleAnnounce Monitor
2009-04-06 03:20:02.403 adding: htpc as a client (events: 0)
2009-04-06 03:30:02.144 MainServer::HandleAnnounce Monitor
2009-04-06 03:30:02.150 adding: htpc as a client (events: 0)
2009-04-06 03:31:29.226 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-04-06 03:40:02.655 MainServer::HandleAnnounce Monitor
2009-04-06 03:40:02.662 adding: htpc as a client (events: 0)
2009-04-06 03:43:58.847 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/var/lib/mythtv/videos:
2009-04-06 03:43:58.871 UPnpMedia: BuildMediaMap Done. Found 77 objects
2009-04-06 03:43:58.876 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/media/TREKSTOR:
2009-04-06 03:43:58.877 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-04-06 03:43:58.877 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/media/cdrom:
2009-04-06 03:43:58.878 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-04-06 03:46:29.254 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-04-06 03:50:02.433 MainServer::HandleAnnounce Monitor
2009-04-06 03:50:02.445 adding: htpc as a client (events: 0)
2009-04-06 03:55:00.936 TVRec(1): Changing from RecordingOnly to None
2009-04-06 03:55:01.093 Finished recording The Mentalist "Tisch 43":
channel 1104

As you can see, nothing suspicious, it looks just like a successful
recording. Syslog is only happily logging cronjobs and network card
messages too.

The only changes I have done to the system during that time, is
keeping up-to-date with the Mythbuntu weekly 0.21 fixes.

Any help is appreciated. WAF has decreased a lot already.

Jan.

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

_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


knowledgejunkie at gmail

Apr 6, 2009, 6:18 AM

Post #2 of 11 (1728 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

On 06/04/2009, Jan Schneider <jan [at] horde> 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 e
> ncoder: 4
> 2009-04-06 03:02:02.352 MainServer: HandleRemoteEncoder(cmd GET_STATE)
> Unknown e
> ncoder: 4

<snip>

>
> As you can see, nothing suspicious, it looks just like a successful

Those error messages look suspicious to me :)

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.

Nick

--
Nick Morrott

MythTV Official wiki:
http://mythtv.org/wiki/
MythTV users list archive:
http://www.gossamer-threads.com/lists/mythtv/users

"An investment in knowledge always pays the best interest." - Benjamin Franklin
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


jan at horde

Apr 6, 2009, 7:05 AM

Post #3 of 11 (1702 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

Zitat von Nick Morrott <knowledgejunkie [at] gmail>:

> On 06/04/2009, Jan Schneider <jan [at] horde> 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 e
>> ncoder: 4
>> 2009-04-06 03:02:02.352 MainServer: HandleRemoteEncoder(cmd GET_STATE)
>> Unknown e
>> ncoder: 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.

> 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.

Jan.

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

_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


mtdean at thirdcontact

Apr 6, 2009, 7:48 AM

Post #4 of 11 (1700 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

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.

Mike
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


jan at horde

Apr 7, 2009, 1:28 PM

Post #5 of 11 (1663 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

Zitat von "Michael T. Dean" <mtdean [at] thirdcontact>:

> 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.

Jan.

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

_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


mtdean at thirdcontact

Apr 7, 2009, 1:54 PM

Post #6 of 11 (1673 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

On 04/07/2009 04:28 PM, Jan Schneider wrote:
> 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.

Then my best guess would be a problem outside of Myth--with the capture
card drivers/capture card/signal/connection/... Look, also, in the
backend log to see if there is any mention of error. Check out the
"Previously Recorded" screen to find why Myth didn't record shows that
it should have.

Mike
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


knowledgejunkie at gmail

Apr 7, 2009, 2:19 PM

Post #7 of 11 (1669 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

On 07/04/2009, Jan Schneider <jan [at] horde> wrote:
> Zitat von "Michael T. Dean" <mtdean [at] thirdcontact>:
>
>
>
> > 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.

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.

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.

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

Nick

--
Nick Morrott

MythTV Official wiki:
http://mythtv.org/wiki/
MythTV users list archive:
http://www.gossamer-threads.com/lists/mythtv/users

"An investment in knowledge always pays the best interest." - Benjamin Franklin
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


jan at horde

Apr 8, 2009, 3:08 PM

Post #8 of 11 (1633 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

Zitat von "Michael T. Dean" <mtdean [at] thirdcontact>:

> On 04/07/2009 04:28 PM, Jan Schneider wrote:
>> 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.
>
> Then my best guess would be a problem outside of Myth--with the
> capture card drivers/capture card/signal/connection/... Look, also,

It has been working fine for weeks, and the signal is great, I have no
other problems with recordings or low quality of recorded stuff.

> in the backend log to see if there is any mention of error. Check

See my next message.

> out the "Previously Recorded" screen to find why Myth didn't record
> shows that it should have.

What it should it say there? Myth *did* record the show, or at least
it thinks so. But the recording file does not exist.

Jan.

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

_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


jan at horde

Apr 8, 2009, 3:20 PM

Post #9 of 11 (1645 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

Zitat von Nick Morrott <knowledgejunkie [at] gmail>:

> On 07/04/2009, Jan Schneider <jan [at] horde> wrote:
>> Zitat von "Michael T. Dean" <mtdean [at] thirdcontact>:
>>
>>
>>
>> > 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] 360
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/

_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users


jarlethorsen at gmail

Apr 16, 2009, 8:13 AM

Post #10 of 11 (1542 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

I have seen similar behaviour on my system (running dvb-s). So far I have
been thinking that there was an issue with multirec (and decryption), and
have now turned off multirec in an effort to get more stable recordings.
Time will show if my assumptions were correct...

--
Jarle Thorsen


jan at horde

Apr 18, 2009, 3:40 AM

Post #11 of 11 (1503 views)
Permalink
Re: MythTV stops/doesn't start recording [In reply to]

Zitat von Jarle Thorsen <jarlethorsen [at] gmail>:

> I have seen similar behaviour on my system (running dvb-s). So far I have been thinking that there was an issue with multirec (and decryption), and have now turned off multirec in an effort to get more stable recordings. Time will show if my assumptions were correct...
>
> --
> Jarle Thorsen

That would be major bummer, because multirec is the only option if you have encrypted channels and don't want to afford yet another card from your greedy providers that think that encrypting all channels (even the free one) over DVB is a great idea. This is a good example of you-do-not-get-what-you-pay-for (talking about the provider here, not the Myth).

Sorry for the rant, but this HTPC adventure has long turned from fun to frustration for me.

Anyway, here is another snippet from the logs. This one I managed to collect from a recording that simply stopped at one point. I have no idea if this is another symptom of the bug that causes recording not to record anything, or a completely different thing.

This was a multirec recording of two encrypted channels, so this seems to work, at least sometimes. This is the log from the moment when one of the two recordings finished, everything is fine at that point:

2009-04-16 23:00:00.234 DVBRec(1:0): Wrote PMT @1459222348 + 1413760
2009-04-16 23:00:00.287 DVBRec(2:0): Wrote PAT @1550144840 + 0
2009-04-16 23:00:00.288 DVBRec(1:0): Wrote PAT @1459251300 + 353440
2009-04-16 23:00:00.309 DVBRec(2:0): Wrote PMT @1550145028 + 1449104
2009-04-16 23:00:00.687 DVBRec(1:0): Wrote PMT @1459402076 + 1343072
2009-04-16 23:00:00.690 TVRec(2): ClearFlags(FinishRecording,) -> FrontendReady,
RunMainLoop,RecorderRunning,RingBufferReady,
2009-04-16 23:00:00.713 TVRec(2): Changing from RecordingOnly to None
2009-04-16 23:00:00.714 TVRec(2): ClearFlags(FrontendReady,CancelNextRecording,)
-> RunMainLoop,RecorderRunning,RingBufferReady,
2009-04-16 23:00:00.717 TVRec(2): Request: Program(no) channel() input() flags(C
loseRec,KillRingBuffer,)
2009-04-16 23:00:00.758 DVBSH(0): RemoveListener(0x9d41a88) -- begin
2009-04-16 23:00:00.759 DVBRec(2:0): Wrote PAT @1550346376 + 1343072
2009-04-16 23:00:00.776 DVBRec(1:0): Wrote PAT @1459440804 + 1767200
2009-04-16 23:00:00.784 DVBSH(0): RemoveListener(0x9d41a88) -- locked
2009-04-16 23:00:00.847 DVBSH(0): RemoveListener(0x9d41a88) -- end
2009-04-16 23:00:00.858 DVBRec(2:0): Close() fd(-1) -- begin
2009-04-16 23:00:00.864 DVBRec(2:0): Close() fd(-1) -- end
2009-04-16 23:00:00.848 DVBSH(0): RemovePIDFilter(0x215)
2009-04-16 23:00:01.017 PIDInfo(0): Closing filter for pid 0x215
2009-04-16 23:00:01.439 DVBSH(0): RemovePIDFilter(0x216)
2009-04-16 23:00:01.448 PIDInfo(0): Closing filter for pid 0x216
2009-04-16 23:00:01.444 TVRec(2): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,
2009-04-16 23:00:01.483 DVBSH(0): RemovePIDFilter(0x217)
2009-04-16 23:00:01.633 PIDInfo(0): Closing filter for pid 0x217
2009-04-16 23:00:01.602 TVRec(2): FinishedRecording(Germany's Next Topmodel - by Heidi Klum) in recgroup: Default
2009-04-16 23:00:01.505 TVRec(2): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady,
2009-04-16 23:00:01.659 DVBSH(0): RemovePIDFilter(0x218)
2009-04-16 23:00:01.883 PIDInfo(0): Closing filter for pid 0x218
2009-04-16 23:00:01.758 Reschedule requested for id 0.
2009-04-16 23:00:01.734 Finished recording Germany's Next Topmodel - by Heidi Klum "10": channel 5040
2009-04-16 23:00:01.977 DVBRec(1:0): Wrote PMT @1459592144 + 848256
2009-04-16 23:00:04.100 Using runtime prefix = /usr
2009-04-16 23:00:02.867 Cardid 1: max bitrate 142089 KB/min
2009-04-16 23:00:04.614 Empty LocalHostName.
2009-04-16 23:00:04.148 TVRec(2): ClearFlags(RecorderRunning,) -> FrontendReady,RunMainLoop,RingBufferReady,
2009-04-16 23:00:04.624 Using localhost value of htpc
2009-04-16 23:00:04.625 TVRec(2): Tearing down RingBuffer
2009-04-16 23:00:04.495 DVBRec(1:0): Wrote PAT @1459620908 + 600848
2009-04-16 23:00:04.681 DVBRec(1:0): Wrote PMT @1459863240 + 494816
2009-04-16 23:00:04.662 Scheduled 127 items in 2.9 = 0.33 match + 2.57 place
2009-04-16 23:00:04.696 New DB connection, total: 1
2009-04-16 23:00:04.658 TVRec(2): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,RingBufferReady,
2009-04-16 23:00:04.696 DVBRec(1:0): Wrote PAT @1459911368 + 353440
2009-04-16 23:00:04.707 Maximal bitrate of busy encoders is 142089 KB/min
2009-04-16 23:00:04.715 DVBRec(1:0): Wrote PMT @1460108580 + 989632
2009-04-16 23:00:04.732 DVBRec(1:0): Wrote PAT @1460146932 + 742224
2009-04-16 23:00:04.733 DVBRec(1:0): Wrote PMT @1460322712 + 883600

Recording on source 1 then continued just fine for a while, until:

2009-04-16 23:06:38.332 DVBRec(1:0): Wrote PAT @1673043584 + 1625824
2009-04-16 23:06:38.736 DVBRec(1:0): Wrote PMT @1673286104 + 1661168
2009-04-16 23:06:38.808 DVBRec(1:0): Wrote PAT @1673334232 + 1237040
2009-04-16 23:06:43.990 DVBRec(1:0): PID 0x203 discontinuity detected

After that, no more DVBRec log entries, until the end of the scheduled recording. Myth just continues as if everything was fine. Though obviously it's not.

2009-04-16 23:14:16.074 AutoExpire: ExpireLiveTV(10000)
2009-04-16 23:14:16.074 AutoExpire: FillDBOrdered: Adding Short LiveTV programsin starttime order
2009-04-16 23:14:16.076 AutoExpire: SendDeleteMessages. Nothing to expire.
2009-04-16 23:15:00.235 TVRec(1): ClearFlags(FinishRecording,) -> FrontendReady,RunMainLoop,RecorderRunning,RingBufferReady,
2009-04-16 23:15:00.240 TVRec(1): Changing from RecordingOnly to None
2009-04-16 23:15:00.240 TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady,
2009-04-16 23:15:00.241 TVRec(1): Request: Program(no) channel() input() flags(CloseRec,KillRingBuffer,)
2009-04-16 23:15:00.243 TVRec(1): FinishedRecording(Bones - Die Knochenj<E4>gerin) in recgroup: Default
2009-04-16 23:15:00.246 Finished recording Bones - Die Knochenj<E4>gerin "Zwei Amerikaner in Gro<DF>britannien": channel 5039
2009-04-16 23:15:00.297 Reschedule requested for id 0.
2009-04-16 23:15:00.319 DVBSH(0): RemoveListener(0xb0f0e238) -- begin
2009-04-16 23:15:00.336 DVBSH(0): RemoveListener(0xb0f0e238) -- locked
2009-04-16 23:15:00.336 DVBSH(0): RemovePIDFilter(0x0)
2009-04-16 23:15:00.340 PIDInfo(0): Closing filter for pid 0x0
2009-04-16 23:15:00.347 DVBSH(0): RemovePIDFilter(0x10)

and so on.

Maybe this helps anyone helping me?

Jan.

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

MythTV users RSS feed   Index | Next | Previous | View Threaded
 
 


Interested in having your list archived? Contact Gossamer Threads
 
  Web Applications & Managed Hosting Powered by Gossamer Threads Inc.