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

Mailing List Archive: MythTV: Dev

0.21 Firewire bus reset every 60 seconds?

 

 

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


warlord at MIT

Jun 11, 2008, 6:39 AM

Post #1 of 7 (896 views)
Permalink
0.21 Firewire bus reset every 60 seconds?

Hi,

I was watching the backend logs during the recording of BBC World News
this morning and noticed that it performed a pair of bus resets every
60 seconds. Watching the video now (an hour later) I notice a
video/audio glitch every 60 seconds, but no particular other issues.
I don't recall having this level of firewire issue when I was running
0.20.

Here's an example of the backend log between the 60-second "okay period":

2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87)
2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87): Updating device list -- begin
2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87): Updating device list -- end
2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in 50 msec...
2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in 100 msec...

In the system logs I do see some instances of:

Jun 11 08:21:57 myth-frontend0 kernel: ohci1394: fw-host0: IR DMA error - OHCI error code 0x1d
Jun 11 08:21:57 myth-frontend0 kernel:
Jun 11 08:27:23 myth-frontend0 kernel: ohci1394: fw-host0: IR DMA error - OHCI error code 0x1d

But the timestamps do not correspond at all to the bus resets.

Without looking at the code it LOOKS like there's a 60 second timer
that doesn't get reset properly once the firewire is working properly.

And then, of course, the backend died of a SEGV (sorry, no crash
dump) during a reset:

2008-06-11 08:44:52.108 LFireDev(000E5CFFFE5AF9A5): ResetBus() -- begin
2008-06-11 08:44:52.220 LFireDev(000E5CFFFE5AF9A5): ResetBus() -- end
2008-06-11 08:44:52.324 LFireDev(000E5CFFFE5AF9A5): SignalReset(91->92)
2008-06-11 08:44:52.429 LFireDev(000E5CFFFE5AF9A5): SignalReset(91->92): Updating device list -- begin
2008-06-11 08:44:52.844 LFireDev(000E5CFFFE5AF9A5): SignalReset(91->92): Updating device list -- end

and

Jun 11 08:45:04 myth-frontend0 kernel: mythbackend[4904]: segfault at 00002aaab4856000 rip 000000363767709c rsp 000000004960da68 error 4

-derek

--
Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
Member, MIT Student Information Processing Board (SIPB)
URL: http://web.mit.edu/warlord/ PP-ASEL-IA N1NWH
warlord[at]MIT.EDU PGP key available
_______________________________________________
mythtv-dev mailing list
mythtv-dev[at]mythtv.org
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


warlord at MIT

Jun 16, 2008, 7:49 AM

Post #2 of 7 (770 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

Hi,

Derek Atkins <warlord[at]MIT.EDU> writes:

> Here's an example of the backend log between the 60-second "okay period":
>
> 2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87)
> 2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87): Updating device list -- begin
> 2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87): Updating device list -- end
> 2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in 50 msec...
> 2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in 100 msec...
> ...

After updating libraw1394 from 1.2.1 to 1.3.0 this kind of error seems
to have been reduced.. But it also seems to be a problem if the
firewire broadcast bus isn't completely synched. For example, the
myth box was rebooted yesterday and then the first recording had
this issue. But then I reset the bus manually using firewire_tester
(which, by the way, took two or three tries before it synced!)..
and then my recording this morning was fine.

So something weird is still going on, but at least the libraw upgrade
seems to have helped.

Any other suggestions? My next step is to setup a script to run
firewire_tester on boot-up to make sure the bus is stable before
mythbackend runs.

-derek

--
Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
Member, MIT Student Information Processing Board (SIPB)
URL: http://web.mit.edu/warlord/ PP-ASEL-IA N1NWH
warlord[at]MIT.EDU PGP key available
_______________________________________________
mythtv-dev mailing list
mythtv-dev[at]mythtv.org
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


erictsmith at gmail

Sep 30, 2008, 6:49 AM

Post #3 of 7 (462 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

On Mon, Jun 16, 2008 at 10:49 AM, Derek Atkins <warlord[at]mit.edu> wrote:

> Hi,
>
> Derek Atkins <warlord[at]MIT.EDU> writes:
>
> > Here's an example of the backend log between the 60-second "okay period":
> >
> > 2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87)
> > 2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
> Updating device list -- begin
> > 2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
> Updating device list -- end
> > 2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
> 50 msec...
> > 2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
> 100 msec...
> > ...
>
> After updating libraw1394 from 1.2.1 to 1.3.0 this kind of error seems
> to have been reduced.. But it also seems to be a problem if the
> firewire broadcast bus isn't completely synched. For example, the
> myth box was rebooted yesterday and then the first recording had
> this issue. But then I reset the bus manually using firewire_tester
> (which, by the way, took two or three tries before it synced!)..
> and then my recording this morning was fine.
>
> So something weird is still going on, but at least the libraw upgrade
> seems to have helped.
>
> Any other suggestions? My next step is to setup a script to run
> firewire_tester on boot-up to make sure the bus is stable before
> mythbackend runs.
>
> -derek
>

I also see this issue, somewhat infrequently, but enough to be a problem.
Does anyone have any suggestions on things I could check to resolve it?


warlord at MIT

Oct 1, 2008, 7:22 AM

Post #4 of 7 (447 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

"Eric Smith" <erictsmith[at]gmail.com> writes:

> On Mon, Jun 16, 2008 at 10:49 AM, Derek Atkins <warlord[at]mit.edu> wrote:
>
> Hi,
>
> Derek Atkins <warlord[at]MIT.EDU> writes:
>
> > Here's an example of the backend log between the 60-second "okay
> period":
> >
> > 2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87)
> > 2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
> Updating device list -- begin
> > 2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
> Updating device list -- end
> > 2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
> 50 msec...
> > 2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
> 100 msec...
> > ...
>
> After updating libraw1394 from 1.2.1 to 1.3.0 this kind of error seems
> to have been reduced.. But it also seems to be a problem if the
> firewire broadcast bus isn't completely synched. For example, the
> myth box was rebooted yesterday and then the first recording had
> this issue. But then I reset the bus manually using firewire_tester
> (which, by the way, took two or three tries before it synced!)..
> and then my recording this morning was fine.
>
> So something weird is still going on, but at least the libraw upgrade
> seems to have helped.
>
> Any other suggestions? My next step is to setup a script to run
> firewire_tester on boot-up to make sure the bus is stable before
> mythbackend runs.
>
> -derek
>
> I also see this issue, somewhat infrequently, but enough to be a problem.
> Does anyone have any suggestions on things I could check to resolve it?

I converted over to an external channel change script that runs
firewire tester on every channel change to force a bus synchronization.
This might reset the bus, so it might not work right if you have
multiple firewire devices. The script is based on the script
from http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh
but I had to make a few changes to get it working right for me.

Since I've changed to this script I haven't lost a single recording,
and the number of times I get the sequencial bus resets from myth
has been reduced dramatically (although not completely eliminated --
I've still seen it happen once or twice).

Another side effect of this script is that LiveTV times out when
using this tuner. Apparently the script takes "too long" and
LiveTV gives up too quickly. I haven't looked into extending
the timeout.

-derek

--
Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
Member, MIT Student Information Processing Board (SIPB)
URL: http://web.mit.edu/warlord/ PP-ASEL-IA N1NWH
warlord[at]MIT.EDU PGP key available
_______________________________________________
mythtv-dev mailing list
mythtv-dev[at]mythtv.org
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev


erictsmith at gmail

Oct 1, 2008, 10:37 AM

Post #5 of 7 (444 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

On Wed, Oct 1, 2008 at 10:22 AM, Derek Atkins <warlord[at]mit.edu> wrote:

> "Eric Smith" <erictsmith[at]gmail.com> writes:
>
> > On Mon, Jun 16, 2008 at 10:49 AM, Derek Atkins <warlord[at]mit.edu> wrote:
> >
> > Hi,
> >
> > Derek Atkins <warlord[at]MIT.EDU> writes:
> >
> > > Here's an example of the backend log between the 60-second "okay
> > period":
> > >
> > > 2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5):
> SignalReset(86->87)
> > > 2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5):
> SignalReset(86->87):
> > Updating device list -- begin
> > > 2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5):
> SignalReset(86->87):
> > Updating device list -- end
> > > 2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No
> Input in
> > 50 msec...
> > > 2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No
> Input in
> > 100 msec...
> > > ...
> >
> > After updating libraw1394 from 1.2.1 to 1.3.0 this kind of error
> seems
> > to have been reduced.. But it also seems to be a problem if the
> > firewire broadcast bus isn't completely synched. For example, the
> > myth box was rebooted yesterday and then the first recording had
> > this issue. But then I reset the bus manually using firewire_tester
> > (which, by the way, took two or three tries before it synced!)..
> > and then my recording this morning was fine.
> >
> > So something weird is still going on, but at least the libraw upgrade
> > seems to have helped.
> >
> > Any other suggestions? My next step is to setup a script to run
> > firewire_tester on boot-up to make sure the bus is stable before
> > mythbackend runs.
> >
> > -derek
> >
> > I also see this issue, somewhat infrequently, but enough to be a problem.
> > Does anyone have any suggestions on things I could check to resolve it?
>
> I converted over to an external channel change script that runs
> firewire tester on every channel change to force a bus synchronization.
> This might reset the bus, so it might not work right if you have
> multiple firewire devices. The script is based on the script
> from http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh
> but I had to make a few changes to get it working right for me.
>
> Since I've changed to this script I haven't lost a single recording,
> and the number of times I get the sequencial bus resets from myth
> has been reduced dramatically (although not completely eliminated --
> I've still seen it happen once or twice).
>
> Another side effect of this script is that LiveTV times out when
> using this tuner. Apparently the script takes "too long" and
> LiveTV gives up too quickly. I haven't looked into extending
> the timeout.
>
> -derek
>
> --
> Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
> Member, MIT Student Information Processing Board (SIPB)
> URL: http://web.mit.edu/warlord/ PP-ASEL-IA N1NWH
> warlord[at]MIT.EDU PGP key available
>


I can certainly give that a try and see what happens. I only have the one
box on that firewire bus so that wouldn't be a problem.
However, that's a bit of a band-aid isn't it? I'd be interested to
understand the root cause of the issue. It certainly appears that the data
stream is coming through fine at the point myth issues the reset. So where
is the missing link that is causing myth to issue the reset?


mcunning at yahoo

Oct 2, 2008, 5:40 PM

Post #6 of 7 (427 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

I have this happen ALL the time, it will work great for days and then I'll get recordings that drop about 1 second every 60 seconds.  Sometimes it will go into an endless loop of bus resets for about 20 min and the backend will die.  I cringe when my wife gives me "the look" when we begin to watch one of her favorite shows and it starts to "glitch."

I've tried lots of things to try to get rid of it, nothing seems to have made much of a difference.

- upgrade to more recent kernel - custom build
- swap cable boxes with the cable co (RCN- 6200)
- different cable
- upgraded libraw1394 headers - recompile mythtv
- update raw1394 and other 1394 kernel modules

Right now I have a cron job that runs every hour and checks to see if there is an active recording.  If there is none, it runs firewire_teser -B -n 1.  I also have nagios run an event handler when the backend dies and first runs firewire_tester.  This seems to have helped but really is just a fancy work around.

I noticed that the raw1394 support myth uses seems to be deprecated, and all development seems to be in the new branch (juju?) perhaps migrating to that might be the best way?  Its beyond my programming skills as of today.

I know that thanks to 5c there isn't a whole lot of firewire users out there, but RCN has yet to put it on most of their channels and it is a great way to get content, and its something MCE hasn't been doing as well.  I'm hoping that someone can find a fix for this.

Thanks,
Mike



----- Original Message ----
From: Eric Smith <erictsmith[at]gmail.com>
To: Development of mythtv <mythtv-dev[at]mythtv.org>
Sent: Wednesday, October 1, 2008 1:37:43 PM
Subject: Re: [mythtv] 0.21 Firewire bus reset every 60 seconds?


On Wed, Oct 1, 2008 at 10:22 AM, Derek Atkins <warlord[at]mit.edu> wrote:

"Eric Smith" <erictsmith[at]gmail.com> writes:

> On Mon, Jun 16, 2008 at 10:49 AM, Derek Atkins <warlord[at]mit.edu> wrote:
>
>     Hi,
>
>     Derek Atkins <warlord[at]MIT.EDU> writes:
>
>     > Here's an example of the backend log between the 60-second "okay
>     period":
>     >
>     > 2008-06-11 08:43:37.498 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87)
>     > 2008-06-11 08:43:37.553 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
>     Updating device list -- begin
>     > 2008-06-11 08:43:37.815 LFireDev(000E5CFFFE5AF9A5): SignalReset(86->87):
>     Updating device list -- end
>     > 2008-06-11 08:43:37.917 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
>     50 msec...
>     > 2008-06-11 08:44:37.501 LFireDev(000E5CFFFE5AF9A5), Warning: No Input in
>     100 msec...
>     > ...
>
>     After updating libraw1394 from 1.2.1 to 1.3.0 this kind of error seems
>     to have been reduced..  But it also seems to be a problem if the
>     firewire broadcast bus isn't completely synched.  For example, the
>     myth box was rebooted yesterday and then the first recording had
>     this issue.  But then I reset the bus manually using firewire_tester
>     (which, by the way, took two or three tries before it synced!)..
>     and then my recording this morning was fine.
>
>     So something weird is still going on, but at least the libraw upgrade
>     seems to have helped.
>
>     Any other suggestions?  My next step is to setup a script to run
>     firewire_tester on boot-up to make sure the bus is stable before
>     mythbackend runs.
>
>     -derek
>
> I also see this issue, somewhat infrequently, but enough to be a problem.
> Does anyone have any suggestions on things I could check to resolve it?

I converted over to an external channel change script that runs
firewire tester on every channel change to force a bus synchronization.
This might reset the bus, so it might not work right if you have
multiple firewire devices.   The script is based on the script
from http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh
but I had to make a few changes to get it working right for me.

Since I've changed to this script I haven't lost a single recording,
and the number of times I get the sequencial bus resets from myth
has been reduced dramatically (although not completely eliminated --
I've still seen it happen once or twice).

Another side effect of this script is that LiveTV times out when
using this tuner.  Apparently the script takes "too long" and
LiveTV gives up too quickly.  I haven't looked into extending
the timeout.


-derek

--
      Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
      Member, MIT Student Information Processing Board  (SIPB)
      URL: http://web.mit.edu/warlord/    PP-ASEL-IA     N1NWH
      warlord[at]MIT.EDU                        PGP key available

 
I can certainly give that a try and see what happens.  I only have the one box on that firewire bus so that wouldn't be a problem.
However, that's a bit of a band-aid isn't it?  I'd be interested to understand the root cause of the issue.  It certainly appears that the data stream is coming through fine at the point myth issues the reset.  So where is the missing link that is causing myth to issue the reset?


warlord at MIT

Oct 3, 2008, 6:41 AM

Post #7 of 7 (405 views)
Permalink
Re: 0.21 Firewire bus reset every 60 seconds? [In reply to]

Hi,

"Robert McNamara" <iamlindoro[at]aol.com> writes:

> I'm pretty sure this has become a -users question, but have the affected
> people tried turning off firewire bus reset in mythtv-setup?
>
> General, a few pages in.

That just causes 0-length recordings.

> Robert

-derek

--
Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
Member, MIT Student Information Processing Board (SIPB)
URL: http://web.mit.edu/warlord/ PP-ASEL-IA N1NWH
warlord[at]MIT.EDU PGP key available
_______________________________________________
mythtv-dev mailing list
mythtv-dev[at]mythtv.org
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev

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


Interested in having your list archived? Contact lists@gossamer-threads.com
 
  Web Applications & Managed Hosting Powered by Gossamer Threads Inc.