[WiP] madVR support in MP1 (9 Viewers)

[daWooky]

Others be warned! It's one of those days I wished I'd fired up clonezilla first.

highly recommended at the moment

@Sebastiii
tried 3d sbs and mvc
sbs tab stuff seem ok also first mvc run was ok,but second bs.

Spoiler

[2017-11-19 15:19:44,774] [Log ] [Auto3D analyze thread] [INFO ] - Auto3D: Start Video Analysis
[2017-11-19 15:19:44,979] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:45,185] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:45,391] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:45,596] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:45,802] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:46,007] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:46,212] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:46,417] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:46,622] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:46,827] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:47,033] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:47,238] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:47,444] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:47,649] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:47,855] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:48,060] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:48,266] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:48,471] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:48,676] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:48,881] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:49,087] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:49,292] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:49,497] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:49,703] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:49,727] [Log ] [52 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:19:49,728] [Log ] [52 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:19:49,908] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:50,114] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:50,319] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:50,524] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:50,729] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:50,935] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:51,140] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:51,345] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:51,551] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:51,756] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:51,962] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:52,167] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:52,372] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:52,577] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:52,783] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:52,989] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:53,194] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:19:54,070] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:19:54,072] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:19:49) to the local TvServer
[2017-11-19 15:19:54,074] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:19:59,787] [Log ] [Auto3D analyze thread] [INFO ] - Auto3D: Start Video Analysis
[2017-11-19 15:19:59,993] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:00,199] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:00,404] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:00,610] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:00,816] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:01,021] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:01,227] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:01,432] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:01,637] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:01,843] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:02,048] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:02,254] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:02,459] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:02,664] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:02,869] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:03,075] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:03,279] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:03,484] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:03,690] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:03,895] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:04,101] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:04,306] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:04,511] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:04,717] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:04,778] [Log ] [52 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:20:04,779] [Log ] [52 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:20:04,923] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:05,128] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:05,333] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:05,538] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:05,744] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:05,949] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:06,155] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:06,360] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:06,565] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:06,770] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:06,975] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:07,181] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:07,386] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:07,592] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:07,797] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:08,003] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:08,208] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:20:09,081] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval

auto3d fault? i update it to .4 and i think i also have to remarry it with tv ^^

 

[daWooky]

re married auto3d to tv^^ but also bs. on first sbs pretty similar, damn

Spoiler

UNCFileFolderExists: Folder'\\SERVA\hd tests\test samples\3d\3d hou-sbs' exists!
[2017-11-19 15:40:20,738] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:40:22,034] [Log ] [MPMain ] [DEBUG] - WakeUpSrv: FolderName = \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv, ShareName = HD Tests, WOL enabled = True
[2017-11-19 15:40:22,034] [Log ] [MPMain ] [DEBUG] - WakeUpServer: The serva server already started and mac address is learnt!
[2017-11-19 15:40:22,034] [Log ] [MPMain ] [DEBUG] - VirtualDirectory: this file is not remote
[2017-11-19 15:40:22,050] [Log ] [MPMain ] [DEBUG] - VideodatabaseSqllite AddFile:\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,081] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Opening file : \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: DLL Version : MediaInfoLib - v0.7.95
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [INFO ] - MediaInfoWrapper.MediaInfoWrapper: Inspecting media : \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Parse speed : 0.3
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: FrameRate : 23,976
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Width : 1920
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Height : 856
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AspectRatio : widescreen
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoCodec : AVC [ "avc.png" ]
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Scan type : progressive
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: IsInterlaced : False
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoResolution : 1080P
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoDuration : 62813
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioRate : 48000
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioChannels : 6 [ "5.1.png" ]
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioCodec : DTS [ "dts.png" ]
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasAudio : True
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasVideo : True
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasSubtitles : False
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: NumSubtitles : 0
[2017-11-19 15:40:22,378] [Log ] [MPMain ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Closing file : \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - RefreshRateChanger.AdaptRefreshRate: 'auto refreshrate changer' disabled
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - g_Player.Play(\\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv Video)
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - Loading external players plugins
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - found plugin:MediaPortal.WinampPlayer.WinampPlugin in C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\ExternalPlayers\ExternalPlayers.dll
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - player:Winamp. author: int_20h
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - found plugin:MediaPortal.ITunesPlayer.ITunesPlugin in C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\ExternalPlayers\ExternalPlayers.dll
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [INFO ] - player:iTunes. author: Frodo
[2017-11-19 15:40:22,394] [Log ] [MPMain ] [DEBUG] - PlayerFactory: Successfully created player instance for file - \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,409] [Log ] [MPMain ] [INFO ] - VideoPlayerlay \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:22,425] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Enabling DX9 exclusive mode
[2017-11-19 15:40:22,425] [Log ] [MPMain ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
[2017-11-19 15:40:22,425] [Log ] [MPMain ] [DEBUG] - VMR9: addvmr9 - thread : MPMain
[2017-11-19 15:40:22,534] [Log ] [MPMain ] [DEBUG] - CycleRefreshRate: return new detected MonitorIndex : 0
[2017-11-19 15:40:22,534] [Log ] [MPMain ] [INFO ] - VMR9: backup current refresh rate value 50Hz
[2017-11-19 15:40:22,678] [Log ] [MPMain ] [DEBUG] - VMR9: madVR _backupCurrentScreenSizeWidth x _backupCurrentScreenSizeHeight : 1920 x 1080
[2017-11-19 15:40:22,679] [Log ] [MPMain ] [INFO ] - VMR9: added madVR Renderer to graph
[2017-11-19 15:40:22,680] [Log ] [MPMain ] [DEBUG] - VMR9: Now active
[2017-11-19 15:40:22,680] [Log ] [MPMain ] [DEBUG] - VMR9: Renderer successfully added
[2017-11-19 15:40:22,734] [Log ] [MPMain ] [DEBUG] - VideoPlayer9: Add LAVSplitter Source to graph
[2017-11-19 15:40:22,850] [Log ] [MPMain ] [INFO ] - VideoPlayer9: found H264 video out pin
[2017-11-19 15:40:22,850] [Log ] [MPMain ] [INFO ] - VideoPlayer9: found HEVC video out pin
[2017-11-19 15:40:22,939] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Video Decoder to graph
[2017-11-19 15:40:22,945] [Log ] [MPMain ] [INFO ] - DirectShowUtil: First try to insert new audio renderer Default DirectSound Device
[2017-11-19 15:40:22,945] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: Digitalaudio (S/PDIF) (Sound Blaster X-Fi Xtreme Audio) to graph for Default DirectSound Device
[2017-11-19 15:40:22,945] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: ReClock Audio Renderer to graph for Default DirectSound Device
[2017-11-19 15:40:22,946] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: MPC Audio Renderer to graph for Default DirectSound Device
[2017-11-19 15:40:22,946] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: MediaPortal - Audio Renderer to graph for Default DirectSound Device
[2017-11-19 15:40:22,947] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: Default DirectSound Device to graph for Default DirectSound Device
[2017-11-19 15:40:22,947] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Found audio renderer
[2017-11-19 15:40:22,949] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: added filterefault DirectSound Device to graph
[2017-11-19 15:40:22,961] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Audio Decoder to graph
[2017-11-19 15:40:22,964] [Log ] [MPMain ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Video
[2017-11-19 15:40:22,965] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Video
[2017-11-19 15:40:23,027] [Log ] [MPMain ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Audio
[2017-11-19 15:40:23,028] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: volume handler value 65535
[2017-11-19 15:40:23,029] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Audio
[2017-11-19 15:40:23,050] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Default DirectSound Device
[2017-11-19 15:40:23,050] [Log ] [MPMain ] [DEBUG] - Check graph connections for: madVR
[2017-11-19 15:40:23,051] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Audio Decoder
[2017-11-19 15:40:23,051] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Video Decoder
[2017-11-19 15:40:23,052] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Splitter Source
[2017-11-19 15:40:23,059] [Log ] [MPMain ] [DEBUG] - SubEngine : init engine : MediaPortal.Player.Subtitles.DirectVobSubEngine
[2017-11-19 15:40:23,078] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: XySubFilter to graph
[2017-11-19 15:40:23,079] [Log ] [MPMain ] [DEBUG] - VideoPlayerVMR9: VobSub filter added to graph
[2017-11-19 15:40:23,079] [Log ] [MPMain ] [DEBUG] - VideoPlayerVMR9: Setting DirectVobsub parameters
[2017-11-19 15:40:23,091] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Unknown; Name=Show Subtitles, Filter=XySubFilter, Id=0, PDWGroup=6590025, LCID=0
[2017-11-19 15:40:23,092] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Unknown; Name=Hide Subtitles, Filter=XySubFilter, Id=1, PDWGroup=6590025, LCID=0
[2017-11-19 15:40:23,092] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Video; Name=V: h264 high L4.1, yuv420p, 1920x856 [default], Filter=LAV Splitter Source, Id=0, PDWGroup=0, LCID=0
[2017-11-19 15:40:23,093] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Audio; Name=A: German [ger] (dts, 48000 Hz, 5.1, 1536 kb/s) [default], Filter=LAV Splitter Source, Id=1, PDWGroup=1, LCID=1031
[2017-11-19 15:40:23,096] [Log ] [MPMain ] [INFO ] - VideoPlayerVMR7: AudioLanguage CultureInfo de
[2017-11-19 15:40:23,101] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, XySubFilter
[2017-11-19 15:40:23,102] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Default DirectSound Device
[2017-11-19 15:40:23,102] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, madVR
[2017-11-19 15:40:23,102] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Audio Decoder
[2017-11-19 15:40:23,103] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Video Decoder
[2017-11-19 15:40:23,103] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Splitter Source
[2017-11-19 15:40:23,103] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, XySubFilter
[2017-11-19 15:40:23,104] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Default DirectSound Device
[2017-11-19 15:40:23,104] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, madVR
[2017-11-19 15:40:23,105] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Audio Decoder
[2017-11-19 15:40:23,105] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Video Decoder
[2017-11-19 15:40:23,106] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Splitter Source
[2017-11-19 15:40:23,106] [Log ] [MPMain ] [INFO ] - VideoPlayerVMR7: CultureInfo Selected active audio track language: German (0)
[2017-11-19 15:40:23,113] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 1920x856
[2017-11-19 15:40:23,113] [Log ] [MPMain ] [INFO ] - overlay: video AR : 1920:856
[2017-11-19 15:40:23,114] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 200x100
[2017-11-19 15:40:23,114] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal
[2017-11-19 15:40:23,115] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1
[2017-11-19 15:40:23,115] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(1920,856)
[2017-11-19 15:40:23,116] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,5)-(200,94)
[2017-11-19 15:40:23,117] [Log ] [MPMain ] [DEBUG] - VideoPlayer: SetSourcePosition 1
[2017-11-19 15:40:23,117] [Log ] [MPMain ] [DEBUG] - VideoPlayer: SetSourcePosition 2
[2017-11-19 15:40:23,158] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl start hr: 0
[2017-11-19 15:40:23,159] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 1920x856
[2017-11-19 15:40:23,159] [Log ] [MPMain ] [INFO ] - overlay: video AR : 1920:856
[2017-11-19 15:40:23,160] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1x1
[2017-11-19 15:40:23,160] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal
[2017-11-19 15:40:23,160] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1
[2017-11-19 15:40:23,161] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(1920,856)
[2017-11-19 15:40:23,161] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,0)-(1,0)
[2017-11-19 15:40:23,162] [Log ] [MPMain ] [INFO ] - VideoPlayeruration:62,812708
[2017-11-19 15:40:23,163] [Log ] [MPMain ] [INFO ] - g_Player.OnStarted() \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv media:Video
[2017-11-19 15:40:23,169] [Log ] [MPMain ] [INFO ] - Auto3D: OnPlayBackStarted: Video : \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:40:23,170] [Log ] [MPMain ] [INFO ] - Auto3D: OnVideoReceived
[2017-11-19 15:40:23,315] [Log ] [MPMain ] [DEBUG] - g_Player: ShowFullScreenWindow
[2017-11-19 15:40:23,316] [Log ] [MPMain ] [INFO ] - g_Player: ShowFullScreenWindow switching to fullscreen video
[2017-11-19 15:40:23,319] [Log ] [13 ] [INFO ] - Auto3D: Automatic Mode
[2017-11-19 15:40:23,341] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFiles deinit
[2017-11-19 15:40:23,358] [Log ] [13 ] [INFO ] - Auto3D: No FHD3D
[2017-11-19 15:40:23,381] [Log ] [MPMain ] [DEBUG] - TextureManager: CleanupThumbs()
[2017-11-19 15:40:23,387] [Log ] [MPMain ] [INFO ] - xml:C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Ares\videoFullScreen.xml image id:130 width:0 height:135 gfx:
[2017-11-19 15:40:23,392] [Log ] [MPMain ] [INFO ] - xml:C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Ares\videoFullScreen.xml image id:130 width:0 height:135 gfx:
[2017-11-19 15:40:23,394] [Log ] [PoolThread21] [DEBUG] - ThreadPool.ProcessQueue() : quitting (inUse:0, total:2)
[2017-11-19 15:40:23,401] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen init
[2017-11-19 15:40:23,404] [Log ] [MPMain ] [DEBUG] - Loading AR modes from "movies" section...
[2017-11-19 15:40:23,412] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 1920x856
[2017-11-19 15:40:23,413] [Log ] [MPMain ] [INFO ] - overlay: video AR : 1920:856
[2017-11-19 15:40:23,413] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1920x1080
[2017-11-19 15:40:23,414] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal
[2017-11-19 15:40:23,414] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1
[2017-11-19 15:40:23,415] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(1920,856)
[2017-11-19 15:40:23,415] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,112)-(1920,968)
[2017-11-19 15:40:23,416] [Log ] [MPMain ] [DEBUG] - VideoPlayer: SetSourcePosition 1
[2017-11-19 15:40:23,416] [Log ] [MPMain ] [DEBUG] - VideoPlayer: SetSourcePosition 2
[2017-11-19 15:40:23,446] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 50
[2017-11-19 15:40:23,461] [Log ] [MPMain ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Ares\myVideo.xml' is missing control id 8 (window property: btnTrailers)
[2017-11-19 15:40:23,573] [Log ] [MPMain ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 50
[2017-11-19 15:40:25,324] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 0
[2017-11-19 15:40:28,363] [Log ] [Auto3D analyze thread] [INFO ] - Auto3D: Start Video Analysis
[2017-11-19 15:40:28,574] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:28,785] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:28,995] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:29,205] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:29,415] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:29,625] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:29,835] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:30,045] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:30,255] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:30,465] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:30,676] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:30,886] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:31,096] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:31,306] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:31,516] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:31,726] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:31,936] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:32,146] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:32,356] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:32,566] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:32,776] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:32,986] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:33,196] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:33,406] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:33,616] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:33,826] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:34,036] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:34,191] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:40:34,191] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:40:22
[2017-11-19 15:40:34,193] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:40:22) to the local TvServer
[2017-11-19 15:40:34,195] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:40:34,247] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:34,457] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:34,667] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:34,877] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:35,087] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:35,297] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:35,507] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:35,717] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:35,785] [Log ] [3 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:40:35,785] [Log ] [3 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:40:35,927] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:36,137] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:36,347] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:36,557] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:36,767] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:36,977] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:43,374] [Log ] [Auto3D analyze thread] [INFO ] - Auto3D: Start Video Analysis
[2017-11-19 15:40:43,585] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:43,795] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:44,005] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:44,215] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:44,425] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:44,635] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:44,845] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:45,055] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:45,265] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:45,475] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:45,685] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:45,895] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:46,105] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:46,315] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:46,525] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:46,735] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:46,945] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:47,155] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:47,365] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:47,575] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:47,785] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:47,996] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:48,206] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:48,416] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:48,626] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:48,836] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:49,046] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:49,207] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:40:49,209] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:40:35) to the local TvServer
[2017-11-19 15:40:49,213] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:40:49,256] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:49,466] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:49,676] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:49,886] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:50,096] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:50,306] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:50,516] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:50,726] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:50,837] [Log ] [3 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:40:50,838] [Log ] [3 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:40:50,936] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:51,146] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:51,356] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:51,566] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:51,776] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:40:51,986] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Frame not ready for madVR
[2017-11-19 15:41:04,219] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:41:04,221] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:40:50) to the local TvServer
[2017-11-19 15:41:04,223] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:41:05,888] [Log ] [3 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:41:05,889] [Log ] [3 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:41:19,230] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:41:19,232] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:41:05) to the local TvServer
[2017-11-19 15:41:19,234] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:41:20,938] [Log ] [3 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:41:20,939] [Log ] [3 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:41:34,241] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:41:34,243] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:41:20) to the local TvServer
[2017-11-19 15:41:34,245] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:41:35,991] [Log ] [3 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer
[2017-11-19 15:41:35,991] [Log ] [3 ] [DEBUG] - PS: User interface not idle: StandbyPrevented
[2017-11-19 15:41:49,252] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:41:49,254] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:41:35) to the local TvServer
[2017-11-19 15:41:49,256] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:41:50,006] [Log ] [MPMain ] [DEBUG] - Centarea: Command "132078" not mapped
[2017-11-19 15:41:50,006] [Log ] [MPMain ] [INFO ] - Main: Stopping media
[2017-11-19 15:41:50,008] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False
[2017-11-19 15:41:50,009] [Log ] [MPMain ] [INFO ] - g_Player.OnStopped()
[2017-11-19 15:41:50,023] [Log ] [MPMain ] [INFO ] - RadioRecorded:OnPlayRecordingBackStopped Video \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:41:50,024] [Log ] [MPMain ] [INFO ] - TvRecorded:OnPlayRecordingBackStopped Video \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:41:50,024] [Log ] [MPMain ] [INFO ] - Auto3D: OnPlayBackStopped: Video : \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:41:50,026] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() - stop
[2017-11-19 15:41:50,026] [Log ] [MPMain ] [INFO ] - VideoPlayer:ended \\SERVA\hd tests\test samples\3d\3d hou-sbs\[Vx1 3d FHD p] br3d-minuscule3d_1080p_sample.mkv
[2017-11-19 15:41:50,027] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup DShow graph
[2017-11-19 15:41:50,027] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup VMR9
[2017-11-19 15:41:50,028] [Log ] [MPMain ] [DEBUG] - VMR9: mediaCtrl.Stop() 1
[2017-11-19 15:41:50,041] [Log ] [MPMain ] [DEBUG] - VMR9: Vmr9MediaCtrl MadStopping()
[2017-11-19 15:41:51,042] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:42:04,263] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:42:04,263] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:42:04
[2017-11-19 15:42:04,265] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:42:04) to the local TvServer
[2017-11-19 15:42:04,267] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:42:06,091] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:42:19,273] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:42:19,273] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:42:19
[2017-11-19 15:42:19,275] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:42:19) to the local TvServer
[2017-11-19 15:42:19,278] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:42:21,138] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:42:34,285] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:42:34,285] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:42:34
[2017-11-19 15:42:34,287] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:42:34) to the local TvServer
[2017-11-19 15:42:34,291] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:42:36,188] [Log ] [44 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:42:49,298] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:42:49,298] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:42:49
[2017-11-19 15:42:49,300] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:42:49) to the local TvServer
[2017-11-19 15:42:49,303] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:42:51,239] [Log ] [44 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:43:04,309] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:43:04,309] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:43:03
[2017-11-19 15:43:04,311] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:43:03) to the local TvServer
[2017-11-19 15:43:04,314] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:43:06,289] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:43:19,321] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:43:19,321] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:43:19
[2017-11-19 15:43:19,323] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:43:19) to the local TvServer
[2017-11-19 15:43:19,326] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2017-11-19 15:43:21,339] [Log ] [3 ] [DEBUG] - PS: User interface is idle
[2017-11-19 15:43:34,333] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2017-11-19 15:43:34,333] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 15:43:34
[2017-11-19 15:43:34,335] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (15:43:34) to the local TvServer
[2017-11-19 15:43:34,338] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()

 

[Sebastiii]

Hey
So it freeze on stop ? (Can you try to grab also madfreeze report when it happen)
There is a possible crash with grabframe that Auto3D use for analyze, so i have change the way to grab to try to avoid it.
the frame not ready message can happen when the video is not ready or where somehow the video will never start and in this situation, madVR can freeze for good
This is one of the crash i would to solve, the video that doesn't start happen rarely on my side.
In fact it's like the directshow graph wait for something and then madVR too and it will not recover.
Other player is multi thread, on thread for the player and another one for the video but too much work to adapt this on MP (i have try with no luck)

 

[Sebastiii]

Yep that the same case
Try to generate freeze report
But for sure it's the issue i was able to reproduce in some condition (and like explain in my previous post), if we can going to rendering then on stop it freeze hard because graph is waiting something to go on from madVR and madVR and waiting something to go on and lead to what we call a deadlock

So we really need to avoid this one but hard to reproduce, so you just start SBS ?

btw, what do you mean by 'bs' ( but also bs. on first sbs pretty similar) ?

 

[Sebastiii]

Ah black screen right ?

 

[daWooky]

In fact it's like the directshow graph wait for something

yeah like it needs a little extra push especially on the first play somehow^^

 

[daWooky]

black screen not bullshit^^

gonna grab ya some freeze and try with previous auto3d .3

 

[Sebastiii]

Hey (yep freeze report can at least try to show where it freeze)
So it doesn't always happen with the same sample ?
Does disable subtitle engine change anything ?

 

[Sebastiii]

Let me explain the logs :
When it failed :

[2017-11-19 15:19:39,147] [ 0] [ 814] - MPMadPresenter::Constructor() - instance 0x35208968
[2017-11-19 15:19:39,147] [ 0] [ 814] - MPMadPresenter::Constructor() Store Device Surface
[2017-11-19 15:21:41,437] [ 0] [ 814] - MPMadDshow::MadStopping start
[2017-11-19 15:21:41,437] [ 0] [ 814] - MPMadPresenter: setStopEvent() m_pSRCB
[2017-11-19 15:21:41,437] [ 0] [ 814] - MPMadPresenter: etStopEvent() m_pORCB
[2017-11-19 15:21:41,538] [ 0] [ 814] - MPMadPresenter: stopping() m_pSRCB
[2017-11-19 15:21:41,538] [ 0] [ 814] - MPMadPresenter: stopping() m_pORCB
[2017-11-19 15:21:41,538] [ 0] [ 814] - MPMadPresenter: stopping() start to stop instance - 1
[2017-11-19 15:21:41,538] [ 0] [ 814] - MPMadPresenter: stopping() start to stop instance - 2
[2017-11-19 15:21:41,538] [ 0] [ 814] - MPMadPresenter: stopping() start to stop instance - 3

You see here when madVR start :
[2017-11-19 15:19:39,147] [ 0] [ 814] - MPMadPresenter::Constructor() - instance 0x35208968
[2017-11-19 15:19:39,147] [ 0] [ 814] - MPMadPresenter::Constructor() Store Device Surface
But no D3D device happen (so madVR is waiting something to send the D3D information to MP)

In this case it works :

[2017-11-19 15:12:31,141] [ 0] [ 814] - MPMadPresenter::Constructor() - instance 0x35207680
[2017-11-19 15:12:31,141] [ 0] [ 814] - MPMadPresenter::Constructor() Store Device Surface
[2017-11-19 15:12:36,399] [ 0] [ 3d8] - MPMadPresenterH: setDeviceSub() device 0x:4667ac00
[2017-11-19 15:12:36,399] [ 0] [ 3d8] - MPMadPresenter: setDeviceSub() send subtitle device to C# 0x:4667ac00
[2017-11-19 15:12:36,406] [ 0] [ 3d8] - MPMadPresenterH: setDeviceOsd() device 0x:4667ac00
[2017-11-19 15:12:36,407] [ 0] [ 3d8] - MPMadPresenter: setDeviceOsd : ForceOsdUpdate
[2017-11-19 15:12:36,407] [ 0] [ 3d8] - MPMadPresenter: setDeviceOsd : reduce madVR frame to : 0
[2017-11-19 15:12:36,513] [ 0] [ 3d8] - MPMadPresenterH: setDeviceOsd() device 0x:0
[2017-11-19 15:12:36,513] [ 0] [ 3d8] - MPMadPresenter::ReinitD3DDevice : ReinitOSDDevice for : 1920 x 1080
[2017-11-19 15:12:36,513] [ 0] [ 3d8] - MPMadPresenter: setDeviceOsd() release m_pMadD3DDev
[2017-11-19 15:12:36,521] [ 0] [ 3d8] - MPMadPresenterH:setDeviceSub() device 0x:0
[2017-11-19 15:12:36,521] [ 0] [ 3d8] - MPMadPresenter: setDeviceSub() send subtitle device to C# 0x:0
[2017-11-19 15:12:41,543] [ 0] [2040] - MPMadPresenterH: setDeviceSub() device 0x:1d082380
[2017-11-19 15:12:41,543] [ 0] [2040] - MPMadPresenter: setDeviceSub() send subtitle device to C# 0x:1d082380
[2017-11-19 15:12:41,549] [ 0] [2040] - MPMadPresenterH: setDeviceOsd() device 0x:1d082380
[2017-11-19 15:12:41,550] [ 0] [2040] - MPMadPresenter: setDeviceOsd : ForceOsdUpdate
[2017-11-19 15:12:41,550] [ 0] [2040] - MPMadPresenter: setDeviceOsd : reduce madVR frame to : 0
[2017-11-19 15:12:43,587] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD from : RenderOsd
[2017-11-19 15:12:43,589] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD for : 1920 x 1080
[2017-11-19 15:12:45,579] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD from : RenderOsd
[2017-11-19 15:12:45,581] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD for : 1920 x 1080

We can see that madVR send us D3D device :
[2017-11-19 15:12:36,399] [ 0] [ 3d8] - MPMadPresenterH: setDeviceSub() device 0x:4667ac00
Then like it's MVC, madVR reset D3D and send us :
[2017-11-19 15:12:36,513] [ 0] [ 3d8] - MPMadPresenterH: setDeviceOsd() device 0x:0
And then we get the final D3D device :
[2017-11-19 15:12:41,550] [ 0] [2040] - MPMadPresenter: setDeviceOsd : ForceOsdUpdate
And when we get those and those line :
[2017-11-19 15:12:45,579] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD from : RenderOsd
[2017-11-19 15:12:45,581] [ 0] [2040] - MPMadPresenter::ReinitOSD : ReinitOSD for : 1920 x 1080

Rendering has started

So when the rendering doesn't start -> boom -> bs -> because of the deadlock

 

[daWooky]

i just try it in the other direction but without manual stopped. Seemed ok

Gesendet von meinem Nexus 7 mit Tapatalk