14:01:16.650 T:5172 NOTICE: special://profile/ is mapped to: special://masterprofile/ 14:01:16.650 T:5172 NOTICE: ----------------------------------------------------------------------- 14:01:16.650 T:5172 NOTICE: Starting Kodi (17.3 Git:20170524-147cec4). Platform: Windows NT x86 32-bit 14:01:16.650 T:5172 NOTICE: Using Release Kodi x32 build 14:01:16.650 T:5172 NOTICE: Kodi compiled May 24 2017 by MSVC 190024215 for Windows NT x86 32-bit version 10.0 (0x0A000000) 14:01:16.650 T:5172 NOTICE: Running on Shuttle Inc SG33 with Windows 10, kernel: Windows NT x86 64-bit version 10.0 14:01:16.651 T:5172 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 14:01:16.651 T:5172 NOTICE: Host CPU: Unknown, 2 cores available 14:01:16.651 T:5172 NOTICE: Desktop Resolution: 1920x1080 32Bit at 60Hz 14:01:16.651 T:5172 NOTICE: Running with restricted rights 14:01:16.653 T:5172 NOTICE: Aero is enabled 14:01:16.653 T:5172 NOTICE: special://xbmc/ is mapped to: C:\Program Files (x86)\Kodi 14:01:16.653 T:5172 NOTICE: special://xbmcbin/ is mapped to: C:\Program Files (x86)\Kodi 14:01:16.653 T:5172 NOTICE: special://xbmcbinaddons/ is mapped to: C:\Program Files (x86)\Kodi/addons 14:01:16.653 T:5172 NOTICE: special://masterprofile/ is mapped to: C:\Users\Partykeller1\AppData\Roaming\Kodi\userdata 14:01:16.653 T:5172 NOTICE: special://home/ is mapped to: C:\Users\Partykeller1\AppData\Roaming\Kodi\ 14:01:16.653 T:5172 NOTICE: special://temp/ is mapped to: C:\Users\Partykeller1\AppData\Roaming\Kodi\cache 14:01:16.653 T:5172 NOTICE: special://logpath/ is mapped to: C:\Users\Partykeller1\AppData\Roaming\Kodi\ 14:01:16.653 T:5172 NOTICE: The executable running is: C:\Program Files (x86)\Kodi\kodi.exe 14:01:16.657 T:5172 NOTICE: Local hostname: Partykeller 14:01:16.657 T:5172 NOTICE: Log File is located: C:\Users\Partykeller1\AppData\Roaming\Kodi\/kodi.log 14:01:16.657 T:5172 NOTICE: ----------------------------------------------------------------------- 14:01:16.665 T:5172 NOTICE: load settings... 14:01:16.678 T:5172 WARNING: CSettingString: unknown options filler "timezonecountries" of "locale.timezonecountry" 14:01:16.678 T:5172 WARNING: CSettingString: unknown options filler "timezones" of "locale.timezone" 14:01:16.693 T:5172 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 14:01:16.693 T:5172 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 14:01:16.693 T:5172 NOTICE: Default Video Player: VideoPlayer 14:01:16.693 T:5172 NOTICE: Default Audio Player: paplayer 14:01:16.693 T:5172 NOTICE: Disabled debug logging due to GUI setting. Level 0. 14:01:16.693 T:5172 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 14:01:16.694 T:5172 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 14:01:16.696 T:5172 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 14:01:16.702 T:5172 NOTICE: Loaded playercorefactory configuration 14:01:16.702 T:5172 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 14:01:16.702 T:5172 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 14:01:16.731 T:5172 NOTICE: Running database version Addons27 14:01:17.547 T:5172 NOTICE: ADDONS: Using repository repository.xbmc.org 14:01:17.582 T:6988 NOTICE: Found 2 Lists of Devices 14:01:17.582 T:6988 NOTICE: Enumerated DIRECTSOUND devices: 14:01:17.583 T:6988 NOTICE: Device 1 14:01:17.583 T:6988 NOTICE: m_deviceName : {D0DE0CDE-3816-46EC-9928-AE138A902962} 14:01:17.583 T:6988 NOTICE: m_displayName : SPDIF - Digitalaudio (S/PDIF) (2- High Definition Audio-Gerät) 14:01:17.583 T:6988 NOTICE: m_displayNameExtra: DIRECTSOUND: Digitalaudio (S/PDIF) (2- High Definition Audio-Gerät) 14:01:17.583 T:6988 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 14:01:17.583 T:6988 NOTICE: m_channels : FL,FR 14:01:17.583 T:6988 NOTICE: m_sampleRates : 48000 14:01:17.583 T:6988 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_RAW 14:01:17.583 T:6988 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 14:01:17.583 T:6988 NOTICE: Device 2 14:01:17.583 T:6988 NOTICE: m_deviceName : default 14:01:17.583 T:6988 NOTICE: m_displayName : default 14:01:17.583 T:6988 NOTICE: m_displayNameExtra: 14:01:17.583 T:6988 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 14:01:17.583 T:6988 NOTICE: m_channels : FL,FR 14:01:17.583 T:6988 NOTICE: m_sampleRates : 48000 14:01:17.583 T:6988 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_RAW 14:01:17.583 T:6988 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 14:01:17.583 T:6988 NOTICE: Enumerated WASAPI devices: 14:01:17.583 T:6988 NOTICE: Device 1 14:01:17.583 T:6988 NOTICE: m_deviceName : {D0DE0CDE-3816-46EC-9928-AE138A902962} 14:01:17.583 T:6988 NOTICE: m_displayName : SPDIF - Digitalaudio (S/PDIF) (2- High Definition Audio-Gerät) 14:01:17.583 T:6988 NOTICE: m_displayNameExtra: WASAPI: Digitalaudio (S/PDIF) (2- High Definition Audio-Gerät) 14:01:17.583 T:6988 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 14:01:17.583 T:6988 NOTICE: m_channels : FL,FR 14:01:17.583 T:6988 NOTICE: m_sampleRates : 192000,96000,88200,48000,44100 14:01:17.583 T:6988 NOTICE: m_dataFormats : AE_FMT_S24NE4MSB,AE_FMT_S32NE,AE_FMT_S32LE,AE_FMT_S32BE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW 14:01:17.583 T:6988 NOTICE: m_streamTypes : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3 14:01:17.583 T:6988 NOTICE: Device 2 14:01:17.583 T:6988 NOTICE: m_deviceName : default 14:01:17.583 T:6988 NOTICE: m_displayName : default 14:01:17.583 T:6988 NOTICE: m_displayNameExtra: 14:01:17.583 T:6988 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 14:01:17.583 T:6988 NOTICE: m_channels : FL,FR 14:01:17.583 T:6988 NOTICE: m_sampleRates : 192000,96000,88200,48000,44100 14:01:17.583 T:6988 NOTICE: m_dataFormats : AE_FMT_S24NE4MSB,AE_FMT_S32NE,AE_FMT_S32LE,AE_FMT_S32BE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW 14:01:17.583 T:6988 NOTICE: m_streamTypes : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3 14:01:17.892 T:5172 NOTICE: Found screen: Generic PnP Monitor on Intel(R) G33/G31 Express Chipset Family (Microsoft Corporation - WDDM 1.0), adapter 0. 14:01:17.892 T:5172 NOTICE: Primary mode: 1920x1080@ 60.00 - Full Screen 14:01:17.893 T:5172 NOTICE: Additional mode: 320x200@ 70.00 - Full Screen 14:01:17.893 T:5172 NOTICE: Additional mode: 320x240@ 70.00 - Full Screen 14:01:17.893 T:5172 NOTICE: Additional mode: 400x300@ 70.00 - Full Screen 14:01:17.893 T:5172 NOTICE: Additional mode: 512x384@ 70.00 - Full Screen 14:01:17.899 T:5172 NOTICE: Additional mode: 640x400@ 70.00 - Full Screen 14:01:17.900 T:5172 NOTICE: Additional mode: 640x480@ 59.94 - Full Screen 14:01:17.900 T:5172 NOTICE: Additional mode: 640x480@ 60.00 - Full Screen 14:01:17.901 T:5172 NOTICE: Additional mode: 640x480@ 70.00 - Full Screen 14:01:17.901 T:5172 NOTICE: Additional mode: 640x480@ 73.00 - Full Screen 14:01:17.901 T:5172 NOTICE: Additional mode: 640x480@ 75.00 - Full Screen 14:01:17.901 T:5172 NOTICE: Additional mode: 800x600@ 56.00 - Full Screen 14:01:17.902 T:5172 NOTICE: Additional mode: 800x600@ 60.00 - Full Screen 14:01:17.902 T:5172 NOTICE: Additional mode: 800x600@ 72.00 - Full Screen 14:01:17.902 T:5172 NOTICE: Additional mode: 800x600@ 75.00 - Full Screen 14:01:17.902 T:5172 NOTICE: Additional mode: 1024x768@ 60.00 - Full Screen 14:01:17.903 T:5172 NOTICE: Additional mode: 1024x768@ 70.00 - Full Screen 14:01:17.903 T:5172 NOTICE: Additional mode: 1024x768@ 75.00 - Full Screen 14:01:17.903 T:5172 NOTICE: Additional mode: 1152x864@ 60.00 - Full Screen 14:01:17.903 T:5172 NOTICE: Additional mode: 1152x864@ 75.00 - Full Screen 14:01:17.904 T:5172 NOTICE: Additional mode: 1280x600@ 60.00 - Full Screen 14:01:17.904 T:5172 NOTICE: Additional mode: 1280x720@ 60.00 - Full Screen 14:01:17.904 T:5172 NOTICE: Additional mode: 1280x768@ 60.00 - Full Screen 14:01:17.904 T:5172 NOTICE: Additional mode: 1280x768@ 75.00 - Full Screen 14:01:17.905 T:5172 NOTICE: Additional mode: 1280x800@ 60.00 - Full Screen 14:01:17.905 T:5172 NOTICE: Additional mode: 1280x960@ 60.00 - Full Screen 14:01:17.905 T:5172 NOTICE: Additional mode: 1280x960@ 75.00 - Full Screen 14:01:17.905 T:5172 NOTICE: Additional mode: 1280x1024@ 60.00 - Full Screen 14:01:17.906 T:5172 NOTICE: Additional mode: 1280x1024@ 75.00 - Full Screen 14:01:17.906 T:5172 NOTICE: Additional mode: 1400x1050@ 60.00 - Full Screen 14:01:17.906 T:5172 NOTICE: Additional mode: 1400x1050@ 75.00 - Full Screen 14:01:17.906 T:5172 NOTICE: Additional mode: 1440x900@ 60.00 - Full Screen 14:01:17.907 T:5172 NOTICE: Additional mode: 1600x900@ 60.00 - Full Screen 14:01:17.907 T:5172 NOTICE: Additional mode: 1680x1050@ 60.00 - Full Screen 14:01:17.907 T:5172 NOTICE: Additional mode: 1920x1080@ 60.00 - Full Screen 14:01:17.907 T:5172 NOTICE: Checking resolution 15 14:01:20.999 T:32 NOTICE: Running database version Addons27 14:01:21.001 T:32 NOTICE: Running database version ViewModes6 14:01:21.026 T:32 NOTICE: Running database version Textures13 14:01:21.031 T:32 NOTICE: Running database version MyMusic60 14:01:21.166 T:32 NOTICE: Running database version MyVideos107 14:01:21.870 T:32 NOTICE: Running database version TV29 14:01:21.873 T:32 NOTICE: Running database version Epg11 14:01:23.295 T:5172 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 14:01:23.997 T:5172 NOTICE: initialize done 14:01:23.997 T:5172 NOTICE: Running the application... 14:01:23.998 T:5172 NOTICE: starting upnp client 14:01:24.171 T:6648 NOTICE: ES: Starting UDP Event server on port 9777 14:01:24.171 T:6648 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 14:01:30.119 T:32 ERROR: PVR - PVR::CPVRClients::ConnectionStateChange - invalid handler data 14:01:30.165 T:6244 NOTICE: AddOnLog: MediaPortal PVR Client: Warning: no recording share defined in the TVServerXBMC settings for card 'RadioWebStream Card (builtin)' 14:01:30.165 T:6244 NOTICE: AddOnLog: MediaPortal PVR Client: Warning: no recording share defined in the TVServerXBMC settings for card 'MediaPortal IPTV Source Filter' 14:01:30.165 T:6244 NOTICE: AddOnLog: MediaPortal PVR Client: Warning: no timeshift share defined in the TVServerXBMC settings for card 'MediaPortal IPTV Source Filter' 14:01:30.166 T:6244 NOTICE: AddOnLog: MediaPortal PVR Client: Warning: no recording share defined in the TVServerXBMC settings for card 'Hauppauge WinTV 88x DVB-S Tuner/Demod' 14:01:30.197 T:6484 NOTICE: PVRManager - stopping 14:01:35.874 T:3824 NOTICE: Skin Helper Widgets --> Backgroundservice started 14:01:36.344 T:6160 NOTICE: Skin Helper Service --> WebService - start helper webservice on port 52307 14:01:36.345 T:4468 NOTICE: Skin Helper Service --> Skin Helper Service version 1.1.3 started 14:01:38.466 T:6484 ERROR: XFILE::CDirectory::GetDirectory - Error getting 14:01:39.075 T:32 ERROR: Previous line repeats 6 times. 14:01:39.075 T:32 NOTICE: EPG::CEpgContainer::Start - EPG thread started 14:02:05.331 T:5172 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 14:02:05.331 T:5172 NOTICE: Enabled debug logging due to GUI setting. Level 2. 14:02:05.332 T:5172 DEBUG: ------ Window Init () ------ 14:02:10.487 T:5172 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0 14:02:10.554 T:5172 DEBUG: CInputManager::OnKey: escape (0xf01b) pressed, action is PreviousMenu 14:02:10.693 T:5172 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 14:02:10.694 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:02:10.871 T:5172 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 14:02:10.886 T:5172 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 14:02:10.886 T:5172 DEBUG: ------ Window Init (Settings.xml) ------ 14:02:10.887 T:5172 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x0000, modifier: 0x0 14:02:10.954 T:5172 DEBUG: ------ Window Init (Pointer.xml) ------ 14:02:11.593 T:32 DEBUG: Thread JobWorker 32 terminating (autodelete) 14:02:11.593 T:4108 DEBUG: Thread JobWorker 4108 terminating (autodelete) 14:02:11.593 T:6244 DEBUG: Thread JobWorker 6244 terminating (autodelete) 14:02:11.593 T:6484 DEBUG: Thread JobWorker 6484 terminating (autodelete) 14:02:12.783 T:5172 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x001b, modifier: 0x0 14:02:12.866 T:5172 DEBUG: CInputManager::OnKey: escape (0xf01b) pressed, action is PreviousMenu 14:02:12.866 T:5172 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 14:02:12.867 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:02:13.166 T:5172 DEBUG: ------ Window Deinit (Settings.xml) ------ 14:02:13.182 T:5172 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 14:02:13.182 T:5172 DEBUG: ------ Window Init (Home.xml) ------ 14:02:13.183 T:5172 DEBUG: CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 14:02:13.183 T:5172 DEBUG: CDirectoryProvider[pvr://recordings/tv/active?view=flat]: refreshing.. 14:02:13.183 T:5172 DEBUG: CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 14:02:13.183 T:5172 DEBUG: CDirectoryProvider[pvr://recordings/radio/active?view=flat]: refreshing.. 14:02:13.186 T:1284 DEBUG: Thread JobWorker start, auto delete: true 14:02:13.186 T:5172 DEBUG: Keyboard: scancode: 0x01, sym: 0x001b, unicode: 0x0000, modifier: 0x0 14:02:13.188 T:1284 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/*?view=lastplayed) 14:02:13.190 T:5172 DEBUG: ------ Window Init (Pointer.xml) ------ 14:02:13.206 T:5740 DEBUG: Thread JobWorker start, auto delete: true 14:02:13.206 T:5740 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/tv/active?view=flat) 14:02:13.207 T:5740 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/radio/*?view=lastplayed) 14:02:13.210 T:1284 DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/radio/active?view=flat) 14:02:15.427 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action leftclick 14:02:15.428 T:5172 DEBUG: Activating window ID: 10700 14:02:15.730 T:5172 DEBUG: ------ Window Deinit (Home.xml) ------ 14:02:15.743 T:5172 DEBUG: ------ Window Init (MyPVRChannels.xml) ------ 14:02:15.743 T:5172 INFO: Loading skin file: MyPVRChannels.xml, load type: KEEP_IN_MEMORY 14:02:15.802 T:5172 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/Alle Kanäle/) 14:02:15.802 T:5172 DEBUG: ParentPath = [] 14:02:15.803 T:5172 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/Alle Kanäle) 14:02:17.341 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action wheeldown 14:02:20.734 T:5172 DEBUG: Previous line repeats 41 times. 14:02:20.734 T:5172 DEBUG: Keyboard: scancode: 0x3a, sym: 0x012d, unicode: 0x0000, modifier: 0x0 14:02:20.735 T:5172 DEBUG: CInputManager::OnKey: capslock (0xf0d9) pressed, action is 14:02:20.736 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:02:20.835 T:5172 DEBUG: Keyboard: scancode: 0x3a, sym: 0x012d, unicode: 0x0000, modifier: 0x0 14:02:21.066 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action wheeldown 14:02:21.068 T:5172 DEBUG: ------ Window Init (Pointer.xml) ------ 14:02:21.084 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action wheeldown 14:02:24.850 T:5172 DEBUG: Previous line repeats 39 times. 14:02:24.850 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action wheelup 14:02:25.557 T:5172 DEBUG: Previous line repeats 1 times. 14:02:25.558 T:5172 DEBUG: CInputManager::ProcessMouse: trying mouse action leftclick 14:02:25.579 T:5172 DEBUG: Loading settings for pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr) 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 14:02:25.585 T:5172 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 14:02:25.585 T:5172 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 14:02:25.586 T:5876 DEBUG: Thread RefClock start, auto delete: false 14:02:25.586 T:5876 DEBUG: CVideoSyncD3D: Setting up Direct3d 14:02:25.587 T:5172 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData::CDVDRadioRDSData 14:02:25.587 T:5172 NOTICE: VideoPlayer: Opening: pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr 14:02:25.587 T:5172 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 14:02:25.588 T:5172 ERROR: DXVA::CProcessorHD::IsFormatSupported: Unsupported format 104 for 1. 14:02:25.588 T:5172 ERROR: DXVA::CProcessorHD::IsFormatSupported: Unsupported format 105 for 1. 14:02:25.588 T:5876 DEBUG: CVideoReferenceClock: Detected refreshrate: 60.000 hertz 14:02:25.588 T:392 DEBUG: Thread VideoPlayer start, auto delete: false 14:02:25.588 T:392 NOTICE: Creating InputStream 14:02:25.589 T:392 DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel 'RTLplus' 14:02:25.589 T:392 DEBUG: opening live stream for channel 'RTLplus' 14:02:25.589 T:392 NOTICE: AddOnLog: MediaPortal PVR Client: Open Live stream for channel uid=210 14:02:26.088 T:5172 DEBUG: ------ Window Init (DialogBusy.xml) ------ 14:02:30.961 T:392 NOTICE: AddOnLog: MediaPortal PVR Client: Channel timeshift buffer: C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer 14:02:30.961 T:392 NOTICE: AddOnLog: MediaPortal PVR Client: Creating a new TsReader... 14:02:30.961 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: PatParser:Reset() 14:02:30.961 T:392 NOTICE: AddOnLog: MediaPortal PVR Client: TsReader open 'C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer' 14:02:30.970 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Found the timeshift buffer at: C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer 14:02:30.970 T:392 INFO: AddOnLog: MediaPortal PVR Client: FileReader::OpenFile() C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer. 14:02:30.970 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: MPTV::FileReader::OpenFile: OpenFile(C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer) succeeded. 14:02:30.970 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: MultiFileReader: buffer file opened return code 0. 14:02:30.978 T:392 INFO: AddOnLog: MediaPortal PVR Client: FileReader::OpenFile() C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer1.ts. 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: MPTV::FileReader::OpenFile: OpenFile(C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer1.ts) succeeded. 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: PatParser: new pat table -1->1 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: PatParser: add pmt# 1 pid: 20 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: got pmt:20 service id:89 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:61 type:6 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Descriptor length 5, N= 1 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Teletext SI: Page 100 Type 1 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:40 type:3 14:02:30.978 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:30 type:2 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:61 type:6 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Descriptor length 5, N= 1 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Teletext SI: Page 100 Type 1 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:40 type:3 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:30 type:2 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:61 type:6 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Descriptor length 5, N= 1 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: Teletext SI: Page 100 Type 1 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:40 type:3 14:02:30.979 T:392 DEBUG: AddOnLog: MediaPortal PVR Client: pmt: pid:30 type:2 14:02:30.979 T:392 NOTICE: AddOnLog: MediaPortal PVR Client: OpenLiveStream: success for channel id 210 (RTLplus) on card 3 14:02:31.129 T:392 DEBUG: CDVDInputStreamPVRManager - CDVDInputStreamPVRManager::Open - playback has started on filename pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr 14:02:31.129 T:392 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr 14:02:31.129 T:392 NOTICE: Creating Demuxer 14:02:31.133 T:392 INFO: ffmpeg[188]: Input #0, mpegts, pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pv': 14:02:31.133 T:392 INFO: ffmpeg[188]: Duration: N/A, bitrate: N/A 14:02:31.133 T:392 INFO: ffmpeg[188]: Program 137 14:02:31.133 T:392 INFO: ffmpeg[188]: Stream #0:0[0x61](deu): Subtitle: dvb_teletext ([6][0][0][0] / 0x0006) 14:02:31.133 T:392 INFO: ffmpeg[188]: Stream #0:1[0x40](deu): Audio: mp3 ([3][0][0][0] / 0x0003), 0 channels (clean effects) 14:02:31.133 T:392 INFO: ffmpeg[188]: Stream #0:2[0x30]: Video: mpeg2video ([2][0][0][0] / 0x0002), none, 90k tbn 14:02:31.133 T:392 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 14:02:31.133 T:392 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 14:02:31.133 T:392 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 14:02:31.134 T:392 NOTICE: Opening stream: 2 source: 256 14:02:31.134 T:392 NOTICE: Creating video codec with codec id: 2 14:02:31.134 T:392 DEBUG: FactoryCodec - Video: - Opening 14:02:31.134 T:392 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 14:02:31.134 T:392 DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 3 threads 14:02:31.135 T:392 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 14:02:31.135 T:392 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 14:02:31.135 T:392 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 14:02:31.135 T:392 NOTICE: Creating video thread 14:02:31.135 T:392 NOTICE: Opening stream: 1 source: 256 14:02:31.135 T:392 NOTICE: Finding audio codec for: 86017 14:02:31.135 T:392 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 14:02:31.136 T:5344 DEBUG: Thread VideoPlayerVideo start, auto delete: false 14:02:31.136 T:5344 NOTICE: running thread: video_thread 14:02:31.146 T:392 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 14:02:31.146 T:392 NOTICE: Creating audio thread 14:02:31.146 T:392 NOTICE: Opening stream: 0 source: 256 14:02:31.147 T:392 NOTICE: waiting for teletext data thread to exit 14:02:31.148 T:392 NOTICE: Creating teletext data thread 14:02:31.148 T:392 DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1 14:02:31.148 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 2 14:02:31.148 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:02:31.150 T:392 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(150) 14:02:31.150 T:5172 DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2 14:02:31.150 T:5172 DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0 14:02:31.154 T:392 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 14:02:31.154 T:392 NOTICE: Opening stream: 2 source: 256 14:02:31.154 T:392 NOTICE: Creating video codec with codec id: 2 14:02:31.154 T:392 DEBUG: FactoryCodec - Video: - Opening 14:02:31.154 T:392 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 14:02:31.154 T:392 DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 3 threads 14:02:31.155 T:6984 DEBUG: Thread VideoPlayerAudio start, auto delete: false 14:02:31.155 T:6984 NOTICE: running thread: CVideoPlayerAudio::Process() 14:02:31.155 T:6984 DEBUG: CDVDAudio::Pause - pausing audio stream 14:02:31.155 T:5404 DEBUG: Thread DVDTeletextData start, auto delete: false 14:02:31.155 T:5404 NOTICE: running thread: CDVDTeletextData 14:02:31.156 T:392 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 14:02:31.156 T:392 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 14:02:31.156 T:392 NOTICE: Opening stream: 1 source: 256 14:02:31.156 T:392 NOTICE: Opening stream: 0 source: 256 14:02:31.157 T:5344 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 14:02:31.157 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 14:02:31.178 T:5344 DEBUG: Previous line repeats 4 times. 14:02:31.178 T:5344 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x540. framerate: 0.00. format: YV12 14:02:31.183 T:6984 DEBUG: CVideoPlayerAudio: Sample rate changed, checking for passthrough 14:02:31.183 T:6984 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 14:02:31.183 T:6984 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 14:02:31.183 T:6984 NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through) 14:02:31.183 T:6984 DEBUG: CVideoPlayerAudio:: synctype set to 1: resample 14:02:31.191 T:5172 DEBUG: CWinRenderer::SelectRenderMethod: Requested render method: 0 14:02:31.193 T:4356 INFO: CActiveAESink::OpenSink - initialize sink 14:02:31.193 T:4356 DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up 14:02:31.198 T:5172 DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 1: Pixel Shaders 14:02:31.201 T:5740 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle Kanäle/pvr.mediaportal.tvserver_210.pvr) 14:02:31.204 T:4356 DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default 14:02:31.209 T:5172 DEBUG: created video buffer 0 14:02:31.211 T:5172 DEBUG: created video buffer 1 14:02:31.211 T:4356 DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 000D0034 14:02:31.217 T:5172 DEBUG: created video buffer 2 14:02:31.217 T:4356 DEBUG: CAESinkDirectSound::Initialize: secondary buffer created 14:02:31.218 T:4356 DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters: 14:02:31.218 T:4356 DEBUG: Audio Device : Digitalaudio (S/PDIF) (2- High Definition Audio-Gerät) 14:02:31.218 T:4356 DEBUG: Sample Rate : 48000 14:02:31.218 T:4356 DEBUG: Sample Format : AE_FMT_FLOAT 14:02:31.218 T:4356 DEBUG: Bits Per Sample : 32 14:02:31.218 T:4356 DEBUG: Valid Bits/Samp : 32 14:02:31.218 T:4356 DEBUG: Channel Count : 2 14:02:31.218 T:4356 DEBUG: Block Align : 8 14:02:31.218 T:4356 DEBUG: Avg. Bytes Sec : 384000 14:02:31.218 T:4356 DEBUG: Samples/Block : 32 14:02:31.218 T:4356 DEBUG: Format cBSize : 22 14:02:31.218 T:4356 DEBUG: Channel Layout : FL,FR 14:02:31.218 T:4356 DEBUG: Channel Mask : 3 14:02:31.218 T:4356 DEBUG: Frames : 720 14:02:31.218 T:4356 DEBUG: Frame Size : 8 14:02:31.218 T:4356 DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized: 14:02:31.218 T:4356 DEBUG: Output Device : default 14:02:31.218 T:4356 DEBUG: Sample Rate : 48000 14:02:31.218 T:4356 DEBUG: Sample Format : AE_FMT_FLOAT 14:02:31.218 T:4356 DEBUG: Channel Count : 2 14:02:31.218 T:4356 DEBUG: Channel Layout: FL,FR 14:02:31.218 T:4356 DEBUG: Frames : 720 14:02:31.218 T:4356 DEBUG: Frame Size : 8 14:02:31.219 T:6988 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 14:02:31.221 T:5172 DEBUG: Previous line repeats 1 times. 14:02:31.221 T:5172 DEBUG: created video buffer 3 14:02:31.221 T:5172 DEBUG: CRenderManager::Configure - 4 14:02:31.226 T:5172 DEBUG: CWinShader::LoadEffect - loading shader special://xbmc/system/shaders/yuv2rgb_d3d.fx 14:02:31.255 T:4356 DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback 14:02:31.261 T:5172 DEBUG: Activating window ID: 12005 14:02:31.261 T:5172 DEBUG: ------ Window Deinit (MyPVRChannels.xml) ------ 14:02:31.262 T:392 DEBUG: CVideoPlayer::HandleMessages - player started 2 14:02:31.263 T:5172 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 14:02:31.263 T:5172 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 14:02:31.265 T:4400 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 14:02:31.266 T:4400 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 14:02:31.291 T:3824 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"channeltype":"tv","id":156,"title":"RTLplus","type":"channel"},"player":{"playerid":1,"speed":1}} 14:02:31.312 T:392 DEBUG: CVideoPlayer::HandleMessages - player started 1 14:02:31.312 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 3 14:02:31.312 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:02:31.312 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 0 14:02:31.312 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:02:31.312 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:02:31.312 T:392 DEBUG: VideoPlayer::Sync - Audio - pts: 529133.000000, cache: 374239.818328, totalcache: 579999.983311 14:02:31.313 T:392 DEBUG: VideoPlayer::Sync - Video - pts: 668355.000000, cache: 50000.000000, totalcache: 100000.000000 14:02:31.313 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:02:31.316 T:6984 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-450866.983311) 14:02:31.316 T:6984 DEBUG: CDVDAudio::Resume - resume audio stream 14:02:31.316 T:5344 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-450866.983311) 14:02:31.316 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:02:31.317 T:6988 DEBUG: ActiveAE - start sync of audio stream 14:02:31.356 T:4468 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"channeltype":"tv","id":156,"title":"RTLplus","type":"channel"},"player":{"playerid":1,"speed":1}} 14:02:31.398 T:5172 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 14:02:31.427 T:6988 DEBUG: ActiveAE::SyncStream - average error of 641.071835, start adjusting 14:02:31.795 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:02:31.847 T:5344 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 14:02:32.114 T:6988 DEBUG: ActiveAE::SyncStream - average error 20.038234 below threshold of 30.000000 14:02:32.254 T:4368 DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{d0de0cde-3816-46ec-9928-ae138a902962} is {9855c4cd-df8c-449c-a181-8191b68bd06c}#0 14:02:32.362 T:5344 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 14:02:32.418 T:5172 DEBUG: ------ Window Init (Pointer.xml) ------ 14:02:32.422 T:5172 DEBUG: ------ Window Init (VideoOSD.xml) ------ 14:02:32.422 T:5172 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 14:02:32.460 T:5172 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 14:02:32.461 T:5172 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 14:02:32.461 T:5172 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: KEEP_IN_MEMORY 14:02:32.782 T:5344 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 14:02:35.030 T:5344 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 14:02:36.063 T:5344 DEBUG: CVideoPlayerVideo::CalcFrameRate framerate was:25.000000 calculated:50.000000 14:02:36.078 T:5344 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x540. framerate: 50.00. format: YV12 14:02:36.153 T:5172 DEBUG: CWinRenderer::SelectRenderMethod: Requested render method: 0 14:02:36.159 T:5172 DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 1: Pixel Shaders 14:02:36.161 T:5172 DEBUG: created video buffer 0 14:02:36.163 T:5172 DEBUG: created video buffer 1 14:02:36.164 T:5172 DEBUG: created video buffer 2 14:02:36.166 T:5172 DEBUG: created video buffer 3 14:02:36.166 T:5172 DEBUG: CRenderManager::Configure - 4 14:02:36.166 T:5344 DEBUG: CPullupCorrection: pattern lost on diff 30000.000000, number of losses 1 14:02:36.166 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:02:36.166 T:5172 DEBUG: CWinShader::LoadEffect - loading shader special://xbmc/system/shaders/yuv2rgb_d3d.fx 14:02:38.640 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 30000.00 10000.00, frameduration: 20000.000000 14:02:40.720 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:02:44.355 T:5172 DEBUG: CWinEventsWin32::WndProcWindow is active 14:02:46.036 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:02:46.676 T:5344 DEBUG: Previous line repeats 7 times. 14:02:46.676 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 2 14:02:47.357 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:02:47.689 T:392 DEBUG: Previous line repeats 1 times. 14:02:47.689 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:02:47.945 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:02:49.103 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 30000.00 10000.00, frameduration: 20000.000000 14:02:51.817 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:02:53.733 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:02:53.798 T:5344 DEBUG: Previous line repeats 1 times. 14:02:53.798 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 3, dropped: 2 14:02:55.101 T:5172 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 14:02:55.117 T:5172 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 14:02:55.118 T:5172 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 14:02:56.289 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 30000.00 10000.00, frameduration: 20000.000000 14:02:59.434 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:01.346 T:1284 DEBUG: Thread JobWorker 1284 terminating (autodelete) 14:03:01.346 T:5740 DEBUG: Thread JobWorker 5740 terminating (autodelete) 14:03:04.008 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:22.774 T:5344 DEBUG: Previous line repeats 10 times. 14:03:22.774 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 1, dropped: 2 14:03:22.774 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:22.878 T:5344 DEBUG: Previous line repeats 2 times. 14:03:22.878 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 1, dropped: 2 14:03:22.878 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:22.918 T:5344 DEBUG: Previous line repeats 1 times. 14:03:22.918 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 2, dropped: 2 14:03:22.954 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:22.955 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 7, Bufferlevel: 1, dropped: 2 14:03:22.955 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:22.984 T:5344 DEBUG: Previous line repeats 1 times. 14:03:22.984 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 7, Bufferlevel: 2, dropped: 2 14:03:23.060 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:03:23.076 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 2 14:03:24.270 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:25.698 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 30000.00 10000.00, frameduration: 20000.000000 14:03:29.466 T:5172 DEBUG: ------ Window Init (Pointer.xml) ------ 14:03:31.486 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:32.598 T:5344 DEBUG: Previous line repeats 7 times. 14:03:32.598 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 2 14:03:33.564 T:4356 WARNING: CWin32DirectSound::GetSpace - buffer underrun - W:15360, P:2632, O:13688. 14:03:33.583 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:33.596 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:03:33.688 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 2, dropped: 2 14:03:34.108 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:34.413 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:34.448 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 2, dropped: 2 14:03:34.567 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:34.647 T:5172 DEBUG: ------ Window Deinit (Pointer.xml) ------ 14:03:35.019 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000 14:03:35.144 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:35.228 T:5344 DEBUG: Previous line repeats 2 times. 14:03:35.229 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 2, dropped: 2 14:03:35.250 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 2, dropped: 2 14:03:35.268 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 2, dropped: 2 14:03:35.312 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:35.312 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 7, Bufferlevel: 1, dropped: 2 14:03:35.312 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:35.355 T:5344 DEBUG: Previous line repeats 1 times. 14:03:35.355 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 7, Bufferlevel: 2, dropped: 2 14:03:35.393 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 2 14:03:36.444 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:36.547 T:5344 DEBUG: Previous line repeats 4 times. 14:03:36.548 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 1, Bufferlevel: 1, dropped: 2 14:03:36.989 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:37.169 T:6988 DEBUG: ActiveAE::SyncStream - average error -1155.553503 above threshold of 1000.000000 14:03:37.209 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:37.211 T:392 DEBUG: ffmpeg[188]: [mpegts] PES packet size mismatch 14:03:37.300 T:392 DEBUG: Previous line repeats 1 times. 14:03:37.300 T:392 DEBUG: ffmpeg[188]: [mpegts] DTS 5940665 < 5967752 out of order 14:03:37.300 T:392 DEBUG: CVideoPlayer::CheckContinuity - wrapback :2, prev:66308355.555556, curr:66007388.888889, diff:-300966.666667 14:03:37.302 T:392 DEBUG: ffmpeg[188]: [mpegts] PES packet size mismatch 14:03:37.559 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:37.559 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 1, dropped: 2 14:03:37.559 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:37.580 T:5344 DEBUG: Previous line repeats 1 times. 14:03:37.580 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 3, dropped: 2 14:03:37.607 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 8, Bufferlevel: 2, dropped: 2 14:03:37.619 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 9, Bufferlevel: 2, dropped: 2 14:03:37.643 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:37.643 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 10, Bufferlevel: 1, dropped: 2 14:03:37.643 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:37.654 T:5344 DEBUG: Previous line repeats 1 times. 14:03:37.654 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 10, Bufferlevel: 3, dropped: 2 14:03:37.669 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 10, Bufferlevel: 2, dropped: 2 14:03:37.746 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 3, dropped: 2 14:03:38.025 T:5344 ERROR: ffmpeg[14E0]: [mpeg2video] Warning MVs not available 14:03:38.070 T:1700 ERROR: ffmpeg[6A4]: [mpeg2video] 00 motion_type at 2 12 14:03:38.070 T:5344 ERROR: ffmpeg[14E0]: [mpeg2video] Warning MVs not available 14:03:38.078 T:6984 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 14:03:38.706 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:38.706 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 4, Bufferlevel: 1, dropped: 2 14:03:38.706 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:38.744 T:5344 DEBUG: Previous line repeats 1 times. 14:03:38.744 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 2, dropped: 2 14:03:38.844 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 2 14:03:39.504 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:39.659 T:5344 DEBUG: Previous line repeats 2 times. 14:03:39.659 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 0, Bufferlevel: 2, dropped: 2 14:03:39.737 T:6984 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 14:03:41.521 T:5344 ERROR: Previous line repeats 1 times. 14:03:41.521 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:41.615 T:5344 DEBUG: Previous line repeats 3 times. 14:03:41.615 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 1, dropped: 2 14:03:41.629 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:41.639 T:5344 DEBUG: Previous line repeats 1 times. 14:03:41.639 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 3, dropped: 2 14:03:43.105 T:6984 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 14:03:43.468 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 14:03:44.043 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 30000.00 10000.00, frameduration: 20000.000000 14:03:44.775 T:6984 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer 14:03:45.565 T:5344 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 50.000000 fps 14:03:45.565 T:5344 DEBUG: CPullupCorrection: pattern lost on diff 100000.000000, number of losses 2 14:03:45.679 T:392 DEBUG: Stream stalled, start buffering. Audio: 100 - Video: 0 14:03:45.679 T:392 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 14:03:45.733 T:6984 DEBUG: CDVDAudio::Flush - flush audio stream 14:03:45.739 T:6984 DEBUG: CDVDAudio::Pause - pausing audio stream 14:03:45.739 T:6984 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 14:03:45.742 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 2 14:03:45.742 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:45.755 T:5344 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 14:03:45.755 T:5344 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 14:03:45.755 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 14:03:45.756 T:6984 DEBUG: Previous line repeats 1 times. 14:03:45.756 T:6984 DEBUG: CDVDAudio::Pause - pausing audio stream 14:03:45.759 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 14:03:45.805 T:392 DEBUG: Previous line repeats 3 times. 14:03:45.805 T:392 DEBUG: CVideoPlayer::HandleMessages - player started 1 14:03:45.922 T:392 DEBUG: CVideoPlayer::HandleMessages - player started 2 14:03:45.922 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 3 14:03:45.922 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:45.922 T:392 DEBUG: CVideoPlayer::SetCaching - caching state 0 14:03:45.922 T:392 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 14:03:45.922 T:392 DEBUG: VideoPlayer::Sync - Audio - pts: 72644166.000000, cache: 297467.641416, totalcache: 579999.983311 14:03:45.922 T:392 DEBUG: VideoPlayer::Sync - Video - pts: 73167388.000000, cache: 50000.000000, totalcache: 100000.000000 14:03:45.924 T:5344 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(71664166.016689) 14:03:45.924 T:5344 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 14:03:45.973 T:6984 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(71664166.016689) 14:03:45.973 T:6988 DEBUG: ActiveAE - start sync of audio stream 14:03:45.974 T:6984 DEBUG: CDVDAudio::Resume - resume audio stream 14:03:46.076 T:6988 DEBUG: ActiveAE::SyncStream - average error of 656.570789, start adjusting 14:03:46.475 T:5344 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 14:03:46.737 T:6988 DEBUG: ActiveAE::SyncStream - average error 14.956917, last average error: 656.570789 14:03:46.856 T:6988 DEBUG: ActiveAE::SyncStream - average error of -30.307329, start adjusting 14:03:46.856 T:6988 DEBUG: ActiveAE::SyncStream - average error -15.307329 below threshold of 30.000000 14:03:47.000 T:5344 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 14:03:49.866 T:5344 DEBUG: CPullupCorrection: detected pattern of length 2: 10000.00 30000.00, frameduration: 20000.000000