[mythtv-users] IOBOUND errors after kernel upgrade from 2.6.31 to 2.6.32

Tom Dexter digitalaudiorock at gmail.com
Tue May 4 01:44:43 UTC 2010


On Wed, Apr 28, 2010 at 11:01 AM, John Drescher <drescherjm at gmail.com> wrote:
>>
> I am using gentoo sources as well. I have not had much issue with
> kernels that were not marked stable.
>(deleted)
>
> /dev/mapper/vg1-portage on /usr/portage type ext4
> (rw,commit=60,barrier=0,data=writeback)
>(deleted)
>
> John

Wow...I'm am totally at my whits end with this.  The performance issue
I mentioned earlier caused by that one change actually went in the
2.6.31-r10, but I was running 2.6.31-r6.  If that were the issue, the
nobarrier option would have fixed it, but it didn't.

I had up until now been running with default ext4 settings...that is
with data=ordered and commit=5.  I changed those to ones like yours
assuming that would make a pretty big performance improvement, but I'm
*still* getting those IOBOUND errors and they're chopping sections out
of my recordings...and I'm seriously pissed.  Tonight I got one while
only on recording was in progress

After tonight I'm going to boot back to 2.6.31 and watch it for a
while.  Really really annoying.  These are all from the same kernel
configs updated with make oldconfig.  The only really significant
change I see in 2.6.32 seems to be in the RCU subsystem.  The old
setting of CONFIG_CLASSIC_RCU=y seems to have been replaced with the
default of CONFIG_TREE_RCU=y...not sure it that could have made a
difference.

As I said, my setup having the OS and database on a drive shared by
that LVM is not ideal, but It's worked fine up until now.  My gut
tells me that some change in EXT4, or maybe something else, in 2.6.32
changed something in performance just enough to make my setup
unreliable.

I was just looking more carefully at my logs from tonight right before
the IOBOUND errors, and I'm setting something I don't get at all:

2010-05-03 20:59:30.051 TVRec(1): ASK_RECORDING 1 29 0 0
2010-05-03 20:59:35.387 AutoExpire: CalcParams(): Max required Free
Space: 7.0 GB w/freq: 5 min
2010-05-03 21:00:00.278 TVRec(1): Changing from Watching RecordingOnly to None
2010-05-03 21:00:00.378 Finished recording House "The Choice": channel 1051
2010-05-03 21:00:00.420 TVRec(3): Changing from Watching RecordingOnly to None
2010-05-03 21:00:00.496 Recording designated 720p because width was 1280
2010-05-03 21:00:00.500 Finished recording Chuck "Chuck Versus the
Role Models": channel 1041
2010-05-03 21:00:00.551 Recording designated 1080i/p because width was 1920
2010-05-03 21:00:00.629 TVRec(2): Changing from Watching RecordingOnly to None
2010-05-03 21:00:00.653 Finished recording Antiques Roadshow "Phoenix,
AZ": channel 1131
2010-05-03 21:00:00.656 Recording designated 1080i/p because width was 1920
2010-05-03 21:00:01.429 Finished recording Chuck "Chuck Versus the
Role Models": channel 1041
2010-05-03 21:00:01.436 Finished recording Antiques Roadshow "Phoenix,
AZ": channel 1131
2010-05-03 21:00:01.437 Finished recording House "The Choice": channel 1051
2010-05-03 21:00:02.358 MainServer::ANN Monitor
2010-05-03 21:00:02.433 adding: mythfront as a client (events: 0)
2010-05-03 21:00:02.706 TVRec(1): Changing from None to Watching RecordingOnly
2010-05-03 21:00:02.718 TVRec(1): HW Tuner: 1->1
2010-05-03 21:00:03.293 AutoExpire: CalcParams(): Max required Free
Space: 7.0 GB w/freq: 15 min
2010-05-03 21:00:03.378 Started recording: American Experience "Roads
to Memphis": channel 1131 on cardid 1, sourceid 1
2010-05-03 21:00:04.513 Reschedule requested for id 0.
2010-05-03 21:00:05.831 Scheduled 87 items in 1.3 = 0.04 match + 1.24 place
2010-05-03 21:00:06.266 mythbackend version:  [23069] www.mythtv.org
2010-05-03 21:00:06.267 mythbackend version:  [23069] www.mythtv.org
2010-05-03 21:00:06.269 mythbackend version:  [23069] www.mythtv.org
2010-05-03 21:00:06.270 Using runtime prefix = /usr
2010-05-03 21:00:06.270 Using runtime prefix = /usr
2010-05-03 21:00:06.271 Using configuration directory = /etc/mythtv/.mythtv
2010-05-03 21:00:06.271 Using configuration directory = /etc/mythtv/.mythtv
2010-05-03 21:00:06.272 Using runtime prefix = /usr
2010-05-03 21:00:06.272 Using configuration directory = /etc/mythtv/.mythtv
2010-05-03 21:00:06.311 Empty LocalHostName.
2010-05-03 21:00:06.312 Empty LocalHostName.
2010-05-03 21:00:06.312 Empty LocalHostName.
2010-05-03 21:00:06.338 Using localhost value of mythback
2010-05-03 21:00:06.338 Using localhost value of mythback
2010-05-03 21:00:06.338 Using localhost value of mythback
2010-05-03 21:00:06.713 New DB connection, total: 1
2010-05-03 21:00:06.714 New DB connection, total: 1
2010-05-03 21:00:06.714 New DB connection, total: 1
2010-05-03 21:00:06.775 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.775 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.776 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.776 Closing DB connection named 'DBManager0'
2010-05-03 21:00:06.777 Closing DB connection named 'DBManager0'
2010-05-03 21:00:06.777 Closing DB connection named 'DBManager0'
2010-05-03 21:00:06.782 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.782 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.784 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.814 Current MythTV Schema Version (DBSchemaVer): 1244
2010-05-03 21:00:06.814 Current MythTV Schema Version (DBSchemaVer): 1244
2010-05-03 21:00:06.815 Current MythTV Schema Version (DBSchemaVer): 1244
2010-05-03 21:00:06.819 New DB connection, total: 2
2010-05-03 21:00:06.820 New DB connection, total: 2
2010-05-03 21:00:06.821 New DB connection, total: 2
2010-05-03 21:00:06.840 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.840 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:06.848 Connected to database 'mythconverg' at host: localhost
2010-05-03 21:00:10.347 AFD: Opened codec 0x8b1aa50, id(MPEG2VIDEO) type(Video)
2010-05-03 21:00:10.349 AFD: codec AC3 has 6 channels
2010-05-03 21:00:10.350 AFD: Opened codec 0x8b1e450, id(AC3) type(Audio)
2010-05-03 21:00:10.350 AFD: codec AC3 has 2 channels
2010-05-03 21:00:10.351 AFD: Opened codec 0x8b1eb80, id(AC3) type(Audio)
2010-05-03 21:00:10.421 AFD: Opened codec 0xa1655a0, id(MPEG2VIDEO) type(Video)
2010-05-03 21:00:10.421 AFD: codec AC3 has 6 channels
2010-05-03 21:00:10.422 AFD: Opened codec 0xa165d10, id(AC3) type(Audio)
2010-05-03 21:00:10.423 AFD: codec AC3 has 2 channels
2010-05-03 21:00:10.423 AFD: Opened codec 0xa17e420, id(AC3) type(Audio)
2010-05-03 21:00:10.424 AFD: codec AC3 has 2 channels
2010-05-03 21:00:10.427 AFD: Opened codec 0xa17eb40, id(AC3) type(Audio)
2010-05-03 21:00:10.550 AFD: Opened codec 0x8600750, id(MPEG2VIDEO) type(Video)
2010-05-03 21:00:10.550 AFD: codec AC3 has 6 channels
2010-05-03 21:00:10.551 AFD: Opened codec 0x8600ec0, id(AC3) type(Audio)
2010-05-03 21:00:10.555 AFD: codec AC3 has 2 channels
2010-05-03 21:00:10.556 AFD: Opened codec 0x8619470, id(AC3) type(Audio)
2010-05-03 21:00:13.463 Preview: Grabbed preview
'/video/recordings/1051_20100503200000.mpg' 1280x720 at 64s
2010-05-03 21:00:14.815 Preview: Grabbed preview
'/video/recordings/1041_20100503200000.mpg' 1920x1088 at 64s
2010-05-03 21:00:15.722 Preview: Grabbed preview
'/video/recordings/1131_20100503200000.mpg' 1920x1088 at 64s
2010-05-03 21:00:28.104 [mpeg2video @ 0xb68bc440]ac-tex damaged at 101 16
2010-05-03 21:00:28.105 [mpeg2video @ 0xb68bc440]Warning MVs not available
2010-05-03 21:00:29.754 TFW, Error: Write() -- IOBOUND begin
remaining(5241) free(0) size(4194304) cnt(1)
2010-05-03 21:00:34.456 mythbackend version:  [23069] www.mythtv.org
2010-05-03 21:00:34.590 Using runtime prefix = /usr
2010-05-03 21:00:34.590 Using configuration directory = /etc/mythtv/.mythtv
2010-05-03 21:00:34.612 Empty LocalHostName.


Three recordings ended at 21:00 and one started.  Nobody was using the
frontend...that's for absolute certain, so what are all those
"Changing from None to Watching RecordingOnly" messages?  Is that
normal for when a recording starts?  Also, what would cause all those
triplicate messages like the three "Using localhost value of
mythback"?

The codec messages must be from the one Commflagging just that was
running (which ended at 21:00:31).

Any suggestions would be welcome.  Thanks.
Tom


More information about the mythtv-users mailing list