2023-03-24 21:17:58.750 T:999     debug <general>: Keyboard: scancode: 0x6c, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-03-24 21:17:59.022 T:999     debug <general>: ------ Window Deinit () ------
2023-03-24 21:17:59.264 T:1004    debug <general>: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms
2023-03-24 21:17:59.264 T:1147    debug <general>: Thread Timer start, auto delete: false
2023-03-24 21:17:59.266 T:999     debug <general>: Keyboard: scancode: 0x67, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-03-24 21:17:59.266 T:999     debug <general>: HandleKey: up (0xf080) pressed, window 10700, action is Up
2023-03-24 21:17:59.270 T:1145    debug <general>: GetImageHash - unable to stat url CineMan%20%d0%a3%d0%b6%d0%b0%d1%81%d1%8b
2023-03-24 21:17:59.463 T:1147    debug <general>: Thread Timer 3666616960 terminating
2023-03-24 21:17:59.466 T:999     debug <general>: Keyboard: scancode: 0x67, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-03-24 21:18:03.144 T:1004    debug <general>: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms
2023-03-24 21:18:03.144 T:1148    debug <general>: Thread Timer start, auto delete: false
2023-03-24 21:18:03.145 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:03.344 T:1148    debug <general>: Thread Timer 3666616960 terminating
2023-03-24 21:18:03.366 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:03.366 T:999     debug <general>: HandleKey: return (0xf00d) pressed, window 10700, action is Select
2023-03-24 21:18:03.367 T:999     debug <general>: AddOnLog: pvr.iptvsimple: pvr.iptvsimple - FormatDateTimeNowOnly - "http://cqif7zqp.ottclub.xyz/iptv/SW5TUP9DA9CYZ6/11010/index.m3u8"
2023-03-24 21:18:03.367 T:999      info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:03.367 T:999     debug <general>: AddOnLog: pvr.iptvsimple: pvr.iptvsimple - GetURLWithFFmpegReconnectOptions - FFmpeg Reconnect Stream URL: http://cqif7zqp.ottclub.xyz/iptv/SW5TUP9DA9CYZ6/11010/index.m3u8|reconnect=1&reconnect_streamed=1&reconnect_delay_max=4294
2023-03-24 21:18:03.367 T:999      info <general>: AddOnLog: pvr.iptvsimple: pvr.iptvsimple - GetChannelStreamProperties - Live Stream URL: http://cqif7zqp.ottclub.xyz/iptv/SW5TUP9DA9CYZ6/11010/index.m3u8
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerCoreFactory::GetPlayers(http://cqif7zqp.ottclub.xyz/iptv/SW5TUP9DA9CYZ6/11010/index.m3u8)
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: streams
2023-03-24 21:18:03.400 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mp2
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=false
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2023-03-24 21:18:03.401 T:999     debug <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2023-03-24 21:18:03.404 T:999     debug <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2023-03-24 21:18:03.404 T:999     debug <general>: Audio ID3 tag processor - new CVideoPlayerAudioID3
2023-03-24 21:18:03.404 T:999      info <general>: VideoPlayer::OpenFile: pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/1@pvr.iptvsimple_237149043.pvr
2023-03-24 21:18:03.405 T:1149    debug <general>: Thread VideoPlayer start, auto delete: false
2023-03-24 21:18:03.406 T:999     debug <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2023-03-24 21:18:03.406 T:1149     info <general>: Creating InputStream
2023-03-24 21:18:03.407 T:1149     info <general>: Creating Demuxer
2023-03-24 21:18:03.408 T:1149    debug <general>: easy_acquire - Created session to http://cqif7zqp.ottclub.xyz
2023-03-24 21:18:03.426 T:1146    debug <general>: Loading settings for pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/1@pvr.iptvsimple_237149043.pvr
2023-03-24 21:18:03.510 T:999     debug <general>: CPlayerGUIInfo::InitCurrentItem(pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/1@pvr.iptvsimple_237149043.pvr)
2023-03-24 21:18:03.650 T:999      info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:03.650 T:999     debug <general>: ------ Window Init (DialogBusy.xml) ------
2023-03-24 21:18:03.816 T:1149     info <general>: ffmpeg[0x36a7e60]: [hls] Skip ('#EXT-X-VERSION:3')
2023-03-24 21:18:03.816 T:1149     info <general>: ffmpeg[0x36a7e60]: [hls] Opening 'http://93.189.63.115/iptv/SW5TUP9DA9CYZ6/11010/1679685444000.ts?md5=OpBpagmF1NN537sP1HmWLw' for reading
2023-03-24 21:18:03.833 T:1149     info <general>: ffmpeg[0x36a7e60]: [hls] Opening 'http://93.189.63.115/iptv/SW5TUP9DA9CYZ6/11010/1679685455000.ts?md5=OpBpagmF1NN537sP1HmWLw' for reading
2023-03-24 21:18:03.953 T:1149    debug <general>: Open - avformat_find_stream_info starting
2023-03-24 21:18:04.948 T:1149    debug <general>: Open - av_find_stream_info finished
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]: Input #0, hls, from 'http://cqif7zqp.ottclub.xyz/iptv/SW5TUP9DA9CYZ6/11010/index.m3u8':
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:   Duration: N/A, start: 66901.021933, bitrate: N/A
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:   Program 0 
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:     Metadata:
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:       variant_bitrate : 0
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:   Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 24 fps, 24 tbr, 90k tbn, 48 tbc
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:     Metadata:
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:       variant_bitrate : 0
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:   Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:     Metadata:
2023-03-24 21:18:04.948 T:1149     info <general>: ffmpeg[0x36a7e60]:       variant_bitrate : 0
2023-03-24 21:18:04.948 T:1149    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-03-24 21:18:04.948 T:1149    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-03-24 21:18:04.949 T:1149     info <general>: Opening stream: 0 source: 256
2023-03-24 21:18:04.949 T:1149     info <general>: Creating video codec with codec id: 27
2023-03-24 21:18:04.949 T:1149     info <general>: CDVDVideoCodecDRMPRIME::Open - using decoder H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-03-24 21:18:04.949 T:1149    debug <general>: CDVDVideoCodecDRMPRIME::Open - using drm device for av_hwdevice_ctx: /dev/dri/card1
2023-03-24 21:18:04.949 T:1149    debug <general>: ffmpeg[0x36a7e60]: [h264] Requested frame threading with a custom get_buffer2() implementation which is not marked as thread safe. This is not supported anymore, make your callback thread-safe.
2023-03-24 21:18:04.952 T:1149     info <general>: ffmpeg[0x36a7e60]: Using device /dev/video0
2023-03-24 21:18:04.953 T:1149    debug <general>: CDVDVideoCodecDRMPRIME::FilterTest - found deinterlacing filter deinterlace_v4l2m2m
2023-03-24 21:18:04.953 T:1149    debug <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
2023-03-24 21:18:04.954 T:1149     info <general>: Creating video thread
2023-03-24 21:18:04.954 T:1162    debug <general>: Thread VideoPlayerVideo start, auto delete: false
2023-03-24 21:18:04.954 T:1162     info <general>: running thread: video_thread
2023-03-24 21:18:04.954 T:1162    debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false
2023-03-24 21:18:04.954 T:1149     info <general>: Opening stream: 1 source: 256
2023-03-24 21:18:04.954 T:1149     info <general>: Finding audio codec for: 86018
2023-03-24 21:18:04.955 T:1149     info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2023-03-24 21:18:04.955 T:1149     info <general>: OpenStream: Allowing max Out-Of-Sync Value of 10 ms
2023-03-24 21:18:04.955 T:1149     info <general>: Creating audio thread
2023-03-24 21:18:04.955 T:1163    debug <general>: Thread VideoPlayerAudio start, auto delete: false
2023-03-24 21:18:04.955 T:1163     info <general>: running thread: CVideoPlayerAudio::Process()
2023-03-24 21:18:04.955 T:1163    debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false
2023-03-24 21:18:04.956 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 2
2023-03-24 21:18:04.956 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:04.956 T:1149    debug <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2023-03-24 21:18:04.956 T:1149    debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2023-03-24 21:18:04.956 T:1146    debug <general>: OnAVChange: CApplication::OnAVChange
2023-03-24 21:18:04.956 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
2023-03-24 21:18:04.956 T:1162    debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-03-24 21:18:04.960 T:1162    debug <general>: ffmpeg[0x36a9630]: [h264] v4l2_request_probe_video_device: try output format failed
2023-03-24 21:18:04.966 T:1163     info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:04.966 T:1163    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-03-24 21:18:04.967 T:1163    debug <general>: CVideoPlayerAudio: stream props changed, checking for passthrough
2023-03-24 21:18:04.968 T:1163     info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2023-03-24 21:18:04.969 T:1163     info <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through)
2023-03-24 21:18:04.969 T:1163    debug <general>: CVideoPlayerAudio:: synctype set to 1: resample
2023-03-24 21:18:05.001 T:1007     info <general>: CActiveAESink::OpenSink - initialize sink
2023-03-24 21:18:05.079 T:1162    debug <general>: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 24.00.
2023-03-24 21:18:05.104 T:999     debug <general>: DeleteRenderer - deleting renderer
2023-03-24 21:18:05.104 T:999     debug <general>: LinuxRendererGLES: Cleaning up GLES resources
2023-03-24 21:18:05.104 T:999     debug <general>: CDRMPlane::SupportsFormatAndModifier - found plane format (NV12) and modifier (LINEAR)
2023-03-24 21:18:05.104 T:999     debug <general>: CRenderManager::Configure - 5
2023-03-24 21:18:05.111 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 69
2023-03-24 21:18:05.111 T:999     debug <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2023-03-24 21:18:05.141 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 70
2023-03-24 21:18:05.172 T:1149    debug <general>: CVideoPlayer::HandleMessages - player started 2
2023-03-24 21:18:05.172 T:1146    debug <general>: OnAVChange: CApplication::OnAVChange
2023-03-24 21:18:05.175 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 72
2023-03-24 21:18:05.201 T:1007    debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0
2023-03-24 21:18:05.201 T:1007     info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0"
2023-03-24 21:18:05.208 T:1007     info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-03-24 21:18:05.209 T:1007     info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-03-24 21:18:05.209 T:1007     info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
2023-03-24 21:18:05.209 T:1007    debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
2023-03-24 21:18:05.209 T:1007    debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
2023-03-24 21:18:05.209 T:1007    debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2023-03-24 21:18:05.209 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2023-03-24 21:18:05.210 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2023-03-24 21:18:05.210 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2023-03-24 21:18:05.210 T:1007    debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2023-03-24 21:18:05.210 T:1007    debug <general>:   Output Device : HDMI
2023-03-24 21:18:05.210 T:1007    debug <general>:   Sample Rate   : 48000
2023-03-24 21:18:05.210 T:1007    debug <general>:   Sample Format : AE_FMT_S24NE4
2023-03-24 21:18:05.210 T:1007    debug <general>:   Channel Count : 2
2023-03-24 21:18:05.210 T:1007    debug <general>:   Channel Layout: FL, FR
2023-03-24 21:18:05.210 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 71
2023-03-24 21:18:05.210 T:1007    debug <general>:   Frames        : 2400
2023-03-24 21:18:05.210 T:1007    debug <general>:   Frame Size    : 8
2023-03-24 21:18:05.215 T:1006    debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-03-24 21:18:05.255 T:1149     info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:05.255 T:1149    debug <general>: CVideoPlayer::HandleMessages - player started 1
2023-03-24 21:18:05.256 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:05.256 T:1149    debug <general>: VideoPlayer::Sync - Audio - pts: 298667.000000, cache: 514222.564983, totalcache: 800000.011921
2023-03-24 21:18:05.256 T:1146    debug <general>: OnAVChange: CApplication::OnAVChange
2023-03-24 21:18:05.256 T:1149    debug <general>: VideoPlayer::Sync - Video - pts: 118999.666667, cache: 50000.000000, totalcache: 100000.000000
2023-03-24 21:18:05.256 T:1162    debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-901333.011921)
2023-03-24 21:18:05.256 T:1163    debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-901333.011921), level: 11, cache: 476928.897983
2023-03-24 21:18:05.256 T:1146    debug <general>: OnAVStarted: CApplication::OnAVStarted
2023-03-24 21:18:05.256 T:1163    debug <general>: CDVDAudio::Resume - resume audio stream
2023-03-24 21:18:05.256 T:1006    debug <general>: ActiveAE - start sync of audio stream
2023-03-24 21:18:05.284 T:999     debug <general>: Activating window ID: 12005
2023-03-24 21:18:05.284 T:999     debug <general>: ------ Window Deinit (MyPVRChannels.xml) ------
2023-03-24 21:18:05.284 T:999     debug <general>: FreeVisualisation() done
2023-03-24 21:18:05.286 T:999     debug <general>: ------ Window Init (VideoFullScreen.xml) ------
2023-03-24 21:18:05.299 T:999     debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2023-03-24 21:18:05.325 T:1146    debug <general>: OnAVChange: CApplication::OnAVChange
2023-03-24 21:18:05.363 T:1006    debug <general>: ActiveAE::SyncStream - average error of 726.795178, start adjusting
2023-03-24 21:18:05.801 T:1162  warning <general>: OutputPicture - timeout waiting for buffer
2023-03-24 21:18:06.063 T:1006    debug <general>: ActiveAE::SyncStream - average error 0.272317, last average error: 726.795178
2023-03-24 21:18:06.213 T:1006    debug <general>: ActiveAE::SyncStream - average error of -49.284559, start adjusting
2023-03-24 21:18:06.213 T:1006    debug <general>: ActiveAE::SyncStream - average error -0.013725 below threshold of 30.000000
2023-03-24 21:18:07.822 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
2023-03-24 21:18:08.502 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:11.269 T:1162    debug <general>: CPtsTracker: detected pattern of length 1: 41666.67, frameduration: 41666.666667
2023-03-24 21:18:14.563 T:1149     info <general>: ffmpeg[0x36a7e60]: [hls] Skip ('#EXT-X-VERSION:3')
2023-03-24 21:18:14.563 T:1149     info <general>: ffmpeg[0x36a7e60]: [http] Opening 'http://93.189.63.115/iptv/SW5TUP9DA9CYZ6/11010/1679685465000.ts?md5=OpBpagmF1NN537sP1HmWLw' for reading
2023-03-24 21:18:20.236 T:1163     info <general>: CVideoPlayerAudio::Process - stream stalled
2023-03-24 21:18:20.536 T:1162    debug <general>: CPtsTracker: pattern lost on diff 83333.000000, number of losses 1
2023-03-24 21:18:20.552 T:1162    debug <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 24.000000 fps
2023-03-24 21:18:20.617 T:1149    debug <general>: Stream stalled, start buffering. Audio: 0 - Video: 0
2023-03-24 21:18:20.617 T:1162    debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-03-24 21:18:20.617 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 1
2023-03-24 21:18:20.617 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:20.617 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:20.618 T:1163    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-03-24 21:18:20.677 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:21.275 T:1149     info <general>: Skipped 18 duplicate messages..
2023-03-24 21:18:21.275 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 2
2023-03-24 21:18:21.275 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:21.275 T:1163    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-03-24 21:18:21.275 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 3
2023-03-24 21:18:21.275 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:21.275 T:1163    debug <general>: CDVDAudio::Resume - resume audio stream
2023-03-24 21:18:21.275 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 0
2023-03-24 21:18:21.275 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:21.275 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:21.281 T:1006    debug <general>: ActiveAE - start sync of audio stream
2023-03-24 21:18:21.311 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:-0.050000
2023-03-24 21:18:21.311 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:21.413 T:1006     info <general>: Skipped 3 duplicate messages..
2023-03-24 21:18:21.413 T:1006    debug <general>: ActiveAE::SyncStream - average error of -215.675013, start adjusting
2023-03-24 21:18:21.414 T:1006    debug <general>: ActiveAE::SyncStream - average error -15.675013 below threshold of 30.000000
2023-03-24 21:18:21.469 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:22.791 T:1162     info <general>: Skipped 23 duplicate messages..
2023-03-24 21:18:22.791 T:1162    debug <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 24.000000 fps
2023-03-24 21:18:22.979 T:1163     info <general>: CVideoPlayerAudio::Process - stream stalled
2023-03-24 21:18:23.027 T:1149    debug <general>: Stream stalled, start buffering. Audio: 0 - Video: 0
2023-03-24 21:18:23.027 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 1
2023-03-24 21:18:23.027 T:1162    debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-03-24 21:18:23.028 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:23.027 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:23.028 T:1163    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-03-24 21:18:23.048 T:1162    error <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2023-03-24 21:18:23.676 T:1162     info <general>: Skipped 14 duplicate messages..
2023-03-24 21:18:23.676 T:1162    debug <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 24.000000 fps
2023-03-24 21:18:32.520 T:1004    debug <general>: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms
2023-03-24 21:18:32.520 T:1190    debug <general>: Thread Timer start, auto delete: false
2023-03-24 21:18:32.533 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:32.728 T:1190    debug <general>: Thread Timer 3666616960 terminating
2023-03-24 21:18:32.733 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:32.733 T:999     debug <general>: HandleKey: return (0xf00d) pressed, window 12005, action is OSD
2023-03-24 21:18:32.733 T:999     debug <general>: ------ Window Init (VideoOSD.xml) ------
2023-03-24 21:18:32.747 T:999     debug <general>: ------ Window Init (DialogSeekBar.xml) ------
2023-03-24 21:18:32.767 T:999     debug <general>: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
2023-03-24 21:18:33.415 T:1004    debug <general>: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms
2023-03-24 21:18:33.416 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:33.416 T:1191    debug <general>: Thread Timer start, auto delete: false
2023-03-24 21:18:33.560 T:1191    debug <general>: Thread Timer 3666616960 terminating
2023-03-24 21:18:33.566 T:999     debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-03-24 21:18:33.567 T:999     debug <general>: HandleKey: return (0xf00d) pressed, window 12901, action is Select
2023-03-24 21:18:33.567 T:999      info <general>: CVideoPlayer::CloseFile()
2023-03-24 21:18:33.567 T:999     debug <general>: DeleteRenderer - deleting renderer
2023-03-24 21:18:33.567 T:999      info <general>: VideoPlayer: waiting for threads to exit
2023-03-24 21:18:33.620 T:1149     info <general>: CVideoPlayer::OnExit()
2023-03-24 21:18:33.620 T:1149    debug <general>: CVideoPlayer::SetCaching - caching state 0
2023-03-24 21:18:33.620 T:1149    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-03-24 21:18:33.620 T:1149    debug <general>: CVideoPlayer: updating file item stream details with available streams
2023-03-24 21:18:33.620 T:1149     info <general>: Closing stream player 1
2023-03-24 21:18:33.620 T:1149     info <general>: Waiting for audio thread to exit
2023-03-24 21:18:33.620 T:1163    debug <general>: CDVDAudio::Resume - resume audio stream
2023-03-24 21:18:33.620 T:1163     info <general>: thread end: CVideoPlayerAudio::OnExit()
2023-03-24 21:18:33.621 T:1163    debug <general>: Thread VideoPlayerAudio 3447939712 terminating
2023-03-24 21:18:33.621 T:1149     info <general>: Closing audio device
2023-03-24 21:18:33.664 T:1149    debug <general>: CDVDAudio::Flush - flush audio stream
2023-03-24 21:18:33.668 T:1006    debug <general>: CActiveAE::DiscardStream - audio stream deleted
2023-03-24 21:18:33.669 T:1006    debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-03-24 21:18:33.676 T:1149     info <general>: Skipped 2 duplicate messages..
2023-03-24 21:18:33.676 T:1149     info <general>: Deleting audio codec
2023-03-24 21:18:33.676 T:1149     info <general>: Closing stream player 2
2023-03-24 21:18:33.676 T:1149     info <general>: waiting for video thread to exit
2023-03-24 21:18:33.703 T:1162     info <general>: thread end: video_thread
2023-03-24 21:18:33.703 T:1162    debug <general>: Thread VideoPlayerVideo 3439547008 terminating
2023-03-24 21:18:33.704 T:1149     info <general>: deleting video codec
2023-03-24 21:18:33.713 T:1007     info <general>: CActiveAESink::OpenSink - initialize sink
2023-03-24 21:18:33.738 T:1149    debug <general>: Thread VideoPlayer 3884970624 terminating
2023-03-24 21:18:33.738 T:999      info <general>: VideoPlayer: finished waiting
2023-03-24 21:18:33.738 T:999      info <general>: CVideoPlayer::CloseFile()
2023-03-24 21:18:33.739 T:999      info <general>: VideoPlayer: waiting for threads to exit
2023-03-24 21:18:33.739 T:999      info <general>: VideoPlayer: finished waiting
2023-03-24 21:18:33.741 T:999     debug <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2023-03-24 21:18:33.741 T:999     debug <general>: Audio ID3 tag processor - delete ~CVideoPlayerAudioID3
2023-03-24 21:18:33.750 T:1145     info <general>: Deleting settings information for files pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/1@pvr.iptvsimple_237149043.pvr
2023-03-24 21:18:33.751 T:1145    debug <general>: DoWork - Saving file state for video item pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/1@pvr.iptvsimple_237149043.pvr
2023-03-24 21:18:33.840 T:1145    debug <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2023-03-24 21:18:33.894 T:999     debug <general>: CGUIWindowManager::PreviousWindow: Deactivate
2023-03-24 21:18:33.894 T:999     debug <general>: ------ Window Deinit (VideoOSD.xml) ------
2023-03-24 21:18:33.895 T:999     debug <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2023-03-24 21:18:33.914 T:1007    debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0
2023-03-24 21:18:33.914 T:1007     info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0"
2023-03-24 21:18:33.915 T:999     debug <general>: CGUIWindowManager::PreviousWindow: Activate new
2023-03-24 21:18:33.915 T:999     debug <general>: ------ Window Init (MyPVRChannels.xml) ------
2023-03-24 21:18:33.920 T:999     debug <general>: CGUIMediaWindow::GetDirectory (pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/)
2023-03-24 21:18:33.920 T:999     debug <general>:   ParentPath = [pvr://channels/tv/%d0%a3%d0%b6%d0%b0%d1%81%d1%8b/]
2023-03-24 21:18:33.926 T:1007     info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2023-03-24 21:18:33.926 T:1007     info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-03-24 21:18:33.926 T:1007     info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
2023-03-24 21:18:33.926 T:1007    debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
2023-03-24 21:18:33.927 T:1007    debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
2023-03-24 21:18:33.927 T:1007    debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2023-03-24 21:18:33.927 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2023-03-24 21:18:33.927 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2023-03-24 21:18:33.927 T:1007    debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2023-03-24 21:18:33.928 T:1007    debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2023-03-24 21:18:33.928 T:1007    debug <general>:   Output Device : HDMI
2023-03-24 21:18:33.928 T:1007    debug <general>:   Sample Rate   : 44100
2023-03-24 21:18:33.928 T:1007    debug <general>:   Sample Format : AE_FMT_S24NE4
2023-03-24 21:18:33.928 T:1007    debug <general>:   Channel Count : 2
2023-03-24 21:18:33.928 T:1007    debug <general>:   Channel Layout: FL, FR
2023-03-24 21:18:33.928 T:1007    debug <general>:   Frames        : 2205
2023-03-24 21:18:33.928 T:1007    debug <general>:   Frame Size    : 8
2023-03-24 21:18:33.934 T:1006    debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-03-24 21:18:33.963 T:999      info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:33.963 T:999     debug <general>: ffmpeg[0x0]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2023-03-24 21:18:34.097 T:1145     info <general>: Skipped 1 duplicate messages..
2023-03-24 21:18:34.097 T:1145    debug <general>: GetImageHash - unable to stat url CineMan%20%d0%9a%d0%b0%d1%82%d0%b0%d1%81%d1%82%d1%80%d0%be%d1%84%d1%8b
2023-03-24 21:18:34.098 T:1210    debug <general>: Thread JobWorker start, auto delete: true
2023-03-24 21:18:34.098 T:1146    debug <general>: GetImageHash - unable to stat url CineMan%20%d0%a3%d0%b6%d0%b0%d1%81%d1%8b
2023-03-24 21:18:34.098 T:1145    debug <general>: GetImageHash - unable to stat url KBC-%d0%9a%d0%be%d1%88%d0%bc%d0%b0%d1%80%d0%bd%d0%be%d0%b5
2023-03-24 21:18:34.099 T:1210    debug <general>: GetImageHash - unable to stat url Kinoshka%20Mystic%20HD
2023-03-24 21:18:34.100 T:1146    debug <general>: GetImageHash - unable to stat url Kinoshka%20Thriller%20HD
2023-03-24 21:18:34.100 T:1210    debug <general>: GetImageHash - unable to stat url Liberty%20%d0%a2%d1%80%d0%b8%d0%bb%d0%bb%d0%b5%d1%80%204K
2023-03-24 21:18:34.101 T:1145    debug <general>: GetImageHash - unable to stat url Liberty%20%d0%a3%d0%b6%d0%b0%d1%81%d1%8b%204K
2023-03-24 21:18:34.101 T:1146    debug <general>: GetImageHash - unable to stat url Magic%20Horror%20HD
2023-03-24 21:18:34.101 T:1210    debug <general>: GetImageHash - unable to stat url Magic%20Thriller%20HD
2023-03-24 21:18:34.108 T:999     debug <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2023-03-24 21:18:34.110 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 69
2023-03-24 21:18:34.110 T:999     debug <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2023-03-24 21:18:34.173 T:999     debug <general>: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
2023-03-24 21:18:34.174 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 70
2023-03-24 21:18:34.201 T:999     debug <general>: CheckIdle - Closing session to http://cqif7zqp.ottclub.xyz (easy=0xea40f778, multi=0xea424c20)
2023-03-24 21:18:34.208 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 72
2023-03-24 21:18:34.239 T:999     debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 71