Scythe42's fixes for 1.4.0 (1 Viewer)

Status
Not open for further replies.
megahorst

megahorst

Super User
  • Team MediaPortal
  • Super User
    • July 8, 2006
    879
    259
    Home Country
    Germany Germany
    Scythe42 said:
    The whole screen was black. Strg+alt+entf did not bring up the taskmanager, the screen stayed black.
    Standby+resume again made MePo visible. It was switched to window mode. I'm not sure, but I think that I have triggered that.
    Click to expand...
    That is one thing you have to trigger. Maybe you tried it getting MP to respond.

    [2013-04-28 20:00:07,409] [Log ] [MPMain ] [INFO ] - D3D: Switching from full screen to windowed mode

    Don't see anything special in the logs. Can you please try without 3rd party plugins and also without PowerScheduler. The usual try and error to limit things down where it is coming from. Also try to add a delay on resuming.

    Let me know if it makes a difference. If not we need to work on this a bit more with logging additional information specific to your system to find out what's blocking MP from rendering.

    Also try to do the minimum steps to reproduce the issue, so I have as clean logs as possible. If MP does not respond (give it a couple of seconds), just kill it from the Task Manager. Avoids ton of other stuff in the logs in the end and makes it easier for me to see when it was stuck.
    Click to expand...


    I was not able to reproduce the black screen issue after resume.
    From my point of view there is no show stopper left.
     
    Lightning303

    Lightning303

    MP Donator
  • Premium Supporter
    • September 12, 2009
    798
    577
    Home Country
    Germany Germany
    elliottmc said:
    Seems good, but would be better if this is the case if people can simply post 'tested and all working fine' and maybe a very brief comment on system (number of displays).
    Click to expand...

    tested and all working fine

    briefly: one dispay :p
     
    Holzi

    Holzi

    Super Moderator
  • Team MediaPortal
    • April 21, 2010
    7,934
    2,235
    Ba-Wü
    Home Country
    Germany Germany
    tourettes said:
    Der_Bub said:
    update: If "Show message.." of ViewModeSwitcher Plugin is enabled No TV Picture (just sound) and on VFD display something like "Message" is displayed. If option is disabled it runs fine :rolleyes:
    Click to expand...

    Next question is that is that a bug in the plugin or does it expose some bug in the new MP code.
    Click to expand...

    Can anyone confirm? A reminder: Where are the logs? ;)
     
    azzuro

    azzuro

    Test Group
  • Team MediaPortal
    • May 10, 2007
    9,954
    5,626
    France - IDF
    Home Country
    France France
    - System Specs used : Workstation
    - How many displays : 2
    - What resolution does each display use : D1: DVI 1920x1080 / D2 : VGA 1680x1050
    - More than one GPU used : No
    - How are displays connected : D1 : DVI / D2 : VGA
    - How are they positioned : D1 : Left / D2 : Right
    - Which one is the primary one : D1
    - On which do you start MP : D2 (set in config)
    - Fullscreen / Windowed : Fullscreen
    - Where is the taskkbar positioned of Windows : D1 Left (Also Right, but it's trick)
    - Skin Used : DefaultWide
    - Test Build Version : MP1.3 FINAL
    Duplicate from last post :
    I have made video capture with phone, also made GPU log
    no software is open, except MP and Windows + GPU-z (+ all softwares launched with Windows)
    during the video, I have touched absolutely nothing!
    Click to expand...
    small stuttering : look video

    link of video : https://www.dropbox.com/s/oc4yz3y58891esm/VID_20130430_125651.3gp
    GPU Graph : GPU-Z Sensor Log1.png
     

    Attachments

    • GPU-Z Sensor Log2.txt
      30.8 KB
    HomeY

    HomeY

    Test Group
  • Team MediaPortal
    • February 23, 2008
    6,475
    4,645
    49
    ::1
    Home Country
    Netherlands Netherlands
    Holzi said:
    Can anyone confirm? A reminder: Where are the logs? ;)
    Click to expand...
    Never used the features, but enabled it, ticked the message box and can confirm a black screen, but no errors in log. LiveTV stops playing after 10 seconds
    Code: 
    [2013-04-30 15:26:07,196] [Log	] [MPMain  ] [INFO ] - g_Player.OnStarted() rtsp://10.0.0.102:554/stream5.0 media:TV
[2013-04-30 15:26:07,215] [Log	] [MPMain  ] [WARN ] - tvhome:startplay.  Phase 2 - 1532 ms - Done starting g_Player.Play()
[2013-04-30 15:26:07,222] [Log	] [MPMain  ] [DEBUG] - g_Player: ShowFullScreenWindow
[2013-04-30 15:26:07,224] [Log	] [MPMain  ] [INFO ] - TVHome: ShowFullScreenWindow switching to fullscreen tv
[2013-04-30 15:26:07,249] [Log	] [MPMain  ] [DEBUG] - Window: TvPlugin.TvFullScreen init
[2013-04-30 15:26:07,251] [Log	] [MPMain  ] [DEBUG] - TvFullScreen:init->OSD:Off
[2013-04-30 15:26:07,253] [Log	] [MPMain  ] [DEBUG] - TvFullScreen: init, playing True, player.CurrentFile rtsp://10.0.0.102:554/stream5.0, TVHome.Card.TimeShiftFileName F:\Timeshifting\live5-0.ts.tsbuffer
[2013-04-30 15:26:07,287] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 15:26:07,288] [Log	] [MPMain  ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 50
[2013-04-30 15:26:07,334] [Log	] [MPMain  ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 50
[2013-04-30 15:26:08,422] [Log	] [3		] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
[2013-04-30 15:26:08,702] [Log	] [33	  ] [DEBUG] - ViewModeSwitcher: OnVideoReceived() AR:1,777778, LastAR:0
[2013-04-30 15:26:08,705] [Log	] [33	  ] [INFO ] - ViewModeSwitcher: Rule "16:9" fits conditions.
[2013-04-30 15:26:08,707] [Log	] [33	  ] [INFO ] - PlaneScene: Crop: top:4, bottom:4, left:8, right:8
[2013-04-30 15:26:08,708] [Log	] [33	  ] [INFO ] - ViewModeSwitcher: Switching to viewmode: Normal
[2013-04-30 15:26:08,715] [Log	] [33	  ] [DEBUG] - WindowManager: route TvPlugin.TvFullScreen:602->MediaPortal.Dialogs.GUIDialogNotify:2016
[2013-04-30 15:26:08,871] [Log	] [33	  ] [DEBUG] - DialogWindow: MediaPortal.Dialogs.GUIDialogNotify init
[2013-04-30 15:27:14,047] [Log	] [47	  ] [INFO ] - PowerScheduler: Keep server alive
[2013-04-30 15:28:21,065] [Log	] [38	  ] [INFO ] - PowerScheduler: Keep server alive

    [2013-04-30 15:26:08,707] [Log ] [33 ] [INFO ] - PlaneScene: Crop: top:4, bottom:4, left:8, right:8
    Maybe the cause?
     
    Last edited:
    T

    tourettes

    Retired Team Member
  • Premium Supporter
    • January 7, 2005
    17,301
    4,800
    HomeY said:
    [2013-04-30 15:26:08,707] [Log ] [33 ] [INFO ] - PlaneScene: Crop: top:4, bottom:4, left:8, right:8 Maybe the cause?
    Click to expand...

    It shouldn't cause the issue, but it is possble. If that is true then it would mean that it might be possible to trigger that bug inside tv plugin by configuring the crop settings from there (context menu... cant check the corretc place as I'm away from dev PC) such regression should be fixed before merging to master as it is quite essential feature for some users who have broadcasters that send "noise" in the top and/or bottom parts of the video (which gets hidden normally by the overscan in settop box).

    Can anyone try to reproduce the issue with MP itself?
     
    Last edited:
    HomeY

    HomeY

    Test Group
  • Team MediaPortal
    • February 23, 2008
    6,475
    4,645
    49
    ::1
    Home Country
    Netherlands Netherlands
    tourettes said:
    It shouldn't cause the issue, but it is possble. If that is true then it would mean that it might be possible to trigger that bug inside tv plugin by configuring the crop settings from there (context menu... cant check the corretc place as I'm away from dev PC) such regression should be fixed before merging to master as it is quite essential feature for some users who have broadcasters that send "noise" in the top and/or bottom parts of the video (which gets hidden normally by the overscan in settop box).

    Can anyone try to reproduce the issue with MP itself?
    Click to expand...
    Good news :) Crop settings don't trigger the issue, doesn't hurt MP at all, working fine.
     
    Holzi

    Holzi

    Super Moderator
  • Team MediaPortal
    • April 21, 2010
    7,934
    2,235
    Ba-Wü
    Home Country
    Germany Germany
    Tested this and there are two issues which will prevent me from using this build at the moment. My remote control is not useable with this build. It is an Hama Remote Control and in MP Config I set it up like this:
    1.jpg

    The problem is that every key press gets executed twice. Means if I press one time left it acts like double left in MP. (like that one button always gets skipped).
    It seems like this is not releated to the remote itself but more to the "Use Centarea" setting. Because if I use the keyboard and this setting is enabled I also get double actions by e.g. press left on the keyboard.

    Log:
    XML: 
    [2013-04-30 16:50:21,360] [Log	] [MPMain  ] [DEBUG] - D3D: Cursor state balanced from -3 to -1
[2013-04-30 16:50:21,360] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:21,450] [Log	] [BassAudio] [DEBUG] - BASS: Finished loading DSP plugins ...
[2013-04-30 16:50:21,450] [Log	] [BassAudio] [INFO ] - BASS: Initializing BASS environment done.
[2013-04-30 16:50:22,181] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:22,200] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 16:50:22,221] [Log	] [7		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 16:50:22,226] [Log	] [7		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 16:50:22,226] [Log	] [7		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 16:50:22,227] [Log	] [7		] [DEBUG] - PS: User interface is idle
[2013-04-30 16:50:22,227] [Log	] [7		] [DEBUG] - PS: Check whether the next event is almost due
[2013-04-30 16:50:23,201] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:24,201] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:24,827] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "40" mapped
[2013-04-30 16:50:25,216] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:26,201] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "38" mapped
[2013-04-30 16:50:26,236] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:26,324] [Log	] [MPMain  ] [INFO ] - TvNotify:LoadNotifies
[2013-04-30 16:50:26,325] [Log	] [MPMain  ] [INFO ] - TvNotify: 0 notifies
[2013-04-30 16:50:27,161] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "37" mapped
[2013-04-30 16:50:27,241] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:27,997] [Log	] [FileSystemManager Thread] [DEBUG] - FileSystemWatchManagerThread : updating watchers
[2013-04-30 16:50:27,997] [Log	] [FileSystemManager Thread] [DEBUG] - FileLookUpCacheThread items : 4859
[2013-04-30 16:50:28,122] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "40" mapped
[2013-04-30 16:50:28,262] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:29,282] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:29,622] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "38" mapped
[2013-04-30 16:50:30,302] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:31,297] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:31,402] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "39" mapped
[2013-04-30 16:50:32,317] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:33,322] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:33,722] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "39" mapped
[2013-04-30 16:50:34,342] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:35,362] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:35,962] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "40" mapped
[2013-04-30 16:50:36,382] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:37,204] [Log	] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2013-04-30 16:50:37,204] [Log	] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 16:50:36
[2013-04-30 16:50:37,206] [Log	] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (16:50:36) to the local TvServer
[2013-04-30 16:50:37,207] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 16:50:37,253] [Log	] [7		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 16:50:37,255] [Log	] [7		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 16:50:37,255] [Log	] [7		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 16:50:37,255] [Log	] [7		] [DEBUG] - PS: User interface is idle
[2013-04-30 16:50:37,255] [Log	] [7		] [DEBUG] - PS: Check whether the next event is almost due
[2013-04-30 16:50:37,403] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:38,402] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 16:50:38,997] [Log	] [MPMain  ] [DEBUG] - Centarea: Command "13" mapped
[2013-04-30 16:50:39,017] [Log	] [MPMain  ] [INFO ] - Main: Exit requested
[2013-04-30 16:50:39,017] [Log	] [MPMain  ] [INFO ] - Main: Exit requested
[2013-04-30 16:50:39,041] [Log	] [MPMain  ] [INFO ] - Main: Stopping FrameMove

    Second problem is that if you have the I.F.C. plugin enabled, MP 'freezes' after you change the TV channel. (I guess this is a similar problem like in ViewModeSwitcher).
    To be precise it takes a long time to switch to the new channel and after that LiveTV is still running but MP is stuck at ZapOSD and doesn't react anymore (Screenshot). You have to close it via TaskManager.
    1.jpg


    Logs:
    XML: 
    [2013-04-30 17:03:30,625] [Log	] [BassAudio] [INFO ] - BASS: Initialize BASS environment ...
[2013-04-30 17:03:30,627] [Log	] [MPMain  ] [DEBUG] - Main: Auto play start listening
[2013-04-30 17:03:30,627] [Log	] [MPMain  ] [INFO ] - Main: Initializing volume handler
[2013-04-30 17:03:30,629] [Log	] [MPMain  ] [DEBUG] - Main: Registering for Device Notifications
[2013-04-30 17:03:30,629] [Log	] [MPMain  ] [DEBUG] - Main: Register for Power Settings Notifications
[2013-04-30 17:03:30,632] [Log	] [MPMain  ] [DEBUG] - D3D: ShowLastActiveModule active : False
[2013-04-30 17:03:30,632] [Log	] [MPMain  ] [DEBUG] - D3D: Activating main form
[2013-04-30 17:03:30,640] [Log	] [BassAudio] [INFO ] - BASS: Loading Settings
[2013-04-30 17:03:30,692] [Log	] [MPMain  ] [DEBUG] - Main: WM_SIZE (SIZE_RESTORED: 1920x1080)
[2013-04-30 17:03:30,693] [Log	] [MPMain  ] [DEBUG] - Main: WM_POWERBROADCAST (PBT_POWERSETTINGCHANGE)
[2013-04-30 17:03:30,693] [Log	] [MPMain  ] [INFO ] - Main: The display is on
[2013-04-30 17:03:30,693] [Log	] [MPMain  ] [DEBUG] - PS: POWERSETTINGCHANGE
[2013-04-30 17:03:30,694] [Log	] [MPMain  ] [INFO ] - GUIMusicPlaylist: Default Playlist loaded
[2013-04-30 17:03:30,703] [Log	] [MPMain  ] [INFO ] - Visualization Window: Loading skin settings...
[2013-04-30 17:03:30,716] [Log	] [MPMain  ] [INFO ] - Visualization Window: Done loading skin settings
[2013-04-30 17:03:30,720] [Log	] [BassAudio] [INFO ] - BASS: Loading audio decoder add-ins...
[2013-04-30 17:03:30,721] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\basscd.dll
[2013-04-30 17:03:30,721] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:30,721] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bassflac.dll
[2013-04-30 17:03:30,721] [Log	] [MPMain  ] [DEBUG] - D3D: OnPaint()
[2013-04-30 17:03:30,721] [Log	] [MPMain  ] [INFO ] - D3D: Stopping splash screen thread
[2013-04-30 17:03:30,722] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bassmidi.dll
[2013-04-30 17:03:30,723] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bassopus.dll
[2013-04-30 17:03:30,724] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\basswma.dll
[2013-04-30 17:03:30,725] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\basswv.dll
[2013-04-30 17:03:30,728] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_aac.dll
[2013-04-30 17:03:30,728] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_ac3.dll
[2013-04-30 17:03:30,729] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_alac.dll
[2013-04-30 17:03:30,730] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_ape.dll
[2013-04-30 17:03:30,730] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_mpc.dll
[2013-04-30 17:03:30,732] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_ofr.dll
[2013-04-30 17:03:30,733] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_spx.dll
[2013-04-30 17:03:30,733] [Log	] [BassAudio] [DEBUG] - BASS: Added DecoderPlugin: C:\Program Files (x86)\Team MediaPortal\MediaPortal\musicplayer\plugins\audio decoders\bass_tta.dll
[2013-04-30 17:03:30,733] [Log	] [BassAudio] [INFO ] - BASS: Loaded 14 Audio Decoders.
[2013-04-30 17:03:30,736] [Log	] [BassAudio] [DEBUG] - BASS: Loading DSP plugins ...
[2013-04-30 17:03:30,742] [Log	] [MPMain  ] [DEBUG] - D3D: Cursor state balanced from -3 to -1
[2013-04-30 17:03:30,742] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:30,823] [Log	] [BassAudio] [DEBUG] - BASS: Finished loading DSP plugins ...
[2013-04-30 17:03:30,823] [Log	] [BassAudio] [INFO ] - BASS: Initializing BASS environment done.
[2013-04-30 17:03:31,558] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 17:03:31,581] [Log	] [33	  ] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:03:31,586] [Log	] [33	  ] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:03:31,586] [Log	] [33	  ] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:03:31,586] [Log	] [33	  ] [DEBUG] - PS: User interface is idle
[2013-04-30 17:03:31,586] [Log	] [33	  ] [DEBUG] - PS: Check whether the next event is almost due
[2013-04-30 17:03:33,407] [Log	] [MPMain  ] [DEBUG] - Cacheing expression: #(skin.setfocus(35,1000))
[2013-04-30 17:03:33,407] [Log	] [MPMain  ] [DEBUG] - Cacheing expression: 35
[2013-04-30 17:03:33,407] [Log	] [MPMain  ] [DEBUG] - Cacheing expression: 1000
[2013-04-30 17:03:34,297] [Log	] [FileSystemManager Thread] [DEBUG] - FileSystemWatchManagerThread : updating watchers
[2013-04-30 17:03:34,297] [Log	] [FileSystemManager Thread] [DEBUG] - FileLookUpCacheThread items : 4869
[2013-04-30 17:03:35,113] [Log	] [MPMain  ] [DEBUG] - Window: WindowPlugins.home.GUIBasicHome deinit
[2013-04-30 17:03:35,116] [Log	] [MPMain  ] [DEBUG] - TextureManager: CleanupThumbs()
[2013-04-30 17:03:35,128] [Log	] [MPMain  ] [DEBUG] - Window: TvPlugin.TVHome init
[2013-04-30 17:03:35,130] [Log	] [MPMain  ] [INFO ] - TVHome:OnPageLoad
[2013-04-30 17:03:35,133] [Log	] [MPMain  ] [INFO ] - ChannelNavigator::Reload()
[2013-04-30 17:03:35,134] [Log	] [MPMain  ] [INFO ] - get channels from database
[2013-04-30 17:03:35,268] [Log	] [MPMain  ] [INFO ] - found:333 tv channels
[2013-04-30 17:03:35,269] [Log	] [MPMain  ] [INFO ] - TvNotify:OnNotifiesChanged
[2013-04-30 17:03:35,395] [Log	] [MPMain  ] [INFO ] - Done.
[2013-04-30 17:03:35,396] [Log	] [MPMain  ] [INFO ] - get all groups from database
[2013-04-30 17:03:35,508] [Log	] [MPMain  ] [INFO ] - loaded 1 tv groups
[2013-04-30 17:03:35,508] [Log	] [MPMain  ] [INFO ] - tv home init:SPORT 1
[2013-04-30 17:03:35,509] [Log	] [MPMain  ] [INFO ] - tv home init:SPORT 1 done
[2013-04-30 17:03:35,660] [Log	] [FileExistsCache Thread] [DEBUG] - FileExistsCacheThread: new items found waiting for caching: 1
[2013-04-30 17:03:35,660] [Log	] [FileExistsCache Thread] [DEBUG] - InsertFilesIntoCacheAsynch: pre-scanning dir : c:\programdata\team mediaportal\mediaportal\thumbs\tv\logos
[2013-04-30 17:03:35,661] [Log	] [FileExistsCache Thread] [DEBUG] - FileExistsCacheThread: no more items to cache, suspending thread.: 1
[2013-04-30 17:03:35,760] [Log	] [MPMain  ] [INFO ] - TexturePacker: Loaded C:\ProgramData\Team MediaPortal\MediaPortal\Cache\Titan\packedgfx27.png texture:2048x2048 miplevels:1
[2013-04-30 17:03:35,760] [Log	] [MPMain  ] [INFO ] - TexturePacker: fontengine add texure:1926
[2013-04-30 17:03:36,272] [Log	] [MPMain  ] [INFO ] - TvNotify:LoadNotifies
[2013-04-30 17:03:36,273] [Log	] [MPMain  ] [INFO ] - TvNotify: 0 notifies
[2013-04-30 17:03:39,828] [Log	] [MPMain  ] [INFO ] - TVHome:turn tv on SPORT 1
[2013-04-30 17:03:39,831] [Log	] [MPMain  ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=SPORT 1
[2013-04-30 17:03:39,841] [Log	] [MPMain  ] [DEBUG] - CiMenu: PrepareCiMenu
[2013-04-30 17:03:40,121] [Log	] [33	  ] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:03:40,123] [Log	] [33	  ] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:03:40,123] [Log	] [33	  ] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:03:40,123] [Log	] [33	  ] [DEBUG] - PS: User interface is idle
[2013-04-30 17:03:40,123] [Log	] [33	  ] [DEBUG] - PS: Check whether the next event is almost due
[2013-04-30 17:03:40,315] [Log	] [MPMain  ] [INFO ] - succeeded:Succeeded TvControl.VirtualCard
[2013-04-30 17:03:40,318] [Log	] [MPMain  ] [INFO ] - tvhome:startplay
[2013-04-30 17:03:40,320] [Log	] [MPMain  ] [INFO ] - tvhome:file:R:\\live4-0.ts.tsbuffer
[2013-04-30 17:03:40,322] [Log	] [MPMain  ] [WARN ] - tvhome:startplay.  Phase 1 - 5 ms - Done method initialization
[2013-04-30 17:03:40,327] [Log	] [MPMain  ] [INFO ] - tvhome:startplay:R:\\live4-0.ts.tsbuffer - using rtsp mode:False
[2013-04-30 17:03:40,338] [Log	] [MPMain  ] [DEBUG] - MediaInfoWrapper: isTv:True, isRadio:False, isRTSP:False, isAVStream:False
[2013-04-30 17:03:40,338] [Log	] [MPMain  ] [DEBUG] - MediaInfoWrapper: disabled for this content
[2013-04-30 17:03:40,356] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.AdaptRefreshRate: framerate on file R:\\live4-0.ts.tsbuffer is 25
[2013-04-30 17:03:40,359] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: current refreshrate is 50hz - changing it to 50hz
[2013-04-30 17:03:40,359] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: using internal win32 method for changing refreshrate. current is 50hz, desired is 50
[2013-04-30 17:03:40,366] [Log	] [MPMain  ] [DEBUG] - W7RefreshRateHelper.SetDisplayConfig(...): SDC_VALIDATE of 50000/1000 succesful
[2013-04-30 17:03:40,372] [Log	] [MPMain  ] [DEBUG] - W7RefreshRateHelper.GetRefreshRate: QueryDisplayConfig returned 50/1
[2013-04-30 17:03:40,372] [Log	] [MPMain  ] [INFO ] - CycleRefreshRate: successfully changed refresh rate to 50Hz (50Hz requested)
[2013-04-30 17:03:40,372] [Log	] [MPMain  ] [DEBUG] - CycleRefresh: DWM Detected, performing shenanigans
[2013-04-30 17:03:40,373] [Log	] [MPMain  ] [INFO ] - g_Player.Play(R:\\live4-0.ts.tsbuffer TV)
[2013-04-30 17:03:40,383] [Log	] [MPMain  ] [DEBUG] - PlayerFactory: Successfully created player instance for file - R:\\live4-0.ts.tsbuffer
[2013-04-30 17:03:40,385] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer play:R:\\live4-0.ts.tsbuffer radio:False
[2013-04-30 17:03:40,386] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: live tv
[2013-04-30 17:03:40,387] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: Enabling DX9 exclusive mode
[2013-04-30 17:03:40,387] [Log	] [MPMain  ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
[2013-04-30 17:03:40,387] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer:play R:\\live4-0.ts.tsbuffer
[2013-04-30 17:03:40,392] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: GetInterfaces()
[2013-04-30 17:03:40,404] [Log	] [MPMain  ] [INFO ] - DirectShowUtils: First try to insert new audio renderer Default DirectSound Device
[2013-04-30 17:03:40,486] [Log	] [MPMain  ] [INFO ] - DirectShowUtils: Found audio renderer
[2013-04-30 17:03:40,487] [Log	] [MPMain  ] [DEBUG] - DirectShowUtils: added filter:Default DirectSound Device to graph
[2013-04-30 17:03:40,493] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: Add TsReader to graph
[2013-04-30 17:03:40,494] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: Open file: R:\\live4-0.ts.tsbuffer
[2013-04-30 17:03:40,494] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer:OnRequestAudioChange()
[2013-04-30 17:03:40,495] [Log	] [MPMain  ] [DEBUG] - TVHome.OnAudioTracksReady()
[2013-04-30 17:03:40,502] [Log	] [MPMain  ] [DEBUG] - TVHome.GetPreferedAudioStreamIndex(): preferred LANG(s):n/a preferAC3:False _preferAudioTypeOverLang:True
[2013-04-30 17:03:40,502] [Log	] [MPMain  ] [DEBUG] - Audio streams avail: 1
[2013-04-30 17:03:40,502] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-04-30 17:03:40,502] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-04-30 17:03:40,502] [Log	] [MPMain  ] [INFO ] - Audio stream: switching to preferred AC3/MPEG audio stream 0 (only 1 track avail.)
[2013-04-30 17:03:40,503] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-04-30 17:03:40,836] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer: OnVideoFormatChanged - streamtype=MPEG2 resolution=720x576 aspect ratio=16:9 bitrate=15000000 isInterlaced=True
[2013-04-30 17:03:40,839] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: Add codecs
[2013-04-30 17:03:41,063] [Log	] [MPMain  ] [INFO ] - VMR9: added EVR Renderer to graph
[2013-04-30 17:03:41,064] [Log	] [MPMain  ] [DEBUG] - VMR9: Now active
[2013-04-30 17:03:41,064] [Log	] [MPMain  ] [DEBUG] - VMR9: Renderer successfully added
[2013-04-30 17:03:41,073] [Log	] [MPMain  ] [INFO ] - Added filter: LAV Video Decoder to graph
[2013-04-30 17:03:41,073] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: UpdateFilters Video done
[2013-04-30 17:03:41,078] [Log	] [MPMain  ] [INFO ] - Added filter: MediaPortal DVBSub3 to graph
[2013-04-30 17:03:41,078] [Log	] [MPMain  ] [DEBUG] - SubtitleRenderer: CreateFilter success: True & True
[2013-04-30 17:03:41,079] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: SubtitleRenderer AddSubtitleFilter
[2013-04-30 17:03:41,086] [Log	] [MPMain  ] [INFO ] - Added filter: ffdshow Audio Decoder to graph
[2013-04-30 17:03:41,086] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: UpdateFilters Audio done
[2013-04-30 17:03:41,088] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: PostProcessingEngine to DummyEngine
[2013-04-30 17:03:41,088] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: Render TsReader outputs
[2013-04-30 17:03:41,089] [Log	] [MPMain  ] [DEBUG] - Filter: TsReader - try to connect: Audio
[2013-04-30 17:03:41,115] [Log	] [MPMain  ] [DEBUG] - Filter: TsReader - try to connect: Video
[2013-04-30 17:03:41,117] [Log	] [MPMain  ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
[2013-04-30 17:03:41,119] [Log	] [MPMain  ] [DEBUG] - Filter: TsReader - try to connect: Subtitle
[2013-04-30 17:03:41,120] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: CleanupCC filter (Tv/Recorded Stream Detected)
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: MediaPortal DVBSub3
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: Enhanced Video Renderer
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: Default DirectSound Device
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: LAV Video Decoder
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: ffdshow Audio Decoder
[2013-04-30 17:03:41,121] [Log	] [MPMain  ] [DEBUG] - Check graph connections for: TsReader
[2013-04-30 17:03:41,122] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: Creating Teletext Receiver
[2013-04-30 17:03:41,123] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Magazine ctor
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Setting up teletext receiver ...
[2013-04-30 17:03:41,124] [Log	] [MPMain  ] [DEBUG] - Setting up callbacks with ITeletextSource
[2013-04-30 17:03:41,125] [Log	] [MPMain  ] [DEBUG] - Setting up ttxtdecoder and pes decoder
[2013-04-30 17:03:41,125] [Log	] [MPMain  ] [DEBUG] - PESDecoder ctor
[2013-04-30 17:03:41,125] [Log	] [MPMain  ] [DEBUG] - Done setting up teletext receiver ...
[2013-04-30 17:03:41,128] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector ctor
[2013-04-30 17:03:41,128] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector: sublangs entry content: deu;ger;
[2013-04-30 17:03:41,128] [Log	] [MPMain  ] [INFO ] - Prefered language 1 is deu
[2013-04-30 17:03:41,128] [Log	] [MPMain  ] [INFO ] - Prefered language 2 is ger
[2013-04-30 17:03:41,128] [Log	] [MPMain  ] [DEBUG] - Calling SetSubtitleStreamEventCallback
[2013-04-30 17:03:41,129] [Log	] [MPMain  ] [DEBUG] - SetOption 0
[2013-04-30 17:03:41,130] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector : Set autoselect mode
[2013-04-30 17:03:41,131] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector: CheckForPreferedLanguage
[2013-04-30 17:03:41,131] [Log	] [MPMain  ] [DEBUG] - Has 1 options
[2013-04-30 17:03:41,132] [Log	] [MPMain  ] [DEBUG] - Subtitle is now None
[2013-04-30 17:03:41,132] [Log	] [MPMain  ] [DEBUG] - End SubtitleSelector ctor
[2013-04-30 17:03:41,136] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: Last subtitle index: 0
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - SetOption 0
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector : Set autoselect mode
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - SubtitleSelector: CheckForPreferedLanguage
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - Has 1 options
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - Subtitle is now None
[2013-04-30 17:03:41,137] [Log	] [MPMain  ] [DEBUG] - TSReaderPlayer: Automatically show subtitles when TV starts is set to False
[2013-04-30 17:03:41,155] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: OnInitialized
[2013-04-30 17:03:41,155] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: position:0, duration:0,479
[2013-04-30 17:03:41,156] [Log	] [MPMain  ] [INFO ] - g_Player.OnStarted() R:\\live4-0.ts.tsbuffer media:TV
[2013-04-30 17:03:41,169] [Log	] [MPMain  ] [WARN ] - tvhome:startplay.  Phase 2 - 847 ms - Done starting g_Player.Play()
[2013-04-30 17:03:41,180] [Log	] [MPMain  ] [WARN ] - TVHome.OnClicked(): Total Time - 1352 ms
[2013-04-30 17:03:41,209] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2013-04-30 17:03:41,209] [Log	] [MPMain  ] [INFO ] - Main: Deactivation request received
[2013-04-30 17:03:41,209] [Log	] [MPMain  ] [INFO ] - Main: Refresh rate changer running. Ignoring deactivation request
[2013-04-30 17:03:41,209] [Log	] [MPMain  ] [DEBUG] - D3D: OnLostFocus()
[2013-04-30 17:03:41,209] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:41,211] [Log	] [MPMain  ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 50
[2013-04-30 17:03:41,263] [Log	] [MPMain  ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 50
[2013-04-30 17:03:41,310] [Log	] [34	  ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: crop T, B  : 0, 0
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: crop L, R  : 0, 0
[2013-04-30 17:03:41,325] [Log	] [35	  ] [INFO ] - PlaneScene: video WxH  : 720x576
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: video AR  : 16:9
[2013-04-30 17:03:41,325] [Log	] [35	  ] [INFO ] - PlaneScene: screen WxH : 850x480
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: AR type	: Stretch
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: PixelRatio : 1
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: src		: (0,0)-(720,576)
[2013-04-30 17:03:41,325] [Log	] [35	  ] [DEBUG] - PlaneScene: dst		: (968,238)-(1818,718)
[2013-04-30 17:03:41,745] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:41,885] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
[2013-04-30 17:03:41,885] [Log	] [MPMain  ] [INFO ] - Main: Activation reuqest received
[2013-04-30 17:03:41,885] [Log	] [MPMain  ] [DEBUG] - D3D: OnGotFocus()
[2013-04-30 17:03:41,886] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:41,906] [Log	] [MPMain  ] [DEBUG] - D3D: MouseMoveEvent()
[2013-04-30 17:03:44,298] [Log	] [FileSystemManager Thread] [DEBUG] - FileSystemWatchManagerThread : updating watchers
[2013-04-30 17:03:44,298] [Log	] [FileSystemManager Thread] [DEBUG] - FileLookUpCacheThread items : 4952
[2013-04-30 17:03:44,506] [Log	] [MPMain  ] [DEBUG] - g_Player: ShowFullScreenWindow
[2013-04-30 17:03:44,509] [Log	] [MPMain  ] [INFO ] - TVHome: ShowFullScreenWindow switching to fullscreen tv
[2013-04-30 17:03:44,512] [Log	] [MPMain  ] [DEBUG] - Window: TvPlugin.TVHome deinit
[2013-04-30 17:03:44,514] [Log	] [MPMain  ] [DEBUG] - TextureManager: CleanupThumbs()
[2013-04-30 17:03:44,519] [Log	] [MPMain  ] [DEBUG] - Window: TvPlugin.TvFullScreen init
[2013-04-30 17:03:44,520] [Log	] [MPMain  ] [DEBUG] - TvFullScreen:init->OSD:Off
[2013-04-30 17:03:44,524] [Log	] [MPMain  ] [DEBUG] - TvFullScreen: init, playing True, player.CurrentFile R:\\live4-0.ts.tsbuffer, TVHome.Card.TimeShiftFileName R:\\live4-0.ts.tsbuffer
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: crop T, B  : 0, 0
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: crop L, R  : 0, 0
[2013-04-30 17:03:44,530] [Log	] [35	  ] [INFO ] - PlaneScene: video WxH  : 720x576
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: video AR  : 16:9
[2013-04-30 17:03:44,530] [Log	] [35	  ] [INFO ] - PlaneScene: screen WxH : 1920x1080
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: AR type	: Stretch
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: PixelRatio : 1
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: src		: (0,0)-(720,576)
[2013-04-30 17:03:44,530] [Log	] [35	  ] [DEBUG] - PlaneScene: dst		: (0,0)-(1920,1080)
[2013-04-30 17:03:46,563] [Log	] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2013-04-30 17:03:46,565] [Log	] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (17:03:44) to the local TvServer
[2013-04-30 17:03:46,567] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 17:03:53,009] [Log	] [MPMain  ] [DEBUG] - UpdateOSD()
[2013-04-30 17:03:53,009] [Log	] [MPMain  ] [DEBUG] - Window: TvPlugin.TvZapOsd init
[2013-04-30 17:03:53,010] [Log	] [MPMain  ] [DEBUG] - zaposd pageload
[2013-04-30 17:03:53,038] [Log	] [MPMain  ] [DEBUG] - ZAP OSD:ON
[2013-04-30 17:03:54,022] [Log	] [MPMain  ] [DEBUG] - ChangeChannelNr()
[2013-04-30 17:03:54,030] [Log	] [MPMain  ] [DEBUG] - ChannelNavigator.ZapToChannel ZDF HD - zapdelay False
[2013-04-30 17:03:54,030] [Log	] [MPMain  ] [DEBUG] - UpdateOSD()
[2013-04-30 17:03:54,041] [Log	] [MPMain  ] [INFO ] - Channel change:ZDF HD
[2013-04-30 17:03:54,041] [Log	] [MPMain  ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=ZDF HD
[2013-04-30 17:03:54,048] [Log	] [MPMain  ] [DEBUG] - TvHome.RenderBlackImage()
[2013-04-30 17:03:54,051] [Log	] [35	  ] [DEBUG] - TvHome.OnVideoReceived() 0
[2013-04-30 17:03:54,051] [Log	] [35	  ] [DEBUG] - RenderBlack: x:0, y:0, w:1920, h:1080
[2013-04-30 17:03:54,052] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer:Pause graph
[2013-04-30 17:03:54,053] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: OnZapping :128
[2013-04-30 17:03:54,851] [Log	] [27	  ] [DEBUG] - Teletext: RESET
[2013-04-30 17:03:54,851] [Log	] [27	  ] [DEBUG] - PESDecoder.Reset
[2013-04-30 17:03:54,852] [Log	] [27	  ] [DEBUG] - SubtitleRenderer: RESET
[2013-04-30 17:03:54,853] [Log	] [27	  ] [DEBUG] - OnSubtitleReset
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - selected_bitmap_index -1
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Number of bitmap options 1
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Bitmap index 0
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Bitmap Lang deu
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - SubtitleSelector: CheckForPreferedLanguage
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Has 2 options
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Bitmap Lang deu Pref index 0
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Setting as pref
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Auto-selection of Bitmap Lang deu
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - SubtitleRender: Now rendering bitmap subtitles in language deu
[2013-04-30 17:03:54,854] [Log	] [27	  ] [DEBUG] - Returns selected_bitmap_index == 0 to ISubStream
[2013-04-30 17:03:54,907] [Log	] [3		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:03:54,912] [Log	] [3		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:03:54,912] [Log	] [3		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:03:54,912] [Log	] [3		] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2013-04-30 17:03:54,912] [Log	] [3		] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2013-04-30 17:03:54,918] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: OnZapping :3
[2013-04-30 17:03:54,918] [Log	] [MPMain  ] [INFO ] - succeeded:Succeeded TvControl.VirtualCard
[2013-04-30 17:03:54,919] [Log	] [MPMain  ] [INFO ] - tvhome:SeektoEnd(12,7746875/13,527),True,rtsp=False
[2013-04-30 17:03:54,919] [Log	] [MPMain  ] [DEBUG] - g_Player.SeekAbsolute() - Preparing to seek to 0:0:13
[2013-04-30 17:03:54,921] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer:seekabs:135270000 start:0 end:135270000
[2013-04-30 17:03:54,921] [Log	] [MPMain  ] [DEBUG] - Teletext: SEEK_START
[2013-04-30 17:03:54,926] [Log	] [MPMain  ] [DEBUG] - Teletext: SEEK_END
[2013-04-30 17:03:54,926] [Log	] [MPMain  ] [DEBUG] - PESDecoder.Reset
[2013-04-30 17:03:54,926] [Log	] [MPMain  ] [DEBUG] - SubtitleRenderer: RESET
[2013-04-30 17:03:54,927] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer: pos: 135270000 start:0 end:135270000
[2013-04-30 17:03:54,927] [Log	] [MPMain  ] [INFO ] - TsReaderPlayer seek done:0
[2013-04-30 17:03:54,928] [Log	] [MPMain  ] [DEBUG] - SubtitleRenderer: OnSeek - clear subtitles
[2013-04-30 17:03:54,928] [Log	] [MPMain  ] [DEBUG] - New StartPos is 13,527
[2013-04-30 17:03:54,928] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer: current pos:13,527 dur:13,527
[2013-04-30 17:03:54,928] [Log	] [MPMain  ] [INFO ] - TSReaderPlayer:Continue graph
[2013-04-30 17:03:59,854] [Log	] [27	  ] [DEBUG] - SubtitleRenderer: RESET
[2013-04-30 17:04:00,053] [Log	] [27	  ] [INFO ] - TsReaderPlayer: OnVideoFormatChanged - streamtype=H264 resolution=1280x720 aspect ratio=16:9 bitrate=15000000 isInterlaced=False
[2013-04-30 17:04:00,733] [Log	] [27	  ] [DEBUG] - MagID 7 is in serial mode
[2013-04-30 17:04:00,733] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - SubtitleSelector: CheckForPreferedLanguage
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Added Teletext option Lang : Teletext Lang		page : 777
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Has 3 options
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Bitmap Lang deu Pref index 0
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Setting as pref
[2013-04-30 17:04:00,738] [Log	] [27	  ] [DEBUG] - Teletext Lang		page : 777 Pref index -1
[2013-04-30 17:04:00,739] [Log	] [27	  ] [DEBUG] - Auto-selection of Bitmap Lang deu
[2013-04-30 17:04:00,739] [Log	] [27	  ] [DEBUG] - SubtitleRender: Now rendering bitmap subtitles in language deu
[2013-04-30 17:04:01,571] [Log	] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2013-04-30 17:04:01,572] [Log	] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (17:03:54) to the local TvServer
[2013-04-30 17:04:01,573] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 17:04:02,631] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:02,631] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:05,096] [Log	] [34	  ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - TvHome.OnVideoReceived() 3
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: crop T, B  : 0, 0
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: crop L, R  : 0, 0
[2013-04-30 17:04:05,111] [Log	] [35	  ] [INFO ] - PlaneScene: video WxH  : 1280x720
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: video AR  : 16:9
[2013-04-30 17:04:05,111] [Log	] [35	  ] [INFO ] - PlaneScene: screen WxH : 1920x1080
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: AR type	: Stretch
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: PixelRatio : 1
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: src		: (0,0)-(1280,720)
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - PlaneScene: dst		: (0,0)-(1920,1080)
[2013-04-30 17:04:05,111] [Log	] [35	  ] [DEBUG] - RenderBlack: x:0, y:0, w:1920, h:1080
[2013-04-30 17:04:05,591] [Log	] [35	  ] [DEBUG] - TvHome.OnVideoReceived() 2
[2013-04-30 17:04:05,591] [Log	] [35	  ] [DEBUG] - RenderBlack: x:0, y:0, w:1920, h:1080
[2013-04-30 17:04:05,631] [Log	] [35	  ] [DEBUG] - TvHome.OnVideoReceived() 1
[2013-04-30 17:04:05,631] [Log	] [35	  ] [DEBUG] - TvHome.StopRenderBlackImage()
[2013-04-30 17:04:09,931] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:04:09,934] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:04:09,934] [Log	] [8		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:04:09,934] [Log	] [8		] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2013-04-30 17:04:09,934] [Log	] [8		] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2013-04-30 17:04:14,752] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:14,752] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:15,896] [Log	] [36	  ] [DEBUG] - SubtitleRenderer: UpdateTimeout
[2013-04-30 17:04:16,582] [Log	] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2013-04-30 17:04:16,583] [Log	] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (17:04:09) to the local TvServer
[2013-04-30 17:04:16,584] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 17:04:17,169] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:17,169] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:20,994] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:20,994] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:21,967] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:21,967] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:24,953] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:04:24,956] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:04:24,956] [Log	] [8		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:04:24,956] [Log	] [8		] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2013-04-30 17:04:24,956] [Log	] [8		] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2013-04-30 17:04:25,767] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:25,767] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:27,044] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2013-04-30 17:04:27,044] [Log	] [MPMain  ] [INFO ] - Main: Deactivation request received
[2013-04-30 17:04:27,045] [Log	] [MPMain  ] [DEBUG] - D3D: OnLostFocus()
[2013-04-30 17:04:29,855] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:29,855] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:31,589] [Log	] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2013-04-30 17:04:31,589] [Log	] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 17:04:31
[2013-04-30 17:04:31,590] [Log	] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (17:04:31) to the local TvServer
[2013-04-30 17:04:31,591] [Log	] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2013-04-30 17:04:31,816] [Log	] [36	  ] [DEBUG] - SubtitleRenderer: UpdateTimeout
[2013-04-30 17:04:32,634] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:32,634] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:35,293] [Log	] [MPMain  ] [DEBUG] - D3D: OnFormClosing()
[2013-04-30 17:04:35,295] [Log	] [MPMain  ] [DEBUG] - g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False
[2013-04-30 17:04:35,295] [Log	] [MPMain  ] [INFO ] - g_Player.OnStopped()
[2013-04-30 17:04:35,297] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: current refreshrate is 50hz - changing it to 50hz
[2013-04-30 17:04:35,297] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: using internal win32 method for changing refreshrate. current is 50hz, desired is 50
[2013-04-30 17:04:35,302] [Log	] [MPMain  ] [DEBUG] - W7RefreshRateHelper.SetDisplayConfig(...): SDC_VALIDATE of 50000/1000 succesful
[2013-04-30 17:04:35,308] [Log	] [MPMain  ] [DEBUG] - W7RefreshRateHelper.GetRefreshRate: QueryDisplayConfig returned 50/1
[2013-04-30 17:04:35,308] [Log	] [MPMain  ] [INFO ] - CycleRefreshRate: successfully changed refresh rate to 50Hz (50Hz requested)
[2013-04-30 17:04:35,308] [Log	] [MPMain  ] [DEBUG] - CycleRefresh: DWM Detected, performing shenanigans
[2013-04-30 17:04:35,308] [Log	] [MPMain  ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: dynamic refresh rate change - notify video renderer
[2013-04-30 17:04:35,474] [Log	] [MPMain  ] [INFO ] - RadioRecorded:OnPlayRecordingBackStopped TV R:\\live4-0.ts.tsbuffer
[2013-04-30 17:04:35,480] [Log	] [MPMain  ] [INFO ] - TVHome:turn tv off
[2013-04-30 17:04:35,504] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableWakeupPlugins:
[2013-04-30 17:04:35,507] [Log	] [8		] [DEBUG] - PS: Inspecting WakeableStandbyPlugins:
[2013-04-30 17:04:35,507] [Log	] [8		] [DEBUG] - PS: Check if user interface is idle
[2013-04-30 17:04:35,507] [Log	] [8		] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2013-04-30 17:04:35,507] [Log	] [8		] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2013-04-30 17:04:35,662] [Log	] [MPMain  ] [INFO ] - TvRecorded:OnPlayRecordingBackStopped TV R:\\live4-0.ts.tsbuffer
[2013-04-30 17:04:35,794] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
[2013-04-30 17:04:35,794] [Log	] [MPMain  ] [INFO ] - Main: Activation reuqest received
[2013-04-30 17:04:35,794] [Log	] [MPMain  ] [DEBUG] - D3D: OnGotFocus()
[2013-04-30 17:04:37,174] [Log	] [27	  ] [DEBUG] - Finished Page 777
[2013-04-30 17:04:37,174] [Log	] [27	  ] [DEBUG] - Convert: Input data length 1000 teletext
[2013-04-30 17:04:40,798] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2013-04-30 17:04:40,798] [Log	] [MPMain  ] [INFO ] - Main: Deactivation request received
[2013-04-30 17:04:40,798] [Log	] [MPMain  ] [INFO ] - Main: Refresh rate changer running. Ignoring deactivation request
[2013-04-30 17:04:40,798] [Log	] [MPMain  ] [DEBUG] - D3D: OnLostFocus()
[2013-04-30 17:04:43,334] [Log	] [MPMain  ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
[2013-04-30 17:04:43,334] [Log	] [MPMain  ] [INFO ] - Main: Activation reuqest received
[2013-04-30 17:04:43,334] [Log	] [MPMain  ] [DEBUG] - D3D: OnGotFocus()

    I think I will try to install another build and compare logs.
    Thanks! :)
     
    Status
    Not open for further replies.

    Users who are viewing this thread

    Online now: 2 (members: 0, guests: 2)

    About The Project

    The vision of the MediaPortal project is to create a free open source media centre application, which supports all advanced media centre functions, and is accessible to all Windows users.

    In reaching this goal we are working every day to make sure our software is one of the best.

             

    Quick Navigation

    Support MediaPortal!

    The team works very hard to make sure the community is running the best HTPC-software. We give away MediaPortal for free but hosting and software is not for us.

    Care to support our work with a few bucks? We'd really appreciate it!

    Community platform by XenForo® © 2010-2021 XenForo Ltd.
    Top Bottom