[arabesc]

I see there are reports that DSPlayer doesn't play Live TV streams. I have this issue too.
Here is a full log.

And here are some excerpts.

In the first case I tried to start channel playback from a channel list, it's failed:

Code:

22:14:01.816 T:15592   DEBUG: CInputManager::OnKey: return (0xf00d) pressed, action is Select
22:14:01.816 T:15592   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
22:14:01.862 T:15592   DEBUG: Loading settings for pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:01.862 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr)
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:14:01.862 T:15592   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:14:01.862 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:14:01.862 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
22:14:01.863 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:14:01.863 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:14:01.863 T:15592   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
22:14:01.863 T:12868   DEBUG: Thread RefClock start, auto delete: false
22:14:01.863 T:12868   DEBUG: CVideoSyncD3D: Setting up Direct3d
22:14:01.863 T:15592   DEBUG: CDSPlayer::CDSPlayer : Create DSPlayer window - hWnd: 984400
22:14:01.865 T:12868   DEBUG: CVideoReferenceClock: Detected refreshrate: 60.000 hertz
22:14:01.869 T:16816   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
22:14:01.872 T:15592  NOTICE: CDSPlayer::OpenFile - DSPlayer: Opening: pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:01.872 T:15592   DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel 'Первый канал HD (AC3)'
22:14:01.872 T:15592   DEBUG: opening live stream for channel 'Первый канал HD (AC3)'
22:14:01.872 T:15592   DEBUG: AddOnLog: DVBViewer Client: Dvb::OpenLiveStream: channel=1
22:14:01.872 T:15592   DEBUG: CurlFile::Open(20E810F0) http://127.0.0.1:8089/upnp/channelstream...9743895.ts
22:14:01.872 T:15592   DEBUG: Curl::Debug - TEXT: Found bundle for host 127.0.0.1: 0x12b92ce8 [can pipeline]
22:14:01.872 T:15592   DEBUG: Curl::Debug - TEXT: Re-using existing connection! (#0) with host 127.0.0.1
22:14:01.872 T:15592   DEBUG: Curl::Debug - TEXT: Connected to 127.0.0.1 (127.0.0.1) port 8089 (#0)
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: GET /upnp/channelstream/3431743002499743895.ts HTTP/1.1
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: Host: 127.0.0.1:8089
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: Kodi/17.6 (Windows NT 10.0; WOW64) App_Bitness/32 Version/17.6-Git:20171126-b654205
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
22:14:01.872 T:15592   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
22:14:03.023 T:15592   DEBUG: Curl::Debug - TEXT: HTTP 1.0, assume close after body
22:14:03.023 T:15592   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.0 200 OK
22:14:03.023 T:15592   DEBUG: Curl::Debug - HEADER_IN: Server: DVBViewer Media Server
22:14:03.023 T:15592   DEBUG: Curl::Debug - HEADER_IN: Accept-Ranges: none
22:14:03.023 T:15592   DEBUG: Curl::Debug - HEADER_IN: Content-Type: video/MP2T
22:14:03.023 T:15592   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: no-cache
22:14:03.023 T:15592   DEBUG: AddOnLog: DVBViewer Client: StreamReader: Started; url=http://127.0.0.1:8089/upnp/channelstream/3431743002499743895.ts
22:14:03.033 T:15592   DEBUG: CDSInputStreamPVRManager - CDSInputStreamPVRManager::Open - playback has started on filename pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:03.033 T:15592  NOTICE: CDSPlayer::OpenFileInternal - DSPlayer: Opening:
22:14:03.033 T:15592   DEBUG: CRenderDSManager::UpdateDisplayLatency - Latency set to 0 msec
22:14:03.033 T:10592   DEBUG: Thread CDSPlayer thread start, auto delete: false
22:14:03.033 T:10592  NOTICE: CDSPlayer::Process - Creating DS Graph
22:14:03.035 T:10592  NOTICE: Loading filter core factory settings from special://xbmc/system/players/dsplayer/filtersconfig_internal.xml (filters configuration).
22:14:03.037 T:10592  NOTICE: Loading filter core factory settings from special://masterprofile/dsplayer/filtersconfig.xml (filters configuration).
22:14:03.037 T:10592  NOTICE: special://masterprofile/dsplayer/filtersconfig.xml does not exist. Skipping.
22:14:03.037 T:10592  NOTICE: Loading filter core factory settings from special://xbmc/system/players/dsplayer/filtersconfig.xml (filters configuration).
22:14:03.041 T:10592  NOTICE: Loading filter core factory settings from special://xbmc/system/players/dsplayer/mediasconfig_internal.xml (medias configuration).
22:14:03.042 T:10592  NOTICE: Successfully loaded rules
22:14:03.043 T:10592   DEBUG: CDSGraph::SetFile Successfully added XBMC to the Running Object Table
22:14:03.043 T:10592   ERROR: CFGLoader::LoadFilterRules Extension "" not found. Please check mediasconfig.xml
22:14:03.043 T:10592   ERROR: CFGManager::RenderFileXbmc Failed to load filters rules
22:14:03.043 T:10592    INFO: CDSGraph::SetFile Rendering file. Elapsed time: 0.05ms
22:14:03.043 T:10592   ERROR: CDSPlayer::Process - Failed creating DS Graph
22:14:03.043 T:10592   DEBUG: CApplication::OnPlayBackStopped: play state was 1, starting 1
22:14:03.043 T:10592   DEBUG: Thread CDSPlayer thread 10592 terminating
22:14:03.043 T:15592   DEBUG: CApplication::OnPlayBackStopped: play state was 3, starting 0
22:14:03.043 T:14504   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
22:14:03.043 T:14504   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
22:14:03.044 T:1620   DEBUG: Metadata and Artwork module --> get_pvr_artwork - return data from cache - pvr_artwork.воскресное "время".первый канал hd (ac3)
22:14:03.080 T:16864   DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop  - data: {"end":false,"item":{"channeltype":"tv","id":1,"title":"Первый канал HD (AC3)","type":"channel"}}
22:14:03.087 T:15592   DEBUG: CDSGraph::CloseFile Deleting filters ...
22:14:03.087 T:15592   DEBUG: CDSGraph::CloseFile ... done!
22:14:03.087 T:15592   DEBUG: CFGLoader::~CFGLoader Ressources released
22:14:03.087 T:15592   DEBUG: CFGManager::~CFGManager Resources released
22:14:03.087 T:15592   DEBUG: CRenderDSManager::DeleteRenderer - deleting renderer
22:14:03.087 T:15592   DEBUG: CDSPlayer::CloseFile File closed
22:14:03.088 T:15976   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop  - data: {"end":false,"item":{"channeltype":"tv","id":1,"title":"Первый канал HD (AC3)","type":"channel"}}
22:14:03.093 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: stream type "STREAM_TYPE_DTSHD" on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.093 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: stream type "STREAM_TYPE_TRUEHD" on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.093 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: stream type "STREAM_TYPE_EAC3" on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.093 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: stream type "STREAM_TYPE_DTS" on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.093 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: stream type "STREAM_TYPE_AC3" on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.095 T:16564  NOTICE: CAESinkWASAPI::EnumerateDevicesEx: sample rate 192khz on device "PIONEER_TV (Аудио Intel® для дисплеев)" seems to be not supported.
22:14:03.096 T:16564  NOTICE: Found 2 Lists of Devices
22:14:03.096 T:16564  NOTICE: Enumerated DIRECTSOUND devices:
22:14:03.096 T:16564  NOTICE:     Device 1
22:14:03.096 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:03.096 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra: DIRECTSOUND: Realtek Digital Output (Realtek High Definition Audio)
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:03.096 T:16564  NOTICE:     Device 2
22:14:03.096 T:16564  NOTICE:         m_deviceName      : {A9FB5D16-1544-40E5-903A-2CDD59F40948}
22:14:03.096 T:16564  NOTICE:         m_displayName     : HDMI - PIONEER_TV (Аудио Intel® для дисплеев)
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra: DIRECTSOUND: PIONEER_TV (Аудио Intel® для дисплеев)
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:03.096 T:16564  NOTICE:     Device 3
22:14:03.096 T:16564  NOTICE:         m_deviceName      : default
22:14:03.096 T:16564  NOTICE:         m_displayName     : default
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra:
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:03.096 T:16564  NOTICE: Enumerated WASAPI devices:
22:14:03.096 T:16564  NOTICE:     Device 1
22:14:03.096 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:03.096 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra: WASAPI: Realtek Digital Output (Realtek High Definition Audio)
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 192000,96000,88200,48000,44100,32000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:03.096 T:16564  NOTICE:     Device 2
22:14:03.096 T:16564  NOTICE:         m_deviceName      : {A9FB5D16-1544-40E5-903A-2CDD59F40948}
22:14:03.096 T:16564  NOTICE:         m_displayName     : HDMI - PIONEER_TV (Аудио Intel® для дисплеев)
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra: WASAPI: PIONEER_TV (Аудио Intel® для дисплеев)
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 192000,48000,44100,32000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_TRUEHD,STREAM_TYPE_EAC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:03.096 T:16564  NOTICE:     Device 3
22:14:03.096 T:16564  NOTICE:         m_deviceName      : default
22:14:03.096 T:16564  NOTICE:         m_displayName     : default
22:14:03.096 T:16564  NOTICE:         m_displayNameExtra:
22:14:03.096 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
22:14:03.096 T:16564  NOTICE:         m_channels        : FL,FR
22:14:03.096 T:16564  NOTICE:         m_sampleRates     : 192000,48000,44100,32000
22:14:03.096 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:03.096 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_TRUEHD,STREAM_TYPE_EAC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:03.096 T:16816    INFO: CActiveAESink::OpenSink - initialize sink
22:14:03.096 T:16816   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:14:03.098 T:16816   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 000B05B2
22:14:03.100 T:16816   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:14:03.101 T:16816   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:14:03.101 T:16816   DEBUG:   Audio Device    : PIONEER_TV (Аудио Intel® для дисплеев)
22:14:03.101 T:16816   DEBUG:   Sample Rate     : 44100
22:14:03.101 T:16816   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:14:03.101 T:16816   DEBUG:   Bits Per Sample : 32
22:14:03.101 T:16816   DEBUG:   Valid Bits/Samp : 32
22:14:03.101 T:16816   DEBUG:   Channel Count   : 2
22:14:03.101 T:16816   DEBUG:   Block Align     : 8
22:14:03.101 T:16816   DEBUG:   Avg. Bytes Sec  : 352800
22:14:03.101 T:16816   DEBUG:   Samples/Block   : 32
22:14:03.101 T:16816   DEBUG:   Format cBSize   : 22
22:14:03.101 T:16816   DEBUG:   Channel Layout  : FL,FR
22:14:03.101 T:16816   DEBUG:   Channel Mask    : 3
22:14:03.101 T:16816   DEBUG:   Frames          : 661
22:14:03.101 T:16816   DEBUG:   Frame Size      : 8
22:14:03.101 T:16816   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:14:03.101 T:16816   DEBUG:   Output Device : default
22:14:03.101 T:16816   DEBUG:   Sample Rate   : 44100
22:14:03.101 T:16816   DEBUG:   Sample Format : AE_FMT_FLOAT
22:14:03.101 T:16816   DEBUG:   Channel Count : 2
22:14:03.101 T:16816   DEBUG:   Channel Layout: FL,FR
22:14:03.101 T:16816   DEBUG:   Frames        : 661
22:14:03.101 T:16816   DEBUG:   Frame Size    : 8
22:14:03.101 T:16564   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:14:03.101 T:15592   DEBUG: CDSPlayer::~CDSPlayer External objects unloaded
22:14:03.107 T:15592   DEBUG: Curl::Debug - TEXT: Closing connection 0
22:14:03.107 T:15592   DEBUG: AddOnLog: DVBViewer Client: StreamReader: Stopped
22:14:03.107 T:15592  NOTICE: CDSPlayer::~CDSPlayer DSPlayer is now closed
22:14:03.110 T:12868   DEBUG: CVideoSyncD3D: Cleaning up Direct3d
22:14:03.110 T:12868   DEBUG: Thread RefClock 12868 terminating

In the second case I tried to start channel playback from a channels widget (it's a history of recently used channels actually) , it's succeeded:

Code:

22:14:06.988 T:15592   DEBUG: CInputManager::OnKey: return (0xf00d) pressed, action is Select
22:14:06.992 T:15592   DEBUG: Loading settings for pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:06.993 T:16788   DEBUG: Thread RefClock start, auto delete: false
22:14:06.993 T:16788   DEBUG: CVideoSyncD3D: Setting up Direct3d
22:14:06.993 T:16788   DEBUG: CVideoReferenceClock: Detected refreshrate: 60.000 hertz
22:14:06.993 T:15592   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData::CDVDRadioRDSData
22:14:06.993 T:15592  NOTICE: VideoPlayer: Opening: pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:06.993 T:15592 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:14:06.995 T:13732   DEBUG: Thread VideoPlayer start, auto delete: false
22:14:06.995 T:13732  NOTICE: Creating InputStream
22:14:06.995 T:13732   DEBUG: PVRManager - PVR::CPVRManager::OpenLiveStream - opening live stream on channel 'Первый канал HD (AC3)'
22:14:06.995 T:13732   DEBUG: opening live stream for channel 'Первый канал HD (AC3)'
22:14:06.995 T:13732   DEBUG: AddOnLog: DVBViewer Client: Dvb::OpenLiveStream: channel=1
22:14:06.996 T:13732   DEBUG: CurlFile::Open(16BF0B00) http://127.0.0.1:8089/upnp/channelstream...9743895.ts
22:14:06.996 T:13732   DEBUG: Curl::Debug - TEXT: timeout on name lookup is not supported
22:14:06.996 T:13732   DEBUG: Curl::Debug - TEXT: Hostname 127.0.0.1 was found in DNS cache
22:14:06.996 T:13732   DEBUG: Curl::Debug - TEXT:   Trying 127.0.0.1...
22:14:06.996 T:13732   DEBUG: Curl::Debug - TEXT: Connected to 127.0.0.1 (127.0.0.1) port 8089 (#1)
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: GET /upnp/channelstream/3431743002499743895.ts HTTP/1.1
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: Host: 127.0.0.1:8089
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: Kodi/17.6 (Windows NT 10.0; WOW64) App_Bitness/32 Version/17.6-Git:20171126-b654205
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
22:14:06.997 T:13732   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
22:14:07.018 T:13732   DEBUG: Curl::Debug - TEXT: HTTP 1.0, assume close after body
22:14:07.018 T:13732   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.0 200 OK
22:14:07.018 T:13732   DEBUG: Curl::Debug - HEADER_IN: Server: DVBViewer Media Server
22:14:07.018 T:13732   DEBUG: Curl::Debug - HEADER_IN: Accept-Ranges: none
22:14:07.018 T:13732   DEBUG: Curl::Debug - HEADER_IN: Content-Type: video/MP2T
22:14:07.018 T:13732   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: no-cache
22:14:07.496 T:15592   DEBUG: ------ Window Init (DialogBusy.xml) ------
22:14:07.614 T:13732   DEBUG: AddOnLog: DVBViewer Client: StreamReader: Started; url=http://127.0.0.1:8089/upnp/channelstream/3431743002499743895.ts
22:14:07.625 T:13732   DEBUG: CDVDInputStreamPVRManager - CDVDInputStreamPVRManager::Open - playback has started on filename pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:07.625 T:13732   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr
22:14:07.625 T:13732  NOTICE: Creating Demuxer
22:14:07.674 T:13732   DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [mpegts]
22:14:07.674 T:13732    INFO: ffmpeg[35A4]: Input #0, mpegts, pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pv':
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:   Duration: N/A, bitrate: N/A
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:   Program 151
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:     Stream #0:0[0x65]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:     Stream #0:1[0x6e](rus): Audio: ac3 ([6][0][0][0] / 0x0006), 0 channels
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:     Stream #0:2[0x6f](rus): Audio: mp3 ([4][0][0][0] / 0x0004), 0 channels
22:14:07.674 T:13732    INFO: ffmpeg[35A4]:     Stream #0:3[0x96](rus): Subtitle: dvb_teletext ([6][0][0][0] / 0x0006)
22:14:07.674 T:13732   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
22:14:07.674 T:13732   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
22:14:07.674 T:13732   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2
22:14:07.674 T:13732   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3
22:14:07.674 T:13732  NOTICE: Opening stream: 0 source: 256
22:14:07.674 T:13732  NOTICE: Creating video codec with codec id: 28
22:14:07.674 T:13732   DEBUG: FactoryCodec - Video:  - Opening
22:14:07.674 T:13732  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:14:07.674 T:13732   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
22:14:07.674 T:13732   DEBUG: FactoryCodec - Video: ff-h264 - Opened
22:14:07.674 T:13732   DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
22:14:07.674 T:13732  NOTICE: Creating video thread
22:14:07.675 T:13732  NOTICE: Opening stream: 1 source: 256
22:14:07.675 T:13732  NOTICE: Finding audio codec for: 86019
22:14:07.675 T:13732   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:14:07.675 T:12472   DEBUG: Thread VideoPlayerVideo start, auto delete: false
22:14:07.675 T:12472  NOTICE: running thread: video_thread
22:14:07.675 T:13732   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:14:07.675 T:13732  NOTICE: Creating audio thread
22:14:07.675 T:13732  NOTICE: Opening stream: 3 source: 256
22:14:07.675 T:13732  NOTICE: waiting for teletext data thread to exit
22:14:07.675 T:16752   DEBUG: Thread VideoPlayerAudio start, auto delete: false
22:14:07.675 T:16752  NOTICE: running thread: CVideoPlayerAudio::Process()
22:14:07.675 T:13732  NOTICE: Creating teletext data thread
22:14:07.675 T:13732   DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1
22:14:07.675 T:13732   DEBUG: CVideoPlayer::SetCaching - caching state 2
22:14:07.675 T:13732   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:14:07.675 T:15372   DEBUG: Thread DVDTeletextData start, auto delete: false
22:14:07.675 T:15372  NOTICE: running thread: CDVDTeletextData
22:14:07.685 T:15592   DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2
22:14:07.685 T:15592   DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0
22:14:07.685 T:16752   DEBUG: CDVDAudio::Pause - pausing audio stream
22:14:07.685 T:15592   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x0000, modifier: 0x0
22:14:07.701 T:14504   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
22:14:07.701 T:17280   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr)
22:14:07.702 T:14504   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
22:14:07.702 T:14504   DEBUG: UPnP: Building didl for object 'pvr://channels/tv/Все каналы/pvr.dvbviewer_1.pvr'
22:14:07.720 T:15976   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay  - data: {"item":{"channeltype":"tv","id":1,"title":"Первый канал HD (AC3)","type":"channel"},"player":{"playerid":1,"speed":1}}
22:14:07.767 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.767 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
22:14:07.801 T:16864   DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay  - data: {"item":{"channeltype":"tv","id":1,"title":"Первый канал HD (AC3)","type":"channel"},"player":{"playerid":1,"speed":1}}
22:14:07.804 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.804 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
22:14:07.840 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.840 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
22:14:07.854 T:15592   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
22:14:07.876 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.876 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
...
22:14:07.943 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.943 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
22:14:07.952 T:15976   DEBUG: Metadata and Artwork module --> get_pvr_artwork - return data from cache - pvr_artwork.воскресное "время".первый канал hd (ac3)
22:14:07.973 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:07.973 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
...
22:14:08.870 T:13732   ERROR: ffmpeg[35A4]: [NULL] SPS unavailable in decode_picture_timing
22:14:08.870 T:13732   ERROR: ffmpeg[35A4]: [NULL] non-existing PPS 0 referenced
22:14:08.874 T:15564   DEBUG: PERIPHERALS::CPeripherals::GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec)
22:14:08.895 T:13732   DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(46)
22:14:08.909 T:13732   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
22:14:08.909 T:13732  NOTICE: Opening stream: 0 source: 256
22:14:08.910 T:13732  NOTICE: Creating video codec with codec id: 28
22:14:08.910 T:13732   DEBUG: FactoryCodec - Video:  - Opening
22:14:08.910 T:13732  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:14:08.910 T:13732   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
22:14:08.910 T:13732   DEBUG: FactoryCodec - Video: ff-h264 - Opened
22:14:08.910 T:12472   DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
22:14:08.910 T:13732  NOTICE: Opening stream: 1 source: 256
22:14:08.910 T:13732  NOTICE: Opening stream: 3 source: 256
22:14:08.910 T:12472    INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
22:14:08.910 T:12472   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
22:14:08.910 T:12472  NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating DXVA(1920x1080)
22:14:08.910 T:12472   DEBUG: DXVA - open decoder
22:14:08.912 T:12472   DEBUG: DXVA - supports 'MPEG2 VLD'
22:14:08.912 T:12472   DEBUG: DXVA - supports 'MPEG2 IDCT'
22:14:08.912 T:12472   DEBUG: DXVA - supports 'VC-1 VLD 2010'
22:14:08.912 T:12472   DEBUG: DXVA - supports E07EC519-e651-4cd6-ac84-1370cceec851
22:14:08.912 T:12472   DEBUG: DXVA - supports 'Intel VC-1 VLD'
22:14:08.912 T:12472   DEBUG: DXVA - supports 'Windows Media Video 9 IDCT'
22:14:08.912 T:12472   DEBUG: DXVA - supports 'VC-1 IDCT'
22:14:08.912 T:12472   DEBUG: DXVA - supports 'H.264 VLD, no FGT'
22:14:08.912 T:12472   DEBUG: DXVA - supports D79BE8DA-0cf1-4c81-b82a-69a4e236f43d
22:14:08.912 T:12472   DEBUG: DXVA - supports F9AACCBB-c2b6-4cfc-8779-5707b1760552
22:14:08.912 T:12472   DEBUG: DXVA - supports 705B9D82-76cf-49d6-b7e6-ac8872db013c
22:14:08.912 T:12472   DEBUG: DXVA - supports 91CD2D6E-897b-4fa1-b0d7-51dc88010e0a
22:14:08.912 T:12472   DEBUG: DXVA - supports 90B899EA-3a62-4705-88b3-8df04b2744e7
22:14:08.912 T:12472   DEBUG: DXVA - supports 442B942A-b4d9-4940-bc45-a882e5f919f3
22:14:08.912 T:12472   DEBUG: DXVA - supports 97688186-56a8-4094-b543-fc9daaa49f4b
22:14:08.912 T:12472   DEBUG: DXVA - supports 1424D4DC-7cf5-4bb1-9cd7-b63717a72a6b
22:14:08.912 T:12472   DEBUG: DXVA - supports C346E8A3-cbed-4d27-87cc-a70eb4dc8c27
22:14:08.912 T:12472   DEBUG: DXVA - supports FFC79924-5eaf-4666-a736-06190f281443
22:14:08.912 T:12472   DEBUG: DXVA - supports F416F7BD-098a-4cf1-a11b-ce54959ca03d
22:14:08.912 T:12472   DEBUG: DXVA - supports BF44DACD-217f-4370-a383-d573bc56707e
22:14:08.912 T:12472   DEBUG: DXVA - supports 2364D06A-f67f-4186-aed0-62b99e1784f1
22:14:08.912 T:12472   DEBUG: DXVA - supports 464BDB3C-91c4-4e9b-896f-225496ac4ed6
22:14:08.912 T:12472   DEBUG: DXVA - supports 28566328-f041-4466-8b14-8f5831e78f8b
22:14:08.912 T:12472   DEBUG: DXVA - supports 6B4A94DB-54fe-4ae1-9be4-7a7dad004600
22:14:08.912 T:12472   DEBUG: DXVA - supports 8C56EB1E-2b47-466f-8d33-7dbcd63f3df2
22:14:08.912 T:12472   DEBUG: DXVA - supports 'HEVC / H.265 variable-length decoder, main'
22:14:08.912 T:12472   DEBUG: DXVA - supports 75FC75F7-c589-4a07-a25b-72e03b0383b3
22:14:08.912 T:12472   DEBUG: DXVA - supports 'HEVC / H.265 variable-length decoder, main10'
22:14:08.912 T:12472   DEBUG: DXVA - supports 463707F8-a1d0-4585-876d-83aa6d60b89e
22:14:08.912 T:12472   DEBUG: DXVA - supports A4C749EF-6ecf-48aa-8448-50a7a1165ff7
22:14:08.912 T:12472   DEBUG: DXVA - supports 76988A52-df13-419a-8e64-ffcf4a336cf5
22:14:08.912 T:12472   DEBUG: DXVA - supports 80A3A7BD-89d8-4497-a2b8-2126af7e6eb8
22:14:08.912 T:12472   DEBUG: DXVA - supports 50925B7B-e931-4978-a12a-586630f095f9
22:14:08.912 T:12472   DEBUG: DXVA - supports 49761BEC-4b63-4349-a5ff-87ffdf088466
22:14:08.912 T:12472   DEBUG: DXVA - trying 'H.264 VLD, no FGT'
22:14:08.912 T:12472   DEBUG: DXVA - Selected input/output format: 103
22:14:08.912 T:12472   DEBUG: DXVA - source requires 4 references
22:14:08.913 T:12472   DEBUG: DXVA - config 0: bitstream type 2
22:14:08.913 T:12472   DEBUG: DXVA - config 1: bitstream type 1
22:14:08.913 T:12472   DEBUG: DXVA - config 2: bitstream type 2, encrypted
22:14:08.913 T:12472   DEBUG: DXVA - config 3: bitstream type 2, encrypted
22:14:08.913 T:12472   DEBUG: DXVA - config 4: bitstream type 1, encrypted
22:14:08.913 T:12472   DEBUG: DXVA - config 5: bitstream type 1, encrypted
22:14:08.913 T:12472   DEBUG: DXVA - allocating 14 surfaces with format 103
22:14:08.917 T:12472   DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-d3d11va
22:14:08.934 T:12472   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
22:14:08.951 T:12472   DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 50.00. format: DXVA
22:14:08.952 T:15592  NOTICE: D3D: rendering method forced to DXVA processor
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Initing Video Enumerator with params: 1920x1080.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 1 rate conversion.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 0x2ef feature caps.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 0x1e device caps.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 0 input format caps.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 8 max input streams.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Video processor has 8 max stream states.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Selected video processor index: 0.
22:14:08.952 T:15592  NOTICE: DXVA::CProcessorHD::InitProcessor: Supported deinterlace methods: Blend:yes, Bob:yes, Adaptive:yes, MoComp:yes.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Selected video processor allows 1 future frames and 1 past frames.
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Filter 0 has following params - max: 1000, min: -1000, default: 0
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Filter 1 has following params - max: 1000, min: 0, default: 100
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Filter 2 has following params - max: 1800, min: -1800, default: 0
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::InitProcessor: Filter 3 has following params - max: 1000, min: 0, default: 100
22:14:08.952 T:15592   DEBUG: DXVA::CProcessorHD::OpenProcessor: Creating processor with input format: (103).
22:14:08.960 T:15592   DEBUG: CWinRenderer::SelectRenderMethod: Selected render method 3: DXVA
22:14:08.960 T:15592   DEBUG: created video buffer 0
22:14:08.960 T:15592   DEBUG: created video buffer 1
22:14:08.960 T:15592   DEBUG: created video buffer 2
22:14:08.960 T:15592   DEBUG: created video buffer 3
22:14:08.960 T:15592   DEBUG: created video buffer 4
22:14:08.960 T:15592   DEBUG: CRenderManager::Configure - 5
22:14:08.961 T:15592   DEBUG: Activating window ID: 12005
22:14:08.961 T:15592   DEBUG: ------ Window Deinit (Home.xml) ------
22:14:08.961 T:15592   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:14:08.961 T:15592    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
22:14:08.962 T:15592   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
22:14:08.962 T:15592    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
22:14:08.969 T:13732   DEBUG: CVideoPlayer::HandleMessages - player started 2
22:14:08.980 T:15592  NOTICE: Display resolution ADJUST : 1920x1080@ 50.00 - Full Screen (137) (weight: 0.000)
22:14:08.980 T:15592   DEBUG: CWinSystemWin32::SetFullScreenEx (windowed fullscreen) on screen 0 with size 1920x1080, refresh 50.000000
22:14:09.034 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{4bc2c163-2e26-4a51-838e-19edf8939d22} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
...
22:14:09.035 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{4bc2c163-2e26-4a51-838e-19edf8939d22} is {fb1de864-e06d-47f4-82a6-8a0aef44493c}#2
22:14:09.037 T:15704   DEBUG: CMMNotificationClient::OnDeviceStateChanged: New device state is DEVICE_STATE_UNPLUGGED
22:14:09.037 T:16564   DEBUG: CActiveAE - device change event
22:14:09.038 T:15704   DEBUG: CMMNotificationClient::OnDefaultDeviceChanged: New default device: flow = eRender, role = eConsole
22:14:09.038 T:15704   DEBUG: CMMNotificationClient::OnDefaultDeviceChanged: New default device: flow = eRender, role = eMultimedia
22:14:09.038 T:15704   DEBUG: CMMNotificationClient::OnDefaultDeviceChanged: New default device: flow = eRender, role = eCommunications
22:14:09.041 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{485e0f59-2663-4612-bf3e-3db29189073d} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
...
22:14:09.042 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{57db140b-6595-4151-afbe-817d7c96f17a} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#27
22:14:09.043 T:16752   DEBUG: CVideoPlayerAudio: Sample rate changed, checking for passthrough
22:14:09.043 T:16752   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:14:09.043 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{667c94fb-8f33-4e9c-b4e9-3e46af58184d} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
22:14:09.043 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{667c94fb-8f33-4e9c-b4e9-3e46af58184d} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#27
22:14:09.043 T:16752   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:14:09.043 T:16752  NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
22:14:09.043 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{79aceb6f-09e8-4b1b-a7ee-b7c47ddac68e} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
...
22:14:09.050 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{a9fb5d16-1544-40e5-903a-2cdd59f40948} is {9c119480-ddc2-4954-a150-5bd240d454ad}#2
22:14:09.148 T:15592   DEBUG: CWinEventsWin32::WndProc: display change event
22:14:09.149 T:15592   DEBUG: CWinSystemWin32::OnDisplayLost - notify display lost event
22:14:09.149 T:15592   DEBUG: CRenderManager::Flush - flushing renderer
22:14:09.155 T:16788   DEBUG: CVideoSyncD3D: Cleaning up Direct3d
22:14:09.159 T:16816   ERROR: CAESinkDirectSound::UpdateCacheStatus: GetCurrentPosition failed. Unable to determine buffer status. HRESULT = 0x88780096
22:14:09.159 T:16816   ERROR: CActiveAESink::OutputSamples - sink returned error
22:14:09.159 T:16816   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
22:14:09.163 T:16432   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{4bc2c163-2e26-4a51-838e-19edf8939d22} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
...
22:14:09.164 T:16432   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{4bc2c163-2e26-4a51-838e-19edf8939d22} is {fb1de864-e06d-47f4-82a6-8a0aef44493c}#2
22:14:09.167 T:16432   DEBUG: CMMNotificationClient::OnDeviceStateChanged: New device state is DEVICE_STATE_NOTPRESENT
22:14:09.168 T:16432   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{485e0f59-2663-4612-bf3e-3db29189073d} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#24
...
22:14:09.174 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{d8fe7739-90d8-44dd-ad84-d35fb6e06e6d} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#22
22:14:09.174 T:16564  NOTICE: Found 2 Lists of Devices
22:14:09.174 T:16564  NOTICE: Enumerated DIRECTSOUND devices:
22:14:09.174 T:16564  NOTICE:     Device 1
22:14:09.174 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:09.174 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:09.174 T:16564  NOTICE:         m_displayNameExtra: DIRECTSOUND: Realtek Digital Output (Realtek High Definition Audio)
22:14:09.174 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.174 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.174 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:09.174 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:09.174 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:09.174 T:16564  NOTICE:     Device 2
22:14:09.174 T:16564  NOTICE:         m_deviceName      : default
22:14:09.174 T:16564  NOTICE:         m_displayName     : default
22:14:09.174 T:16564  NOTICE:         m_displayNameExtra:
22:14:09.174 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.174 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.174 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:09.174 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:09.174 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:09.174 T:16564  NOTICE: Enumerated WASAPI devices:
22:14:09.174 T:16564  NOTICE:     Device 1
22:14:09.174 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:09.174 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:09.175 T:16564  NOTICE:         m_displayNameExtra: WASAPI: Realtek Digital Output (Realtek High Definition Audio)
22:14:09.175 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.175 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.175 T:16564  NOTICE:         m_sampleRates     : 192000,96000,88200,48000,44100,32000
22:14:09.175 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:09.175 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:09.175 T:16564  NOTICE:     Device 2
22:14:09.175 T:16564  NOTICE:         m_deviceName      : default
22:14:09.175 T:16564  NOTICE:         m_displayName     : default
22:14:09.175 T:16564  NOTICE:         m_displayNameExtra:
22:14:09.175 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.175 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.175 T:16564  NOTICE:         m_sampleRates     : 192000,96000,88200,48000,44100,32000
22:14:09.175 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:09.175 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:09.175 T:16816    INFO: CActiveAESink::OpenSink - initialize sink
22:14:09.175 T:16816   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:14:09.175 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{d8fe7739-90d8-44dd-ad84-d35fb6e06e6d} is {8943b373-388c-4395-b557-bc6dbaffafdb}#2
...
22:14:09.185 T:15704   DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.1.00000000}.{f3f00173-a718-4f92-844a-63453e72d9b4} is {b3f8fa53-0004-438e-9003-51a46e139bfc}#27
22:14:09.205 T:16816   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 000B05B2
22:14:09.210 T:16816   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:14:09.210 T:16816   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:14:09.210 T:16816   DEBUG:   Audio Device    : Realtek Digital Output (Realtek High Definition Audio)
22:14:09.210 T:16816   DEBUG:   Sample Rate     : 44100
22:14:09.210 T:16816   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:14:09.210 T:16816   DEBUG:   Bits Per Sample : 32
22:14:09.210 T:16816   DEBUG:   Valid Bits/Samp : 32
22:14:09.210 T:16816   DEBUG:   Channel Count   : 2
22:14:09.210 T:16816   DEBUG:   Block Align     : 8
22:14:09.210 T:16816   DEBUG:   Avg. Bytes Sec  : 352800
22:14:09.210 T:16816   DEBUG:   Samples/Block   : 32
22:14:09.210 T:16816   DEBUG:   Format cBSize   : 22
22:14:09.210 T:16816   DEBUG:   Channel Layout  : FL,FR
22:14:09.210 T:16816   DEBUG:   Channel Mask    : 3
22:14:09.210 T:16816   DEBUG:   Frames          : 661
22:14:09.210 T:16816   DEBUG:   Frame Size      : 8
22:14:09.210 T:16816   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:14:09.210 T:16816   DEBUG:   Output Device : default
22:14:09.210 T:16816   DEBUG:   Sample Rate   : 44100
22:14:09.210 T:16816   DEBUG:   Sample Format : AE_FMT_FLOAT
22:14:09.210 T:16816   DEBUG:   Channel Count : 2
22:14:09.210 T:16816   DEBUG:   Channel Layout: FL,FR
22:14:09.210 T:16816   DEBUG:   Frames        : 661
22:14:09.210 T:16816   DEBUG:   Frame Size    : 8
22:14:09.211 T:16564   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:14:09.211 T:16564   DEBUG: CActiveAE - device change event
22:14:09.211 T:15592  NOTICE: VideoPlayer: OnLostDisplay received
22:14:09.211 T:16788   DEBUG: CVideoSyncD3D: Setting up Direct3d
22:14:09.211 T:15592   DEBUG: CWinSystemWin32::OnDisplayReset - notify display reset event
22:14:09.211 T:12472   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1
22:14:09.211 T:15592  NOTICE: VideoPlayer: OnResetDisplay received
22:14:09.211 T:12472   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
22:14:09.212 T:16788   DEBUG: CVideoReferenceClock: Detected refreshrate: 50.000 hertz
22:14:09.213 T:15592   DEBUG: created video buffer 0
22:14:09.213 T:15592   DEBUG: created video buffer 1
22:14:09.213 T:15592   DEBUG: created video buffer 2
22:14:09.213 T:15592   DEBUG: created video buffer 3
22:14:09.213 T:15592   DEBUG: created video buffer 4
22:14:09.213 T:15592 WARNING: CWinRenderer::UpdateVideoFilter - chosen scaling method 1 is not supported by renderer
22:14:09.213 T:15592   DEBUG: CWinRenderer::CreateIntermediateRenderTarget: format 88
22:14:09.237 T:16816   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
22:14:09.237 T:16816   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
22:14:09.247 T:16564  NOTICE: Found 2 Lists of Devices
22:14:09.247 T:16564  NOTICE: Enumerated DIRECTSOUND devices:
22:14:09.247 T:16564  NOTICE:     Device 1
22:14:09.247 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:09.247 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:09.247 T:16564  NOTICE:         m_displayNameExtra: DIRECTSOUND: Realtek Digital Output (Realtek High Definition Audio)
22:14:09.247 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.247 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.247 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:09.247 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:09.247 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:09.247 T:16564  NOTICE:     Device 2
22:14:09.247 T:16564  NOTICE:         m_deviceName      : default
22:14:09.247 T:16564  NOTICE:         m_displayName     : default
22:14:09.247 T:16564  NOTICE:         m_displayNameExtra:
22:14:09.247 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.247 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.247 T:16564  NOTICE:         m_sampleRates     : 48000
22:14:09.247 T:16564  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_RAW
22:14:09.247 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
22:14:09.247 T:16564  NOTICE: Enumerated WASAPI devices:
22:14:09.247 T:16564  NOTICE:     Device 1
22:14:09.247 T:16564  NOTICE:         m_deviceName      : {4BC2C163-2E26-4A51-838E-19EDF8939D22}
22:14:09.247 T:16564  NOTICE:         m_displayName     : SPDIF - Realtek Digital Output (Realtek High Definition Audio)
22:14:09.247 T:16564  NOTICE:         m_displayNameExtra: WASAPI: Realtek Digital Output (Realtek High Definition Audio)
22:14:09.247 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.247 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.248 T:16564  NOTICE:         m_sampleRates     : 192000,96000,88200,48000,44100,32000
22:14:09.248 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:09.248 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:09.248 T:16564  NOTICE:     Device 2
22:14:09.248 T:16564  NOTICE:         m_deviceName      : default
22:14:09.248 T:16564  NOTICE:         m_displayName     : default
22:14:09.248 T:16564  NOTICE:         m_displayNameExtra:
22:14:09.248 T:16564  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
22:14:09.248 T:16564  NOTICE:         m_channels        : FL,FR
22:14:09.248 T:16564  NOTICE:         m_sampleRates     : 192000,96000,88200,48000,44100,32000
22:14:09.248 T:16564  NOTICE:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_RAW
22:14:09.248 T:16564  NOTICE:         m_streamTypes     : STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_512,STREAM_TYPE_AC3
22:14:09.248 T:16816    INFO: CActiveAESink::OpenSink - initialize sink
22:14:09.248 T:16816   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:14:09.249 T:16816   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 000B05B2
22:14:09.251 T:16816   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:14:09.251 T:16816   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:14:09.251 T:16816   DEBUG:   Audio Device    : Realtek Digital Output (Realtek High Definition Audio)
22:14:09.251 T:16816   DEBUG:   Sample Rate     : 44100
22:14:09.251 T:16816   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:14:09.251 T:16816   DEBUG:   Bits Per Sample : 32
22:14:09.251 T:16816   DEBUG:   Valid Bits/Samp : 32
22:14:09.251 T:16816   DEBUG:   Channel Count   : 2
22:14:09.251 T:16816   DEBUG:   Block Align     : 8
22:14:09.251 T:16816   DEBUG:   Avg. Bytes Sec  : 352800
22:14:09.251 T:16816   DEBUG:   Samples/Block   : 32
22:14:09.251 T:16816   DEBUG:   Format cBSize   : 22
22:14:09.251 T:16816   DEBUG:   Channel Layout  : FL,FR
22:14:09.251 T:16816   DEBUG:   Channel Mask    : 3
22:14:09.251 T:16816   DEBUG:   Frames          : 661
22:14:09.251 T:16816   DEBUG:   Frame Size      : 8
22:14:09.251 T:16816   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:14:09.251 T:16816   DEBUG:   Output Device : default
22:14:09.251 T:16816   DEBUG:   Sample Rate   : 44100
22:14:09.251 T:16816   DEBUG:   Sample Format : AE_FMT_FLOAT
22:14:09.251 T:16816   DEBUG:   Channel Count : 2
22:14:09.251 T:16816   DEBUG:   Channel Layout: FL,FR
22:14:09.251 T:16816   DEBUG:   Frames        : 661
22:14:09.251 T:16816   DEBUG:   Frame Size    : 8
22:14:09.252 T:16564   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:14:09.252 T:16752   DEBUG: CVideoPlayerAudio:: synctype set to 1: resample
22:14:09.265 T:15564   DEBUG: PERIPHERALS::CPeripherals::GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec)
22:14:09.271 T:16816   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
22:14:09.271 T:16816    INFO: CActiveAESink::OpenSink - initialize sink
22:14:09.271 T:16816   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up
22:14:09.276 T:16816   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
22:14:09.277 T:16816   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 000B05B2
22:14:09.279 T:16816   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
22:14:09.279 T:16816   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
22:14:09.279 T:16816   DEBUG:   Audio Device    : Realtek Digital Output (Realtek High Definition Audio)
22:14:09.279 T:16816   DEBUG:   Sample Rate     : 48000
22:14:09.279 T:16816   DEBUG:   Sample Format   : AE_FMT_FLOAT
22:14:09.279 T:16816   DEBUG:   Bits Per Sample : 32
22:14:09.279 T:16816   DEBUG:   Valid Bits/Samp : 32
22:14:09.279 T:16816   DEBUG:   Channel Count   : 2
22:14:09.279 T:16816   DEBUG:   Block Align     : 8
22:14:09.279 T:16816   DEBUG:   Avg. Bytes Sec  : 384000
22:14:09.279 T:16816   DEBUG:   Samples/Block   : 32
22:14:09.279 T:16816   DEBUG:   Format cBSize   : 22
22:14:09.279 T:16816   DEBUG:   Channel Layout  : FL,FR
22:14:09.279 T:16816   DEBUG:   Channel Mask    : 3
22:14:09.279 T:16816   DEBUG:   Frames          : 720
22:14:09.279 T:16816   DEBUG:   Frame Size      : 8
22:14:09.279 T:16816   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
22:14:09.279 T:16816   DEBUG:   Output Device : default
22:14:09.279 T:16816   DEBUG:   Sample Rate   : 48000
22:14:09.279 T:16816   DEBUG:   Sample Format : AE_FMT_FLOAT
22:14:09.279 T:16816   DEBUG:   Channel Count : 2
22:14:09.279 T:16816   DEBUG:   Channel Layout: FL,FR
22:14:09.279 T:16816   DEBUG:   Frames        : 720
22:14:09.279 T:16816   DEBUG:   Frame Size    : 8
22:14:09.280 T:16564   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
22:14:09.280 T:16752   DEBUG: Previous line repeats 1 times.
22:14:09.280 T:16752   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 1
22:14:09.280 T:16752   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
22:14:09.287 T:13732   DEBUG: CVideoPlayer::HandleMessages - player started 1
22:14:09.287 T:13732   DEBUG: CVideoPlayer::SetCaching - caching state 3
22:14:09.287 T:13732   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:14:09.287 T:13732   DEBUG: CVideoPlayer::SetCaching - caching state 0
22:14:09.287 T:13732   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
22:14:09.287 T:13732   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
22:14:09.287 T:13732   DEBUG: VideoPlayer::Sync - Audio - pts: 89077426455.000000, cache: 319999.933243, totalcache: 579999.983311
22:14:09.287 T:13732   DEBUG: VideoPlayer::Sync - Video - pts: 89077692544.000000, cache: 50000.000000, totalcache: 100000.000000
22:14:09.287 T:16752   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(89076446455.016693)
22:14:09.287 T:12472   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(89076446455.016693)
22:14:09.287 T:16752   DEBUG: CDVDAudio::Resume - resume audio stream
22:14:09.287 T:13732   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
22:14:09.287 T:12472   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
22:14:09.287 T:16564   DEBUG: ActiveAE - start sync of audio stream
22:14:09.298 T:16816   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
22:14:09.305 T:12472   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
22:14:09.385 T:12472   DEBUG: Previous line repeats 10 times.
22:14:09.385 T:12472   DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
22:14:09.390 T:16564   DEBUG: Previous line repeats 1 times.
22:14:09.390 T:16564   DEBUG: ActiveAE::SyncStream - average error of 673.765791, start adjusting
22:14:09.905 T:12472 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer

The obvious difference is that in the second case the CDVD* player was used instead of the CDSPlayer from the first case.

What is wrong there and how to fix it?