[mythtv-users] Something that happed after MythFillDatabase is a resource hog.

[Douglas Peale]

I was watching an SD show as it was recording tonight, and it paused twice. The first time was about 5 seconds, the second was
over a minute. So long I thought my computer had crashed. The computer would not respond to the keyboard at all. To the point
that the numlock key would not change the state of the numlock LED.

Anyway, the system suddenly started working again, before I hit the reset button, so I watched to the end of the show.

Looking at the backend log, I found that mythfilldatabase had been running just before time of the pauses. I was happy that the
show was not corrupted, but it may have been that the interruptions occurred during a commercial and I did not notice.

In the front end log I found this:
QPainter::begin: Paint device returned engine == 0, type: 3
QPainter::setRenderHint: Painter must be active to set rendering hints
QPainter::setPen: Painter not active
QPainter::setBrush: Painter not active
QPainter::drawRects: Painter not active
QPainter::end: Painter not active, aborted

repeated many hundreds of times.


At the times of the pauses, burred amongst the QPainter errors, I found these:

2010-11-21 22:45:28.360 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:28.743 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:28.760 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:29.144 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:29.264 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:29.463 RingBuf(/var/lib/mythtv/recordings/1009_20101121215000.mpg): Waited 2.0 seconds for data
			to become available... 0 < 32768
2010-11-21 22:45:29.665 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:29.895 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:30.279 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:30.296 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:30.547 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:30.680 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:30.800 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:31.201 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:31.431 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:31.464 RingBuf(/var/lib/mythtv/recordings/1009_20101121215000.mpg): Waited 4.0 seconds for data
			to become available... 0 < 32768
2010-11-21 22:45:31.698 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:31.815 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:31.832 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:32.082 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:32.216 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL
2010-11-21 22:45:32.336 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUUUUUUUUUuuL


2010-11-21 22:47:40.412 Player(0): Timed out waiting for free video buffers.
2010-11-21 22:48:11.528 ALSA, Error: WriteAudio: buffer underrun


This is an excerpt from the backend log than covers the times of the pauses:

2010-11-21 22:38:48.158 adding: Compromise as a client (events: 1)
2010-11-21 22:38:48.204 Reschedule requested for id -1.
2010-11-21 22:38:48.205 Received a remote 'Clear Cache' request
2010-11-21 22:38:48.206 Received a remote 'Clear Cache' request
2010-11-21 22:38:48.262 Received a remote 'Clear Cache' request
2010-11-21 22:38:48.793 Scheduled 128 items in 0.6 = 0.03 match + 0.56 place
2010-11-21 22:38:49.205 mythfilldatabase run complete.
2010-11-21 22:38:49.210 DataDirect: Deleting temporary files
2010-11-21 22:39:09.642 RecBase(2:2): GetKeyframePositions(68579,9223372036854775807,#513) out of 2993
2010-11-21 22:39:44.710 RecBase(2:2): GetKeyframePositions(83171,9223372036854775807,#33) out of 3026
2010-11-21 22:40:02.913 RecBase(2:2): GetKeyframePositions(84173,9223372036854775807,#19) out of 3045
2010-11-21 22:40:27.917 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2010-11-21 22:41:12.974 RecBase(2:2): GetKeyframePositions(84704,9223372036854775807,#67) out of 3112
2010-11-21 22:45:27.952 New DB connection, total: 4
2010-11-21 22:45:32.739 Connected to database 'mythconverg' at host: localhost
2010-11-21 22:47:31.031 Player(0): Timed out waiting for free video buffers.
2010-11-21 22:47:40.081 TFW, Error: Write() -- IOBOUND begin remaining(4301) free(0) size(4194304) cnt(1)
2010-11-21 22:48:11.857 TFW, Error: Write() -- IOBOUND end
2010-11-21 22:48:59.096 RecBase(2:2): GetKeyframePositions(86666,9223372036854775807,#434) out of 3546
2010-11-21 22:49:34.022 RecBase(2:2): GetKeyframePositions(98636,9223372036854775807,#70) out of 3616
2010-11-21 22:50:27.231 RecBase(2:2): GetKeyframePositions(99684,9223372036854775807,#68) out of 3684
2010-11-21 22:51:03.139 RecBase(2:2): GetKeyframePositions(101259,9223372036854775807,#35) out of 3719
2010-11-21 22:51:22.156 RecBase(2:2): GetKeyframePositions(102246,9223372036854775807,#16) out of 3735

The system is an i7 machine with 6 GB of ram and a single 1.5 TB drive.