[ATrpms-users] High CPU after upgrading to .20

DM - ATRPMS atrpms at tazman.net
Wed Sep 27 21:56:26 CEST 2006 

Update:

I cranked up the cooler in the frontend machine (temporarily) and 
actually realized that the current lockup systems are not (totally) from 
the cooling issue.  So I tweaked a couple PCI settings in BIOS and left 
the cooler in 'jet mode' and lockups are not a problem for the time 
being.  The downside is, the new settings suck up the little remaining 
processor (keeping it around 98-100% during playback) and create small 
playback stutters.  I also installed the new RPMs released overnight.

The mythfrontend log is not significantly changed, so I am not reposting.

If anyone has any ideas, I would appreciate it.

TIA!

Dan

P.S. I suspect this is an issue that should be addressed upstream, but 
it would be really nice if new myth* (especially frontend) versions 
would be able to still understand the older protocols so we don't have 
to update EVERYTHING all at once.

DM - ATRPMS wrote:
> Hi all...
> 
> This seems to be a common theme, but I'm seeming to have difficulties 
> here nevertheless.  I have a backend/frontend system and a frontend only 
> that I updated to .20 (including a bunch of other patching activity) 
> yesterday.  The backend system works fine (both recording and playback, 
> or simultaneously), but the frontend only system CPU jumped through the 
> roof (~90% from 35-40).  Behavior is actually similar to before I got 
> XvMC working on the box.  (This is designed to be a low-impact box, so 
> high CPU creates a cooling issue resulting in lockups, among other things.)
> 
> Here's what I did:
> -Updated all non[kernel|glib|myth] stuff on the backend and frontend
> -Rebooted both
> -Updated glib on both
> -Rebooted and tested (no noted problems)
> -Updated kernel (including relevant KMDLs) on both
> -Rebooted, reconfigured w/ nvidia-graphics-switch on the frontend 
> (backend is an ATI, so not relevant)
> -Rebooted frontend again, tested both
> -Updated all remaining packages (myth only) - got dependency errors on 
> both (update failed).  Unfortunately i didn't note what failed...
> -Updated Myth in the smallest chunks possible - worked that way.
> -Testing revealed behavior above.
> 
> This should not be the MMX issue, as these packages were downloaded and 
> installed yesterday.  I don't think it is an XvMC issue as the log 
> doesn't indicate the same issues I was having when first getting it 
> working (log with important, playback, general switches on):
> 
> 2006-09-26 20:09:32.734 Current Schema Version: 1160
> 2006-09-26 20:09:32.735 mythfrontend version: 0.20.20060828-3 www.mythtv.org
> 2006-09-26 20:09:32.735 Enabled verbose msgs: important general playback
> 2006-09-26 20:09:33.229 max_width: 1024 max_height: 768
> 2006-09-26 20:09:33.581 Total desktop dim: 1024x768, with 1 screen[s].
> 2006-09-26 20:09:33.586 Using screen 0, 1024x768 at 0,0
> 2006-09-26 20:09:33.589 Switching to square mode (G.A.N.T.)
> 2006-09-26 20:09:33.660 Using the Qt painter
> 2006-09-26 20:09:34.386 Joystick disabled.
> 2006-09-26 20:09:35.832 Loading from: 
> /usr/share/mythtv/themes/G.A.N.T./base.xml
> 2006-09-26 20:09:35.886 Loading from: 
> /usr/share/mythtv/themes/default/base.xml
> 2006-09-26 20:09:36.264 Registering Internal as a media playback plugin.
> 2006-09-26 20:09:36.361 Registering MythDVD DVD Media Handler as a media 
> handler ext()
> 2006-09-26 20:09:36.367 Registering MythDVD VCD Media Handler as a media 
> handler ext()
> 2006-09-26 20:09:36.497 Registering MythGallery Media Handler 1/2 as a 
> media handler ext()
> 2006-09-26 20:09:36.500 Registering MythGallery Media Handler 2/2 as a 
> media handler ext(gif,jpg,png)
> 2006-09-26 20:10:08.603 Connecting to backend server: x.x.x.x:6543 (try 
> 1 of 5)
> 2006-09-26 20:10:08.608 Using protocol version 30
> 2006-09-26 20:10:08.807 Received a remote 'Clear Cache' request
> 2006-09-26 20:10:24.033 XMLParse::LoadTheme using 
> /usr/share/mythtv/themes/G.A.N.T./ui.xml
> 2006-09-26 20:10:31.575 New DB connection, total: 2
> 2006-09-26 20:10:31.584 Connected to database 'mythconverg' at host: x.x.x.x
> 2006-09-26 20:10:31.713 TV: Attempting to change from None to 
> WatchingPreRecorded
> 2006-09-26 20:10:31.762 
> RingBuf(myth://x.x.x.x:6543/1029_20060926090000.mpg): 
> OpenFile(myth://x.x.x.x:6543/1029_20060926090000.mpg, 12)
> 2006-09-26 20:10:31.903 
> RingBuf(myth://x.x.x.x:6543/1029_20060926090000.mpg): 
> CalcReadAheadThresh(4000 KB)
>                           -> threshhold(402 KB) min read(32 KB) blk 
> size(64 KB)
> 2006-09-26 20:10:31.928 DPMS Deactivated
> 0: start_time: 0.036 duration: 329.189
> 1: start_time: 0.026 duration: 329.156
> stream: start_time: 0.289 duration: 3657.765 bitrate=5187 kb/s
> 2006-09-26 20:10:32.995 AFD: Stream #0, has id 0x480 codec id 
> MPEG2VIDEO, type Video, bitrate 6000000 at 0x0x9327aa0
> 2006-09-26 20:10:33.199 VideoOutputXv: XvMC version: 1.1
> 2006-09-26 20:10:33.258 XvMCSurfaceTypes::find(w 480, h 480, chroma 1, 
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 202, 2020 
> <=p, port, surfNum)
> 2006-09-26 20:10:33.258 Trying XvMC port 202
> 2006-09-26 20:10:33.318 Found a suitable XvMC surface 0
> 2006-09-26 20:10:33.337 VideoOutputXv: @ j=1 Looking for flag[s]: 
> XvInputMask
> 2006-09-26 20:10:33.337 VideoOutputXv: Adaptor#0: NV17 Video Texture has 
> flag[s]: XvInputMask XvImageMask
> 2006-09-26 20:10:33.338 XvMCSurfaceTypes::find(w 480, h 480, chroma 1, 
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 202, 2020 
> <=p, port, surfNum)
> 2006-09-26 20:10:33.338 Trying XvMC port 202
> 2006-09-26 20:10:33.339 Found a suitable XvMC surface 0
> 2006-09-26 20:10:33.340 VideoOutputXv: Grabbed xv port 202
> 2006-09-26 20:10:33.341 VideoOutputXv: XvMC surface found with IDCT 
> support on port 202
> 2006-09-26 20:10:33.380 VideoOutputXv: Closing XVideo port 202
> 2006-09-26 20:10:33.424 detectInterlace(Detect Scan, Interlaced Scan, 
> 29.97, 480) ->Interlaced Scan
> 2006-09-26 20:10:33.443 AFD: Looking for decoder for MPEG2VIDEO_XVMC
> 2006-09-26 20:10:33.444 AFD: Opened codec 0x933fdc0, id(MPEG2VIDEO_XVMC) 
> type(Video)
> 2006-09-26 20:10:33.445 AFD: Stream #1, has id 0x448 codec id MP2, type 
> Audio, bitrate 384000 at 0x0x929b930
> 2006-09-26 20:10:33.445 AFD: Looking for decoder for MP2
> 2006-09-26 20:10:33.497 AFD: Opened codec 0x9361760, id(MP2) type(Audio)
> 2006-09-26 20:10:33.498 
> RingBuf(myth://x.x.x.x:6543/1029_20060926090000.mpg): 
> CalcReadAheadThresh(6384 KB)
>                           -> threshhold(489 KB) min read(32 KB) blk 
> size(128 KB)
> 2006-09-26 20:10:33.573 Opening OSS audio device '/dev/dsp'.
> 2006-09-26 20:10:33.598 Dec: Trying to select track (w/lang)
> 2006-09-26 20:10:33.598 Dec: Selecting first track
> 2006-09-26 20:10:33.598 Dec: Selected track #1 in the Unknown language(0)
> 2006-09-26 20:10:33.598 Resyncing position map. posmapStarted = 0 
> livetv(0) watchingRec(0)
> 2006-09-26 20:10:33.917 Position map filled from DB to: 7309
> 2006-09-26 20:10:33.922 SyncPositionMap prerecorded, from DB: 7309 entries
> 2006-09-26 20:10:33.925 SyncPositionMap, new totframes: 109635, new 
> length: 3658, posMap size: 7309
> 2006-09-26 20:10:33.926 AFD: Position map found
> 2006-09-26 20:10:33.926 AFD: Successfully opened decoder for file: 
> "myth://x.x.x.x:6543/1029_20060926090000.mpg". novideo(0)
> 2006-09-26 20:10:34.135 VideoOutputXv: ctor
> 2006-09-26 20:10:34.139 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
> 2006-09-26 20:10:34.139 Display Rect  left: 0, top: 0, width: 1024, 
> height: 768, aspect: 1.33333
> 2006-09-26 20:10:34.140 Video Rect    left: 0, top: 0, width: 480, 
> height: 480, aspect: 1.33333
> 2006-09-26 20:10:34.141 VideoOutputXv: Pixel dimensions: Screen 
> 1024x768, window 1024x768
> 2006-09-26 20:10:34.142 VideoOutputXv: Estimated display dimensions: 
> 302x232 mm  Aspect: 1.30172
> 2006-09-26 20:10:34.143 VideoOutputXv: Estimated window dimensions: 
> 302x232 mm  Aspect: 1.30172
> 2006-09-26 20:10:34.143 VideoOutputXv: XvMC version: 1.1
> 2006-09-26 20:10:34.152 VideoOutputXv: XvMCTex: Init failed
> 2006-09-26 20:10:34.154 VideoOutputXv: @ j=1 Looking for flag[s]: 
> XvInputMask
> 2006-09-26 20:10:34.155 VideoOutputXv: Adaptor#0: NV17 Video Texture has 
> flag[s]: XvInputMask XvImageMask
> 2006-09-26 20:10:34.155 XvMCSurfaceTypes::find(w 480, h 480, chroma 1, 
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 202, 2020 
> <=p, port, surfNum)
> 2006-09-26 20:10:34.155 Trying XvMC port 202
> 2006-09-26 20:10:34.156 Found a suitable XvMC surface 0
> 2006-09-26 20:10:34.157 VideoOutputXv: Grabbed xv port 202
> 2006-09-26 20:10:34.157 VideoOutputXv: XvMC surface found with IDCT 
> support on port 202
> 2006-09-26 20:10:34.158 VideoOutputXv: XvMC Adaptor Name: 'NV17 Video 
> Texture'
> 2006-09-26 20:10:34.333 VideoOutputXv: Chromakeying not possible with 
> this XVideo port.
> 2006-09-26 20:10:34.333 Display Rect  left: 0, top: 0, width: 1024, 
> height: 768, aspect: 1.33333
> 2006-09-26 20:10:34.334 Video Rect    left: 0, top: 0, width: 480, 
> height: 480, aspect: 1.33333
> 2006-09-26 20:10:34.334 NVP: Forcing decode extra audio option on.
>                          XvMC playback requires it.
> 2006-09-26 20:10:37.999 NVP: ClearAfterSeek(1)
> 2006-09-26 20:10:37.999 VideoOutputXv: ClearAfterSeek()
> 2006-09-26 20:10:37.999 VideoOutputXv: DiscardFrames(0)
> 2006-09-26 20:10:37.999 VideoOutputXv: DiscardFrames() 1: AAAAAAAA
> 2006-09-26 20:10:37.999 VideoOutputXv: DiscardFrames() 3: AAAAAAAA -- done()
> 2006-09-26 20:10:38.000 TV: StartPlayer(): took 5944 ms to start player.
> 2006-09-26 20:10:38.001 VideoOutputXv: DiscardFrames(1)
> 2006-09-26 20:10:38.002 VideoOutputXv: DiscardFrames() 1: AAAAAAAA
> 2006-09-26 20:10:38.002 VideoOutputXv: DiscardFrames() 2: AAAAAAAA
> 2006-09-26 20:10:38.002 VideoOutputXv: DiscardFrames() 3: AAAAAAAA -- done()
> 2006-09-26 20:10:38.003 TV: Changing from None to WatchingPreRecorded
> 2006-09-26 20:10:38.048 New DB connection, total: 3
> 2006-09-26 20:10:38.048 The realtime priority setting is not enabled.
> 2006-09-26 20:10:38.094 Connected to database 'mythconverg' at host: x.x.x.x
> 2006-09-26 20:10:38.222 Using deinterlace method bobdeint
> 2006-09-26 20:10:38.336 nVidiaVideoSync: VBlank ioctl did not work, 
> unimplemented in this driver?
> 2006-09-26 20:10:38.337 DRMVideoSync: Could not open device 
> /dev/dri/card0, No such file or directory
> 2006-09-26 20:10:38.338 Set video sync frame interval to 33366
> 2006-09-26 20:10:38.347 Using video as timebase
> 2006-09-26 20:10:38.347 Video timing method: RTC
> 2006-09-26 20:10:38.347 Refresh rate: 13319, frame interval: 33366
> 2006-09-26 20:10:38.366 VideoOutputXv Error: ProcessFrameXvMC: Failed to 
> get OSD lock
> 2006-09-26 20:10:39.551 NVP: prebuffering pause
> 2006-09-26 20:10:39.552 NVP: Waiting for prebuffer.. 0 AALLAdDA
> 
> <above 2 lines repeat>
> 
> 'video_output' mean = '34237.50', std. dev. = '5342.39', fps = '29.21'
> 2006-09-26 20:10:41.790 NVP: prebuffering pause
> 2006-09-26 20:10:41.791 NVP: Waiting for prebuffer.. 0 LALAAdDA
> 
> <above 2 lines repeat>
> 
> 2006-09-26 20:10:44.750 VideoOutputXv: Frame H is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.751 NVP: prebuffering pause
> 2006-09-26 20:10:44.751 VideoOutputXv: Frame H is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.752 NVP: Waiting for prebuffer.. 0 AdAAALDd
> 2006-09-26 20:10:44.782 NVP: prebuffering pause
> 2006-09-26 20:10:44.782 NVP: Waiting for prebuffer.. 0 DDALALAA
> 2006-09-26 20:10:44.805 NVP: prebuffering pause
> 2006-09-26 20:10:44.806 NVP: Waiting for prebuffer.. 0 AAALDdLA
> 2006-09-26 20:10:44.810 TV: Attempting to change from 
> WatchingPreRecorded to None
> 2006-09-26 20:10:44.827 TV: StopStuff() -- begin
> 2006-09-26 20:10:44.827 TV: StopStuff(): stopping ring buffer[s]
> 2006-09-26 20:10:44.834 NVP: prebuffering pause
> 2006-09-26 20:10:44.834 NVP: Waiting for prebuffer.. 0 AALLAdAD
> 2006-09-26 20:10:44.870 NVP: prebuffering pause
> 2006-09-26 20:10:44.871 NVP: Waiting for prebuffer.. 0 DLALAAAA
> 2006-09-26 20:10:44.898 NVP: prebuffering pause
> 2006-09-26 20:10:44.898 NVP: Waiting for prebuffer.. 0 ALAdDALA
> 2006-09-26 20:10:44.904 TV: StopStuff(): stopping player[s] (1/2)
> 2006-09-26 20:10:44.904 TV: StopStuff(): stopping player[s] (2/2)
> 2006-09-26 20:10:44.918 NVP: Exited decoder loop.
> 2006-09-26 20:10:44.925 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.925 VideoOutputXv: dtor
> 2006-09-26 20:10:44.925 VideoOutputXv: DiscardFrames(1)
> 2006-09-26 20:10:44.926 VideoOutputXv: DiscardFrames() 1: ALAdAAdD
> 2006-09-26 20:10:44.926 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.926 VideoOutputXv: DiscardFrames() 2: ADAdAAdD
> 2006-09-26 20:10:44.927 VideoOutputXv: Frame D is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.927 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.927 VideoOutputXv: Frame D is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.928 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.928 VideoOutputXv: DiscardFrames() 3: AAAdAAdD -- done()
> 2006-09-26 20:10:44.928 VideoOutputXv: DiscardFrames(1)
> 2006-09-26 20:10:44.928 VideoOutputXv: DiscardFrames() 1: AAAdAAdD
> 2006-09-26 20:10:44.928 VideoOutputXv: Frame D is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.929 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.929 VideoOutputXv: DiscardFrames() 2: AAAdAAdD
> 2006-09-26 20:10:44.929 VideoOutputXv: Frame D is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.930 VideoOutputXv: Frame G is in use by avlib and so 
> is being held for later discarding.
> 2006-09-26 20:10:44.930 VideoOutputXv: DiscardFrames() 3: AAAdAAdD -- done()
> 2006-09-26 20:10:44.943 VideoOutputXv: Closing XVideo port 202
> 2006-09-26 20:10:45.160 TV: StopStuff() -- end
> 2006-09-26 20:10:45.160 TV: Changing from WatchingPreRecorded to None
> 2006-09-26 20:10:45.456 DPMS Reactivated.
> 
> 
> Thanks!
> 
> Dan
> 
> 
> _______________________________________________
> atrpms-users mailing list
> atrpms-users at atrpms.net
> http://lists.atrpms.net/mailman/listinfo/atrpms-users

More information about the atrpms-users mailing list