[mythtv-users] MythTV 0.26 won't change refresh rate correctly

Daniel Osborne myth at danielosborne.net
Sat Dec 22 01:28:42 UTC 2012 

Hi, I've been running 0.24 until this week. With 0.24, I followed the wiki
on http://www.mythtv.org/wiki/User_Manual:JudderFree and got judder-free
working just fine.

Then I updated my system completely (Gentoo x86_64):
Kernel 3.1.10->3.5.7
nVidia 295.75->304.64
Mythtv 0.24->0.26

...and everything in between.

Now when MythTV is running, it doesn't properly change the refresh rate.
When the system boots up, it's at 60 Hz (TV reports 1080/60p). Now, if I
play almost ANY video (24fps movie or regular cable), the refresh rate is
set to 30 (29.975 I guess, my TV just reports 1080i, it's the 30.0 mode
w/an * in xrandr below). Exiting the video doesn't restore the 60p either.

Now, I know I have most of the settings still configured correctly, at
least outside of Myth, because I can change the refresh manually with
xrandr and it works (TV changes what it reports), plus XBMC on the same
machine also switches correctly.

So what changed in MythTV?
I verified the checkbox for 'Separate GUI and Video Modes' is still
checked, and refresh rate is set to 'Any'. I also toggled it off and back
on again, just to make sure.

I did notice a couple of things though:
I have one video which is actually 25, and it changes the refresh to 60 Hz.
(again, TV reports 1080/60p)
When changing the resolution with xrandr, I can't use the modes listed, I
have to use the nvidia ones (??), for example:
To set 1080/24p, I have to execute xrandr -r 58

I supplied xrandr output, xorg.conf and mythfrontend log. I have the
xorg.log as well, but it's large so I didn't know if I should attach it or
not.
My TV is a LG 55LD520.

dragon mythtv # DISPLAY=:0.0 xrandr
Screen 0: minimum 8 x 8, current 1920 x 1080, maximum 8192 x 8192
VGA-0 disconnected (normal left inverted right x axis y axis)
HDMI-0 connected 1920x1080+0+0 (normal left inverted right x axis y axis)
160mm x 90mm
   1920x1080      60.0 +   60.0     60.0     59.9     30.0     24.0
30.0     30.0*
   1280x1024      60.0
   1280x720       60.0     59.9
   1024x768       60.0
   800x600        60.3
   720x480        59.9
   640x480        60.0     59.9

dragon mythtv # cat /etc/X11/xorg.conf
Section "Device"
        Identifier "nvidia"
        Driver  "nvidia"
        Option  "NoLogo"              "true"
        Option  "DynamicTwinView"     "false"
        Option  "NoFlip"              "false"
        Option  "ModeValidation"      "NoVesaModes, NoXServerModes,
NoVertRefresh"
        Option  "UseDisplayDevice"    "DFP-0"
        Option  "ModeDebug"           "true"
        Option  "HWCursor"            "false"
EndSection

Section "Screen"
        Identifier      "screen"
        Device          "nvidia"
        SubSection      "Display"
                Modes "1920x1080_60_0" "1920x1080_24_0
        EndSubSection
EndSection

Section "Extensions"
        Option  "Composite"           "false"
EndSection

-------------
Mythfrontend debug log when watching LiveTV:

2012-12-21 16:27:39.512886 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(GET_FREE_RECORDER_COUNT) called from UI thread
2012-12-21 16:27:39.519250 I [4989/4989] CoreContext tv_play.cpp:1017 (TV)
- TV: Creating TV object
2012-12-21 16:27:39.552647 N [4989/4989] CoreContext
mythmainwindow.cpp:2601 (PauseIdleTimer) - Suspending idle timer
2012-12-21 16:27:39.556481 I [4989/4989] CoreContext tv_play.cpp:1232
(Init) - TV: Created TvPlayWindow.
2012-12-21 16:27:39.558749 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(GET_FREE_RECORDER_COUNT) called from UI thread
2012-12-21 16:27:39.564293 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(GET_NEXT_FREE_RECORDER,-1) called from UI thread
2012-12-21 16:27:39.573905 I [4989/4989] CoreContext tv_play.cpp:2155
(HandleStateChange) - TV: Attempting to change from None to WatchingLiveTV
2012-12-21 16:27:39.573954 I [4989/4989] CoreContext
mythcorecontext.cpp:375 (ConnectCommandSocket) - MythCoreContext:
Connecting to backend server: xxxx::40:6543 (try 1 of 1)
2012-12-21 16:27:39.574731 I [4989/4989] CoreContext
mythcorecontext.cpp:1191 (CheckProtoVersion) - Using protocol version 75
2012-12-21 16:27:39.579222 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_RECORDER 1,GET_FREE_INPUTS...) called from UI
thread
2012-12-21 16:27:39.581235 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_RECORDER 2,GET_FREE_INPUTS...) called from UI
thread
2012-12-21 16:27:39.582881 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_RECORDER 3,GET_FREE_INPUTS...) called from UI
thread
2012-12-21 16:27:39.584685 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_RECORDER 4,GET_FREE_INPUTS...) called from UI
thread
2012-12-21 16:27:39.587554 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_RECORDER 4,GET_FREE_INPUTS...) called from UI
thread
2012-12-21 16:27:39.595955 N [4989/4989] CoreContext tv_play.cpp:2222
(HandleStateChange) - TV: Spawning LiveTV Recorder -- begin
2012-12-21 16:27:39.844837 N [4989/4989] CoreContext tv_play.cpp:2229
(HandleStateChange) - TV: Spawning LiveTV Recorder -- end
2012-12-21 16:27:39.854449 I [4989/4989] CoreContext tv_play.cpp:2250
(HandleStateChange) - TV:
playbackURL(/mnt/recordings/7058_20121222002739.mpg) cardtype(DUMMY)
2012-12-21 16:27:39.921220 N [4989/4989] CoreContext audioplayer.cpp:167
(ReinitAudio) - AudioPlayer: Enabling Audio
2012-12-21 16:27:39.929891 I [4989/4989] CoreContext mythpainter_ogl.cpp:62
(ClearCache) - Clearing OpenGL painter cache.
2012-12-21 16:27:40.218396 I [4989/4989] CoreContext
mythrender_vdpau.cpp:1682 (CreatePresentationSurfaces) - VDPAU: Created 2
output surfaces.
2012-12-21 16:27:40.218433 I [4989/4989] CoreContext
mythrender_vdpau.cpp:402 (Create) - VDPAU: Created VDPAU render device
1920x1080
2012-12-21 16:27:40.279832 I [4989/4989] CoreContext mythplayer.cpp:1754
(InitAVSync) - Player(1): Video timing method: RTC
2012-12-21 16:27:40.280260 I [4989/4989] CoreContext tv_play.cpp:5240
(StartPlayer) - TV: Created player.
2012-12-21 16:27:40.280307 I [4989/4989] CoreContext tv_play.cpp:2394
(HandleStateChange) - TV: Changing from None to WatchingLiveTV
2012-12-21 16:27:40.280324 I [4989/4989] CoreContext tv_play.cpp:2406
(HandleStateChange) - TV: State is LiveTV & mctx == ctx
2012-12-21 16:27:40.282059 I [4989/4989] CoreContext tv_play.cpp:2408
(HandleStateChange) - TV: UpdateOSDInput done
2012-12-21 16:27:40.282085 I [4989/4989] CoreContext tv_play.cpp:2410
(HandleStateChange) - TV: UpdateLCD done
2012-12-21 16:27:40.282731 I [4989/4989] CoreContext tv_play.cpp:2412
(HandleStateChange) - TV: ITVRestart done
2012-12-21 16:27:40.503118 I [4989/4989] CoreContext tv_play.cpp:2485
(HandleStateChange) - TV: Main UI disabled.
2012-12-21 16:27:40.503180 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(MESSAGE,SYSTEM_EVENT LIVETV_STARTED SENDER dragon)
called from UI thread
2012-12-21 16:27:40.503855 I [4989/4989] CoreContext tv_play.cpp:403
(StartTV) - TV: Entering main playback loop.
2012-12-21 16:27:40.507305 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(QUERY_SG_FILEQUERY,172.20.0.40...) called from UI
thread
2012-12-21 16:27:40.511790 I [4989/4989] CoreContext
mythcorecontext.cpp:1191 (CheckProtoVersion) - Using protocol version 75
2012-12-21 16:27:40.512683 I [4989/4989] CoreContext
mythcorecontext.cpp:1191 (CheckProtoVersion) - Using protocol version 75
2012-12-21 16:27:40.586829 D [4989/4989] CoreContext lcddevice.cpp:598
(switchToChannel) - LCDdevice: switchToChannel
2012-12-21 16:27:40.604876 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_CHANGED HOSTNAME dragon
CHANID 7058 STARTTIME 2012-12-22T00:27:40 SENDER dragon) called from UI
thread
2012-12-21 16:27:41.805044 W [4989/4989] CoreContext mythpainter.cpp:34
(Teardown) - MythPainter: 1 images not yet de-allocated.
2012-12-21 16:27:41.805058 I [4989/4989] CoreContext
mythpainter_vdpau.cpp:108 (ClearCache) - VDPAU Painter: Clearing VDPAU
painter cache.
2012-12-21 16:27:41.830731 I [4989/4989] CoreContext mythpainter_ogl.cpp:62
(ClearCache) - Clearing OpenGL painter cache.
2012-12-21 16:27:42.070899 I [4989/4989] CoreContext
mythrender_vdpau.cpp:1682 (CreatePresentationSurfaces) - VDPAU: Created 2
output surfaces.
2012-12-21 16:27:42.070913 I [4989/4989] CoreContext
mythrender_vdpau.cpp:402 (Create) - VDPAU: Created VDPAU render device
1920x1080
2012-12-21 16:27:42.119650 N [4989/4989] CoreContext mythplayer.cpp:520
(CheckExtraAudioDecode) - Player(1): Forcing decode extra audio option on
(Video method requires it).
2012-12-21 16:27:42.120019 I [4989/4989] CoreContext
avformatdecoder.cpp:2141 (ScanStreams) - AFD: Opened codec 0x7f7df4874d60,
id(MPEG2VIDEO) type(Video)
2012-12-21 16:27:42.120040 I [4989/4989] CoreContext
avformatdecoder.cpp:1999 (ScanStreams) - AFD: codec AC3 has 2 channels
2012-12-21 16:27:42.121008 I [4989/4989] CoreContext
avformatdecoder.cpp:2141 (ScanStreams) - AFD: Opened codec 0x7f7df42a9920,
id(AC3) type(Audio)
2012-12-21 16:27:42.157751 I [4989/4989] CoreContext
audio/audiooutputbase.cpp:791 (Reconfigure) - AO: Opening audio device
'hdmi:CARD=NVidia,DEV=1' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2012-12-21 16:27:42.159044 E [4989/4989] CoreContext
audio/audiooutputalsa.cpp:783 (SetParameters) - ALSA: Requested 500000us
got 341333 buffer time
2012-12-21 16:27:42.159223 E [4989/4989] CoreContext
audio/audiooutputalsa.cpp:242 (IncPreallocBufferSize) - ALSA: Try to
manually increase audio buffer with: echo 128 | sudo tee
/proc/asound/card0/pcm7p/sub0/prealloc
2012-12-21 16:27:42.184371 N [4989/4989] CoreContext
avformatdecoder.cpp:783 (SetEof) - AFD: Resetting byte context eof (livetv
1 was eof 0)
2012-12-21 16:27:42.332900 I [4989/4989] CoreContext
mythrender_vdpau.cpp:588 (CheckOutputSurfaces) - VDPAU: Added 2 output
surfaces (total 4, max 4)
2012-12-21 16:27:42.633989 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 104ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:42.737933 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 208ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:42.841647 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 312ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:42.945458 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 416ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:43.049316 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 520ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:43.153210 N [4989/4989] CoreContext mythplayer.cpp:2095
(PrebufferEnoughFrames) - Player(1): Waited 624ms for video buffers
AAAAAAAAAAdDdL
2012-12-21 16:27:50.345822 I [4989/4989] CoreContext tv_play.cpp:2155
(HandleStateChange) - TV: Attempting to change from WatchingLiveTV to None
2012-12-21 16:27:50.390232 W [4989/4989] CoreContext mythpainter.cpp:34
(Teardown) - MythPainter: 6 images not yet de-allocated.
2012-12-21 16:27:50.390251 I [4989/4989] CoreContext
mythpainter_vdpau.cpp:108 (ClearCache) - VDPAU Painter: Clearing VDPAU
painter cache.
2012-12-21 16:27:50.479115 I [4989/4989] CoreContext DisplayRes.cpp:195
(SwitchToGUI) - Trying 1920x1080 0.000 Hz
2012-12-21 16:27:50.479145 I [4989/4989] CoreContext DisplayRes.cpp:211
(SwitchToGUI) - SwitchToGUI: Switched to 1920x1080 0.000 Hz
2012-12-21 16:27:50.574983 I [4989/4989] CoreContext tv_play.cpp:2394
(HandleStateChange) - TV: Changing from WatchingLiveTV to None
2012-12-21 16:27:50.575388 I [4989/4989] CoreContext tv_play.cpp:405
(StartTV) - TV: Exiting main playback loop.
2012-12-21 16:27:50.586866 D [4989/4989] CoreContext lcddevice.cpp:576
(switchToTime) - LCDdevice: switchToTime
2012-12-21 16:27:50.594379 D [4989/4989] CoreContext
mythcorecontext.cpp:947 (SendReceiveStringList) -
SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STOPPED SENDER dragon)
called from UI thread
2012-12-21 16:27:50.594937 N [4989/4989] CoreContext
mythmainwindow.cpp:2606 (PauseIdleTimer) - Resuming idle timer

AMD Phenom II X4 810
ASUS M3N78-VM w/GeForce 8300
Gentoo x86_64

Thanks for any help you can provide.

Daniel
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20121221/bcef899f/attachment.html>

More information about the mythtv-users mailing list