MediaPortal.log, Playback of the Video ==================================================================================================================================================================================================================================== [2018-12-10 13:51:09,482] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:51:12,452] [Log ] [MPMain ] [DEBUG] - WindowManager: unroute to MediaPortal.Dialogs.GUIDialogMenu:2012->MediaPortal.GUI.Video.GUIVideoFiles:6 [2018-12-10 13:51:12,452] [Log ] [MPMain ] [DEBUG] - [WIFI_REMOTE] Sending dialog close to clients [2018-12-10 13:51:12,490] [Log ] [MPMain ] [INFO ] - RefreshRateChanger.AdaptRefreshRate: 'auto refreshrate changer' disabled [2018-12-10 13:51:12,490] [Log ] [MPMain ] [INFO ] - g_Player.Play(\\PCTIM\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv Video) [2018-12-10 13:51:12,490] [Log ] [MPMain ] [DEBUG] - PlayerFactory: Successfully created player instance for file - \\PCTIM\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv [2018-12-10 13:51:12,490] [Log ] [MPMain ] [INFO ] - VideoPlayer:play \\PCTIM\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv [2018-12-10 13:51:12,490] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Enabling DX9 exclusive mode [2018-12-10 13:51:12,490] [Log ] [MPMain ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete. [2018-12-10 13:51:12,506] [Log ] [MPMain ] [DEBUG] - VMR9: addvmr9 - thread : MPMain [2018-12-10 13:51:12,698] [Log ] [MPMain ] [INFO ] - VMR9: added EVR Renderer to graph [2018-12-10 13:51:12,701] [Log ] [MPMain ] [DEBUG] - VMR9: Now active for playlist [2018-12-10 13:51:12,702] [Log ] [MPMain ] [DEBUG] - VMR9: Now active [2018-12-10 13:51:12,704] [Log ] [MPMain ] [DEBUG] - VMR9: Renderer successfully added [2018-12-10 13:51:12,707] [Log ] [MPMain ] [DEBUG] - VideoPlayer9: Add LAVSplitter Source to graph [2018-12-10 13:51:12,831] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: ffdshow Video Decoder to graph [2018-12-10 13:51:12,835] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Video Decoder to graph [2018-12-10 13:51:12,836] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Attach volume handler device to audio renderer: Default DirectSound Device [2018-12-10 13:51:12,905] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:51:12,907] [Log ] [MPMain ] [INFO ] - DirectShowUtils: First try to insert new audio renderer Default DirectSound Device [2018-12-10 13:51:12,908] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: SAMSUNG (Intel(R) Display-Audio) to graph for Default DirectSound Device [2018-12-10 13:51:12,910] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: MediaPortal - Audio Renderer to graph for Default DirectSound Device [2018-12-10 13:51:12,911] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: Default DirectSound Device to graph for Default DirectSound Device [2018-12-10 13:51:12,912] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Found audio renderer [2018-12-10 13:51:12,914] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: added filter:Default DirectSound Device to graph [2018-12-10 13:51:12,933] [Log ] [MPMain ] [INFO ] - VideoPlayer9: found AAC LAVF Audio out pin [2018-12-10 13:51:12,935] [Log ] [MPMain ] [INFO ] - VideoPlayer9: found AAC Audio out pin [2018-12-10 13:51:12,938] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Audio Decoder to graph [2018-12-10 13:51:12,939] [Log ] [MPMain ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Video [2018-12-10 13:51:12,940] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Video [2018-12-10 13:51:12,964] [Log ] [MPMain ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:51:12,985] [Log ] [MPMain ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Audio [2018-12-10 13:51:12,986] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: volume handler value 65535 [2018-12-10 13:51:12,988] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Audio [2018-12-10 13:51:13,002] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Default DirectSound Device [2018-12-10 13:51:13,004] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Enhanced Video Renderer [2018-12-10 13:51:13,005] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Audio Decoder [2018-12-10 13:51:13,007] [Log ] [MPMain ] [DEBUG] - Check graph connections for: ffdshow Video Decoder [2018-12-10 13:51:13,008] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Video Decoder [2018-12-10 13:51:13,009] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Splitter Source [2018-12-10 13:51:13,011] [Log ] [MPMain ] [DEBUG] - SubEngine : init engine : MediaPortal.Player.Subtitles.MpcEngine [2018-12-10 13:51:13,012] [Log ] [MPMain ] [DEBUG] - MpcEngine: using texture size of 1280x720 [2018-12-10 13:51:13,014] [Log ] [MPMain ] [INFO ] - FFDshow interfaces found -> Subtitles disabled [2018-12-10 13:51:13,016] [Log ] [MPMain ] [INFO ] - MpcEngine: Subtitle VideoPlayer CultureInfo de [2018-12-10 13:51:13,023] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Video; Name=V: hevc main, yuv420p, 1920x1080 [default], Filter=LAV Splitter Source, Id=0, PDWGroup=0, LCID=0 [2018-12-10 13:51:13,024] [Log ] [MPMain ] [DEBUG] - VideoPlayer: FoundStreams: Type=Audio; Name=A: German [ger] (aac lc, 48000 Hz, stereo) [default], Filter=LAV Splitter Source, Id=1, PDWGroup=1, LCID=1031 [2018-12-10 13:51:13,026] [Log ] [MPMain ] [INFO ] - VideoPlayerVMR7: AudioLanguage CultureInfo de [2018-12-10 13:51:13,027] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Default DirectSound Device [2018-12-10 13:51:13,029] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Enhanced Video Renderer [2018-12-10 13:51:13,030] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Audio Decoder [2018-12-10 13:51:13,031] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, ffdshow Video Decoder [2018-12-10 13:51:13,033] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Video Decoder [2018-12-10 13:51:13,034] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Splitter Source [2018-12-10 13:51:13,035] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Default DirectSound Device [2018-12-10 13:51:13,037] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, Enhanced Video Renderer [2018-12-10 13:51:13,038] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Audio Decoder [2018-12-10 13:51:13,040] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, ffdshow Video Decoder [2018-12-10 13:51:13,041] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Video Decoder [2018-12-10 13:51:13,042] [Log ] [MPMain ] [DEBUG] - GetFilterByName: LAV Splitter Source, LAV Splitter Source [2018-12-10 13:51:13,044] [Log ] [MPMain ] [INFO ] - VideoPlayerVMR7: CultureInfo Selected active audio track language: German (0) [2018-12-10 13:51:13,046] [Log ] [MPMain ] [WARN ] - Right + Left cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,047] [Log ] [MPMain ] [WARN ] - Top + Bottom cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,048] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 0x0 [2018-12-10 13:51:13,049] [Log ] [MPMain ] [INFO ] - overlay: video AR : 0:0 [2018-12-10 13:51:13,051] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 200x100 [2018-12-10 13:51:13,052] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:51:13,053] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:51:13,054] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(0,0) [2018-12-10 13:51:13,055] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,-2147483648)-(200,0) [2018-12-10 13:51:13,056] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl start put_WindowStyle [2018-12-10 13:51:13,064] [Log ] [43 ] [DEBUG] - PS: User interface is idle [2018-12-10 13:51:13,089] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl start hr: 1 [2018-12-10 13:51:13,249] [Log ] [16 ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:51:13,441] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl hr: 0 [2018-12-10 13:51:13,443] [Log ] [MPMain ] [INFO ] - VideoPlayer:Duration:3852,98 [2018-12-10 13:51:13,446] [Log ] [MPMain ] [INFO ] - g_Player.OnStarted() \\SERVER\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv media:Video [2018-12-10 13:51:13,454] [Log ] [14 ] [WARN ] - Right + Left cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,459] [Log ] [14 ] [WARN ] - Top + Bottom cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,461] [Log ] [14 ] [INFO ] - overlay: video WxH : 0x0 [2018-12-10 13:51:13,463] [Log ] [14 ] [INFO ] - overlay: video AR : 0:0 [2018-12-10 13:51:13,465] [Log ] [14 ] [INFO ] - overlay: screen WxH : 241x135 [2018-12-10 13:51:13,467] [Log ] [14 ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:51:13,468] [Log ] [14 ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:51:13,470] [Log ] [14 ] [INFO ] - overlay: src : (0,0)-(0,0) [2018-12-10 13:51:13,471] [Log ] [14 ] [INFO ] - overlay: dst : (35,-2147483620)-(276,28) [2018-12-10 13:51:13,476] [Log ] [MPMain ] [DEBUG] - [WIFI_REMOTE] Playback started! [2018-12-10 13:51:13,480] [Log ] [14 ] [DEBUG] - GUIVideoOverlay : SetCurrentFile DoesPostRender _fileName Muse - Live @ Reeperbahn Festival (2018) [2018-12-10 13:51:13,711] [Log ] [MPMain ] [INFO ] - [WIFI_REMOTE] {"Type":"nowplaying","Duration":3852,"File":"\\\\SERVER\\Konzerte\\Muse\\Muse - Live @ Reeperbahn Festival (2018).mkv","Position":0,"IsTv":false,"IsFullscreen":false,"MediaInfo":null} [2018-12-10 13:51:13,714] [Log ] [34 ] [DEBUG] - [WIFI_REMOTE] Start now-playing update thread [2018-12-10 13:51:13,715] [Log ] [MPMain ] [DEBUG] - g_Player: ShowFullScreenWindow [2018-12-10 13:51:13,725] [Log ] [MPMain ] [INFO ] - g_Player: ShowFullScreenWindow switching to fullscreen video [2018-12-10 13:51:13,755] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFiles deinit [2018-12-10 13:51:13,782] [Log ] [MPMain ] [DEBUG] - TextureManager: CleanupThumbs() [2018-12-10 13:51:13,792] [Log ] [MPMain ] [INFO ] - xml:C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Apollo\Themes\DarknessMod\videoFullScreen.xml image id:130 width:0 height:135 gfx: [2018-12-10 13:51:13,799] [Log ] [MPMain ] [WARN ] - Right + Left cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,814] [Log ] [MPMain ] [WARN ] - Top + Bottom cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,816] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 0x0 [2018-12-10 13:51:13,853] [Log ] [MPMain ] [INFO ] - overlay: video AR : 0:0 [2018-12-10 13:51:13,854] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1x1 [2018-12-10 13:51:13,857] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:51:13,859] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:51:13,861] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(0,0) [2018-12-10 13:51:13,862] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,-2147483648)-(1,0) [2018-12-10 13:51:13,864] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen init [2018-12-10 13:51:13,866] [Log ] [MPMain ] [DEBUG] - Loading AR modes from "movies" section... [2018-12-10 13:51:13,867] [Log ] [MPMain ] [WARN ] - Right + Left cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,868] [Log ] [MPMain ] [WARN ] - Top + Bottom cropping larger than screenwidth! Setting to 0 [2018-12-10 13:51:13,870] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 0x0 [2018-12-10 13:51:13,872] [Log ] [MPMain ] [INFO ] - overlay: video AR : 0:0 [2018-12-10 13:51:13,878] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1920x1080 [2018-12-10 13:51:13,879] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:51:13,881] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:51:13,883] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(0,0) [2018-12-10 13:51:13,884] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,-2147483648)-(1920,0) [2018-12-10 13:51:13,905] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 70 [2018-12-10 13:51:13,934] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:70 enabled:True allowed:False [2018-12-10 13:51:13,948] [Log ] [MPMain ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Apollo\Themes\DarknessMod\myVideo.xml' is missing control id 8 (window property: btnTrailers) [2018-12-10 13:51:13,954] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:71 enabled:True allowed:False [2018-12-10 13:51:13,970] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:72 enabled:True allowed:False [2018-12-10 13:51:13,989] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:73 enabled:True allowed:False [2018-12-10 13:51:14,004] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:74 enabled:True allowed:False [2018-12-10 13:51:14,020] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:75 enabled:True allowed:False [2018-12-10 13:51:14,038] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:76 enabled:True allowed:False [2018-12-10 13:51:14,070] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:77 enabled:True allowed:False [2018-12-10 13:51:14,084] [Log ] [MPMain ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 78 [2018-12-10 13:51:14,088] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:0 enabled:True allowed:False [2018-12-10 13:51:14,120] [Log ] [14 ] [DEBUG] - PlaneScene: crop T, B : 0, 0 [2018-12-10 13:51:14,122] [Log ] [14 ] [DEBUG] - PlaneScene: crop L, R : 0, 0 [2018-12-10 13:51:14,123] [Log ] [14 ] [INFO ] - PlaneScene: video WxH : 1920x1080 [2018-12-10 13:51:14,125] [Log ] [14 ] [DEBUG] - PlaneScene: video AR : 16:9 [2018-12-10 13:51:14,127] [Log ] [14 ] [INFO ] - PlaneScene: screen WxH : 1920x1080 [2018-12-10 13:51:14,129] [Log ] [14 ] [DEBUG] - PlaneScene: AR type : Normal [2018-12-10 13:51:14,131] [Log ] [14 ] [DEBUG] - PlaneScene: PixelRatio : 1 [2018-12-10 13:51:14,132] [Log ] [14 ] [DEBUG] - PlaneScene: src : (0,0)-(1920,1080) [2018-12-10 13:51:14,133] [Log ] [14 ] [DEBUG] - PlaneScene: dst : (0,0)-(1920,1080) [2018-12-10 13:51:24,499] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:51:24,502] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 13:51:12 [2018-12-10 13:51:24,508] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:51:12) to the local TvServer [2018-12-10 13:51:24,514] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:51:28,149] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 0 [2018-12-10 13:51:28,160] [Log ] [43 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:51:28,208] [Log ] [43 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:51:28,285] [Log ] [14 ] [INFO ] - planescene:PresentImage() frame:0 enabled:True allowed:False [2018-12-10 13:51:28,291] [Log ] [MPMain ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 1 [2018-12-10 13:51:39,522] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:51:39,575] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:51:28) to the local TvServer [2018-12-10 13:51:39,602] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:51:42,331] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 0 [2018-12-10 13:51:43,355] [Log ] [43 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:51:43,358] [Log ] [43 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:51:54,651] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:51:54,655] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:51:43) to the local TvServer [2018-12-10 13:51:54,659] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:51:58,754] [Log ] [43 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:51:58,756] [Log ] [43 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:52:09,669] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:52:09,684] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:51:58) to the local TvServer [2018-12-10 13:52:09,688] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:52:13,893] [Log ] [43 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:52:13,896] [Log ] [43 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:52:19,068] [Log ] [MPMain ] [INFO ] - Main: Stopping media [2018-12-10 13:52:19,072] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False [2018-12-10 13:52:19,074] [Log ] [MPMain ] [INFO ] - g_Player.OnStopped() [2018-12-10 13:52:19,083] [Log ] [MPMain ] [INFO ] - GUIVideoFiles: OnPlayBackStopped idFile=10698 timeMovieStopped=65 resumeData= bdtitle=1000 [2018-12-10 13:52:19,098] [Log ] [MPMain ] [DEBUG] - GUIVideoFiles: OnPlayBackStopped store resume time [2018-12-10 13:52:19,113] [Log ] [MPMain ] [INFO ] - RadioRecorded:OnPlayRecordingBackStopped Video \\SERVER\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv [2018-12-10 13:52:19,114] [Log ] [MPMain ] [INFO ] - TvRecorded:OnPlayRecordingBackStopped Video \\SERVER\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv [2018-12-10 13:52:19,116] [Log ] [MPMain ] [DEBUG] - [WIFI_REMOTE] Playback stopped! [2018-12-10 13:52:19,134] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() - stop [2018-12-10 13:52:19,139] [Log ] [MPMain ] [INFO ] - VideoPlayer:ended \\SERVER\Konzerte\Muse\Muse - Live @ Reeperbahn Festival (2018).mkv [2018-12-10 13:52:19,141] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup DShow graph [2018-12-10 13:52:19,178] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup VMR9 [2018-12-10 13:52:19,179] [Log ] [MPMain ] [DEBUG] - VMR9: mediaCtrl.Stop() 1 [2018-12-10 13:52:19,291] [Log ] [MPMain ] [DEBUG] - VMR9: mediaCtrl.Stop() 2 [2018-12-10 13:52:19,367] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup VideoCodec [2018-12-10 13:52:19,369] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup AudioCodec [2018-12-10 13:52:19,372] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup AudioRenderer [2018-12-10 13:52:19,375] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup InterfaceSourceFilter [2018-12-10 13:52:19,388] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup PostProcess [2018-12-10 13:52:19,390] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup PostProcess MediaPortal AudioSwitcher [2018-12-10 13:52:19,392] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter Enhanced Video Renderer [2018-12-10 13:52:19,394] [Log ] [MPMain ] [DEBUG] - Remove filter from graph: Enhanced Video Renderer [2018-12-10 13:52:19,396] [Log ] [MPMain ] [INFO ] - Playback stopped and reverting volume OSD back to default device. [2018-12-10 13:52:19,469] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:52:19,471] [Log ] [MPMain ] [DEBUG] - VideoPlayer9: rotEntry Dispose 1 [2018-12-10 13:52:19,473] [Log ] [MPMain ] [DEBUG] - VideoPlayer9: rotEntry Dispose 2 [2018-12-10 13:52:19,576] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup Graphbuilder [2018-12-10 13:52:19,578] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Cleanup Sub/PostProcess [2018-12-10 13:52:19,579] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose [2018-12-10 13:52:19,581] [Log ] [MPMain ] [DEBUG] - SubtitleRenderer: starting cleanup [2018-12-10 13:52:19,582] [Log ] [MPMain ] [DEBUG] - SubtitleRenderer: cleanup done [2018-12-10 13:52:19,584] [Log ] [MPMain ] [DEBUG] - VMR9: Inactive for playlist [2018-12-10 13:52:19,585] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose 1 [2018-12-10 13:52:19,591] [Log ] [MPMain ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:52:19,593] [Log ] [MPMain ] [INFO ] - Playback stopped and reverting volume OSD back to default device. [2018-12-10 13:52:19,656] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:52:19,656] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose 4 [2018-12-10 13:52:19,656] [Log ] [MPMain ] [INFO ] - Releasing filter System.__ComObject, remaining references: 1 [2018-12-10 13:52:19,656] [Log ] [MPMain ] [DEBUG] - VMR9: Inactive [2018-12-10 13:52:19,671] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose done [2018-12-10 13:52:19,671] [Log ] [MPMain ] [DEBUG] - VideoPlayer9: Cleanup done [2018-12-10 13:52:19,671] [Log ] [MPMain ] [INFO ] - VideoPlayer9: Disabling DX9 exclusive mode [2018-12-10 13:52:19,675] [Log ] [MPMain ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete. [2018-12-10 13:52:19,675] [Log ] [MPMain ] [INFO ] - GUIVideoFullscreen:stop [2018-12-10 13:52:19,675] [Log ] [MPMain ] [DEBUG] - Windowmanager: Goto previous window [2018-12-10 13:52:19,675] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen deinit [2018-12-10 13:52:19,675] [Log ] [MPMain ] [DEBUG] - TextureManager: CleanupThumbs() [2018-12-10 13:52:19,675] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFiles init MediaPortal.log, Playback of the (unedited) Recording ==================================================================================================================================================================================================================================== [2018-12-10 13:25:53,297] [Log ] [MPMain ] [INFO ] - PlayRecording:D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts - using rtsp mode:False [2018-12-10 13:25:53,297] [Log ] [MPMain ] [INFO ] - g_Player.Play(D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts Recording) [2018-12-10 13:25:53,297] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Opening file : D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:53,297] [Log ] [MPMain ] [DEBUG] - PlayerFactory: Successfully created player instance for file - D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:53,297] [Log ] [MPMain ] [INFO ] - TSReaderPlayer play:D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts radio:False [2018-12-10 13:25:53,297] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Enabling DX9 exclusive mode [2018-12-10 13:25:53,313] [Log ] [MPMain ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete. [2018-12-10 13:25:53,313] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:play D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:53,313] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: GetInterfaces() [2018-12-10 13:25:53,328] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Attach volume handler device to audio renderer: Default DirectSound Device [2018-12-10 13:25:53,397] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:25:53,397] [Log ] [MPMain ] [INFO ] - DirectShowUtils: First try to insert new audio renderer Default DirectSound Device [2018-12-10 13:25:53,397] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: SAMSUNG (Intel(R) Display-Audio) to graph for Default DirectSound Device [2018-12-10 13:25:53,397] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: MediaPortal - Audio Renderer to graph for Default DirectSound Device [2018-12-10 13:25:53,397] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: Default DirectSound Device to graph for Default DirectSound Device [2018-12-10 13:25:53,397] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Found audio renderer [2018-12-10 13:25:53,413] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: added filter:Default DirectSound Device to graph [2018-12-10 13:25:53,422] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Add TsReader to graph [2018-12-10 13:25:53,424] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Open file: D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:53,428] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:OnRequestAudioChange() [2018-12-10 13:25:53,430] [Log ] [MPMain ] [DEBUG] - TVHome.OnAudioTracksReady() [2018-12-10 13:25:53,440] [Log ] [MPMain ] [DEBUG] - TVHome.GetPreferedAudioStreamIndex(): preferred LANG(s):deu;ger preferAC3:False preferAudioTypeOverLang:True [2018-12-10 13:25:53,441] [Log ] [MPMain ] [DEBUG] - Audio streams avail: 3 [2018-12-10 13:25:53,443] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,445] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,447] [Log ] [MPMain ] [DEBUG] - Stream 0 lang deu, lang priority index 0 [2018-12-10 13:25:53,449] [Log ] [MPMain ] [DEBUG] - Setting mpeg pref [2018-12-10 13:25:53,450] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,451] [Log ] [MPMain ] [DEBUG] - Stream 1 lang fre, lang priority index -1 [2018-12-10 13:25:53,452] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,453] [Log ] [MPMain ] [DEBUG] - Stream 2 lang mis, lang priority index -1 [2018-12-10 13:25:53,455] [Log ] [MPMain ] [INFO ] - Audio stream: switching to preferred MPEG audio stream 0, based on LANG deu [2018-12-10 13:25:53,457] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,460] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: OnVideoFormatChanged - streamtype=HEVC resolution=1920x1080 aspect ratio=16:9 bitrate=5000000 isInterlaced=False MP main thread [2018-12-10 13:25:53,466] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Add codecs [2018-12-10 13:25:53,467] [Log ] [MPMain ] [DEBUG] - VMR9: addvmr9 - thread : MPMain [2018-12-10 13:25:53,663] [Log ] [MPMain ] [INFO ] - VMR9: added EVR Renderer to graph [2018-12-10 13:25:53,664] [Log ] [MPMain ] [DEBUG] - VMR9: Now active for playlist [2018-12-10 13:25:53,666] [Log ] [MPMain ] [DEBUG] - VMR9: Now active [2018-12-10 13:25:53,667] [Log ] [MPMain ] [DEBUG] - VMR9: Renderer successfully added [2018-12-10 13:25:53,674] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Video Decoder to graph [2018-12-10 13:25:53,676] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: UpdateFilters Video done [2018-12-10 13:25:53,679] [Log ] [MPMain ] [INFO ] - DirectShowUtil: Added filter: LAV Audio Decoder to graph [2018-12-10 13:25:53,680] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: UpdateFilters Audio done [2018-12-10 13:25:53,681] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: PostProcessingEngine to DummyEngine [2018-12-10 13:25:53,682] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Render TsReader outputs [2018-12-10 13:25:53,684] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Audio [2018-12-10 13:25:53,685] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: volume handler value 65535 [2018-12-10 13:25:53,686] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Audio [2018-12-10 13:25:53,700] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Video [2018-12-10 13:25:53,701] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Video [2018-12-10 13:25:53,704] [Log ] [MPMain ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:25:53,735] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Subtitle [2018-12-10 13:25:53,736] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Subtitle [2018-12-10 13:25:53,746] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: RenderUnconnectedOutputPins Pin Subtitle - failed [2018-12-10 13:25:53,749] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: CleanupCC filter (Tv/Recorded Stream Detected) [2018-12-10 13:25:53,751] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Enhanced Video Renderer [2018-12-10 13:25:53,752] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Default DirectSound Device [2018-12-10 13:25:53,753] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Video Decoder [2018-12-10 13:25:53,755] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Audio Decoder [2018-12-10 13:25:53,756] [Log ] [MPMain ] [DEBUG] - Check graph connections for: TsReader [2018-12-10 13:25:53,759] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Last subtitle index: 0 [2018-12-10 13:25:53,761] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:GetVideoSize() failed [2018-12-10 13:25:53,762] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:VideoSize:100x100 [2018-12-10 13:25:53,763] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl start put_WindowStyle [2018-12-10 13:25:53,796] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl start hr: 1 [2018-12-10 13:25:53,841] [Log ] [MPMain ] [DEBUG] - VMR9: StartMediaCtrl hr: 0 [2018-12-10 13:25:53,846] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetVideoWindow() [2018-12-10 13:25:53,848] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 100x100 [2018-12-10 13:25:53,850] [Log ] [MPMain ] [INFO ] - overlay: video AR : 100:100 [2018-12-10 13:25:53,852] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1x1 [2018-12-10 13:25:53,853] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:25:53,855] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:25:53,856] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(100,100) [2018-12-10 13:25:53,857] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,0)-(1,1) [2018-12-10 13:25:53,859] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:Window (0,0)-(100,100) - (0,0)-(1,1) [2018-12-10 13:25:53,862] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 1 [2018-12-10 13:25:53,864] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 2 [2018-12-10 13:25:53,868] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: OnInitialized [2018-12-10 13:25:53,869] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetVideoWindow() [2018-12-10 13:25:53,871] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 100x100 [2018-12-10 13:25:53,872] [Log ] [MPMain ] [INFO ] - overlay: video AR : 100:100 [2018-12-10 13:25:53,874] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1x1 [2018-12-10 13:25:53,876] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:25:53,877] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:25:53,879] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(100,100) [2018-12-10 13:25:53,881] [Log ] [MPMain ] [INFO ] - overlay: dst : (0,0)-(1,1) [2018-12-10 13:25:53,883] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:Window (0,0)-(100,100) - (0,0)-(1,1) [2018-12-10 13:25:53,885] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 1 [2018-12-10 13:25:53,887] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 2 [2018-12-10 13:25:53,889] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: position:0,036, duration:4911,006 [2018-12-10 13:25:53,891] [Log ] [MPMain ] [INFO ] - g_Player.OnStarted() D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts media:Recording [2018-12-10 13:25:53,897] [Log ] [MPMain ] [DEBUG] - TVHome.GetPreferedAudioStreamIndex(): preferred LANG(s):deu;ger preferAC3:False preferAudioTypeOverLang:True [2018-12-10 13:25:53,898] [Log ] [MPMain ] [DEBUG] - Audio streams avail: 3 [2018-12-10 13:25:53,900] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,901] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,902] [Log ] [MPMain ] [DEBUG] - Stream 0 lang deu, lang priority index 0 [2018-12-10 13:25:53,903] [Log ] [MPMain ] [DEBUG] - Setting mpeg pref [2018-12-10 13:25:53,905] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,906] [Log ] [MPMain ] [DEBUG] - Stream 1 lang fre, lang priority index -1 [2018-12-10 13:25:53,908] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded [2018-12-10 13:25:53,905] [Log ] [56 ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:25:53,909] [Log ] [MPMain ] [DEBUG] - Stream 2 lang mis, lang priority index -1 [2018-12-10 13:25:53,912] [Log ] [MPMain ] [INFO ] - Audio stream: switching to preferred MPEG audio stream 0, based on LANG deu [2018-12-10 13:25:53,913] [Log ] [MPMain ] [DEBUG] - TVRecorded.OnPlayRecordingBackStarted(): setting audioIndex on tsreader 0 [2018-12-10 13:25:53,915] [Log ] [MPMain ] [DEBUG] - [WIFI_REMOTE] Playback started! [2018-12-10 13:25:53,927] [Log ] [MPMain ] [INFO ] - [WIFI_REMOTE] {"Type":"nowplaying","Duration":4911,"File":"D:\\Aufnahmen\\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts","Position":0,"IsTv":true,"IsFullscreen":false,"MediaInfo":{"MediaType":"recording","MpExtId":"623","MpExtMediaType":13,"MpExtProviderId":0,"ChannelId":11,"RecordingId":623,"ChannelName":"arte","ProgramName":"Muse - Reeperbahn Festival 2018","ProgramDescription":"Aus: D 2018\nDer Auftritt der Stadionrocker in intimer Kulisse war einer der Höhepunkte des Hamburger Festivals. Mit ihrem Mix aus Rock und Elektro-Sound begeisterte das britische Trio im Club \"Docks\".\nRegie: Tom Kirk\nMit: Tom Kirk (Regie)\n","ProgramBegin":"\/Date(1543010595000+0100)\/","ProgramEnd":"\/Date(1543015507000+0100)\/"}} [2018-12-10 13:25:53,929] [Log ] [MPMain ] [DEBUG] - g_Player: ShowFullScreenWindow [2018-12-10 13:25:53,932] [Log ] [MPMain ] [INFO ] - g_Player: ShowFullScreenWindow switching to fullscreen video [2018-12-10 13:25:53,936] [Log ] [MPMain ] [DEBUG] - Window: TvPlugin.TvRecorded deinit [2018-12-10 13:25:53,929] [Log ] [48 ] [DEBUG] - [WIFI_REMOTE] Start now-playing update thread [2018-12-10 13:25:53,953] [Log ] [MPMain ] [DEBUG] - TextureManager: CleanupThumbs() [2018-12-10 13:25:53,960] [Log ] [MPMain ] [INFO ] - xml:C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Apollo\Themes\DarknessMod\videoFullScreen.xml image id:130 width:0 height:135 gfx: [2018-12-10 13:25:53,967] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetVideoWindow() [2018-12-10 13:25:53,972] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen init [2018-12-10 13:25:53,974] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetVideoWindow() [2018-12-10 13:25:53,975] [Log ] [MPMain ] [DEBUG] - Loading AR modes from "mytv" section... [2018-12-10 13:25:53,977] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetVideoWindow() [2018-12-10 13:25:53,979] [Log ] [MPMain ] [INFO ] - overlay: video WxH : 100x100 [2018-12-10 13:25:53,980] [Log ] [MPMain ] [INFO ] - overlay: video AR : 100:100 [2018-12-10 13:25:53,982] [Log ] [MPMain ] [INFO ] - overlay: screen WxH : 1920x1080 [2018-12-10 13:25:53,983] [Log ] [MPMain ] [INFO ] - overlay: AR type : Normal [2018-12-10 13:25:53,986] [Log ] [MPMain ] [INFO ] - overlay: PixelRatio : 1 [2018-12-10 13:25:53,988] [Log ] [MPMain ] [INFO ] - overlay: src : (0,0)-(100,100) [2018-12-10 13:25:53,989] [Log ] [MPMain ] [INFO ] - overlay: dst : (420,0)-(1500,1080) [2018-12-10 13:25:53,991] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:Window (0,0)-(100,100) - (420,0)-(1500,1080) [2018-12-10 13:25:53,993] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 1 [2018-12-10 13:25:53,996] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: SetSourcePosition 2 [2018-12-10 13:25:54,008] [Log ] [MPMain ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Apollo\Themes\DarknessMod\mytvrecordedtv.xml' is missing control id 7 (window property: btnCompress) [2018-12-10 13:25:54,067] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 51 [2018-12-10 13:25:54,069] [Log ] [MPMain ] [DEBUG] - GUIVideoOverlay : SetCurrentFile DoesPostRender _fileName Muse - Reeperbahn Festival 2018 - arte - 2018-11-23 [2018-12-10 13:25:54,093] [Log ] [MPMain ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 51 [2018-12-10 13:25:54,136] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: DLL Version : MediaInfoLib - v0.7.95 [2018-12-10 13:25:54,137] [Log ] [MediaInfoWrapper] [INFO ] - MediaInfoWrapper.MediaInfoWrapper: Inspecting media : D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:54,138] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Parse speed : 0.3 [2018-12-10 13:25:54,140] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: FrameRate : 50 [2018-12-10 13:25:54,141] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Width : 1920 [2018-12-10 13:25:54,142] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Height : 1080 [2018-12-10 13:25:54,143] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AspectRatio : widescreen [2018-12-10 13:25:54,144] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoCodec : HEVC [ "hevc.png" ] [2018-12-10 13:25:54,145] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Scan type : [2018-12-10 13:25:54,146] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: IsInterlaced : False [2018-12-10 13:25:54,147] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoResolution : 1080P [2018-12-10 13:25:54,148] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoDuration : 4910960 [2018-12-10 13:25:54,149] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioRate : 48000 [2018-12-10 13:25:54,150] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioChannels : 2 [ "stereo.png" ] [2018-12-10 13:25:54,151] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioCodec : AAC LC [ "aac lc.png" ] [2018-12-10 13:25:54,152] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasAudio : True [2018-12-10 13:25:54,154] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasVideo : True [2018-12-10 13:25:54,155] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasSubtitles : True [2018-12-10 13:25:54,156] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: NumSubtitles : 2 [2018-12-10 13:25:54,157] [Log ] [MediaInfoWrapper] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Closing file : D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:25:54,169] [Log ] [5 ] [DEBUG] - PlaneScene: crop T, B : 0, 0 [2018-12-10 13:25:54,170] [Log ] [5 ] [DEBUG] - PlaneScene: crop L, R : 0, 0 [2018-12-10 13:25:54,171] [Log ] [5 ] [INFO ] - PlaneScene: video WxH : 1920x1080 [2018-12-10 13:25:54,173] [Log ] [5 ] [DEBUG] - PlaneScene: video AR : 16:9 [2018-12-10 13:25:54,174] [Log ] [5 ] [INFO ] - PlaneScene: screen WxH : 1920x1080 [2018-12-10 13:25:54,175] [Log ] [5 ] [DEBUG] - PlaneScene: AR type : Normal [2018-12-10 13:25:54,176] [Log ] [5 ] [DEBUG] - PlaneScene: PixelRatio : 1 [2018-12-10 13:25:54,177] [Log ] [5 ] [DEBUG] - PlaneScene: src : (0,0)-(1920,1080) [2018-12-10 13:25:54,178] [Log ] [5 ] [DEBUG] - PlaneScene: dst : (0,0)-(1920,1080) [2018-12-10 13:25:54,601] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:25:54,602] [Log ] [PS StandbyWakeup] [DEBUG] - PS: New user input detected - set time of last user activity to 13:25:53 [2018-12-10 13:25:54,605] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:25:53) to the local TvServer [2018-12-10 13:25:54,609] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:25:56,593] [Log ] [47 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:25:56,595] [Log ] [47 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:25:58,985] [Log ] [MPMain ] [DEBUG] - GUIVideoFullscreen.ChangetheTimeCode - skipping [2018-12-10 13:25:58,988] [Log ] [MPMain ] [DEBUG] - g_Player.SeekAbsolute() - Preparing to seek to 0:8:0 [2018-12-10 13:25:58,992] [Log ] [MPMain ] [INFO ] - TsReaderPlayer:seekabs:4800000000 start:0 end:49141200000 [2018-12-10 13:25:59,504] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: pos: 4800000000 start:0 end:49141200000 [2018-12-10 13:25:59,506] [Log ] [MPMain ] [INFO ] - TsReaderPlayer seek done:1 [2018-12-10 13:25:59,507] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: current pos:480 dur:4914,12 [2018-12-10 13:26:09,615] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:26:09,618] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:25:59) to the local TvServer [2018-12-10 13:26:09,622] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:26:11,666] [Log ] [47 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:26:11,668] [Log ] [47 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:26:24,628] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:26:24,631] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:26:11) to the local TvServer [2018-12-10 13:26:24,634] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:26:26,739] [Log ] [47 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:26:26,741] [Log ] [47 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:26:39,641] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval [2018-12-10 13:26:39,644] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Signal time of last user activity (13:26:26) to the local TvServer [2018-12-10 13:26:39,648] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings() [2018-12-10 13:26:41,811] [Log ] [47 ] [DEBUG] - PS: User interface is not idle: Media is playing - reset time of last user activity and system idle timer [2018-12-10 13:26:41,813] [Log ] [47 ] [DEBUG] - PS: User interface not idle: StandbyPrevented [2018-12-10 13:26:44,063] [Log ] [MPMain ] [INFO ] - GUIVideoFullscreen:stop [2018-12-10 13:26:44,065] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False [2018-12-10 13:26:44,066] [Log ] [MPMain ] [INFO ] - g_Player.OnStopped() [2018-12-10 13:26:44,068] [Log ] [MPMain ] [INFO ] - RadioRecorded:OnPlayRecordingBackStopped Recording D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:26:44,069] [Log ] [MPMain ] [INFO ] - TvRecorded:OnPlayRecordingBackStopped Recording D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts [2018-12-10 13:26:44,081] [Log ] [MPMain ] [DEBUG] - [WIFI_REMOTE] Playback stopped! [2018-12-10 13:26:44,083] [Log ] [MPMain ] [DEBUG] - g_Player.doStop() - stop [2018-12-10 13:26:44,085] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Saving subtitle index: 0 [2018-12-10 13:26:44,091] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Cleanup DShow graph False [2018-12-10 13:26:44,094] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Cleanup VMR9 [2018-12-10 13:26:44,095] [Log ] [MPMain ] [DEBUG] - VMR9: mediaCtrl.Stop() 1 [2018-12-10 13:26:44,117] [Log ] [MPMain ] [DEBUG] - VMR9: mediaCtrl.Stop() 2 [2018-12-10 13:26:44,119] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup _audioRendererFilter [2018-12-10 13:26:44,139] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup _fileSource [2018-12-10 13:26:44,140] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup FreePostProcess [2018-12-10 13:26:44,142] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup PostProcess [2018-12-10 13:26:44,244] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup AudioCodec [2018-12-10 13:26:44,346] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup VideoCodec [2018-12-10 13:26:44,348] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter Enhanced Video Renderer [2018-12-10 13:26:44,350] [Log ] [MPMain ] [DEBUG] - Remove filter from graph: Enhanced Video Renderer [2018-12-10 13:26:44,351] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter LAV Audio Decoder [2018-12-10 13:26:44,353] [Log ] [MPMain ] [INFO ] - Disconnecting pin Output [2018-12-10 13:26:44,354] [Log ] [MPMain ] [INFO ] - Not connected [2018-12-10 13:26:44,356] [Log ] [MPMain ] [DEBUG] - Remove filter from graph: LAV Audio Decoder [2018-12-10 13:26:44,357] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter LAV Video Decoder [2018-12-10 13:26:44,358] [Log ] [MPMain ] [INFO ] - Disconnecting pin Output [2018-12-10 13:26:44,359] [Log ] [MPMain ] [INFO ] - Not connected [2018-12-10 13:26:44,360] [Log ] [MPMain ] [DEBUG] - Remove filter from graph: LAV Video Decoder [2018-12-10 13:26:44,361] [Log ] [MPMain ] [INFO ] - Playback stopped and reverting volume OSD back to default device. [2018-12-10 13:26:44,428] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:26:44,431] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: rotEntry Dispose 1 [2018-12-10 13:26:44,433] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: rotEntry Dispose 2 [2018-12-10 13:26:44,437] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup _graphBuilder [2018-12-10 13:26:44,438] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose [2018-12-10 13:26:44,439] [Log ] [MPMain ] [DEBUG] - SubtitleRenderer: starting cleanup [2018-12-10 13:26:44,441] [Log ] [MPMain ] [DEBUG] - SubtitleRenderer: cleanup done [2018-12-10 13:26:44,442] [Log ] [MPMain ] [DEBUG] - VMR9: Inactive for playlist [2018-12-10 13:26:44,443] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose 1 [2018-12-10 13:26:44,453] [Log ] [MPMain ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces [2018-12-10 13:26:44,454] [Log ] [MPMain ] [INFO ] - Playback stopped and reverting volume OSD back to default device. [2018-12-10 13:26:44,515] [Log ] [MPMain ] [INFO ] - Mixer: changed audio device to default : SAMSUNG (Intel(R) Display-Audio) [2018-12-10 13:26:44,515] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose 4 [2018-12-10 13:26:44,515] [Log ] [MPMain ] [INFO ] - Releasing filter System.__ComObject, remaining references: 1 [2018-12-10 13:26:44,515] [Log ] [MPMain ] [DEBUG] - VMR9: Inactive [2018-12-10 13:26:44,515] [Log ] [MPMain ] [DEBUG] - VMR9: Dispose done [2018-12-10 13:26:44,515] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: Cleanup done [2018-12-10 13:26:44,515] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Disabling DX9 exclusive mode [2018-12-10 13:26:44,515] [Log ] [MPMain ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete. [2018-12-10 13:26:44,531] [Log ] [MPMain ] [DEBUG] - Windowmanager: Goto previous window [2018-12-10 13:26:44,531] [Log ] [MPMain ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen deinit [2018-12-10 13:26:44,531] [Log ] [MPMain ] [DEBUG] - TextureManager: CleanupThumbs() [2018-12-10 13:26:44,531] [Log ] [MPMain ] [DEBUG] - Window: TvPlugin.TvRecorded init [2018-12-10 13:26:44,531] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished loading '0' radiogroupIDs after '1' ms. [2018-12-10 13:26:44,547] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished loading '139' recordings after '13' ms. [2018-12-10 13:26:44,547] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished loading '24' channels after '16 ms. [2018-12-10 13:26:44,562] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished loading '0' activerecordings after '19 ms. [2018-12-10 13:26:44,584] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished loading facade items after '42' ms. [2018-12-10 13:26:44,685] [Log ] [MPMain ] [DEBUG] - LoadDirectory() - finished sorting facade after '150' ms. [2018-12-10 13:26:44,685] [Log ] [UpdateThumbnails] [DEBUG] - TvRecorded: Updated '0' thumbnails [2018-12-10 13:26:44,685] [Log ] [UpdateThumbnails] [DEBUG] - SendThreadCallbackAndWait - Waitone [2018-12-10 13:26:44,731] [Log ] [MPMain ] [DEBUG] - D3D: OnPaint() [2018-12-10 13:26:44,731] [Log ] [MPMain ] [DEBUG] - D3D: Force mouse cursor to false [2018-12-10 13:26:44,731] [Log ] [MPMain ] [DEBUG] - D3D: Cursor ShowMouseCursor state -1 TsReader.log, Playback of the (unedited) Recording ==================================================================================================================================================================================================================================== [2018-12-10 13:25:53,420] [00000000] [23c8] - CTsDuration - ctor [2018-12-10 13:25:53,420] [00000000] [23c8] - CTsDuration - ctor [2018-12-10 13:25:53,420] [00000000] [23c8] - TsAVRT::LoadAVRT() - Loading AVRT libraries [2018-12-10 13:25:53,421] [00000000] [23c8] - [2018-12-10 13:25:53,421] [00000000] [23c8] - =================== New filter instance ========================================= [2018-12-10 13:25:53,421] [00000000] [23c8] - Logging format: [Date Time] [InstanceID-instanceCount] [ThreadID] Message.... [2018-12-10 13:25:53,421] [00000000] [23c8] - ================================================================================= [2018-12-10 13:25:53,421] [00000000] [23c8] - CRTSPClient::CRTSPClient() [2018-12-10 13:25:53,421] [00000000] [23c8] - CMemoryBuffer::ctor [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - ------------- v4.2.2.39 ------------- instanceCount:1 [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - CTsReaderFilter::ctor [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- UseFPSfromDTSPTS = yes [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- Buffering delay = 0 ms [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- Audio delay = 0 ms [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- Slow Play = 0 PPM [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- AutoSpeedAdjust = 2 [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- RTSP generic timeout = 500 ms [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- RTSP file timeout = 2000 ms [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - --- Slow motion video allowed during zapping = yes [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - Wait for seeking to eof - false - constructor [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - SetMediaPosnUpdate : m_pClock invalid [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - CTsReaderFilter::Start demux thread [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - CTsReaderFilter::Start duration thread [2018-12-10 13:25:53,421] [3A1B3320] [23c8] - CTsReaderFilter::timeGetTime():0x1a0601, m_tGTStartTime:0xc01a0600, GET_TIME_NOW:0x40000001, timer res:1 ms [2018-12-10 13:25:53,421] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc start(), threadID:0x21ec [2018-12-10 13:25:53,421] [3A1B3320] [21ec] - TsAVRT::SetMMCSThread - Priority: 0, AvrtHandle: 5952 [2018-12-10 13:25:53,422] [3A1B3320] [1afc] - CTsReaderFilter::ThreadProc start(), threadID:0x1afc [2018-12-10 13:25:53,422] [3A1B3320] [23c8] - SetRelaxedMode [2018-12-10 13:25:53,422] [3A1B3320] [23c8] - Normal discontinuities filtering [2018-12-10 13:25:53,422] [3A1B3320] [23c8] - CALLBACK SET [2018-12-10 13:25:53,422] [3A1B3320] [23c8] - SetRequestAudioChangeCallback SET [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - CTsReaderFilter::Load() [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - memorybuffer: run:0 1 [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - memorybuffer: Clear() buffers:0, bytes:0 [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - memorybuffer: running:0 [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - CRTSPClient:Stop() [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - CRTSPClient::Shutdown() [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - memorybuffer: Clear() buffers:0, bytes:0 [2018-12-10 13:25:53,425] [3A1B3320] [23c8] - CRTSPClient:Stop(): done [2018-12-10 13:25:53,426] [3A1B3320] [23c8] - OnNewChannel: PAT change detected: -1->1 [2018-12-10 13:25:53,426] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc - Flush [2018-12-10 13:25:53,426] [3A1B3320] [21ec] - demux:Flush(), clearAVready = 1, isMidStream = 0 [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - OnNewChannel: New channel found (PAT/PMT/SDT changed) [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - pcr pid: 0x 30 [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - pmt pid: 0x 20 [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - Video pid: 0x 30 type: HEVC (0x24) DescriptorData: 0x 0 [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - Audio pid: 0x 40 type: LATM AAC (0x11) language: deu [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - Audio pid: 0x 41 type: LATM AAC (0x11) language: fre [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - Audio pid: 0x 42 type: LATM AAC (0x11) language: mis [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - OnNewChannel: OnRequestAudioChange() [2018-12-10 13:25:53,428] [3A1B3320] [23c8] - demux:Wait for Audio stream selection :1 [2018-12-10 13:25:53,457] [3A1B3320] [23c8] - SetAudioStream : 0 [2018-12-10 13:25:53,457] [3A1B3320] [23c8] - Old Audio -1, New Audio 17 [2018-12-10 13:25:53,457] [3A1B3320] [23c8] - demux:Wait for Audio stream selection :0 [2018-12-10 13:25:53,458] [3A1B3320] [23c8] - OnNewChannel: New Audio stream type = 0x11 [2018-12-10 13:25:53,459] [3A1B3320] [23c8] - HEVC: Random access point, insert SPS(49), PPS(13), VPS(39) [2018-12-10 13:25:53,459] [3A1B3320] [23c8] - DeMultiplexer: HEVC: First Gop after new PAT, 1920x1080 @ 16:9, 50.000Hz progressive [2018-12-10 13:25:53,459] [3A1B3320] [23c8] - DeMultiplexer: 30 new video format, 1920x1080 @ 16:9, 50.000Hz progressive [2018-12-10 13:25:53,463] [3A1B3320] [23c8] - HEVC: Random access point, insert SPS(49), PPS(13), VPS(39) [2018-12-10 13:25:53,463] [3A1B3320] [23c8] - demux: AAC LATM header: sampleRate = 48000, channels = 2, bitrate = 0, objectType = 2, bytesRead = 9738 [2018-12-10 13:25:53,463] [3A1B3320] [23c8] - demux: AAC LATM good sync = 56 e1 54 20 0 11 90, byteCount = 1413, headerCount = 12 [2018-12-10 13:25:53,464] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc - Flush [2018-12-10 13:25:53,464] [3A1B3320] [21ec] - demux:Flush(), clearAVready = 1, isMidStream = 0 [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - demux:Start() Succeeded : BytesProcessed:524288, DTS/PTS count = 13/53, GOPts = 0 [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - UpdateDuration - find pcr [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - UpdateDuration - found startPcr, iterations:1 offset:131072 [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - UpdateDuration - found endPcr, iterations:1 offset:259136 [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - CTsReaderFilter::Load(), duration - start:0 end:1a583dc7 4911.005859 [2018-12-10 13:25:53,466] [3A1B3320] [23c8] - CTsReaderFilter::Load() succeeded, file/stream: D:\Aufnahmen\Muse - Reeperbahn Festival 2018 - arte - 2018-11-23.ts, isTimeshift:0, isUNC:0 [2018-12-10 13:25:53,663] [3A1B3320] [23c8] - filt:IID_IMediaSeeking() [2018-12-10 13:25:53,687] [3A1B3320] [23c8] - demux:GetVideoStreamType(), FPS from DTS/PTS = 50.000000, DTSdiff/PTSdiff/MPEG2 = 0.080000/0.020000/0.000000, DTS/PTS count = 13/53 [2018-12-10 13:25:53,688] [3A1B3320] [23c8] - audPin:CompleteConnect() ok, filter: LAV Audio Decoder, iPosition: 0 [2018-12-10 13:25:53,688] [3A1B3320] [23c8] - audPin:CompleteConnect() DisableSlowPlayDiscontinuity = 0 [2018-12-10 13:25:53,703] [3A1B3320] [23c8] - vidPin:CompleteConnect() ok, filter: LAV Video Decoder [2018-12-10 13:25:53,761] [3A1B3320] [23c8] - filt:IID_IMediaSeeking() [2018-12-10 13:25:53,795] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - IsTimeShifting = 0 - state = 0 [2018-12-10 13:25:53,796] [3A1B3320] [ 4b4] - audPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x4b4, GET_TIME_NOW:0x40000178 [2018-12-10 13:25:53,796] [3A1B3320] [23c8] - MPAR/Reclock not found [2018-12-10 13:25:53,796] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - END - state = 1 [2018-12-10 13:25:53,796] [3A1B3320] [ eb8] - vidPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0xeb8, GET_TIME_NOW:0x40000178 [2018-12-10 13:25:53,796] [3A1B3320] [1afc] - CTsReaderFilter:: DurationThread -> busy [2018-12-10 13:25:53,796] [3A1B3320] [1afc] - CTsReaderFilter:: ForcePosnUpdate: 0.000 s [2018-12-10 13:25:53,796] [3A1B3320] [ eb8] - vidPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00 [2018-12-10 13:25:53,797] [3A1B3320] [ 4b4] - audPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00 [2018-12-10 13:25:53,804] [3A1B3320] [21ec] - HEVC: Random access point, insert SPS(49), PPS(13), VPS(39) [2018-12-10 13:25:53,804] [3A1B3320] [21ec] - HEVC: SPS/PPS/VPS found 1.005000 [2018-12-10 13:25:53,804] [3A1B3320] [21ec] - HEVC: First random access frame found. RefFVS = 1.005000, Ref = 1.005000, IRAP = 1 [2018-12-10 13:25:53,816] [3A1B3320] [21ec] - HEVC: 2nd random access frame found 1.485000 [2018-12-10 13:25:53,826] [3A1B3320] [21ec] - demux: AAC LATM good sync = 56 e1 54 20 0 11 90, byteCount = 1413, headerCount = 12 [2018-12-10 13:25:53,839] [3A1B3320] [21ec] - demux:CheckCompensation(): Audio Samples : 2, First : 0.365, Last : 0.983, buffThresh : 3, pfLoopDel : 19, SampDur : 309.0 ms [2018-12-10 13:25:53,839] [3A1B3320] [21ec] - demux:CheckCompensation(): Video Samples : 68, First : 0.945, Last : 2.285, Zero : 1.005, buffThresh : 15, SampDur : 19.7 ms [2018-12-10 13:25:53,839] [3A1B3320] [21ec] - demux:CheckCompensation(): (AudBackBuff : 0.464) Audio pts < Video pts. Add 0.485 sec of extra video comp [2018-12-10 13:25:53,839] [3A1B3320] [21ec] - demux:CheckCompensation(): Compensation = 0.319, Clock on start 0.000 rtStartTime:0 [2018-12-10 13:25:53,841] [3A1B3320] [23c8] - CTsReaderFilter::GetState(), VFW_S_CANT_CUE, m_audioReady 0 [2018-12-10 13:25:53,841] [3A1B3320] [23c8] - CTsReaderFilter::GetState(), VFW_S_CANT_CUE, m_audioReady 0 [2018-12-10 13:25:53,841] [3A1B3320] [23c8] - CTsReaderFilter::Run(1705.90) state 1 seeking 0 [2018-12-10 13:25:53,841] [3A1B3320] [23c8] - CTsReaderFilter::Run(1705.90) state 2 -->done [2018-12-10 13:25:53,844] [3A1B3320] [ 4b4] - audPin:CalcAverageFtime m_nMaxAFT: 16, SampDur: 0.309000 [2018-12-10 13:25:53,845] [3A1B3320] [ eb8] - demux:GetVideoStreamType(), FPS from DTS/PTS = 50.000000, DTSdiff/PTSdiff/MPEG2 = 0.080000/0.020000/0.000000, DTS/PTS count = 19/79 [2018-12-10 13:25:53,845] [3A1B3320] [ eb8] - vidPin: Add pmt and set discontinuity L:1 B:1 fTime:0.297 SampCnt:0 [2018-12-10 13:25:53,845] [3A1B3320] [ eb8] - Vid/Ref : 1.005, I-frame(00), Compensated = 0.297 ( 0.297 A/V buffers=01/68), Clk : 0.000000, SampCnt 0, stallPt 2.500 [2018-12-10 13:25:53,846] [3A1B3320] [ 4b4] - audPin: Set discontinuity L:1 B:0 fTime:0.950 SampCnt:0 [2018-12-10 13:25:53,846] [3A1B3320] [ 4b4] - audPin: Add pmt, fTime:0.950 SampCnt:0, Ch:2, Sr:48000 [2018-12-10 13:25:53,846] [3A1B3320] [ 4b4] - Aud/Ref : 0.983, Compensated = 0.663 ( 0.950 A/V buffers=01/67), Clk : 0.000000, SampCnt 0, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:25:53,846] [3A1B3320] [ eb8] - Vid/Ref : 0.945, ?-frame(00), Compensated = 0.207 ( 0.208 A/V buffers=00/67), Clk : 0.000000, SampCnt 1, stallPt 2.500 [2018-12-10 13:25:53,897] [3A1B3320] [ eb8] - Vid/Ref : 0.965, ?-frame(00), Compensated = 0.237 ( 0.193 A/V buffers=01/102), Clk : 0.045000, SampCnt 2, stallPt 2.500 [2018-12-10 13:25:53,915] [3A1B3320] [23c8] - SetAudioStream : 0 [2018-12-10 13:25:53,915] [3A1B3320] [23c8] - Old Audio 17, New Audio 17 [2018-12-10 13:25:53,915] [3A1B3320] [23c8] - demux:Wait for Audio stream selection :0 [2018-12-10 13:25:53,944] [3A1B3320] [21ec] - demux: AAC LATM header: sampleRate = 48000, channels = 2, bitrate = 0, objectType = 2, bytesRead = 9738 [2018-12-10 13:25:53,944] [3A1B3320] [21ec] - demux: AAC LATM good sync = 56 e1 2e 20 0 11 90, byteCount = 1566, headerCount = 12 [2018-12-10 13:25:53,989] [3A1B3320] [ 4b4] - Aud/Ref : 1.581, Compensated = 1.261 ( 1.719 A/V buffers=03/174), Clk : 0.138000, SampCnt 1, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:25:54,792] [3A1B3320] [ 4b4] - audPin: Set discontinuity L:1 B:1 fTime:1.948 SampCnt:2 [2018-12-10 13:25:54,792] [3A1B3320] [ 4b4] - Aud/Ref : 2.797, Compensated = 2.477 ( 1.948 A/V buffers=03/164), Clk : 0.869083, SampCnt 2, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:25:58,994] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - IsTimeShifting = 0 - state = 2 [2018-12-10 13:25:58,994] [3A1B3320] [23c8] - MPAR/Reclock not found [2018-12-10 13:25:58,994] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - END - state = 1 [2018-12-10 13:25:58,994] [3A1B3320] [23c8] - vidPin: UpdateFromSeek, m_rtStart 480.000000, m_dRateSeeking 1.000000 [2018-12-10 13:25:58,994] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart()-- LiveTv : 0, TimeShifting: 0 480.000 ( Abs 480.000000 / 4914.120605 )- isSamePosn: 0, OnZap: 0, Force 0, ForceRC 0, Media changing 0 [2018-12-10 13:25:58,995] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc - Flush [2018-12-10 13:25:58,996] [3A1B3320] [21ec] - demux:Flush(), clearAVready = 1, isMidStream = 0 [2018-12-10 13:25:58,999] [3A1B3320] [23c8] - CTsReaderFilter:: Seek-> 480.000000/4914.120000 [2018-12-10 13:25:58,999] [3A1B3320] [23c8] - FileSeek: seek to 480.000000 filepos:f48d83c pid:30 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - FileSeek: stop seek: 480.226533 at aa28ec0 - target: 480.000000, diff: 0.226533, iterations: 11 [2018-12-10 13:25:59,503] [3A1B3320] [ eb8] - vidPin:OnThreadStartPlay(480.000000), rate:1.00, threadID:0xeb8, GET_TIME_NOW:0x400017c4 [2018-12-10 13:25:59,503] [3A1B3320] [ 4b4] - audPin:OnThreadStartPlay(480.000000), rate:1.00, threadID:0x4b4, GET_TIME_NOW:0x400017c4 [2018-12-10 13:25:59,503] [3A1B3320] [ eb8] - vidPin:DeliverNewSegment(start 480.000000, stop 461168607232.000000), rate:1.00 [2018-12-10 13:25:59,503] [3A1B3320] [ 4b4] - audPin:DeliverNewSegment(start 480.000000, stop 461168607232.000000), rate:1.00 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart() End 1 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - audPin: UpdateFromSeek, m_rtStart 480.000000, m_dRateSeeking 1.000000 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart()-- No new seek 480.000000 ( Abs 480.000000 / 4914.120605 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 0 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - audPin: UpdateFromSeek, m_rtStart 480.000000, m_dRateSeeking 1.000000 [2018-12-10 13:25:59,503] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart()-- No new seek 480.000000 ( Abs 480.000000 / 4914.120605 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 0 [2018-12-10 13:25:59,503] [3A1B3320] [1afc] - CTsReaderFilter:: ForcePosnUpdate: 480.000 s [2018-12-10 13:25:59,521] [3A1B3320] [21ec] - HEVC: Random access point, insert SPS(49), PPS(13), VPS(39) [2018-12-10 13:25:59,522] [3A1B3320] [21ec] - HEVC: First random access frame found. RefFVS = 480.924988, Ref = 481.304993, IRAP = 1 [2018-12-10 13:25:59,543] [3A1B3320] [21ec] - demux: AAC LATM header: sampleRate = 48000, channels = 2, bitrate = 0, objectType = 2, bytesRead = 9738 [2018-12-10 13:25:59,543] [3A1B3320] [21ec] - demux: AAC LATM good sync = 56 e1 55 20 0 11 90, byteCount = 1373, headerCount = 12 [2018-12-10 13:25:59,567] [3A1B3320] [21ec] - demux:CheckCompensation(): Audio Samples : 2, First : 480.557, Last : 481.175, buffThresh : 3, pfLoopDel : 19, SampDur : 309.0 ms [2018-12-10 13:25:59,567] [3A1B3320] [21ec] - demux:CheckCompensation(): Video Samples : 58, First : 481.285, Last : 482.425, Zero : 481.305, buffThresh : 15, SampDur : 19.7 ms [2018-12-10 13:25:59,567] [3A1B3320] [21ec] - demux:CheckCompensation(): (AudBackBuff : 0.464) Audio pts < Video pts. Add 0.593 sec of extra video comp [2018-12-10 13:25:59,567] [3A1B3320] [21ec] - demux:CheckCompensation(): Compensation = 0.511, Clock on start 0.000 rtStartTime:480000 [2018-12-10 13:25:59,568] [3A1B3320] [ 4b4] - audPin:CalcAverageFtime m_nMaxAFT: 16, SampDur: 0.309000 [2018-12-10 13:25:59,568] [3A1B3320] [ eb8] - vidPin: Set discontinuity L:1 B:1 fTime:0.319 SampCnt:0 [2018-12-10 13:25:59,568] [3A1B3320] [ eb8] - Vid/Ref : 481.305, I-frame(00), Compensated = 0.318 ( 0.319 A/V buffers=01/58), Clk : 0.000000, SampCnt 0, stallPt 2.500 [2018-12-10 13:25:59,570] [3A1B3320] [ 4b4] - audPin: Set discontinuity L:1 B:0 fTime:0.950 SampCnt:0 [2018-12-10 13:25:59,570] [3A1B3320] [ eb8] - Vid/Ref : 481.285, ?-frame(00), Compensated = 0.286 ( 0.287 A/V buffers=01/57), Clk : 0.000000, SampCnt 1, stallPt 2.500 [2018-12-10 13:25:59,570] [3A1B3320] [ 4b4] - Aud/Ref : 481.175, Compensated = 0.663 ( 0.950 A/V buffers=01/57), Clk : 0.000000, SampCnt 0, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:25:59,576] [3A1B3320] [23c8] - CTsReaderFilter::GetState(), VFW_S_CANT_CUE, m_audioReady 1 [2018-12-10 13:25:59,576] [3A1B3320] [23c8] - CTsReaderFilter::Run(1711.57) state 1 seeking 0 [2018-12-10 13:25:59,576] [3A1B3320] [23c8] - CTsReaderFilter::Run(1711.57) state 2 -->done [2018-12-10 13:25:59,581] [3A1B3320] [ eb8] - Vid/Ref : 481.385, ?-frame(00), Compensated = 0.446 ( 0.446 A/V buffers=00/56), Clk : 0.000000, SampCnt 2, stallPt 2.500 [2018-12-10 13:25:59,583] [3A1B3320] [ eb8] - Vid/Ref : 481.325, ?-frame(00), Compensated = 0.350 ( 0.351 A/V buffers=00/55), Clk : 0.000000, SampCnt 3, stallPt 2.500 [2018-12-10 13:25:59,593] [3A1B3320] [ eb8] - Vid/Ref : 481.345, ?-frame(00), Compensated = 0.382 ( 0.375 A/V buffers=00/66), Clk : 0.007000, SampCnt 4, stallPt 2.500 [2018-12-10 13:25:59,716] [3A1B3320] [ 4b4] - Aud/Ref : 481.794, Compensated = 1.282 ( 1.747 A/V buffers=03/131), Clk : 0.131000, SampCnt 1, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:26:00,520] [3A1B3320] [ 4b4] - Aud/Ref : 482.391, Compensated = 1.879 ( 1.323 A/V buffers=03/147), Clk : 0.886479, SampCnt 2, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:26:00,677] [3A1B3320] [1afc] - CTsReaderFilter::Duration - correction to predicted duration: 4911.006 s [2018-12-10 13:26:01,224] [3A1B3320] [ 4b4] - Aud/Ref : 483.010, Compensated = 2.498 ( 1.144 A/V buffers=03/146), Clk : 1.590917, SampCnt 3, Sleep 1 ms, stallPt 1.500 [2018-12-10 13:26:03,894] [3A1B3320] [1afc] - CTsReaderFilter::Duration - correction to predicted duration: 4911.006 s [2018-12-10 13:26:44,096] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - IsTimeShifting = 0 - state = 2 [2018-12-10 13:26:44,096] [3A1B3320] [23c8] - MPAR/Reclock not found [2018-12-10 13:26:44,096] [3A1B3320] [23c8] - CTsReaderFilter::Pause() - END - state = 1 [2018-12-10 13:26:44,096] [3A1B3320] [ eb8] - Vid/Ref : 525.545, ?-frame(00), Compensated = 45.033 ( 0.571 A/V buffers=03/140), Clk : 44.462104, SampCnt 2214, stallPt 2.500 [2018-12-10 13:26:44,098] [3A1B3320] [ eb8] - Vid/Ref : 525.565, ?-frame(00), Compensated = 45.053 ( 0.591 A/V buffers=03/139), Clk : 44.462104, SampCnt 2215, stallPt 2.500 [2018-12-10 13:26:44,100] [3A1B3320] [ eb8] - Vid/Ref : 525.665, ?-frame(00), Compensated = 45.153 ( 0.691 A/V buffers=03/138), Clk : 44.462104, SampCnt 2216, stallPt 2.500 [2018-12-10 13:26:44,115] [3A1B3320] [23c8] - CTsReaderFilter::Stop(), state 1 [2018-12-10 13:26:44,116] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc - Flush [2018-12-10 13:26:44,116] [3A1B3320] [21ec] - demux:Flush(), clearAVready = 1, isMidStream = 0 [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - CTsReaderFilter::Stop() done, state 0 [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - vidPin: UpdateFromSeek, m_rtStart 524.461975, m_dRateSeeking 1.000000 [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart() End - Stopped [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - audPin: UpdateFromSeek, m_rtStart 524.461975, m_dRateSeeking 1.000000 [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart() End - Stopped [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - audPin: UpdateFromSeek, m_rtStart 524.461975, m_dRateSeeking 1.000000 [2018-12-10 13:26:44,117] [3A1B3320] [23c8] - CTsReaderFilter::--SeekPreStart() End - Stopped [2018-12-10 13:26:44,117] [3A1B3320] [1afc] - CTsReaderFilter:: ForcePosnUpdate: 524.462 s [2018-12-10 13:26:44,119] [3A1B3320] [1afc] - CTsReaderFilter:: DurationThread -> idle [2018-12-10 13:26:44,130] [3A1B3320] [23c8] - CTsReaderFilter::dtor [2018-12-10 13:26:44,130] [3A1B3320] [1afc] - CTsReaderFilter::ThreadProc stopped() [2018-12-10 13:26:44,130] [3A1B3320] [21ec] - TsAVRT::RevertMMCSThread - AvrtHandle: 5952 [2018-12-10 13:26:44,130] [3A1B3320] [21ec] - CDeMultiplexer::ThreadProc stopped() [2018-12-10 13:26:44,130] [3A1B3320] [23c8] - audPin:dtor() [2018-12-10 13:26:44,130] [3A1B3320] [23c8] - vidPin:dtor() [2018-12-10 13:26:44,130] [3A1B3320] [23c8] - subPin:dtor() [2018-12-10 13:26:44,130] [3A1B3320] [23c8] - CTsReaderFilter::dtor - finished [2018-12-10 13:26:44,135] [00000000] [23c8] - CMemoryBuffer::dtor [2018-12-10 13:26:44,135] [00000000] [23c8] - memorybuffer: Clear() buffers:0, bytes:0 [2018-12-10 13:26:44,135] [00000000] [23c8] - CRTSPClient::~CRTSPClient() [2018-12-10 13:26:44,135] [00000000] [23c8] - CRTSPClient::Shutdown() [2018-12-10 13:26:44,135] [00000000] [23c8] - CDeMultiplexer::dtor [2018-12-10 13:26:44,135] [00000000] [23c8] - demux:Flush(), clearAVready = 1, isMidStream = 0 [2018-12-10 13:26:44,136] [00000000] [23c8] - CDeMultiplexer::dtor - finished, instanceCount:0